builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0378 starttime: 1467817502.63 results: success (0) buildid: 20160706071026 builduid: dd9f5df0a2fd4df48e2f8624da8b715a revision: cc1d6ac8152de5394ca090ff24aa89c590ef87a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:02.632770) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:02.633455) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:02.633886) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:02.662776) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:02.663227) ========= 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.kzEp69qc9Y/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.BwpvKakHHg/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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-06 08:05:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-07-06 08:05:03 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.589711 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:03.360687) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:03.361030) ========= 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.kzEp69qc9Y/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.BwpvKakHHg/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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.020473 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:03.406696) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 08:05:03.407065) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cc1d6ac8152de5394ca090ff24aa89c590ef87a4 --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 cc1d6ac8152de5394ca090ff24aa89c590ef87a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kzEp69qc9Y/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.BwpvKakHHg/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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-06 08:05:03,489 truncating revision to first 12 chars 2016-07-06 08:05:03,489 Setting DEBUG logging. 2016-07-06 08:05:03,489 attempt 1/10 2016-07-06 08:05:03,489 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cc1d6ac8152d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 08:05:04,606 unpacking tar archive at: fx-team-cc1d6ac8152d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.384674 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 08:05:04.814795) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:04.815104) ========= script_repo_revision: cc1d6ac8152de5394ca090ff24aa89c590ef87a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:04.815496) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:04.815783) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 08:05:04.840081) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-06 08:05:04.840478) ========= /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.kzEp69qc9Y/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.BwpvKakHHg/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:05:05 INFO - MultiFileLogger online at 20160706 08:05:05 in /builds/slave/test 08:05:05 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 08:05:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:05:05 INFO - {'append_to_log': False, 08:05:05 INFO - 'base_work_dir': '/builds/slave/test', 08:05:05 INFO - 'blob_upload_branch': 'Fx-Team', 08:05:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:05:05 INFO - 'branch': 'Fx-Team', 08:05:05 INFO - 'buildbot_json_path': 'buildprops.json', 08:05:05 INFO - 'config_files': ('talos/mac_config.py',), 08:05:05 INFO - 'default_actions': ('clobber', 08:05:05 INFO - 'read-buildbot-config', 08:05:05 INFO - 'download-and-extract', 08:05:05 INFO - 'populate-webroot', 08:05:05 INFO - 'create-virtualenv', 08:05:05 INFO - 'install', 08:05:05 INFO - 'run-tests'), 08:05:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:05:05 INFO - 'download_minidump_stackwalk': True, 08:05:05 INFO - 'download_symbols': 'ondemand', 08:05:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:05:05 INFO - 'tooltool.py': '/tools/tooltool.py', 08:05:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:05:05 INFO - '/tools/misc-python/virtualenv.py')}, 08:05:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:05:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:05:05 INFO - 'installer_path': 'installer.exe', 08:05:05 INFO - 'log_level': 'info', 08:05:05 INFO - 'log_name': 'talos', 08:05:05 INFO - 'log_to_console': True, 08:05:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:05:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:05:05 INFO - 'opt_config_files': (), 08:05:05 INFO - 'pip_index': False, 08:05:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:05:05 INFO - 'cmd': ('bash', 08:05:05 INFO - '-c', 08:05:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:05:05 INFO - 'enabled': True, 08:05:05 INFO - 'halt_on_failure': False, 08:05:05 INFO - 'name': 'check_screen_resolution'},), 08:05:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:05:05 INFO - 'cmd': ('bash', 08:05:05 INFO - '-c', 08:05:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:05:05 INFO - 'enabled': True, 08:05:05 INFO - 'halt_on_failure': False, 08:05:05 INFO - 'name': 'check_screen_resolution'},), 08:05:05 INFO - 'run_cmd_checks_enabled': True, 08:05:05 INFO - 'sps_profile': False, 08:05:05 INFO - 'sps_profile_interval': 0, 08:05:05 INFO - 'suite': 'dromaeojs', 08:05:05 INFO - 'system_bits': '32', 08:05:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:05:05 INFO - 'title': 't-yosemite-r7-0378', 08:05:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:05:05 INFO - 'use_talos_json': True, 08:05:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:05:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:05:05 INFO - 'work_dir': 'build'} 08:05:05 INFO - ##### 08:05:05 INFO - ##### Running clobber step. 08:05:05 INFO - ##### 08:05:05 INFO - Running pre-action listener: _resource_record_pre_action 08:05:05 INFO - Running main action method: clobber 08:05:05 INFO - rmtree: /builds/slave/test/build 08:05:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:05:07 INFO - Running post-action listener: _resource_record_post_action 08:05:07 INFO - ##### 08:05:07 INFO - ##### Finished clobber step (success) 08:05:07 INFO - ##### 08:05:07 INFO - ##### 08:05:07 INFO - ##### Running read-buildbot-config step. 08:05:07 INFO - ##### 08:05:07 INFO - Running pre-action listener: _resource_record_pre_action 08:05:07 INFO - Running main action method: read_buildbot_config 08:05:07 INFO - Using buildbot properties: 08:05:07 INFO - { 08:05:07 INFO - "project": "", 08:05:07 INFO - "product": "firefox", 08:05:07 INFO - "script_repo_revision": "production", 08:05:07 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:05:07 INFO - "repository": "", 08:05:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:05:07 INFO - "buildid": "20160706071026", 08:05:07 INFO - "pgo_build": "False", 08:05:07 INFO - "basedir": "/builds/slave/test", 08:05:07 INFO - "buildnumber": 86, 08:05:07 INFO - "slavename": "t-yosemite-r7-0378", 08:05:07 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:05:07 INFO - "platform": "yosemite_r7", 08:05:07 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:05:07 INFO - "branch": "fx-team", 08:05:07 INFO - "repo_path": "integration/fx-team", 08:05:07 INFO - "revision": "cc1d6ac8152de5394ca090ff24aa89c590ef87a4", 08:05:07 INFO - "stage_platform": "macosx64", 08:05:07 INFO - "builduid": "dd9f5df0a2fd4df48e2f8624da8b715a", 08:05:07 INFO - "slavebuilddir": "test" 08:05:07 INFO - } 08:05:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 08:05:07 INFO - Running post-action listener: _resource_record_post_action 08:05:07 INFO - ##### 08:05:07 INFO - ##### Finished read-buildbot-config step (success) 08:05:07 INFO - ##### 08:05:07 INFO - ##### 08:05:07 INFO - ##### Running download-and-extract step. 08:05:07 INFO - ##### 08:05:07 INFO - Running pre-action listener: _resource_record_pre_action 08:05:07 INFO - Running main action method: download_and_extract 08:05:07 INFO - mkdir: /builds/slave/test/build/tests 08:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:05:07 INFO - https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:05:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 08:05:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 08:05:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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 08:05:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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 08:05:09 INFO - Downloaded 1199 bytes. 08:05:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 08:05:09 INFO - Using the following test package requirements: 08:05:09 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 08:05:09 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:05:09 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 08:05:09 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:05:09 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 08:05:09 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:05:09 INFO - u'jsshell-mac.zip'], 08:05:09 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:05:09 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 08:05:09 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 08:05:09 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:05:09 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 08:05:09 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:05:09 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 08:05:09 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:05:09 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 08:05:09 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:05:09 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 08:05:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:05:09 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')]} 08:05:09 INFO - https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:05:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:05:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:05:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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 08:05:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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 08:05:10 INFO - Downloaded 18802562 bytes. 08:05:10 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'] 08:05:10 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 08:05:13 INFO - Return code: 0 08:05:13 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 08:05: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')]} 08:05:13 INFO - https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:05:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:05:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:05:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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 08:05:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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 08:05:19 INFO - Downloaded 18802562 bytes. 08:05: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'] 08:05: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 08:05:23 INFO - Return code: 0 08:05: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')]} 08:05:23 INFO - https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:05:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 08:05:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 08:05:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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 08:05:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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 08:05:24 INFO - Downloaded 14403333 bytes. 08:05:24 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'] 08:05:24 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 08:05:24 INFO - Return code: 0 08:05:24 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')]} 08:05:24 INFO - https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:05:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:05:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:05:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 08:05:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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 08:05:29 INFO - Downloaded 112488666 bytes. 08:05:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:05:29 INFO - mkdir: /builds/slave/test/properties 08:05:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:05:29 INFO - Writing to file /builds/slave/test/properties/build_url 08:05:29 INFO - Contents: 08:05:29 INFO - build_url:https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:05:30 INFO - Running post-action listener: _resource_record_post_action 08:05:30 INFO - Running post-action listener: set_extra_try_arguments 08:05:30 INFO - ##### 08:05:30 INFO - ##### Finished download-and-extract step (success) 08:05:30 INFO - ##### 08:05:30 INFO - ##### 08:05:30 INFO - ##### Running populate-webroot step. 08:05:30 INFO - ##### 08:05:30 INFO - Running pre-action listener: _resource_record_pre_action 08:05:30 INFO - Running main action method: populate_webroot 08:05:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:05:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:05:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:05:30 INFO - '--tppagecycles', 08:05:30 INFO - '7'], 08:05:30 INFO - 'tests': ['tsvgm']}}, 08:05:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:05:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:05:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:05:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:05:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:30 INFO - 'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:05:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:05:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:30 INFO - 'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['damp', 'tps']}, 08:05:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:30 INFO - 'tests': ['damp', 'tps']}, 08:05:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['dromaeo_dom']}, 08:05:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:05:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['basic_compositor_video']}, 08:05:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:05:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['a11yr', 08:05:30 INFO - 'ts_paint', 08:05:30 INFO - 'tpaint', 08:05:30 INFO - 'sessionrestore', 08:05:30 INFO - 'sessionrestore_no_auto_restore', 08:05:30 INFO - 'tabpaint']}, 08:05:30 INFO - 'other-e10s': {'tests': ['a11yr', 08:05:30 INFO - 'ts_paint', 08:05:30 INFO - 'tpaint', 08:05:30 INFO - 'sessionrestore', 08:05:30 INFO - 'sessionrestore_no_auto_restore', 08:05:30 INFO - 'tabpaint']}, 08:05:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:05:30 INFO - 'ts_paint', 08:05:30 INFO - 'tpaint', 08:05:30 INFO - 'sessionrestore', 08:05:30 INFO - 'sessionrestore_no_auto_restore', 08:05:30 INFO - 'tabpaint']}, 08:05:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:05:30 INFO - 'ts_paint', 08:05:30 INFO - 'tpaint', 08:05:30 INFO - 'sessionrestore', 08:05:30 INFO - 'sessionrestore_no_auto_restore', 08:05:30 INFO - 'tabpaint']}, 08:05:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['a11yr', 08:05:30 INFO - 'ts_paint', 08:05:30 INFO - 'tpaint', 08:05:30 INFO - 'sessionrestore', 08:05:30 INFO - 'sessionrestore_no_auto_restore', 08:05:30 INFO - 'tabpaint']}, 08:05:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['a11yr', 08:05:30 INFO - 'ts_paint', 08:05:30 INFO - 'tpaint', 08:05:30 INFO - 'sessionrestore', 08:05:30 INFO - 'sessionrestore_no_auto_restore', 08:05:30 INFO - 'tabpaint']}, 08:05:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['tsvgx', 08:05:30 INFO - 'tsvgr_opacity', 08:05:30 INFO - 'tart', 08:05:30 INFO - 'tscrollx', 08:05:30 INFO - 'cart']}, 08:05:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:05:30 INFO - 'tsvgr_opacity', 08:05:30 INFO - 'tart', 08:05:30 INFO - 'tscrollx', 08:05:30 INFO - 'cart']}, 08:05:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:30 INFO - 'talos_options': ['--disable-e10s'], 08:05:30 INFO - 'tests': ['tp5o']}, 08:05:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:30 INFO - 'tests': ['tp5o']}, 08:05:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:30 INFO - 'talos_options': ['--disable-e10s', 08:05:30 INFO - '--xperf_path', 08:05:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:05:30 INFO - 'tests': ['tp5n']}, 08:05:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:05:30 INFO - 'talos_options': ['--xperf_path', 08:05:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:05:30 INFO - 'tests': ['tp5n']}}, 08:05:30 INFO - 'talos.zip': {'path': '', 08:05:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:05:30 INFO - Running post-action listener: _resource_record_post_action 08:05:30 INFO - ##### 08:05:30 INFO - ##### Finished populate-webroot step (success) 08:05:30 INFO - ##### 08:05:30 INFO - ##### 08:05:30 INFO - ##### Running create-virtualenv step. 08:05:30 INFO - ##### 08:05:30 INFO - Running pre-action listener: _resource_record_pre_action 08:05:30 INFO - Running main action method: create_virtualenv 08:05:30 INFO - Creating virtualenv /builds/slave/test/build/venv 08:05:30 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 08:05:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:05:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:05:30 INFO - Using real prefix '/tools/python27' 08:05:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:05:31 INFO - Installing distribute.............................................................................................................................................................................................done. 08:05:34 INFO - Installing pip.................done. 08:05:34 INFO - Return code: 0 08:05:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:34 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 0x10e939a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe629579440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, '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.kzEp69qc9Y/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.BwpvKakHHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:05:34 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 08:05:34 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 08:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:34 INFO - 'HOME': '/Users/cltbld', 08:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:34 INFO - 'LOGNAME': 'cltbld', 08:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:34 INFO - 'MOZ_NO_REMOTE': '1', 08:05:34 INFO - 'NO_EM_RESTART': '1', 08:05:34 INFO - 'PAGER': '/bin/cat', 08:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:34 INFO - 'PWD': '/builds/slave/test', 08:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:34 INFO - 'SHELL': '/bin/bash', 08:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:05:34 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:34 INFO - 'USER': 'cltbld', 08:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:34 INFO - 'XPC_FLAGS': '0x0', 08:05:34 INFO - 'XPC_SERVICE_NAME': '0', 08:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:34 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 08:05:34 INFO - Cleaning up... 08:05:34 INFO - Return code: 0 08:05:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:34 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 0x10e939a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe629579440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, '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.kzEp69qc9Y/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.BwpvKakHHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:05:34 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 08:05:34 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 08:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:34 INFO - 'HOME': '/Users/cltbld', 08:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:34 INFO - 'LOGNAME': 'cltbld', 08:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:34 INFO - 'MOZ_NO_REMOTE': '1', 08:05:34 INFO - 'NO_EM_RESTART': '1', 08:05:34 INFO - 'PAGER': '/bin/cat', 08:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:34 INFO - 'PWD': '/builds/slave/test', 08:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:34 INFO - 'SHELL': '/bin/bash', 08:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:05:34 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:34 INFO - 'USER': 'cltbld', 08:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:34 INFO - 'XPC_FLAGS': '0x0', 08:05:34 INFO - 'XPC_SERVICE_NAME': '0', 08:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:34 INFO - Downloading/unpacking psutil>=3.1.1 08:05:34 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 08:05:34 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 08:05:34 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 08:05:34 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 08:05:34 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 08:05:34 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 08:05:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:05:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:37 INFO - Installing collected packages: psutil 08:05:37 INFO - Running setup.py install for psutil 08:05:37 INFO - building 'psutil._psutil_osx' extension 08:05:37 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 08:05:38 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 08:05:38 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 08:05:38 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 08:05:38 INFO - building 'psutil._psutil_posix' extension 08:05:38 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 08:05:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:05:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:05:38 INFO - ^ 08:05:38 INFO - 1 warning generated. 08:05:38 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 08:05:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:38 INFO - Successfully installed psutil 08:05:38 INFO - Cleaning up... 08:05:38 INFO - Return code: 0 08:05:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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')]} 08:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e939a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe629579440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, '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.kzEp69qc9Y/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.BwpvKakHHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:05: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:05: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 mozsystemmonitor==0.3 08:05:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:05:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:38 INFO - 'HOME': '/Users/cltbld', 08:05:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:38 INFO - 'LOGNAME': 'cltbld', 08:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:38 INFO - 'MOZ_NO_REMOTE': '1', 08:05:38 INFO - 'NO_EM_RESTART': '1', 08:05:38 INFO - 'PAGER': '/bin/cat', 08:05:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:38 INFO - 'PWD': '/builds/slave/test', 08:05:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:38 INFO - 'SHELL': '/bin/bash', 08:05:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:05:38 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:05:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:38 INFO - 'USER': 'cltbld', 08:05:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:38 INFO - 'XPC_FLAGS': '0x0', 08:05:38 INFO - 'XPC_SERVICE_NAME': '0', 08:05:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:05:41 INFO - Installing collected packages: mozsystemmonitor 08:05:41 INFO - Running setup.py install for mozsystemmonitor 08:05:41 INFO - Successfully installed mozsystemmonitor 08:05:41 INFO - Cleaning up... 08:05:41 INFO - Return code: 0 08:05:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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')]} 08:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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', '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 0x10e939a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe629579440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, '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.kzEp69qc9Y/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.BwpvKakHHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:05: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:05: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 jsonschema==2.5.1 08:05:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:05:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:41 INFO - 'HOME': '/Users/cltbld', 08:05:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:41 INFO - 'LOGNAME': 'cltbld', 08:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:41 INFO - 'MOZ_NO_REMOTE': '1', 08:05:41 INFO - 'NO_EM_RESTART': '1', 08:05:41 INFO - 'PAGER': '/bin/cat', 08:05:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:41 INFO - 'PWD': '/builds/slave/test', 08:05:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:41 INFO - 'SHELL': '/bin/bash', 08:05:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:05:41 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:05:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:41 INFO - 'USER': 'cltbld', 08:05:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:41 INFO - 'XPC_FLAGS': '0x0', 08:05:41 INFO - 'XPC_SERVICE_NAME': '0', 08:05:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:41 INFO - Downloading/unpacking jsonschema==2.5.1 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:05:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05:44 INFO - Downloading functools32-3.2.3-2.tar.gz 08:05:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:05:45 INFO - warning: no files found matching '*.txt' 08:05:45 INFO - no previously-included directories found matching 'build' 08:05:45 INFO - no previously-included directories found matching 'dist' 08:05:45 INFO - no previously-included directories found matching '.git*' 08:05:45 INFO - Installing collected packages: jsonschema, functools32 08:05:45 INFO - Running setup.py install for functools32 08:05:45 INFO - warning: no files found matching '*.txt' 08:05:45 INFO - no previously-included directories found matching 'build' 08:05:45 INFO - no previously-included directories found matching 'dist' 08:05:45 INFO - no previously-included directories found matching '.git*' 08:05:45 INFO - Successfully installed jsonschema functools32 08:05:45 INFO - Cleaning up... 08:05:45 INFO - Return code: 0 08:05:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e939a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe629579440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, '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.kzEp69qc9Y/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.BwpvKakHHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:05:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:45 INFO - 'HOME': '/Users/cltbld', 08:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:45 INFO - 'LOGNAME': 'cltbld', 08:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:45 INFO - 'MOZ_NO_REMOTE': '1', 08:05:45 INFO - 'NO_EM_RESTART': '1', 08:05:45 INFO - 'PAGER': '/bin/cat', 08:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:45 INFO - 'PWD': '/builds/slave/test', 08:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:45 INFO - 'SHELL': '/bin/bash', 08:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:05:45 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:45 INFO - 'USER': 'cltbld', 08:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:45 INFO - 'XPC_FLAGS': '0x0', 08:05:45 INFO - 'XPC_SERVICE_NAME': '0', 08:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:45 INFO - Downloading/unpacking blobuploader==1.2.4 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05:48 INFO - Downloading blobuploader-1.2.4.tar.gz 08:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:05:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:05:48 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 08:05:48 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 08:05:48 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 08:05:48 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 08:05:48 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 08:05:48 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 08:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:05:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:05:48 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 08:05:48 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 08:05:48 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 08:05:48 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 08:05:48 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 08:05:48 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 08:05:49 INFO - Downloading docopt-0.6.1.tar.gz 08:05:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:05:49 INFO - Installing collected packages: blobuploader, requests, docopt 08:05:49 INFO - Running setup.py install for blobuploader 08:05:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:05:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:05:49 INFO - Running setup.py install for requests 08:05:49 INFO - Running setup.py install for docopt 08:05:50 INFO - Successfully installed blobuploader requests docopt 08:05:50 INFO - Cleaning up... 08:05:50 INFO - Return code: 0 08:05:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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')]} 08:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:50 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 0x10e939a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe629579440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, '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.kzEp69qc9Y/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.BwpvKakHHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:05:50 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 08:05:50 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 08:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:50 INFO - 'HOME': '/Users/cltbld', 08:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:50 INFO - 'LOGNAME': 'cltbld', 08:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:50 INFO - 'MOZ_NO_REMOTE': '1', 08:05:50 INFO - 'NO_EM_RESTART': '1', 08:05:50 INFO - 'PAGER': '/bin/cat', 08:05:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:50 INFO - 'PWD': '/builds/slave/test', 08:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:50 INFO - 'SHELL': '/bin/bash', 08:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:05:50 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:50 INFO - 'USER': 'cltbld', 08:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:50 INFO - 'XPC_FLAGS': '0x0', 08:05:50 INFO - 'XPC_SERVICE_NAME': '0', 08:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:50 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-kEbuSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:50 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-eYdOWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:50 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-rscf5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:50 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-EO_d7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:50 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-nEWABo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:50 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-pLdEGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:51 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-eKt0ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:51 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-QQxWnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:51 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-3zJj9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:51 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-PoFJ_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:51 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-lSDtvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:51 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-I26JC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:51 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-hetRyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:51 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-wv_sP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:51 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-9vHaUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:52 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-um3vZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:52 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-Ijix6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:05:52 INFO - Running setup.py install for manifestparser 08:05:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:05:52 INFO - Running setup.py install for mozcrash 08:05:52 INFO - Running setup.py install for mozdebug 08:05:52 INFO - Running setup.py install for mozdevice 08:05:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:05:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:05:52 INFO - Running setup.py install for mozfile 08:05:52 INFO - Running setup.py install for mozhttpd 08:05:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:05:53 INFO - Running setup.py install for mozinfo 08:05:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:05:53 INFO - Running setup.py install for mozInstall 08:05:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:05:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:05:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:05:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:05:53 INFO - Running setup.py install for mozleak 08:05:53 INFO - Running setup.py install for mozlog 08:05:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:05:53 INFO - Running setup.py install for moznetwork 08:05:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:05:53 INFO - Running setup.py install for mozprocess 08:05:54 INFO - Running setup.py install for mozprofile 08:05:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:05:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:05:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:05:54 INFO - Running setup.py install for mozrunner 08:05:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:05:54 INFO - Running setup.py install for mozscreenshot 08:05:54 INFO - Running setup.py install for moztest 08:05:54 INFO - Running setup.py install for mozversion 08:05:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:05:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:05:54 INFO - Cleaning up... 08:05:54 INFO - Return code: 0 08:05:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:54 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 0x10e939a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe629579440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, '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.kzEp69qc9Y/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.BwpvKakHHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:05:54 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 08:05:54 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 08:05:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:05:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:54 INFO - 'HOME': '/Users/cltbld', 08:05:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:54 INFO - 'LOGNAME': 'cltbld', 08:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:54 INFO - 'MOZ_NO_REMOTE': '1', 08:05:54 INFO - 'NO_EM_RESTART': '1', 08:05:54 INFO - 'PAGER': '/bin/cat', 08:05:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:54 INFO - 'PWD': '/builds/slave/test', 08:05:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:54 INFO - 'SHELL': '/bin/bash', 08:05:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:05:54 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:05:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:54 INFO - 'USER': 'cltbld', 08:05:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:54 INFO - 'XPC_FLAGS': '0x0', 08:05:54 INFO - 'XPC_SERVICE_NAME': '0', 08:05:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:55 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-nmjf9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:55 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)) 08:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:55 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-DMalD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:55 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)) 08:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:55 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-hJhnzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:55 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)) 08:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:55 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-03p929-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:55 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)) 08:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:55 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-9Nz55o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:55 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)) 08:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:55 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-CeMpMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:55 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)) 08:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:55 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-6ecNJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:55 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)) 08:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:55 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-qRv9c7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:55 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)) 08:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:55 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-GtlmfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:56 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)) 08:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:56 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-uvEIKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:56 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)) 08:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:56 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-O7vSAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:56 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)) 08:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:56 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-XCdxE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:56 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-BGQP3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:56 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)) 08:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:56 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-lC1uwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:56 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-HmPgET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:56 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)) 08:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:56 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-KDH4cC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:56 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)) 08:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:56 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-XSLtS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:56 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)) 08:05:56 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)) 08:05:56 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)) 08:05:56 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)) 08:05:56 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)) 08:05:56 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)) 08:05:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:59 INFO - Downloading blessings-1.6.tar.gz 08:05:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:05:59 INFO - Installing collected packages: blessings 08:05:59 INFO - Running setup.py install for blessings 08:05:59 INFO - Successfully installed blessings 08:05:59 INFO - Cleaning up... 08:05:59 INFO - Return code: 0 08:05:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:05:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:06:00 INFO - Reading from file tmpfile_stdout 08:06:00 INFO - Current package versions: 08:06:00 INFO - blessings == 1.6 08:06:00 INFO - blobuploader == 1.2.4 08:06:00 INFO - docopt == 0.6.1 08:06:00 INFO - functools32 == 3.2.3-2 08:06:00 INFO - jsonschema == 2.5.1 08:06:00 INFO - manifestparser == 1.1 08:06:00 INFO - mozInstall == 1.12 08:06:00 INFO - mozcrash == 0.17 08:06:00 INFO - mozdebug == 0.1 08:06:00 INFO - mozdevice == 0.48 08:06:00 INFO - mozfile == 1.2 08:06:00 INFO - mozhttpd == 0.7 08:06:00 INFO - mozinfo == 0.9 08:06:00 INFO - mozleak == 0.1 08:06:00 INFO - mozlog == 3.2 08:06:00 INFO - moznetwork == 0.27 08:06:00 INFO - mozprocess == 0.22 08:06:00 INFO - mozprofile == 0.28 08:06:00 INFO - mozrunner == 6.11 08:06:00 INFO - mozscreenshot == 0.1 08:06:00 INFO - mozsystemmonitor == 0.3 08:06:00 INFO - moztest == 0.7 08:06:00 INFO - mozversion == 1.4 08:06:00 INFO - psutil == 3.1.1 08:06:00 INFO - requests == 1.2.3 08:06:00 INFO - wsgiref == 0.1.2 08:06:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06:00 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 0x10e939a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe629579440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, '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.kzEp69qc9Y/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.BwpvKakHHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:06:00 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 08:06:00 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 08:06:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:06:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:00 INFO - 'HOME': '/Users/cltbld', 08:06:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:00 INFO - 'LOGNAME': 'cltbld', 08:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:00 INFO - 'MOZ_NO_REMOTE': '1', 08:06:00 INFO - 'NO_EM_RESTART': '1', 08:06:00 INFO - 'PAGER': '/bin/cat', 08:06:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:00 INFO - 'PWD': '/builds/slave/test', 08:06:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:00 INFO - 'SHELL': '/bin/bash', 08:06:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:06:00 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:06:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:00 INFO - 'USER': 'cltbld', 08:06:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:00 INFO - 'XPC_FLAGS': '0x0', 08:06:00 INFO - 'XPC_SERVICE_NAME': '0', 08:06:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:00 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)) 08:06:00 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)) 08:06:00 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)) 08:06:00 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)) 08:06:00 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)) 08:06:00 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)) 08:06:00 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)) 08:06:00 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)) 08:06:00 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)) 08:06:00 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)) 08:06:00 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)) 08:06:00 INFO - Cleaning up... 08:06:00 INFO - Return code: 0 08:06:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e939a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9ce618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe629579440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, '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.kzEp69qc9Y/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.BwpvKakHHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 08:06:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:06:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:06:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:00 INFO - 'HOME': '/Users/cltbld', 08:06:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:00 INFO - 'LOGNAME': 'cltbld', 08:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:00 INFO - 'MOZ_NO_REMOTE': '1', 08:06:00 INFO - 'NO_EM_RESTART': '1', 08:06:00 INFO - 'PAGER': '/bin/cat', 08:06:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:00 INFO - 'PWD': '/builds/slave/test', 08:06:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:00 INFO - 'SHELL': '/bin/bash', 08:06:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:06:00 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:06:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:00 INFO - 'USER': 'cltbld', 08:06:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:00 INFO - 'XPC_FLAGS': '0x0', 08:06:00 INFO - 'XPC_SERVICE_NAME': '0', 08:06:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:06:00 INFO - Cleaning up... 08:06:00 INFO - Return code: 0 08:06:00 INFO - Running post-action listener: _resource_record_post_action 08:06:00 INFO - Running post-action listener: _start_resource_monitoring 08:06:00 INFO - Starting resource monitoring. 08:06:00 INFO - ##### 08:06:00 INFO - ##### Finished create-virtualenv step (success) 08:06:00 INFO - ##### 08:06:00 INFO - ##### 08:06:00 INFO - ##### Running install step. 08:06:00 INFO - ##### 08:06:00 INFO - Running pre-action listener: _resource_record_pre_action 08:06:00 INFO - Running main action method: install 08:06:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:06:00 INFO - Reading from file tmpfile_stdout 08:06:00 INFO - Detecting whether we're running mozinstall >=1.0... 08:06:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:06:00 INFO - Reading from file tmpfile_stdout 08:06:00 INFO - Output received: 08:06:00 INFO - Usage: mozinstall [options] installer 08:06:00 INFO - Options: 08:06:00 INFO - -h, --help show this help message and exit 08:06:00 INFO - -d DEST, --destination=DEST 08:06:00 INFO - Directory to install application into. [default: 08:06:00 INFO - "/builds/slave/test"] 08:06:00 INFO - --app=APP Application being installed. [default: firefox] 08:06:00 INFO - mkdir: /builds/slave/test/build/application 08:06:00 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'] 08:06:00 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 08:06:36 INFO - Reading from file tmpfile_stdout 08:06:36 INFO - Output received: 08:06:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:06:36 INFO - Running post-action listener: _resource_record_post_action 08:06:36 INFO - ##### 08:06:36 INFO - ##### Finished install step (success) 08:06:36 INFO - ##### 08:06:36 INFO - ##### 08:06:36 INFO - ##### Running run-tests step. 08:06:36 INFO - ##### 08:06:36 INFO - Running pre-action listener: _resource_record_pre_action 08:06:36 INFO - Running main action method: run_tests 08:06:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:06:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:06:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:06:36 INFO - 2016-07-06 08:06:36.100 screenresolution[2230:21761] starting screenresolution argv=screenresolution get 08:06:36 INFO - 2016-07-06 08:06:36.103 screenresolution[2230:21761] Display 0: 1600x1200x32@60 08:06:36 INFO - 2016-07-06 08:06:36.109 screenresolution[2231:21763] starting screenresolution argv=screenresolution list 08:06:36 INFO - Available Modes on Display 0 08:06:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:06:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:06:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:06:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:06:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:06:36 INFO - Intel Iris: 08:06:36 INFO - Chipset Model: Intel Iris 08:06:36 INFO - Type: GPU 08:06:36 INFO - Bus: Built-In 08:06:36 INFO - VRAM (Dynamic, Max): 1536 MB 08:06:36 INFO - Vendor: Intel (0x8086) 08:06:36 INFO - Device ID: 0x0a2e 08:06:36 INFO - Revision ID: 0x0009 08:06:36 INFO - Displays: 08:06:36 INFO - Display: 08:06:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:06:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:06:36 INFO - Display Serial Number: 1600x1200 60 08:06:36 INFO - Main Display: Yes 08:06:36 INFO - Mirror: Off 08:06:36 INFO - Online: Yes 08:06:36 INFO - Rotation: Supported 08:06:36 INFO - Return code: 0 08:06:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:06:36 INFO - Python 2.7.3 08:06:36 INFO - Return code: 0 08:06:36 INFO - grabbing minidump binary from tooltool 08:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:06:36 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 0x10e89dd30>, '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 0x10e602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e895b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:06:36 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 08:06:36 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 08:06:36 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 08:06:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:06:36 INFO - Return code: 0 08:06:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:06:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:06:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:06:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:06:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:06:36 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/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0378', '--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 08:06:36 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/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0378 --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 08:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kzEp69qc9Y/Render', 08:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:36 INFO - 'HOME': '/Users/cltbld', 08:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:36 INFO - 'LOGNAME': 'cltbld', 08:06:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:06:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:36 INFO - 'MOZ_NO_REMOTE': '1', 08:06:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:06:36 INFO - 'NO_EM_RESTART': '1', 08:06:36 INFO - 'PAGER': '/bin/cat', 08:06:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:36 INFO - 'PWD': '/builds/slave/test', 08:06:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:36 INFO - 'SHELL': '/bin/bash', 08:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BwpvKakHHg/Listeners', 08:06:36 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 08:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:36 INFO - 'USER': 'cltbld', 08:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:36 INFO - 'XPC_FLAGS': '0x0', 08:06:36 INFO - 'XPC_SERVICE_NAME': '0', 08:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:36 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/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0378', '--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 08:06:36 INFO - mozversion application_buildid: 20160706071026 08:06:36 INFO - mozversion application_changeset: cc1d6ac8152de5394ca090ff24aa89c590ef87a4 08:06:36 INFO - mozversion application_display_name: Nightly 08:06:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:06:36 INFO - mozversion application_name: Firefox 08:06:36 INFO - mozversion application_remotingname: firefox 08:06:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:06:36 INFO - mozversion application_vendor: Mozilla 08:06:36 INFO - mozversion application_version: 50.0a1 08:06:36 INFO - mozversion platform_buildid: 20160706071026 08:06:36 INFO - mozversion platform_changeset: cc1d6ac8152de5394ca090ff24aa89c590ef87a4 08:06:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:06:36 INFO - mozversion platform_version: 50.0a1 08:06:36 INFO - using testdate: 1467817596 08:06:36 INFO - actual date: 1467817596 08:06:36 INFO - starting webserver on 'localhost:56465' 08:06:36 INFO - SUITE-START | Running 2 tests 08:06:36 INFO - TEST-START | dromaeo_css 08:06:36 INFO - operating with platform_type : mac_ 08:06:36 INFO - Initialising browser for dromaeo_css test... 08:06:36 INFO - TEST-INFO | started process 2241 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmpA2oMPA/profile http://localhost:56465/getInfo.html) 08:06:39 INFO - PROCESS | 2241 | __metrics Screen width/height:1600/1200 08:06:39 INFO - PROCESS | 2241 | colorDepth:24 08:06:39 INFO - PROCESS | 2241 | Browser inner width/height: 1024/689 08:06:39 INFO - PROCESS | 2241 | __metrics 08:06:39 INFO - PROCESS | 2241 | 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" 08:06:39 INFO - PROCESS | 2241 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:06:39 INFO - TEST-INFO | 2241: exit 0 08:06:39 INFO - Browser initialized. 08:06:39 INFO - Running cycle 1/1 for dromaeo_css test... 08:06:40 INFO - TEST-INFO | started process 2243 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmpA2oMPA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:06:51 INFO - PROCESS | 2243 | RSS: Main: 244879360 08:06:51 INFO - PROCESS | 2243 | 08:07:42 INFO - PROCESS | 2243 | 1467817662813 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] 08:09:54 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:56465/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56465/tests/dromaeo/cssquery-ext.html) 08:09:55 INFO - PROCESS | 2243 | RSS: Main: 361648128 08:09:55 INFO - PROCESS | 2243 | 08:12:59 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:56465/tests/dromaeo/cssquery-ext.html (next: http://localhost:56465/tests/dromaeo/cssquery-jquery.html) 08:12:59 INFO - PROCESS | 2243 | RSS: Main: 373121024 08:12:59 INFO - PROCESS | 2243 | 08:16:01 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:56465/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56465/tests/dromaeo/cssquery-mootools.html) 08:16:02 INFO - PROCESS | 2243 | RSS: Main: 363196416 08:16:02 INFO - PROCESS | 2243 | 08:19:04 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:56465/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56465/tests/dromaeo/cssquery-prototype.html) 08:19:05 INFO - PROCESS | 2243 | RSS: Main: 373932032 08:19:05 INFO - PROCESS | 2243 | 08:22:08 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:56465/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56465/tests/dromaeo/cssquery-yui.html) 08:22:09 INFO - PROCESS | 2243 | RSS: Main: 334991360 08:22:09 INFO - PROCESS | 2243 | 08:25:12 INFO - PROCESS | 2243 | Cycle 1(1): loaded http://localhost:56465/tests/dromaeo/cssquery-yui.html (next: http://localhost:56465/tests/dromaeo/cssquery-dojo.html) 08:25:12 INFO - PROCESS | 2243 | RSS: Main: 384565248 08:25:12 INFO - PROCESS | 2243 | 08:25:12 INFO - PROCESS | 2243 | __start_tp_report 08:25:12 INFO - PROCESS | 2243 | _x_x_mozilla_page_load 08:25:12 INFO - PROCESS | 2243 | _x_x_mozilla_page_load_details 08:25:12 INFO - PROCESS | 2243 | |i|pagename|runs| 08:25:12 INFO - PROCESS | 2243 | |0;dojo.html;2901.810257794304;3382.103742508236;3403.8978830635087;3408.9659103408967;3423.726101911846;5642.294713160855;5674.886502269957;5720.084786434171;5789.160571717099;5820.243368362112;5635.380108188096;5706.315242170935;5771.711414429283;5772.307323121221;5815.709214539267;4331.41525894005;5165.199394093921;5252.3697156341295;5263.210518422229;5321.201819727034;4495.707778994355;5281.947180528184;5344.866378340534;5359.517643412092;5366.543843773284;5168.3539557555305;5196.986587615379;5199.766010529535;5227.50338717825;5283.656562323456;5219.42586315505;5241.764120614544;5244;5327.334083239595;5348.224507446437;5220;5224.608154388436;5253.238280449314;5307.203919412119;5310.893782124374;5687.090065589486;5725.599208055438;5819.563532735062;5863.442972917566;5895.292564892197;6207.751689932397;6261.46777523911;6274.215723034621;6280.968595157041;6328.335524769879;6284.497240220771;6334.2398912130375;6344.6510441925675;6349.746010159634;6361.491080713532;5698.857528561819;5762.567807414377;5854.253783538442;5868.031774757203;5935.37678543751;6261;6284.24589049317;6297.1813664223355;6326;6347.873042539123;6246.281677607042;6257.405546473078;6265.8746825063245;6266.373362663697;6270.717817698214;5536.474034966672;6195.380461953769;6271.811845644638;6294.8111556653375;6349.365063493706;6240.188775459162;6275.215598050244;6334.968325158345;6343.9365606343345;6352.460040896575;6327.588706734047;6332.746690132343;6359.491240700733;6372.745090196433;6381.298057213703;4891.9755401222765;4913.140200464904;4946.505349465025;4963.156263435152;5024.849254522297;3852.57621661617;4406.4051353066925;4420.977895110504;4433.844815431445;4543.727376357429;5757.740901659436;5787.88424231513;5789.884202316014;5794.449527294901;5846.532277417797;5254.816081437131;6186.752529898754;6216.906746398808;6364.49084073273;6396.136521569621;5699.2590963174525;5797.130430435468;5807.157962095473;5895.233619629421;5910.615809972338;5478.287822583039;5500.559955203574;5505.862353441196;5507.146392309199;5525.640833345819;6431.549791514736;6433.067205255212;6444.7744328948265;6449.484041276781;6460.773872914426;6196.132541444112;6285.685715714105;6336.778212762531;6347;6365.2361716594305;6340.873182536416;6376.744930202925;6386.648734319657;6397.0404439332615;6413.326600707045;6117.462039585284;6221.844453888689;6320.209973753281;6324.304326523987;6337.04944258365;5312.9734351328;6250.124982502544;6274.341194174728;6301;6335.493160547053;6214.098955651496;6223.284322302993;6229.127922091002;6241.251049874044;6242.094896239929;5997.940020599738;6111.04482360895;6122.256477468552;6183.196184495987;6201.751929922752;5956.851078723066;6067.180930574493;6071.868664785156;6095;6110.877782444415;6164.106204600218;6213.565050446426;6220.968895155495;6228;6238.812835614939;5807.157962095388;5820.883582328414;5906.822795316148;6079.392060793885;6091.1472393864005 08:25:12 INFO - PROCESS | 2243 | |1;ext.html;482.9809102793108;519.4441947116587;527.8284557518806;539.870431096537;540.9161579955106;29754.702452975464;30307;31912.840435797818;42335.78832105835;43315.566844331544;44202.778986105106;44621;44627.77686111565;44633;44640.77679611606;9722.222222222223;9930.404175749478;9937.304388692794;9937.751556211117;9949.005099490028;8834.46993180408;9000.774980625503;9739.318247722662;11922.284662920209;12102.27386356817;12073.456694448727;12081.577144799934;12166.452509637043;12167.939160304186;12169.087318451151;8854.955725221396;12083.879161208364;12146.210496317799;12152.574659886906;12152.756944861141;11543.94228028863;11797.292162470194;11808.763824723543;12104.213226140273;12131.575394861226;41898.79050604728;42244;42289;42292.78853605743;42321.57678423238;3585.15748799032;3709.7959612221316;3844.9615503844884;3927.3519869221545;4303.031817840992;32060.358792824245;32283;33273.6672633273;33296.33407331864;33307.500387494205;3250.6099268087746;3414.7609667323295;3433.141714571357;3466.636003219651;3472.791632502058;505139;506928.46535767824;507373;508281;509078;61938.45153871099;62033;62377;62427;62495.687521562104;65335.67332163309;65955.34044659587;65996;66143.00785488222;66150;16543.6691266174;16828;16832.24254908532;16842.663146736995;16946;11675.941620291844;11865.822012669816;12994.610161694975;13091.541796037092;13120.8847247985;15269.770953435707;15364.462243821412;15369.309375968858;15397;16710.4151354702;5656.4060773619;6599.571027883172;6641;6652.667366631649;6665.866682666319;919.9264058875274;949.2690628216235;963.9444807935299;971.4171497101823;991.8561808537866;30651.927182548505;30947.69052309493;30998.690013100033;31330;31478.842605787282;884.9424787388928;993.6969224386631;1048.905598496139;1110.8555887734542;1201.1891972918245;15876.206189690469;16022.679546409006;16078.276477558538;16195.43315983935;16443.58891027734;679986;684842;685367;686664;889937;51113.72215694637;60231.69884150551;61118.0832287516;61202.69398652978;65959;1341.9329033548283;1433.6917562723856;1460.6640472691129;1461.5030889497623;1466.3621324723565;30948.607312670534;31069.844650776602;32025;32057.519137212494;32636.673633264312;50457.74771126121;50665.74667126641;50807.74596127144;50890;50945.74527127489;631201;633117;635547.6445235673;636917;637891;26211.606825897245;26295.868520657274;27129.186124416297;28364.290892727848;41619;35727.64272357243;41498.585014149474;48162.27756583724;48530.51469485401;51451.42742862917;40281.59718402896;40901.38647920343;40957.79521102375;41404.58595414007;42264;39669.60330396659;40598.79700601478;41322.38016429816;41680.3747943775;42293.94265143396;38771;39353.21293574055;39526.60473395229;39666;39680;34527.82736086303;35451.468227977115;36240.81879590585;36318.636813632584;36428.81785591055;22912.921390048345;25846.353841154123;26164.60753088743;26270;26380.193936858523 08:25:12 INFO - PROCESS | 2243 | |2;jquery.html;382.77033779732164;385.39037893460096;387.4014647369813;392.53288586581965;412.36084069691987;14880.404783808635;20877.895610521944;21766.782332176674;21871.562568748635;21921;16221.351145954148;19449.611007779873;21552.353429397102;21556;21684.783152168475;5653.4911857932775;5912.379200183983;5936.554758393117;5937;5940.138679891435;5612.186232996213;5787.21872547207;5869.55978301627;5886.823395298126;5893.1749555062115;5799.478046975772;5823.3012038555435;5831.387704291052;5832.59171857968;5840.153177789218;4925.581325587329;5618.775248990056;5810.1865738796605;5816.243888294516;5830.883382332372;5096.05722941257;5103.438621751604;5106.744662766884;5110.718910459923;5121.564667003309;20348.491287717778;26655.600165997526;26794.86602566994;27156;27265.45469090607;2291.8051965582945;2538.7842033427182;2574.8712564371895;2754.559270516708;3085.6142982127235;14997.62885311369;15794.526164215093;16240.75638865418;16326.265318060598;16426.01443913369;1737.9304827806873;1810.9094545272815;1868.6075924055965;1898.8860668359944;1911.3310341380545;123673.38163309125;124575.9999999991;125201;125525.37237313847;125734;9565.904340956502;10604.681859544226;10668.14654827612;11063.944680276547;11081.5567377304;13805.930970345285;13894;13901.026928114934;13918;13935.442582296595;7591.3547348475995;8001.959990200012;8297.087320394752;8565.229129378262;8740.169683880022;8660.437071590326;8702.738917832476;8706.434081784664;8772.605232764541;8785.648574057093;6588.748137853792;6702.731890724414;6704.597724136567;6755;6795.694193761194;1610.4443966831425;1858.6375656746804;1862.6181632765251;1878.3613571385795;1909.255390397746;946.6592026870321;954.231843366097;1079.956801727922;1087.1683162380784;1097.6597254851022;21966.45083872916;22751.544969100527;22764;22779.08883644669;22903.427414314774;847.2715660053545;988.945607991553;992.3202606214766;993.9453330066771;1062.2139616683598;11401.543938242377;11656.941715291368;11839.34883581394;11939;11956.880431195577;551610;573187;575983;587822;596171;76260.23739762642;96529.03470965341;120601;123862.38068809599;128327.35836320759;982.2044144243185;995.7311525888115;1019.2763138171832;1052.847337136111;1054.314695447965;15451.768223476882;16526.869844681274;18414.723779143056;18740;19415.805841941965;47065.2940205904;55344.44655553393;55547.444525555846;55704.72147639236;56849.715751420976;82967.58516207381;83269.58365208378;83676;83825.5808720977;83845.58077209575;7879.330256927996;8037.437379383387;8038.919610803817;8044.3564514839845;8087.716929907425;11291.3789741565;11522.732499425225;12167.81748273759;12315.322657253937;12815.61553153407;10656.733581660521;10948.507317170586;10963.890361096606;10990.120790336594;10995.945020274847;10095.949520252352;11237.662870113909;11368.488258414822;11409.543618255177;11571.958523732905;10336.656234689442;10610.469476526298;11531.711707207387;11776.82334764962;11861.703457413634;6980.232174460907;7302.087239095114;7316.707631436853;7397.926020739724;7564.319211270792;10613.628523001658;11271.49278282463;11363;11413.429328533706;11505.942470287595 08:25:12 INFO - PROCESS | 2243 | |3;mootools.html;1418.0924208506565;1527.030335736807;1600.487843889955;1754.1229385307347;1901.9429417117492;8030.678772849086;8443.873341899876;8457.703980360673;8461.026981897076;8477.109903460141;8549.444286121397;8817.426867253624;8822.161894620016;8824.338174636912;8874.866876996834;2294.793468587831;2364.574376612209;2365.420471984366;2368.1948137633203;2368.2539999900064;1985.8510611704107;2100.978990210101;2326.1044497868356;2365.515069410775;2368.3013511014206;2325.6744055832196;2350.2361732437025;2356.1871154451774;2363.1728894886824;2364.810815134785;2300.907963681451;2314.36355002374;2318.9652155217686;2323.314622186459;2325.976740232593;2315.988420057889;2328.9767102329097;2329.126577533425;2332.941676458085;2336.4742932840145;7869.606519673994;8681.175288347597;8723.171298726611;8828.205461508494;8829.867551986725;5231.215317702337;5325.254464374991;5386.542143917733;5464.153056276284;5474.808381706661;3588.3361578108133;3706;3706.4440333949856;3739.2521495700967;3758.530183727034;1074.4788777442934;1092.5192915117323;1095.9780804383947;1111.5664890692635;1121.0975164992178;11657.883421165765;11899.107566932515;11923.463444145034;11925.522979080826;11933.462994165284;4595.37962375075;4629.629629629621;4850.878728031827;4854.83008094715;4863.829765958175;10239.18086553074;10249.436281004468;10266.178705703674;10285.691429257135;10285.794284114425;5380.354357477127;5383.192521121863;5384.973075134678;5387.326584176978;5400.054990376669;3733.2720119576966;4432;4557.680962332671;4567.862964111082;4918.385201849769;4047.838086476508;4064.7561146331313;4093.038136038029;4174.83300667976;4179.749215047107;1268.6701457621084;1293.7606542789615;1293.8835504804424;1306.549240512022;1308.3523655790445;946.9905300946903;949.3022628368144;950.2872845365976;954.746992047108;978.7798144105955;5909.349971503131;5912.852178695567;6068.362821903725;6077.088436734437;6198.2871969723155;424.82369816525795;444.5643269595814;446.2257982400532;452.8958339581915;454.86126731346593;3999.6600288975183;4109.404136400205;4132.938005929914;4146.274401979642;4169.45797046382;10570.2600817942;10628.627998020187;10860.239783215256;10965.95823396776;11054.668359949215;10971.177161712903;10991.285566438157;10998.835017474745;11019.889801102046;11037.172212084126;8241.95879020601;8398.412111152284;8463.026751923484;8482.533460659599;8485.278751306209;6029.216201893726;6030.517558595214;6037.396260374103;6107.175531303217;6122.7244773984385;9808.901910981085;10156.492175391053;10206.642767503101;10250.462430635464;10261.333013354108;8954.641814327615;10523.473826308807;10531.578736850746;10542.52558634848;10555.208359372924;7622.847543049219;7628.351590114902;7687.961560192163;7692.576908270099;7707.499012564165;8145.51126932386;8417.07412184647;8424.326053915795;8428.367872409717;8443.197896199852;6641.369069938348;7554.4334174936;7633.313001829862;7661.310482056641;7826.726064587712;7482.027336446227;7513.549187048795;7588.886166707614;7648.923510764821;7660.502067365604;6793.320667933167;7030.964845175741;7035.226125126334;7042.401395881408;7043.647817609201;4857.659963802641;4962.950370496249;5000.974995125001;5017.974910125426;5079.238114282887;5033.270175824412;5054.588564427427;5127.41034781005;5230.241614965885;5242.947570524246 08:25:12 INFO - PROCESS | 2243 | |4;prototype.html;270.5738461922473;302.9969700302997;309.06045621311216;315.5881574545219;317.7426284709387;4722.866512037112;5172.508611681889;5440.265564148839;5496.427322240545;5510.752016159272;5355.563218735476;5369.858632699866;5387.4073851876265;5403.189521571757;5488.423715509874;3351.631320554737;3860.5946375630574;4028.5769994150487;4041.696872734542;4043.757374557536;3607.981960090196;3631.7094632429475;3951.767251502906;4107.363358679395;4129.814158362866;3427.091820667513;3738.6822120119823;3742.719296052793;3856.1464516257906;4096.180763847227;3128.012747825821;3764.096616811959;3923.7253392262555;4094.406311084876;4111.218868414991;2957;3373.4771110477914;3513.3851575974104;3518.4194607889667;3521.4365701487895;4667.55305855186;4783.091212669617;4909.361782968227;5195.1687729963405;5226.189940559219;4688.648351373627;4701.717896926161;4703;4705.247160454311;4725.196716558194;5074.695518268915;5106.442535026816;5492.80775172867;5584.5811564132855;5620.241267428885;3402.9829850850833;3774.999625099351;3940.7438516496622;3953.446517487554;3972.245273398045;5100.770465329032;5623.662580245198;5803.738831752581;5966.47854797024;5995.6702381369005;3393.423118069934;3980.2735126438483;3994.7603143810884;4007.6393124618926;4014.437978682987;3686.594474607791;3853.8843834685003;4061.614146656063;4069.755814651071;4089.141280331104;3896.337622604164;4486.618637415856;4689.976550117228;4701.623870090384;4743.478217396084;4166.66666666666;4682.367880336179;5317.229001794718;5633.577481688808;5697.373288938214;1115.576081089197;1251.4368534159664;1310.88202061813;1375.8580378286;1381.951631692886;700.2787129256874;799.6081919859228;845.6406027438314;855.7475544714324;863.4430792139035;4154.543000269968;4374.162851602271;4399.824007039747;4404.955950440519;4406.628928869418;4330.198913201068;4648.883777905579;4823.614110871122;4853.379175245328;4881.926771098437;3746.8501259949844;4012.8194231259663;4310.31035034393;4349.499807522112;4396.12077584478;2316.339843144696;2322.8734063978955;2551.2601345609937;2563.410954935491;2566.242958327298;4428.047969686533;4490.730556166641;4783.808647654125;4986.4514903360605;5113.360829896263;4345.804438800262;4487.88780280489;4506.174999125359;4683.604386819528;4955.306257124005;4286.142771445723;4598.908021839545;4628.865927847699;4739.43598612457;5050.3182070420025;3913.882583522499;3992.7604343739467;4329.467261651969;4358.622067897405;4933.58064564516;4000.3799411091327;4266.488021437447;4739.644526660445;4770.689905156154;4862.05189987949;5349.592683292456;5742.425757424224;5991.400859913973;6024.819255422344;6047.758089676539;3926.7447615906017;3946.625070618287;3948.9605103948593;3960.9207815844097;3964.306246406925;5338.839834804962;5558.972205138948;5825;5825.76696932118;5836.766529338949;4767.475577686382;5587.77648894056;5609.663420194802;5626.521745651502;5640.915386269291;4847.321375007572;4905.288733133747;5579.939811435815;5593.07714227148;5623.88752224945;4593.104344652761;4970.229614409773;5278.255307240137;5489.752961116839;5540.279763630702;4549.9317510236715;4835.887745818552;5501.724913754216;5572;5612.663240205601;5478.643888147258;5489.161131021153;5515.448455154453;5524.823226644615;5541.722913854372 08:25:12 INFO - PROCESS | 2243 | |5;yui.html;777.9611019449026;824.5629816197415;825.5954582254697;833.1044127562868;855.7603870916143;15110.546683599483;15566.299516521756;16196.947198432094;16211.270492827822;16757.581060473494;15207.543773686779;15231.695366092672;15239.238038098107;15248.39006439744;15256.542303730906;733.156869599959;851.2338894994492;852.4927668037539;860.2730692564804;902.4269588811087;744.6090802328794;750.8873668949647;750.9249075092474;751.6805357722973;753.0586766541822;947.1759569174827;951.4719330771419;951.8191543606692;982.5824024789472;986.6152200641756;908.4140729229642;914.6524320758136;915.5742579700471;916.3173435790379;921.0651189043126;566.4562020460363;678.0101052463409;682.5495173185723;683.3542302524116;698.7589281697806;15433.845661543353;15438.5368438947;15461.458848940345;15470;15582.454614088452;14314.355853986595;14403.063800852911;14481.565553033426;14550.781738273934;14620.415183392652;11794.646160615195;11890.227135236182;11962.880371196263;12010.579629712918;12069.876132298303;4572.359869618256;4630.305454181866;4688;4703.764811759398;4802.567768900781;99669;123835.65171315745;126139.10791338138;126458.36770816088;126573;11371.488283027284;11559.422028898689;11572.305661660328;11702.239354441934;11957.88042119585;23675;23677.644835327483;23683.289501314644;23700.051997920236;23737.931793069354;7797.649105790139;7800.063992320957;7931.167227441036;7940.325072368798;7954.642041108164;4393.450818647669;4460.888477788081;4477.7984990675495;4492.258777301774;4599.931001034987;3606.567211934532;3711.7030637548933;3712.7957962311793;3716.7955762432784;3718.8140592970244;646.9741210351627;654.2508827392609;655.8294843340766;668.3683918696785;670.5775361522308;584.7222569279559;609.1441524657863;610.0513701194596;630.6058713304185;637.5855693798975;8305.46014509055;8776.297896168291;8790.164934331227;8854;9141.725748227564;563.456264704562;576.0047751691139;576.9855753606109;580.7473748919234;626.2078470734524;4406.9559304407185;4426.402435671143;4429.5962654178265;4464.303564645414;4571.634269258451;109840;137545;168532;171559;173682;115731.42134289275;116876.41561792306;120935;121404;130239.34880325537;106110.87778244546;113409.4329528347;114647.99999999833;115630.86819289584;116854.41572792082;8144.063432705315;8655;8678.305735541267;8700.869486957827;8886.555672216493;58413.12380314214;67348;67541;67951.3204867945;71130.64434677793;93630;102545.4618180713;106804.93195068261;108109;108372.45813771196;11765.411729413667;11903.166778325434;12016.819747703617;12437.344919333385;12552.560660376843;12623.179493332904;12658.683532911751;12744.872551274368;13253.939684825384;13279.734405312032;12042;12059;12126.818097728365;12481.875181248071;12543.3101179436;10208.846867296848;11970.521179153086;12059.095567832623;12107.728688487581;12223.511059557519;11656.88343116592;11775.470103845177;11848.348340840992;11899.46452409661;12244.877551224374;9696.70909872705;10005;10042.548085336324;10175.643852465424;10372.688819335432;10077;10204.2346823987;10207;10209.591616335263;10248 08:25:12 INFO - PROCESS | 2243 | __end_tp_report 08:25:12 INFO - PROCESS | 2243 | __start_cc_report 08:25:12 INFO - PROCESS | 2243 | _x_x_mozilla_cycle_collect,1203 08:25:12 INFO - PROCESS | 2243 | __end_cc_report 08:25:12 INFO - PROCESS | 2243 | __startTimestamp1467818712498__endTimestamp 08:25:12 INFO - PROCESS | 2243 | 08:25:12 INFO - PROCESS | 2243 | ------- Summary: start ------- 08:25:12 INFO - PROCESS | 2243 | Number of tests: 6 08:25:12 INFO - PROCESS | 2243 | 08:25:12 INFO - PROCESS | 2243 | [#0] dojo.html Cycles:165 Average:5784.67 Median:5997.94 stddev:687.08 (11.5%) stddev-sans-first:650.91 08:25:12 INFO - PROCESS | 2243 | Values: 2901.8 3382.1 3403.9 3409.0 3423.7 5642.3 5674.9 5720.1 5789.2 5820.2 5635.4 5706.3 5771.7 5772.3 5815.7 4331.4 5165.2 5252.4 5263.2 5321.2 4495.7 5281.9 5344.9 5359.5 5366.5 5168.4 5197.0 5199.8 5227.5 5283.7 5219.4 5241.8 5244.0 5327.3 5348.2 5220.0 5224.6 5253.2 5307.2 5310.9 5687.1 5725.6 5819.6 5863.4 5895.3 6207.8 6261.5 6274.2 6281.0 6328.3 6284.5 6334.2 6344.7 6349.7 6361.5 5698.9 5762.6 5854.3 5868.0 5935.4 6261.0 6284.2 6297.2 6326.0 6347.9 6246.3 6257.4 6265.9 6266.4 6270.7 5536.5 6195.4 6271.8 6294.8 6349.4 6240.2 6275.2 6335.0 6343.9 6352.5 6327.6 6332.7 6359.5 6372.7 6381.3 4892.0 4913.1 4946.5 4963.2 5024.8 3852.6 4406.4 4421.0 4433.8 4543.7 5757.7 5787.9 5789.9 5794.4 5846.5 5254.8 6186.8 6216.9 6364.5 6396.1 5699.3 5797.1 5807.2 5895.2 5910.6 5478.3 5500.6 5505.9 5507.1 5525.6 6431.5 6433.1 6444.8 6449.5 6460.8 6196.1 6285.7 6336.8 6347.0 6365.2 6340.9 6376.7 6386.6 6397.0 6413.3 6117.5 6221.8 6320.2 6324.3 6337.0 5313.0 6250.1 6274.3 6301.0 6335.5 6214.1 6223.3 6229.1 6241.3 6242.1 5997.9 6111.0 6122.3 6183.2 6201.8 5956.9 6067.2 6071.9 6095.0 6110.9 6164.1 6213.6 6221.0 6228.0 6238.8 5807.2 5820.9 5906.8 6079.4 6091.1 08:25:12 INFO - PROCESS | 2243 | 08:25:12 INFO - PROCESS | 2243 | [#1] ext.html Cycles:180 Average:75469.96 Median:39674.80 stddev:168999.84 (426.0%) stddev-sans-first:169380.15 08:25:12 INFO - PROCESS | 2243 | Values: 483.0 519.4 527.8 539.9 540.9 29754.7 30307.0 31912.8 42335.8 43315.6 44202.8 44621.0 44627.8 44633.0 44640.8 9722.2 9930.4 9937.3 9937.8 9949.0 8834.5 9000.8 9739.3 11922.3 12102.3 12073.5 12081.6 12166.5 12167.9 12169.1 8855.0 12083.9 12146.2 12152.6 12152.8 11543.9 11797.3 11808.8 12104.2 12131.6 41898.8 42244.0 42289.0 42292.8 42321.6 3585.2 3709.8 3845.0 3927.4 4303.0 32060.4 32283.0 33273.7 33296.3 33307.5 3250.6 3414.8 3433.1 3466.6 3472.8 505139.0 506928.5 507373.0 508281.0 509078.0 61938.5 62033.0 62377.0 62427.0 62495.7 65335.7 65955.3 65996.0 66143.0 66150.0 16543.7 16828.0 16832.2 16842.7 16946.0 11675.9 11865.8 12994.6 13091.5 13120.9 15269.8 15364.5 15369.3 15397.0 16710.4 5656.4 6599.6 6641.0 6652.7 6665.9 919.9 949.3 963.9 971.4 991.9 30651.9 30947.7 30998.7 31330.0 31478.8 884.9 993.7 1048.9 1110.9 1201.2 15876.2 16022.7 16078.3 16195.4 16443.6 679986.0 684842.0 685367.0 686664.0 889937.0 51113.7 60231.7 61118.1 61202.7 65959.0 1341.9 1433.7 1460.7 1461.5 1466.4 30948.6 31069.8 32025.0 32057.5 32636.7 50457.7 50665.7 50807.7 50890.0 50945.7 631201.0 633117.0 635547.6 636917.0 637891.0 26211.6 26295.9 27129.2 28364.3 41619.0 35727.6 41498.6 48162.3 48530.5 51451.4 40281.6 40901.4 40957.8 41404.6 42264.0 39669.6 40598.8 41322.4 41680.4 42293.9 38771.0 39353.2 39526.6 39666.0 39680.0 34527.8 35451.5 36240.8 36318.6 36428.8 22912.9 25846.4 26164.6 26270.0 26380.2 08:25:12 INFO - PROCESS | 2243 | 08:25:12 INFO - PROCESS | 2243 | [#2] jquery.html Cycles:180 Average:34463.06 Median:2415.29 stddev:96345.86 (3989.0%) stddev-sans-first:96582.15 08:25:12 INFO - PROCESS | 2243 | Values: 382.8 385.4 387.4 392.5 412.4 14880.4 20877.9 21766.8 21871.6 21921.0 16221.4 19449.6 21552.4 21556.0 21684.8 5653.5 5912.4 5936.6 5937.0 5940.1 5612.2 5787.2 5869.6 5886.8 5893.2 5799.5 5823.3 5831.4 5832.6 5840.2 4925.6 5618.8 5810.2 5816.2 5830.9 5096.1 5103.4 5106.7 5110.7 5121.6 20348.5 26655.6 26794.9 27156.0 27265.5 2291.8 2538.8 2574.9 2754.6 3085.6 14997.6 15794.5 16240.8 16326.3 16426.0 1737.9 1810.9 1868.6 1898.9 1911.3 123673.4 124576.0 125201.0 125525.4 125734.0 9565.9 10604.7 10668.1 11063.9 11081.6 13805.9 13894.0 13901.0 13918.0 13935.4 7591.4 8002.0 8297.1 8565.2 8740.2 8660.4 8702.7 8706.4 8772.6 8785.6 6588.7 6702.7 6704.6 6755.0 6795.7 1610.4 1858.6 1862.6 1878.4 1909.3 946.7 954.2 1080.0 1087.2 1097.7 21966.5 22751.5 22764.0 22779.1 22903.4 847.3 988.9 992.3 993.9 1062.2 11401.5 11656.9 11839.3 11939.0 11956.9 551610.0 573187.0 575983.0 587822.0 596171.0 76260.2 96529.0 120601.0 123862.4 128327.4 982.2 995.7 1019.3 1052.8 1054.3 15451.8 16526.9 18414.7 18740.0 19415.8 47065.3 55344.4 55547.4 55704.7 56849.7 82967.6 83269.6 83676.0 83825.6 83845.6 7879.3 8037.4 8038.9 8044.4 8087.7 11291.4 11522.7 12167.8 12315.3 12815.6 10656.7 10948.5 10963.9 10990.1 10995.9 10095.9 11237.7 11368.5 11409.5 11572.0 10336.7 10610.5 11531.7 11776.8 11861.7 6980.2 7302.1 7316.7 7397.9 7564.3 10613.6 11271.5 11363.0 11413.4 11505.9 08:25:12 INFO - PROCESS | 2243 | 08:25:12 INFO - PROCESS | 2243 | [#3] mootools.html Cycles:180 Average:5768.20 Median:4562.77 stddev:3271.52 (71.7%) stddev-sans-first:3264.36 08:25:12 INFO - PROCESS | 2243 | Values: 1418.1 1527.0 1600.5 1754.1 1901.9 8030.7 8443.9 8457.7 8461.0 8477.1 8549.4 8817.4 8822.2 8824.3 8874.9 2294.8 2364.6 2365.4 2368.2 2368.3 1985.9 2101.0 2326.1 2365.5 2368.3 2325.7 2350.2 2356.2 2363.2 2364.8 2300.9 2314.4 2319.0 2323.3 2326.0 2316.0 2329.0 2329.1 2332.9 2336.5 7869.6 8681.2 8723.2 8828.2 8829.9 5231.2 5325.3 5386.5 5464.2 5474.8 3588.3 3706.0 3706.4 3739.3 3758.5 1074.5 1092.5 1096.0 1111.6 1121.1 11657.9 11899.1 11923.5 11925.5 11933.5 4595.4 4629.6 4850.9 4854.8 4863.8 10239.2 10249.4 10266.2 10285.7 10285.8 5380.4 5383.2 5385.0 5387.3 5400.1 3733.3 4432.0 4557.7 4567.9 4918.4 4047.8 4064.8 4093.0 4174.8 4179.7 1268.7 1293.8 1293.9 1306.5 1308.4 947.0 949.3 950.3 954.7 978.8 5909.3 5912.9 6068.4 6077.1 6198.3 424.8 444.6 446.2 452.9 454.9 3999.7 4109.4 4132.9 4146.3 4169.5 10570.3 10628.6 10860.2 10966.0 11054.7 10971.2 10991.3 10998.8 11019.9 11037.2 8242.0 8398.4 8463.0 8482.5 8485.3 6029.2 6030.5 6037.4 6107.2 6122.7 9808.9 10156.5 10206.6 10250.5 10261.3 8954.6 10523.5 10531.6 10542.5 10555.2 7622.8 7628.4 7688.0 7692.6 7707.5 8145.5 8417.1 8424.3 8428.4 8443.2 6641.4 7554.4 7633.3 7661.3 7826.7 7482.0 7513.5 7588.9 7648.9 7660.5 6793.3 7031.0 7035.2 7042.4 7043.6 4857.7 4963.0 5001.0 5018.0 5079.2 5033.3 5054.6 5127.4 5230.2 5242.9 08:25:12 INFO - PROCESS | 2243 | 08:25:12 INFO - PROCESS | 2243 | [#4] prototype.html Cycles:180 Average:4288.89 Median:4658.22 stddev:1318.93 (28.3%) stddev-sans-first:1287.68 08:25:12 INFO - PROCESS | 2243 | Values: 270.6 303.0 309.1 315.6 317.7 4722.9 5172.5 5440.3 5496.4 5510.8 5355.6 5369.9 5387.4 5403.2 5488.4 3351.6 3860.6 4028.6 4041.7 4043.8 3608.0 3631.7 3951.8 4107.4 4129.8 3427.1 3738.7 3742.7 3856.1 4096.2 3128.0 3764.1 3923.7 4094.4 4111.2 2957.0 3373.5 3513.4 3518.4 3521.4 4667.6 4783.1 4909.4 5195.2 5226.2 4688.6 4701.7 4703.0 4705.2 4725.2 5074.7 5106.4 5492.8 5584.6 5620.2 3403.0 3775.0 3940.7 3953.4 3972.2 5100.8 5623.7 5803.7 5966.5 5995.7 3393.4 3980.3 3994.8 4007.6 4014.4 3686.6 3853.9 4061.6 4069.8 4089.1 3896.3 4486.6 4690.0 4701.6 4743.5 4166.7 4682.4 5317.2 5633.6 5697.4 1115.6 1251.4 1310.9 1375.9 1382.0 700.3 799.6 845.6 855.7 863.4 4154.5 4374.2 4399.8 4405.0 4406.6 4330.2 4648.9 4823.6 4853.4 4881.9 3746.9 4012.8 4310.3 4349.5 4396.1 2316.3 2322.9 2551.3 2563.4 2566.2 4428.0 4490.7 4783.8 4986.5 5113.4 4345.8 4487.9 4506.2 4683.6 4955.3 4286.1 4598.9 4628.9 4739.4 5050.3 3913.9 3992.8 4329.5 4358.6 4933.6 4000.4 4266.5 4739.6 4770.7 4862.1 5349.6 5742.4 5991.4 6024.8 6047.8 3926.7 3946.6 3949.0 3960.9 3964.3 5338.8 5559.0 5825.0 5825.8 5836.8 4767.5 5587.8 5609.7 5626.5 5640.9 4847.3 4905.3 5579.9 5593.1 5623.9 4593.1 4970.2 5278.3 5489.8 5540.3 4549.9 4835.9 5501.7 5572.0 5612.7 5478.6 5489.2 5515.4 5524.8 5541.7 08:25:12 INFO - PROCESS | 2243 | 08:25:12 INFO - PROCESS | 2243 | [#5] yui.html Cycles:180 Average:25457.76 Median:23718.99 stddev:41250.90 (173.9%) stddev-sans-first:41324.99 08:25:12 INFO - PROCESS | 2243 | Values: 778.0 824.6 825.6 833.1 855.8 15110.5 15566.3 16196.9 16211.3 16757.6 15207.5 15231.7 15239.2 15248.4 15256.5 733.2 851.2 852.5 860.3 902.4 744.6 750.9 750.9 751.7 753.1 947.2 951.5 951.8 982.6 986.6 908.4 914.7 915.6 916.3 921.1 566.5 678.0 682.5 683.4 698.8 15433.8 15438.5 15461.5 15470.0 15582.5 14314.4 14403.1 14481.6 14550.8 14620.4 11794.6 11890.2 11962.9 12010.6 12069.9 4572.4 4630.3 4688.0 4703.8 4802.6 99669.0 123835.7 126139.1 126458.4 126573.0 11371.5 11559.4 11572.3 11702.2 11957.9 23675.0 23677.6 23683.3 23700.1 23737.9 7797.6 7800.1 7931.2 7940.3 7954.6 4393.5 4460.9 4477.8 4492.3 4599.9 3606.6 3711.7 3712.8 3716.8 3718.8 647.0 654.3 655.8 668.4 670.6 584.7 609.1 610.1 630.6 637.6 8305.5 8776.3 8790.2 8854.0 9141.7 563.5 576.0 577.0 580.7 626.2 4407.0 4426.4 4429.6 4464.3 4571.6 109840.0 137545.0 168532.0 171559.0 173682.0 115731.4 116876.4 120935.0 121404.0 130239.3 106110.9 113409.4 114648.0 115630.9 116854.4 8144.1 8655.0 8678.3 8700.9 8886.6 58413.1 67348.0 67541.0 67951.3 71130.6 93630.0 102545.5 106804.9 108109.0 108372.5 11765.4 11903.2 12016.8 12437.3 12552.6 12623.2 12658.7 12744.9 13253.9 13279.7 12042.0 12059.0 12126.8 12481.9 12543.3 10208.8 11970.5 12059.1 12107.7 12223.5 11656.9 11775.5 11848.3 11899.5 12244.9 9696.7 10005.0 10042.5 10175.6 10372.7 10077.0 10204.2 10207.0 10209.6 10248.0 08:25:12 INFO - PROCESS | 2243 | -------- Summary: end -------- 08:25:12 INFO - PROCESS | 2243 | 08:25:13 INFO - TEST-INFO | 2243: exit 0 08:25:13 INFO - TEST-OK | dromaeo_css | took 1116592ms 08:25:13 INFO - TEST-START | kraken 08:25:13 INFO - operating with platform_type : mac_ 08:25:13 INFO - Initialising browser for kraken test... 08:25:13 INFO - TEST-INFO | started process 2246 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmpL85hMB/profile http://localhost:56465/getInfo.html) 08:25:15 INFO - PROCESS | 2246 | __metrics Screen width/height:1600/1200 08:25:15 INFO - PROCESS | 2246 | colorDepth:24 08:25:15 INFO - PROCESS | 2246 | Browser inner width/height: 1024/689 08:25:15 INFO - PROCESS | 2246 | __metrics 08:25:15 INFO - PROCESS | 2246 | 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" 08:25:15 INFO - PROCESS | 2246 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:25:16 INFO - TEST-INFO | 2246: exit 0 08:25:16 INFO - Browser initialized. 08:25:16 INFO - Running cycle 1/1 for kraken test... 08:25:16 INFO - TEST-INFO | started process 2247 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmpL85hMB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:25:28 INFO - PROCESS | 2247 | RSS: Main: 244965376 08:25:28 INFO - PROCESS | 2247 | 08:25:31 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56465/tests/kraken/driver.html?testName=audio-beat-detection) 08:25:32 INFO - PROCESS | 2247 | RSS: Main: 294502400 08:25:32 INFO - PROCESS | 2247 | 08:25:34 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56465/tests/kraken/driver.html?testName=audio-dft) 08:25:35 INFO - PROCESS | 2247 | RSS: Main: 304967680 08:25:35 INFO - PROCESS | 2247 | 08:25:38 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56465/tests/kraken/driver.html?testName=audio-fft) 08:25:39 INFO - PROCESS | 2247 | RSS: Main: 392531968 08:25:39 INFO - PROCESS | 2247 | 08:25:41 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56465/tests/kraken/driver.html?testName=audio-oscillator) 08:25:42 INFO - PROCESS | 2247 | RSS: Main: 293289984 08:25:42 INFO - PROCESS | 2247 | 08:25:44 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56465/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:25:44 INFO - PROCESS | 2247 | RSS: Main: 297406464 08:25:44 INFO - PROCESS | 2247 | 08:25:47 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56465/tests/kraken/driver.html?testName=imaging-darkroom) 08:25:48 INFO - PROCESS | 2247 | RSS: Main: 334319616 08:25:48 INFO - PROCESS | 2247 | 08:25:51 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56465/tests/kraken/driver.html?testName=imaging-desaturate) 08:25:51 INFO - PROCESS | 2247 | RSS: Main: 336834560 08:25:51 INFO - PROCESS | 2247 | 08:25:54 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56465/tests/kraken/driver.html?testName=json-parse-financial) 08:25:55 INFO - PROCESS | 2247 | RSS: Main: 335982592 08:25:55 INFO - PROCESS | 2247 | 08:25:56 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56465/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:25:57 INFO - PROCESS | 2247 | RSS: Main: 313757696 08:25:57 INFO - PROCESS | 2247 | 08:25:59 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56465/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:25:59 INFO - PROCESS | 2247 | RSS: Main: 319504384 08:25:59 INFO - PROCESS | 2247 | 08:26:01 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56465/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:26:02 INFO - PROCESS | 2247 | RSS: Main: 318447616 08:26:02 INFO - PROCESS | 2247 | 08:26:05 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56465/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:26:06 INFO - PROCESS | 2247 | RSS: Main: 328323072 08:26:06 INFO - PROCESS | 2247 | 08:26:09 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56465/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:26:09 INFO - PROCESS | 2247 | RSS: Main: 330596352 08:26:09 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | Cycle 1(1): loaded http://localhost:56465/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56465/tests/kraken/driver.html?testName=ai-astar) 08:26:11 INFO - PROCESS | 2247 | RSS: Main: 373841920 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | __start_tp_report 08:26:11 INFO - PROCESS | 2247 | _x_x_mozilla_page_load 08:26:11 INFO - PROCESS | 2247 | _x_x_mozilla_page_load_details 08:26:11 INFO - PROCESS | 2247 | |i|pagename|runs| 08:26:11 INFO - PROCESS | 2247 | |0;ai-astar;101;112;112;111;114;116;159;166;111;113 08:26:11 INFO - PROCESS | 2247 | |1;audio-beat-detection;147;108;107;160;108;107;130;114;177;133 08:26:11 INFO - PROCESS | 2247 | |2;audio-dft;182;184;184;185;184;185;183;183;181;184 08:26:11 INFO - PROCESS | 2247 | |3;audio-fft;80;73;57;89;60;58;66;61;85;59 08:26:11 INFO - PROCESS | 2247 | |4;audio-oscillator;85;83;94;72;75;68;97;69;67;101 08:26:11 INFO - PROCESS | 2247 | |5;imaging-gaussian-blur;88;85;89;85;89;85;89;85;88;85 08:26:11 INFO - PROCESS | 2247 | |6;imaging-darkroom;93;93;94;94;92;94;94;94;93;94 08:26:11 INFO - PROCESS | 2247 | |7;imaging-desaturate;85;87;85;87;84;84;85;84;85;84 08:26:11 INFO - PROCESS | 2247 | |8;json-parse-financial;55;54;54;54;53;54;54;54;54;56 08:26:11 INFO - PROCESS | 2247 | |9;json-stringify-tinderbox;66;73;78;73;73;70;68;72;70;70 08:26:11 INFO - PROCESS | 2247 | |10;stanford-crypto-aes;84;83;82;85;82;81;84;83;92;98 08:26:11 INFO - PROCESS | 2247 | |11;stanford-crypto-ccm;136;137;134;149;139;143;150;144;237;129 08:26:11 INFO - PROCESS | 2247 | |12;stanford-crypto-pbkdf2;163;159;206;218;164;161;160;162;161;164 08:26:11 INFO - PROCESS | 2247 | |13;stanford-crypto-sha256-iterative;67;67;67;67;66;66;62;79;82;83 08:26:11 INFO - PROCESS | 2247 | __end_tp_report 08:26:11 INFO - PROCESS | 2247 | __start_cc_report 08:26:11 INFO - PROCESS | 2247 | _x_x_mozilla_cycle_collect,2047 08:26:11 INFO - PROCESS | 2247 | __end_cc_report 08:26:11 INFO - PROCESS | 2247 | __startTimestamp1467818771863__endTimestamp 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | ------- Summary: start ------- 08:26:11 INFO - PROCESS | 2247 | Number of tests: 14 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#0] ai-astar Cycles:10 Average:121.50 Median:113.50 stddev:22.03 (19.4%) stddev-sans-first:22.08 08:26:11 INFO - PROCESS | 2247 | Values: 101.0 112.0 112.0 111.0 114.0 116.0 159.0 166.0 111.0 113.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:131.50 stddev:25.12 (19.1%) stddev-sans-first:25.80 08:26:11 INFO - PROCESS | 2247 | Values: 147.0 108.0 107.0 160.0 108.0 107.0 130.0 114.0 177.0 133.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.27 (0.7%) stddev-sans-first:1.22 08:26:11 INFO - PROCESS | 2247 | Values: 182.0 184.0 184.0 185.0 184.0 185.0 183.0 183.0 181.0 184.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#3] audio-fft Cycles:10 Average:68.80 Median:69.50 stddev:12.07 (17.4%) stddev-sans-first:12.10 08:26:11 INFO - PROCESS | 2247 | Values: 80.0 73.0 57.0 89.0 60.0 58.0 66.0 61.0 85.0 59.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:79.00 stddev:12.78 (16.2%) stddev-sans-first:13.48 08:26:11 INFO - PROCESS | 2247 | Values: 85.0 83.0 94.0 72.0 75.0 68.0 97.0 69.0 67.0 101.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.93 (2.2%) stddev-sans-first:2.00 08:26:11 INFO - PROCESS | 2247 | Values: 88.0 85.0 89.0 85.0 89.0 85.0 89.0 85.0 88.0 85.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.71 (0.8%) stddev-sans-first:0.73 08:26:11 INFO - PROCESS | 2247 | Values: 93.0 93.0 94.0 94.0 92.0 94.0 94.0 94.0 93.0 94.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:1.15 (1.4%) stddev-sans-first:1.22 08:26:11 INFO - PROCESS | 2247 | Values: 85.0 87.0 85.0 87.0 84.0 84.0 85.0 84.0 85.0 84.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.79 (1.5%) stddev-sans-first:0.78 08:26:11 INFO - PROCESS | 2247 | Values: 55.0 54.0 54.0 54.0 53.0 54.0 54.0 54.0 54.0 56.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.50 stddev:3.30 (4.6%) stddev-sans-first:2.89 08:26:11 INFO - PROCESS | 2247 | Values: 66.0 73.0 78.0 73.0 73.0 70.0 68.0 72.0 70.0 70.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:84.00 stddev:5.38 (6.4%) stddev-sans-first:5.68 08:26:11 INFO - PROCESS | 2247 | Values: 84.0 83.0 82.0 85.0 82.0 81.0 84.0 83.0 92.0 98.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:143.50 stddev:31.34 (21.8%) stddev-sans-first:32.84 08:26:11 INFO - PROCESS | 2247 | Values: 136.0 137.0 134.0 149.0 139.0 143.0 150.0 144.0 237.0 129.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:163.50 stddev:21.44 (13.1%) stddev-sans-first:22.50 08:26:11 INFO - PROCESS | 2247 | Values: 163.0 159.0 206.0 218.0 164.0 161.0 160.0 162.0 161.0 164.0 08:26:11 INFO - PROCESS | 2247 | 08:26:11 INFO - PROCESS | 2247 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:67.00 stddev:7.62 (11.4%) stddev-sans-first:7.97 08:26:11 INFO - PROCESS | 2247 | Values: 67.0 67.0 67.0 67.0 66.0 66.0 62.0 79.0 82.0 83.0 08:26:11 INFO - PROCESS | 2247 | -------- Summary: end -------- 08:26:11 INFO - PROCESS | 2247 | 08:26:12 INFO - TEST-INFO | 2247: exit 0 08:26:12 INFO - TEST-OK | kraken | took 59416ms 08:26:12 INFO - SUITE-END | took 1176s 08:26:12 INFO - Completed test suite (00:19:36) 08:26:12 INFO - javascript benchmark 08:26:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2901.810257794304, 3382.103742508236, 3403.8978830635087, 3408.9659103408967, 3423.726101911846, 5642.294713160855, 5674.886502269957, 5720.084786434171, 5789.160571717099, 5820.243368362112, 5635.380108188096, 5706.315242170935, 5771.711414429283, 5772.307323121221, 5815.709214539267, 4331.41525894005, 5165.199394093921, 5252.3697156341295, 5263.210518422229, 5321.201819727034, 4495.707778994355, 5281.947180528184, 5344.866378340534, 5359.517643412092, 5366.543843773284, 5168.3539557555305, 5196.986587615379, 5199.766010529535, 5227.50338717825, 5283.656562323456, 5219.42586315505, 5241.764120614544, 5244.0, 5327.334083239595, 5348.224507446437, 5220.0, 5224.608154388436, 5253.238280449314, 5307.203919412119, 5310.893782124374, 5687.090065589486, 5725.599208055438, 5819.563532735062, 5863.442972917566, 5895.292564892197, 6207.751689932397, 6261.46777523911, 6274.215723034621, 6280.968595157041, 6328.335524769879, 6284.497240220771, 6334.2398912130375, 6344.6510441925675, 6349.746010159634, 6361.491080713532, 5698.857528561819, 5762.567807414377, 5854.253783538442, 5868.031774757203, 5935.37678543751, 6261.0, 6284.24589049317, 6297.1813664223355, 6326.0, 6347.873042539123, 6246.281677607042, 6257.405546473078, 6265.8746825063245, 6266.373362663697, 6270.717817698214, 5536.474034966672, 6195.380461953769, 6271.811845644638, 6294.8111556653375, 6349.365063493706, 6240.188775459162, 6275.215598050244, 6334.968325158345, 6343.9365606343345, 6352.460040896575, 6327.588706734047, 6332.746690132343, 6359.491240700733, 6372.745090196433, 6381.298057213703, 4891.9755401222765, 4913.140200464904, 4946.505349465025, 4963.156263435152, 5024.849254522297, 3852.57621661617, 4406.4051353066925, 4420.977895110504, 4433.844815431445, 4543.727376357429, 5757.740901659436, 5787.88424231513, 5789.884202316014, 5794.449527294901, 5846.532277417797, 5254.816081437131, 6186.752529898754, 6216.906746398808, 6364.49084073273, 6396.136521569621, 5699.2590963174525, 5797.130430435468, 5807.157962095473, 5895.233619629421, 5910.615809972338, 5478.287822583039, 5500.559955203574, 5505.862353441196, 5507.146392309199, 5525.640833345819, 6431.549791514736, 6433.067205255212, 6444.7744328948265, 6449.484041276781, 6460.773872914426, 6196.132541444112, 6285.685715714105, 6336.778212762531, 6347.0, 6365.2361716594305, 6340.873182536416, 6376.744930202925, 6386.648734319657, 6397.0404439332615, 6413.326600707045, 6117.462039585284, 6221.844453888689, 6320.209973753281, 6324.304326523987, 6337.04944258365, 5312.9734351328, 6250.124982502544, 6274.341194174728, 6301.0, 6335.493160547053, 6214.098955651496, 6223.284322302993, 6229.127922091002, 6241.251049874044, 6242.094896239929, 5997.940020599738, 6111.04482360895, 6122.256477468552, 6183.196184495987, 6201.751929922752, 5956.851078723066, 6067.180930574493, 6071.868664785156, 6095.0, 6110.877782444415, 6164.106204600218, 6213.565050446426, 6220.968895155495, 6228.0, 6238.812835614939, 5807.157962095388, 5820.883582328414, 5906.822795316148, 6079.392060793885, 6091.1472393864005], "value": 5738.50336045361, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.9809102793108, 519.4441947116587, 527.8284557518806, 539.870431096537, 540.9161579955106, 29754.702452975464, 30307.0, 31912.840435797818, 42335.78832105835, 43315.566844331544, 44202.778986105106, 44621.0, 44627.77686111565, 44633.0, 44640.77679611606, 9722.222222222223, 9930.404175749478, 9937.304388692794, 9937.751556211117, 9949.005099490028, 8834.46993180408, 9000.774980625503, 9739.318247722662, 11922.284662920209, 12102.27386356817, 12073.456694448727, 12081.577144799934, 12166.452509637043, 12167.939160304186, 12169.087318451151, 8854.955725221396, 12083.879161208364, 12146.210496317799, 12152.574659886906, 12152.756944861141, 11543.94228028863, 11797.292162470194, 11808.763824723543, 12104.213226140273, 12131.575394861226, 41898.79050604728, 42244.0, 42289.0, 42292.78853605743, 42321.57678423238, 3585.15748799032, 3709.7959612221316, 3844.9615503844884, 3927.3519869221545, 4303.031817840992, 32060.358792824245, 32283.0, 33273.6672633273, 33296.33407331864, 33307.500387494205, 3250.6099268087746, 3414.7609667323295, 3433.141714571357, 3466.636003219651, 3472.791632502058, 505139.0, 506928.46535767824, 507373.0, 508281.0, 509078.0, 61938.45153871099, 62033.0, 62377.0, 62427.0, 62495.687521562104, 65335.67332163309, 65955.34044659587, 65996.0, 66143.00785488222, 66150.0, 16543.6691266174, 16828.0, 16832.24254908532, 16842.663146736995, 16946.0, 11675.941620291844, 11865.822012669816, 12994.610161694975, 13091.541796037092, 13120.8847247985, 15269.770953435707, 15364.462243821412, 15369.309375968858, 15397.0, 16710.4151354702, 5656.4060773619, 6599.571027883172, 6641.0, 6652.667366631649, 6665.866682666319, 919.9264058875274, 949.2690628216235, 963.9444807935299, 971.4171497101823, 991.8561808537866, 30651.927182548505, 30947.69052309493, 30998.690013100033, 31330.0, 31478.842605787282, 884.9424787388928, 993.6969224386631, 1048.905598496139, 1110.8555887734542, 1201.1891972918245, 15876.206189690469, 16022.679546409006, 16078.276477558538, 16195.43315983935, 16443.58891027734, 679986.0, 684842.0, 685367.0, 686664.0, 889937.0, 51113.72215694637, 60231.69884150551, 61118.0832287516, 61202.69398652978, 65959.0, 1341.9329033548283, 1433.6917562723856, 1460.6640472691129, 1461.5030889497623, 1466.3621324723565, 30948.607312670534, 31069.844650776602, 32025.0, 32057.519137212494, 32636.673633264312, 50457.74771126121, 50665.74667126641, 50807.74596127144, 50890.0, 50945.74527127489, 631201.0, 633117.0, 635547.6445235673, 636917.0, 637891.0, 26211.606825897245, 26295.868520657274, 27129.186124416297, 28364.290892727848, 41619.0, 35727.64272357243, 41498.585014149474, 48162.27756583724, 48530.51469485401, 51451.42742862917, 40281.59718402896, 40901.38647920343, 40957.79521102375, 41404.58595414007, 42264.0, 39669.60330396659, 40598.79700601478, 41322.38016429816, 41680.3747943775, 42293.94265143396, 38771.0, 39353.21293574055, 39526.60473395229, 39666.0, 39680.0, 34527.82736086303, 35451.468227977115, 36240.81879590585, 36318.636813632584, 36428.81785591055, 22912.921390048345, 25846.353841154123, 26164.60753088743, 26270.0, 26380.193936858523], "value": 20833.628609349773, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.77033779732164, 385.39037893460096, 387.4014647369813, 392.53288586581965, 412.36084069691987, 14880.404783808635, 20877.895610521944, 21766.782332176674, 21871.562568748635, 21921.0, 16221.351145954148, 19449.611007779873, 21552.353429397102, 21556.0, 21684.783152168475, 5653.4911857932775, 5912.379200183983, 5936.554758393117, 5937.0, 5940.138679891435, 5612.186232996213, 5787.21872547207, 5869.55978301627, 5886.823395298126, 5893.1749555062115, 5799.478046975772, 5823.3012038555435, 5831.387704291052, 5832.59171857968, 5840.153177789218, 4925.581325587329, 5618.775248990056, 5810.1865738796605, 5816.243888294516, 5830.883382332372, 5096.05722941257, 5103.438621751604, 5106.744662766884, 5110.718910459923, 5121.564667003309, 20348.491287717778, 26655.600165997526, 26794.86602566994, 27156.0, 27265.45469090607, 2291.8051965582945, 2538.7842033427182, 2574.8712564371895, 2754.559270516708, 3085.6142982127235, 14997.62885311369, 15794.526164215093, 16240.75638865418, 16326.265318060598, 16426.01443913369, 1737.9304827806873, 1810.9094545272815, 1868.6075924055965, 1898.8860668359944, 1911.3310341380545, 123673.38163309125, 124575.9999999991, 125201.0, 125525.37237313847, 125734.0, 9565.904340956502, 10604.681859544226, 10668.14654827612, 11063.944680276547, 11081.5567377304, 13805.930970345285, 13894.0, 13901.026928114934, 13918.0, 13935.442582296595, 7591.3547348475995, 8001.959990200012, 8297.087320394752, 8565.229129378262, 8740.169683880022, 8660.437071590326, 8702.738917832476, 8706.434081784664, 8772.605232764541, 8785.648574057093, 6588.748137853792, 6702.731890724414, 6704.597724136567, 6755.0, 6795.694193761194, 1610.4443966831425, 1858.6375656746804, 1862.6181632765251, 1878.3613571385795, 1909.255390397746, 946.6592026870321, 954.231843366097, 1079.956801727922, 1087.1683162380784, 1097.6597254851022, 21966.45083872916, 22751.544969100527, 22764.0, 22779.08883644669, 22903.427414314774, 847.2715660053545, 988.945607991553, 992.3202606214766, 993.9453330066771, 1062.2139616683598, 11401.543938242377, 11656.941715291368, 11839.34883581394, 11939.0, 11956.880431195577, 551610.0, 573187.0, 575983.0, 587822.0, 596171.0, 76260.23739762642, 96529.03470965341, 120601.0, 123862.38068809599, 128327.35836320759, 982.2044144243185, 995.7311525888115, 1019.2763138171832, 1052.847337136111, 1054.314695447965, 15451.768223476882, 16526.869844681274, 18414.723779143056, 18740.0, 19415.805841941965, 47065.2940205904, 55344.44655553393, 55547.444525555846, 55704.72147639236, 56849.715751420976, 82967.58516207381, 83269.58365208378, 83676.0, 83825.5808720977, 83845.58077209575, 7879.330256927996, 8037.437379383387, 8038.919610803817, 8044.3564514839845, 8087.716929907425, 11291.3789741565, 11522.732499425225, 12167.81748273759, 12315.322657253937, 12815.61553153407, 10656.733581660521, 10948.507317170586, 10963.890361096606, 10990.120790336594, 10995.945020274847, 10095.949520252352, 11237.662870113909, 11368.488258414822, 11409.543618255177, 11571.958523732905, 10336.656234689442, 10610.469476526298, 11531.711707207387, 11776.82334764962, 11861.703457413634, 6980.232174460907, 7302.087239095114, 7316.707631436853, 7397.926020739724, 7564.319211270792, 10613.628523001658, 11271.49278282463, 11363.0, 11413.429328533706, 11505.942470287595], "value": 9775.19485326318, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.0924208506565, 1527.030335736807, 1600.487843889955, 1754.1229385307347, 1901.9429417117492, 8030.678772849086, 8443.873341899876, 8457.703980360673, 8461.026981897076, 8477.109903460141, 8549.444286121397, 8817.426867253624, 8822.161894620016, 8824.338174636912, 8874.866876996834, 2294.793468587831, 2364.574376612209, 2365.420471984366, 2368.1948137633203, 2368.2539999900064, 1985.8510611704107, 2100.978990210101, 2326.1044497868356, 2365.515069410775, 2368.3013511014206, 2325.6744055832196, 2350.2361732437025, 2356.1871154451774, 2363.1728894886824, 2364.810815134785, 2300.907963681451, 2314.36355002374, 2318.9652155217686, 2323.314622186459, 2325.976740232593, 2315.988420057889, 2328.9767102329097, 2329.126577533425, 2332.941676458085, 2336.4742932840145, 7869.606519673994, 8681.175288347597, 8723.171298726611, 8828.205461508494, 8829.867551986725, 5231.215317702337, 5325.254464374991, 5386.542143917733, 5464.153056276284, 5474.808381706661, 3588.3361578108133, 3706.0, 3706.4440333949856, 3739.2521495700967, 3758.530183727034, 1074.4788777442934, 1092.5192915117323, 1095.9780804383947, 1111.5664890692635, 1121.0975164992178, 11657.883421165765, 11899.107566932515, 11923.463444145034, 11925.522979080826, 11933.462994165284, 4595.37962375075, 4629.629629629621, 4850.878728031827, 4854.83008094715, 4863.829765958175, 10239.18086553074, 10249.436281004468, 10266.178705703674, 10285.691429257135, 10285.794284114425, 5380.354357477127, 5383.192521121863, 5384.973075134678, 5387.326584176978, 5400.054990376669, 3733.2720119576966, 4432.0, 4557.680962332671, 4567.862964111082, 4918.385201849769, 4047.838086476508, 4064.7561146331313, 4093.038136038029, 4174.83300667976, 4179.749215047107, 1268.6701457621084, 1293.7606542789615, 1293.8835504804424, 1306.549240512022, 1308.3523655790445, 946.9905300946903, 949.3022628368144, 950.2872845365976, 954.746992047108, 978.7798144105955, 5909.349971503131, 5912.852178695567, 6068.362821903725, 6077.088436734437, 6198.2871969723155, 424.82369816525795, 444.5643269595814, 446.2257982400532, 452.8958339581915, 454.86126731346593, 3999.6600288975183, 4109.404136400205, 4132.938005929914, 4146.274401979642, 4169.45797046382, 10570.2600817942, 10628.627998020187, 10860.239783215256, 10965.95823396776, 11054.668359949215, 10971.177161712903, 10991.285566438157, 10998.835017474745, 11019.889801102046, 11037.172212084126, 8241.95879020601, 8398.412111152284, 8463.026751923484, 8482.533460659599, 8485.278751306209, 6029.216201893726, 6030.517558595214, 6037.396260374103, 6107.175531303217, 6122.7244773984385, 9808.901910981085, 10156.492175391053, 10206.642767503101, 10250.462430635464, 10261.333013354108, 8954.641814327615, 10523.473826308807, 10531.578736850746, 10542.52558634848, 10555.208359372924, 7622.847543049219, 7628.351590114902, 7687.961560192163, 7692.576908270099, 7707.499012564165, 8145.51126932386, 8417.07412184647, 8424.326053915795, 8428.367872409717, 8443.197896199852, 6641.369069938348, 7554.4334174936, 7633.313001829862, 7661.310482056641, 7826.726064587712, 7482.027336446227, 7513.549187048795, 7588.886166707614, 7648.923510764821, 7660.502067365604, 6793.320667933167, 7030.964845175741, 7035.226125126334, 7042.401395881408, 7043.647817609201, 4857.659963802641, 4962.950370496249, 5000.974995125001, 5017.974910125426, 5079.238114282887, 5033.270175824412, 5054.588564427427, 5127.41034781005, 5230.241614965885, 5242.947570524246], "value": 4548.274093982519, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.5738461922473, 302.9969700302997, 309.06045621311216, 315.5881574545219, 317.7426284709387, 4722.866512037112, 5172.508611681889, 5440.265564148839, 5496.427322240545, 5510.752016159272, 5355.563218735476, 5369.858632699866, 5387.4073851876265, 5403.189521571757, 5488.423715509874, 3351.631320554737, 3860.5946375630574, 4028.5769994150487, 4041.696872734542, 4043.757374557536, 3607.981960090196, 3631.7094632429475, 3951.767251502906, 4107.363358679395, 4129.814158362866, 3427.091820667513, 3738.6822120119823, 3742.719296052793, 3856.1464516257906, 4096.180763847227, 3128.012747825821, 3764.096616811959, 3923.7253392262555, 4094.406311084876, 4111.218868414991, 2957.0, 3373.4771110477914, 3513.3851575974104, 3518.4194607889667, 3521.4365701487895, 4667.55305855186, 4783.091212669617, 4909.361782968227, 5195.1687729963405, 5226.189940559219, 4688.648351373627, 4701.717896926161, 4703.0, 4705.247160454311, 4725.196716558194, 5074.695518268915, 5106.442535026816, 5492.80775172867, 5584.5811564132855, 5620.241267428885, 3402.9829850850833, 3774.999625099351, 3940.7438516496622, 3953.446517487554, 3972.245273398045, 5100.770465329032, 5623.662580245198, 5803.738831752581, 5966.47854797024, 5995.6702381369005, 3393.423118069934, 3980.2735126438483, 3994.7603143810884, 4007.6393124618926, 4014.437978682987, 3686.594474607791, 3853.8843834685003, 4061.614146656063, 4069.755814651071, 4089.141280331104, 3896.337622604164, 4486.618637415856, 4689.976550117228, 4701.623870090384, 4743.478217396084, 4166.66666666666, 4682.367880336179, 5317.229001794718, 5633.577481688808, 5697.373288938214, 1115.576081089197, 1251.4368534159664, 1310.88202061813, 1375.8580378286, 1381.951631692886, 700.2787129256874, 799.6081919859228, 845.6406027438314, 855.7475544714324, 863.4430792139035, 4154.543000269968, 4374.162851602271, 4399.824007039747, 4404.955950440519, 4406.628928869418, 4330.198913201068, 4648.883777905579, 4823.614110871122, 4853.379175245328, 4881.926771098437, 3746.8501259949844, 4012.8194231259663, 4310.31035034393, 4349.499807522112, 4396.12077584478, 2316.339843144696, 2322.8734063978955, 2551.2601345609937, 2563.410954935491, 2566.242958327298, 4428.047969686533, 4490.730556166641, 4783.808647654125, 4986.4514903360605, 5113.360829896263, 4345.804438800262, 4487.88780280489, 4506.174999125359, 4683.604386819528, 4955.306257124005, 4286.142771445723, 4598.908021839545, 4628.865927847699, 4739.43598612457, 5050.3182070420025, 3913.882583522499, 3992.7604343739467, 4329.467261651969, 4358.622067897405, 4933.58064564516, 4000.3799411091327, 4266.488021437447, 4739.644526660445, 4770.689905156154, 4862.05189987949, 5349.592683292456, 5742.425757424224, 5991.400859913973, 6024.819255422344, 6047.758089676539, 3926.7447615906017, 3946.625070618287, 3948.9605103948593, 3960.9207815844097, 3964.306246406925, 5338.839834804962, 5558.972205138948, 5825.0, 5825.76696932118, 5836.766529338949, 4767.475577686382, 5587.77648894056, 5609.663420194802, 5626.521745651502, 5640.915386269291, 4847.321375007572, 4905.288733133747, 5579.939811435815, 5593.07714227148, 5623.88752224945, 4593.104344652761, 4970.229614409773, 5278.255307240137, 5489.752961116839, 5540.279763630702, 4549.9317510236715, 4835.887745818552, 5501.724913754216, 5572.0, 5612.663240205601, 5478.643888147258, 5489.161131021153, 5515.448455154453, 5524.823226644615, 5541.722913854372], "value": 3873.230811695793, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.9611019449026, 824.5629816197415, 825.5954582254697, 833.1044127562868, 855.7603870916143, 15110.546683599483, 15566.299516521756, 16196.947198432094, 16211.270492827822, 16757.581060473494, 15207.543773686779, 15231.695366092672, 15239.238038098107, 15248.39006439744, 15256.542303730906, 733.156869599959, 851.2338894994492, 852.4927668037539, 860.2730692564804, 902.4269588811087, 744.6090802328794, 750.8873668949647, 750.9249075092474, 751.6805357722973, 753.0586766541822, 947.1759569174827, 951.4719330771419, 951.8191543606692, 982.5824024789472, 986.6152200641756, 908.4140729229642, 914.6524320758136, 915.5742579700471, 916.3173435790379, 921.0651189043126, 566.4562020460363, 678.0101052463409, 682.5495173185723, 683.3542302524116, 698.7589281697806, 15433.845661543353, 15438.5368438947, 15461.458848940345, 15470.0, 15582.454614088452, 14314.355853986595, 14403.063800852911, 14481.565553033426, 14550.781738273934, 14620.415183392652, 11794.646160615195, 11890.227135236182, 11962.880371196263, 12010.579629712918, 12069.876132298303, 4572.359869618256, 4630.305454181866, 4688.0, 4703.764811759398, 4802.567768900781, 99669.0, 123835.65171315745, 126139.10791338138, 126458.36770816088, 126573.0, 11371.488283027284, 11559.422028898689, 11572.305661660328, 11702.239354441934, 11957.88042119585, 23675.0, 23677.644835327483, 23683.289501314644, 23700.051997920236, 23737.931793069354, 7797.649105790139, 7800.063992320957, 7931.167227441036, 7940.325072368798, 7954.642041108164, 4393.450818647669, 4460.888477788081, 4477.7984990675495, 4492.258777301774, 4599.931001034987, 3606.567211934532, 3711.7030637548933, 3712.7957962311793, 3716.7955762432784, 3718.8140592970244, 646.9741210351627, 654.2508827392609, 655.8294843340766, 668.3683918696785, 670.5775361522308, 584.7222569279559, 609.1441524657863, 610.0513701194596, 630.6058713304185, 637.5855693798975, 8305.46014509055, 8776.297896168291, 8790.164934331227, 8854.0, 9141.725748227564, 563.456264704562, 576.0047751691139, 576.9855753606109, 580.7473748919234, 626.2078470734524, 4406.9559304407185, 4426.402435671143, 4429.5962654178265, 4464.303564645414, 4571.634269258451, 109840.0, 137545.0, 168532.0, 171559.0, 173682.0, 115731.42134289275, 116876.41561792306, 120935.0, 121404.0, 130239.34880325537, 106110.87778244546, 113409.4329528347, 114647.99999999833, 115630.86819289584, 116854.41572792082, 8144.063432705315, 8655.0, 8678.305735541267, 8700.869486957827, 8886.555672216493, 58413.12380314214, 67348.0, 67541.0, 67951.3204867945, 71130.64434677793, 93630.0, 102545.4618180713, 106804.93195068261, 108109.0, 108372.45813771196, 11765.411729413667, 11903.166778325434, 12016.819747703617, 12437.344919333385, 12552.560660376843, 12623.179493332904, 12658.683532911751, 12744.872551274368, 13253.939684825384, 13279.734405312032, 12042.0, 12059.0, 12126.818097728365, 12481.875181248071, 12543.3101179436, 10208.846867296848, 11970.521179153086, 12059.095567832623, 12107.728688487581, 12223.511059557519, 11656.88343116592, 11775.470103845177, 11848.348340840992, 11899.46452409661, 12244.877551224374, 9696.70909872705, 10005.0, 10042.548085336324, 10175.643852465424, 10372.688819335432, 10077.0, 10204.2346823987, 10207.0, 10209.591616335263, 10248.0], "value": 7795.103288723059, "unit": "score"}], "name": "dromaeo_css", "value": 7371.914273686639, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 111.0, 114.0, 116.0, 159.0, 166.0, 111.0, 113.0], "value": 121.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 108.0, 107.0, 160.0, 108.0, 107.0, 130.0, 114.0, 177.0, 133.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 184.0, 185.0, 184.0, 185.0, 183.0, 183.0, 181.0, 184.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 57.0, 89.0, 60.0, 58.0, 66.0, 61.0, 85.0, 59.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 94.0, 72.0, 75.0, 68.0, 97.0, 69.0, 67.0, 101.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 89.0, 85.0, 89.0, 85.0, 88.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 92.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 85.0, 87.0, 84.0, 84.0, 85.0, 84.0, 85.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0, 54.0, 54.0, 56.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 78.0, 73.0, 73.0, 70.0, 68.0, 72.0, 70.0, 70.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 85.0, 82.0, 81.0, 84.0, 83.0, 92.0, 98.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 134.0, 149.0, 139.0, 143.0, 150.0, 144.0, 237.0, 129.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 159.0, 206.0, 218.0, 164.0, 161.0, 160.0, 162.0, 161.0, 164.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 62.0, 79.0, 82.0, 83.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1452.3999999999999, "alertThreshold": 2.0}]} 08:26:13 INFO - Return code: 0 08:26:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:26:13 INFO - # TBPL SUCCESS # 08:26:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:26:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:26:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:26:13 INFO - 2016-07-06 08:26:13.162 screenresolution[2251:23855] starting screenresolution argv=screenresolution get 08:26:13 INFO - 2016-07-06 08:26:13.165 screenresolution[2251:23855] Display 0: 1600x1200x32@60 08:26:13 INFO - 2016-07-06 08:26:13.171 screenresolution[2252:23858] starting screenresolution argv=screenresolution list 08:26:13 INFO - Available Modes on Display 0 08:26:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:26:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:26:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:26:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:26:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:26:13 INFO - Intel Iris: 08:26:13 INFO - Chipset Model: Intel Iris 08:26:13 INFO - Type: GPU 08:26:13 INFO - Bus: Built-In 08:26:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:26:13 INFO - Vendor: Intel (0x8086) 08:26:13 INFO - Device ID: 0x0a2e 08:26:13 INFO - Revision ID: 0x0009 08:26:13 INFO - Displays: 08:26:13 INFO - Display: 08:26:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:26:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:26:13 INFO - Display Serial Number: 1600x1200 60 08:26:13 INFO - Main Display: Yes 08:26:13 INFO - Mirror: Off 08:26:13 INFO - Online: Yes 08:26:13 INFO - Rotation: Supported 08:26:13 INFO - Return code: 0 08:26:13 INFO - Running post-action listener: _resource_record_post_action 08:26:13 INFO - ##### 08:26:13 INFO - ##### Finished run-tests step (success) 08:26:13 INFO - ##### 08:26:13 INFO - Running post-run listener: _resource_record_post_run 08:26:13 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 0; Write bytes: 704441856; Read time: 0; Write time: 1958 08:26:13 INFO - TinderboxPrint: CPU usage
53.5% 08:26:13 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:26:13 INFO - TinderboxPrint: I/O write bytes / time
704,441,856 / 1,958 08:26:13 INFO - TinderboxPrint: CPU idle
2,255.7 (46.5%) 08:26:13 INFO - TinderboxPrint: CPU system
962.8 (19.9%) 08:26:13 INFO - TinderboxPrint: CPU user
1,631.3 (33.6%) 08:26:13 INFO - TinderboxPrint: Swap in / out
406,409,216 / 0 08:26:13 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 400521728; Write bytes: 395435520; Read time: 31114; Write time: 809 08:26:13 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 0; Write bytes: 293261312; Read time: 0; Write time: 1104 08:26:13 INFO - Running post-run listener: _upload_blobber_files 08:26:13 INFO - Blob upload gear active. 08:26:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:26:13 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 08:26:13 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'] 08:26:13 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 08:26:14 INFO - (blobuploader) - INFO - Open directory for files ... 08:26:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:26:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:26:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:26:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:26:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:26:15 INFO - (blobuploader) - INFO - Done attempting. 08:26:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:26:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:26:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:26:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:26:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:26:16 INFO - (blobuploader) - INFO - Done attempting. 08:26:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:26:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:26:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:26:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:26:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:26:18 INFO - (blobuploader) - INFO - Done attempting. 08:26:18 INFO - (blobuploader) - INFO - Iteration through files over. 08:26:18 INFO - Return code: 0 08:26:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:26:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:26:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32edf29a79a3a69a060c91131c49876a264e0d605c52187eadf727232e8712f626b3b68a729cb4708cb252a1a5f91db824195097a32faff4c593eadd4377ca11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c10171af9a2fc3723678fe21abae3ee032862935fc33c49662c8039ab040cbe75e357647f71bfc31832758a85bd4bb9fe09e47c3c4546057ef90f759880755", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/385a10e0919f57ea1b49cc742e29bbf6ae0d371ac233baa93452f7abb764dd6a52ca7d620bfc2f10d84c5fee3f03f7dbb1db4bb7f72347ddef7aff7644f2a4c5"} 08:26:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:26:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:26:18 INFO - Contents: 08:26:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32edf29a79a3a69a060c91131c49876a264e0d605c52187eadf727232e8712f626b3b68a729cb4708cb252a1a5f91db824195097a32faff4c593eadd4377ca11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c10171af9a2fc3723678fe21abae3ee032862935fc33c49662c8039ab040cbe75e357647f71bfc31832758a85bd4bb9fe09e47c3c4546057ef90f759880755", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/385a10e0919f57ea1b49cc742e29bbf6ae0d371ac233baa93452f7abb764dd6a52ca7d620bfc2f10d84c5fee3f03f7dbb1db4bb7f72347ddef7aff7644f2a4c5"} 08:26:18 INFO - Running post-run listener: copy_logs_to_upload_dir 08:26:18 INFO - Copying logs to upload dir... 08:26:18 INFO - mkdir: /builds/slave/test/build/upload/logs 08:26:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.738598 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-06 08:26:18.617371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 08:26:18.621124) ========= 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.kzEp69qc9Y/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.BwpvKakHHg/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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/32edf29a79a3a69a060c91131c49876a264e0d605c52187eadf727232e8712f626b3b68a729cb4708cb252a1a5f91db824195097a32faff4c593eadd4377ca11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c10171af9a2fc3723678fe21abae3ee032862935fc33c49662c8039ab040cbe75e357647f71bfc31832758a85bd4bb9fe09e47c3c4546057ef90f759880755", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/385a10e0919f57ea1b49cc742e29bbf6ae0d371ac233baa93452f7abb764dd6a52ca7d620bfc2f10d84c5fee3f03f7dbb1db4bb7f72347ddef7aff7644f2a4c5"} build_url:https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010697 build_url: 'https://queue.taskcluster.net/v1/task/LA-fO21zRrG0Wo7ajmciqQ/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/32edf29a79a3a69a060c91131c49876a264e0d605c52187eadf727232e8712f626b3b68a729cb4708cb252a1a5f91db824195097a32faff4c593eadd4377ca11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c10171af9a2fc3723678fe21abae3ee032862935fc33c49662c8039ab040cbe75e357647f71bfc31832758a85bd4bb9fe09e47c3c4546057ef90f759880755", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/385a10e0919f57ea1b49cc742e29bbf6ae0d371ac233baa93452f7abb764dd6a52ca7d620bfc2f10d84c5fee3f03f7dbb1db4bb7f72347ddef7aff7644f2a4c5"}' ========= master_lag: 0.69 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 08:26:19.325027) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 08:26:19.325380) ========= 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.kzEp69qc9Y/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.BwpvKakHHg/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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.005128 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 08:26:19.377112) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 08:26:19.377441) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 08:26:19.377814) ========= ========= Total master_lag: 0.93 =========