builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0304 starttime: 1467783423.84 results: success (0) buildid: 20160705205623 builduid: c4a51a39c70c4e73aafe0e9496136fe5 revision: cec1ab33c0d6bdc2638e0e76566a5009f033d949 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:03.845081) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:03.845465) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:03.845731) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:03.869251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:03.869543) ========= 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.eIXdam6JlK/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.FWb7YWC0t4/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-05 22:37:03-- 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% 5.80M=0.002s 2016-07-05 22:37:04 (5.80 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.480592 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:04.372312) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:04.372622) ========= 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.eIXdam6JlK/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.FWb7YWC0t4/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.020772 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:04.420793) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 22:37:04.421153) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cec1ab33c0d6bdc2638e0e76566a5009f033d949 --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 cec1ab33c0d6bdc2638e0e76566a5009f033d949 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eIXdam6JlK/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.FWb7YWC0t4/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-05 22:37:04,500 truncating revision to first 12 chars 2016-07-05 22:37:04,500 Setting DEBUG logging. 2016-07-05 22:37:04,500 attempt 1/10 2016-07-05 22:37:04,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cec1ab33c0d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 22:37:05,706 unpacking tar archive at: fx-team-cec1ab33c0d6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.481424 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 22:37:05.926356) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:05.926859) ========= script_repo_revision: cec1ab33c0d6bdc2638e0e76566a5009f033d949 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:05.927544) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:05.927988) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 22:37:05.947092) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-07-05 22:37:05.947613) ========= /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.eIXdam6JlK/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.FWb7YWC0t4/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37:06 INFO - MultiFileLogger online at 20160705 22:37:06 in /builds/slave/test 22:37:06 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 22:37:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:37:06 INFO - {'append_to_log': False, 22:37:06 INFO - 'base_work_dir': '/builds/slave/test', 22:37:06 INFO - 'blob_upload_branch': 'Fx-Team', 22:37:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:37:06 INFO - 'branch': 'Fx-Team', 22:37:06 INFO - 'buildbot_json_path': 'buildprops.json', 22:37:06 INFO - 'config_files': ('talos/mac_config.py',), 22:37:06 INFO - 'default_actions': ('clobber', 22:37:06 INFO - 'read-buildbot-config', 22:37:06 INFO - 'download-and-extract', 22:37:06 INFO - 'populate-webroot', 22:37:06 INFO - 'create-virtualenv', 22:37:06 INFO - 'install', 22:37:06 INFO - 'run-tests'), 22:37:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:37:06 INFO - 'download_minidump_stackwalk': True, 22:37:06 INFO - 'download_symbols': 'ondemand', 22:37:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:37:06 INFO - 'tooltool.py': '/tools/tooltool.py', 22:37:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:37:06 INFO - '/tools/misc-python/virtualenv.py')}, 22:37:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:37:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:37:06 INFO - 'installer_path': 'installer.exe', 22:37:06 INFO - 'log_level': 'info', 22:37:06 INFO - 'log_name': 'talos', 22:37:06 INFO - 'log_to_console': True, 22:37:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:37:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:37:06 INFO - 'opt_config_files': (), 22:37:06 INFO - 'pip_index': False, 22:37:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:37:06 INFO - 'cmd': ('bash', 22:37:06 INFO - '-c', 22:37:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:37:06 INFO - 'enabled': True, 22:37:06 INFO - 'halt_on_failure': False, 22:37:06 INFO - 'name': 'check_screen_resolution'},), 22:37:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:37:06 INFO - 'cmd': ('bash', 22:37:06 INFO - '-c', 22:37:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:37:06 INFO - 'enabled': True, 22:37:06 INFO - 'halt_on_failure': False, 22:37:06 INFO - 'name': 'check_screen_resolution'},), 22:37:06 INFO - 'run_cmd_checks_enabled': True, 22:37:06 INFO - 'sps_profile': False, 22:37:06 INFO - 'sps_profile_interval': 0, 22:37:06 INFO - 'suite': 'dromaeojs', 22:37:06 INFO - 'system_bits': '32', 22:37:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:37:06 INFO - 'title': 't-yosemite-r7-0304', 22:37:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:37:06 INFO - 'use_talos_json': True, 22:37:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:37:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:37:06 INFO - 'work_dir': 'build'} 22:37:06 INFO - ##### 22:37:06 INFO - ##### Running clobber step. 22:37:06 INFO - ##### 22:37:06 INFO - Running pre-action listener: _resource_record_pre_action 22:37:06 INFO - Running main action method: clobber 22:37:06 INFO - rmtree: /builds/slave/test/build 22:37:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:37:07 INFO - Running post-action listener: _resource_record_post_action 22:37:07 INFO - ##### 22:37:07 INFO - ##### Finished clobber step (success) 22:37:07 INFO - ##### 22:37:07 INFO - ##### 22:37:07 INFO - ##### Running read-buildbot-config step. 22:37:07 INFO - ##### 22:37:07 INFO - Running pre-action listener: _resource_record_pre_action 22:37:07 INFO - Running main action method: read_buildbot_config 22:37:07 INFO - Using buildbot properties: 22:37:07 INFO - { 22:37:07 INFO - "project": "", 22:37:07 INFO - "product": "firefox", 22:37:07 INFO - "script_repo_revision": "production", 22:37:07 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:37:07 INFO - "repository": "", 22:37:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:37:07 INFO - "buildid": "20160705205623", 22:37:07 INFO - "pgo_build": "False", 22:37:07 INFO - "basedir": "/builds/slave/test", 22:37:07 INFO - "buildnumber": 871, 22:37:07 INFO - "slavename": "t-yosemite-r7-0304", 22:37:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:37:07 INFO - "platform": "yosemite_r7", 22:37:07 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:37:07 INFO - "branch": "fx-team", 22:37:07 INFO - "repo_path": "integration/fx-team", 22:37:07 INFO - "revision": "cec1ab33c0d6bdc2638e0e76566a5009f033d949", 22:37:07 INFO - "stage_platform": "macosx64", 22:37:07 INFO - "builduid": "c4a51a39c70c4e73aafe0e9496136fe5", 22:37:07 INFO - "slavebuilddir": "test" 22:37:07 INFO - } 22:37:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 22:37:07 INFO - Running post-action listener: _resource_record_post_action 22:37:07 INFO - ##### 22:37:07 INFO - ##### Finished read-buildbot-config step (success) 22:37:07 INFO - ##### 22:37:07 INFO - ##### 22:37:07 INFO - ##### Running download-and-extract step. 22:37:07 INFO - ##### 22:37:07 INFO - Running pre-action listener: _resource_record_pre_action 22:37:07 INFO - Running main action method: download_and_extract 22:37:07 INFO - mkdir: /builds/slave/test/build/tests 22:37: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')]} 22:37:07 INFO - https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:37:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:37:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:37:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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 22:37:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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 22:37:08 INFO - Downloaded 1199 bytes. 22:37:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:37:08 INFO - Using the following test package requirements: 22:37:08 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:37:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:37:08 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 22:37:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:37:08 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 22:37:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:37:08 INFO - u'jsshell-mac.zip'], 22:37:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:37:08 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 22:37:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:37:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:37:08 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 22:37:08 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:37:08 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 22:37:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:37:08 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 22:37:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:37:08 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 22:37:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:37:08 INFO - https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:37:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:37:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:37:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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 22:37:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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 22:37:16 INFO - Downloaded 18802536 bytes. 22:37:16 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'] 22:37:16 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 22:37:19 INFO - Return code: 0 22:37:19 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 22:37:19 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')]} 22:37:19 INFO - https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:37:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:37:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:37:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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 22:37:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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 22:37:20 INFO - Downloaded 18802536 bytes. 22:37:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:37:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:37:24 INFO - Return code: 0 22:37: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')]} 22:37:24 INFO - https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:37:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:37:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:37:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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 22:37:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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 22:37:25 INFO - Downloaded 14403333 bytes. 22:37:25 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'] 22:37:25 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 22:37:26 INFO - Return code: 0 22:37:26 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')]} 22:37:26 INFO - https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:37:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:37:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:37:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 22:37:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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 22:37:29 INFO - Downloaded 112488386 bytes. 22:37:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:37:29 INFO - mkdir: /builds/slave/test/properties 22:37:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:37:29 INFO - Writing to file /builds/slave/test/properties/build_url 22:37:29 INFO - Contents: 22:37:29 INFO - build_url:https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:37:30 INFO - Running post-action listener: _resource_record_post_action 22:37:30 INFO - Running post-action listener: set_extra_try_arguments 22:37:30 INFO - ##### 22:37:30 INFO - ##### Finished download-and-extract step (success) 22:37:30 INFO - ##### 22:37:30 INFO - ##### 22:37:30 INFO - ##### Running populate-webroot step. 22:37:30 INFO - ##### 22:37:30 INFO - Running pre-action listener: _resource_record_pre_action 22:37:30 INFO - Running main action method: populate_webroot 22:37:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:37:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:37:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:37:30 INFO - '--tppagecycles', 22:37:30 INFO - '7'], 22:37:30 INFO - 'tests': ['tsvgm']}}, 22:37:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:37:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:37:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:37:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:37:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:30 INFO - 'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:37:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:37:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:30 INFO - 'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['damp', 'tps']}, 22:37:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:30 INFO - 'tests': ['damp', 'tps']}, 22:37:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['dromaeo_dom']}, 22:37:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:37:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['basic_compositor_video']}, 22:37:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:37:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['a11yr', 22:37:30 INFO - 'ts_paint', 22:37:30 INFO - 'tpaint', 22:37:30 INFO - 'sessionrestore', 22:37:30 INFO - 'sessionrestore_no_auto_restore', 22:37:30 INFO - 'tabpaint']}, 22:37:30 INFO - 'other-e10s': {'tests': ['a11yr', 22:37:30 INFO - 'ts_paint', 22:37:30 INFO - 'tpaint', 22:37:30 INFO - 'sessionrestore', 22:37:30 INFO - 'sessionrestore_no_auto_restore', 22:37:30 INFO - 'tabpaint']}, 22:37:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:37:30 INFO - 'ts_paint', 22:37:30 INFO - 'tpaint', 22:37:30 INFO - 'sessionrestore', 22:37:30 INFO - 'sessionrestore_no_auto_restore', 22:37:30 INFO - 'tabpaint']}, 22:37:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:37:30 INFO - 'ts_paint', 22:37:30 INFO - 'tpaint', 22:37:30 INFO - 'sessionrestore', 22:37:30 INFO - 'sessionrestore_no_auto_restore', 22:37:30 INFO - 'tabpaint']}, 22:37:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['a11yr', 22:37:30 INFO - 'ts_paint', 22:37:30 INFO - 'tpaint', 22:37:30 INFO - 'sessionrestore', 22:37:30 INFO - 'sessionrestore_no_auto_restore', 22:37:30 INFO - 'tabpaint']}, 22:37:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['a11yr', 22:37:30 INFO - 'ts_paint', 22:37:30 INFO - 'tpaint', 22:37:30 INFO - 'sessionrestore', 22:37:30 INFO - 'sessionrestore_no_auto_restore', 22:37:30 INFO - 'tabpaint']}, 22:37:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['tsvgx', 22:37:30 INFO - 'tsvgr_opacity', 22:37:30 INFO - 'tart', 22:37:30 INFO - 'tscrollx', 22:37:30 INFO - 'cart']}, 22:37:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:37:30 INFO - 'tsvgr_opacity', 22:37:30 INFO - 'tart', 22:37:30 INFO - 'tscrollx', 22:37:30 INFO - 'cart']}, 22:37:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:30 INFO - 'talos_options': ['--disable-e10s'], 22:37:30 INFO - 'tests': ['tp5o']}, 22:37:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:30 INFO - 'tests': ['tp5o']}, 22:37:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:30 INFO - 'talos_options': ['--disable-e10s', 22:37:30 INFO - '--xperf_path', 22:37:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:37:30 INFO - 'tests': ['tp5n']}, 22:37:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:37:30 INFO - 'talos_options': ['--xperf_path', 22:37:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:37:30 INFO - 'tests': ['tp5n']}}, 22:37:30 INFO - 'talos.zip': {'path': '', 22:37:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:37:30 INFO - Running post-action listener: _resource_record_post_action 22:37:30 INFO - ##### 22:37:30 INFO - ##### Finished populate-webroot step (success) 22:37:30 INFO - ##### 22:37:30 INFO - ##### 22:37:30 INFO - ##### Running create-virtualenv step. 22:37:30 INFO - ##### 22:37:30 INFO - Running pre-action listener: _resource_record_pre_action 22:37:30 INFO - Running main action method: create_virtualenv 22:37:30 INFO - Creating virtualenv /builds/slave/test/build/venv 22:37: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 22:37:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:37:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:37:31 INFO - Using real prefix '/tools/python27' 22:37:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:37:31 INFO - Installing distribute.............................................................................................................................................................................................done. 22:37:34 INFO - Installing pip.................done. 22:37:34 INFO - Return code: 0 22:37:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x101cbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7f25a1c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, '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.eIXdam6JlK/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.FWb7YWC0t4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37: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 22:37: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 22:37:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:37:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:34 INFO - 'HOME': '/Users/cltbld', 22:37:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:34 INFO - 'LOGNAME': 'cltbld', 22:37:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:34 INFO - 'MOZ_NO_REMOTE': '1', 22:37:34 INFO - 'NO_EM_RESTART': '1', 22:37:34 INFO - 'PAGER': '/bin/cat', 22:37:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:34 INFO - 'PWD': '/builds/slave/test', 22:37:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:34 INFO - 'SHELL': '/bin/bash', 22:37:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:37:34 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:37:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:34 INFO - 'USER': 'cltbld', 22:37:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:34 INFO - 'XPC_FLAGS': '0x0', 22:37:34 INFO - 'XPC_SERVICE_NAME': '0', 22:37:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37: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 22:37:34 INFO - Cleaning up... 22:37:34 INFO - Return code: 0 22:37:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x101cbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7f25a1c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, '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.eIXdam6JlK/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.FWb7YWC0t4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37: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 22:37: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 22:37:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:37:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:34 INFO - 'HOME': '/Users/cltbld', 22:37:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:34 INFO - 'LOGNAME': 'cltbld', 22:37:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:34 INFO - 'MOZ_NO_REMOTE': '1', 22:37:34 INFO - 'NO_EM_RESTART': '1', 22:37:34 INFO - 'PAGER': '/bin/cat', 22:37:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:34 INFO - 'PWD': '/builds/slave/test', 22:37:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:34 INFO - 'SHELL': '/bin/bash', 22:37:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:37:34 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:37:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:34 INFO - 'USER': 'cltbld', 22:37:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:34 INFO - 'XPC_FLAGS': '0x0', 22:37:34 INFO - 'XPC_SERVICE_NAME': '0', 22:37:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:35 INFO - Downloading/unpacking psutil>=3.1.1 22:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:37:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:37:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:37:37 INFO - Installing collected packages: psutil 22:37:37 INFO - Running setup.py install for psutil 22:37:37 INFO - building 'psutil._psutil_osx' extension 22:37: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 22:37: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 22:37: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 22:37: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 22:37:38 INFO - building 'psutil._psutil_posix' extension 22:37: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 22:37:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:37:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:37:38 INFO - ^ 22:37:38 INFO - 1 warning generated. 22:37: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 22:37:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:37:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:37:38 INFO - Successfully installed psutil 22:37:38 INFO - Cleaning up... 22:37:38 INFO - Return code: 0 22:37:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x101cbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7f25a1c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, '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.eIXdam6JlK/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.FWb7YWC0t4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37: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 22:37: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 22:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:38 INFO - 'HOME': '/Users/cltbld', 22:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:38 INFO - 'LOGNAME': 'cltbld', 22:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:38 INFO - 'MOZ_NO_REMOTE': '1', 22:37:38 INFO - 'NO_EM_RESTART': '1', 22:37:38 INFO - 'PAGER': '/bin/cat', 22:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:38 INFO - 'PWD': '/builds/slave/test', 22:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:38 INFO - 'SHELL': '/bin/bash', 22:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:37:38 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:38 INFO - 'USER': 'cltbld', 22:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:38 INFO - 'XPC_FLAGS': '0x0', 22:37:38 INFO - 'XPC_SERVICE_NAME': '0', 22:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:37:41 INFO - Installing collected packages: mozsystemmonitor 22:37:41 INFO - Running setup.py install for mozsystemmonitor 22:37:41 INFO - Successfully installed mozsystemmonitor 22:37:41 INFO - Cleaning up... 22:37:41 INFO - Return code: 0 22:37:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x101cbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7f25a1c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, '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.eIXdam6JlK/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.FWb7YWC0t4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37: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 22:37: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 22:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:41 INFO - 'HOME': '/Users/cltbld', 22:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:41 INFO - 'LOGNAME': 'cltbld', 22:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:41 INFO - 'MOZ_NO_REMOTE': '1', 22:37:41 INFO - 'NO_EM_RESTART': '1', 22:37:41 INFO - 'PAGER': '/bin/cat', 22:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:41 INFO - 'PWD': '/builds/slave/test', 22:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:41 INFO - 'SHELL': '/bin/bash', 22:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:37:41 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:41 INFO - 'USER': 'cltbld', 22:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:41 INFO - 'XPC_FLAGS': '0x0', 22:37:41 INFO - 'XPC_SERVICE_NAME': '0', 22:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:41 INFO - Downloading/unpacking jsonschema==2.5.1 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:37:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:44 INFO - Downloading functools32-3.2.3-2.tar.gz 22:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:37:44 INFO - warning: no files found matching '*.txt' 22:37:44 INFO - no previously-included directories found matching 'build' 22:37:44 INFO - no previously-included directories found matching 'dist' 22:37:44 INFO - no previously-included directories found matching '.git*' 22:37:44 INFO - Installing collected packages: jsonschema, functools32 22:37:44 INFO - Running setup.py install for functools32 22:37:44 INFO - warning: no files found matching '*.txt' 22:37:44 INFO - no previously-included directories found matching 'build' 22:37:44 INFO - no previously-included directories found matching 'dist' 22:37:44 INFO - no previously-included directories found matching '.git*' 22:37:44 INFO - Successfully installed jsonschema functools32 22:37:44 INFO - Cleaning up... 22:37:45 INFO - Return code: 0 22:37:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x101cbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7f25a1c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, '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.eIXdam6JlK/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.FWb7YWC0t4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37: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 22:37: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 22:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:45 INFO - 'HOME': '/Users/cltbld', 22:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:45 INFO - 'LOGNAME': 'cltbld', 22:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:45 INFO - 'MOZ_NO_REMOTE': '1', 22:37:45 INFO - 'NO_EM_RESTART': '1', 22:37:45 INFO - 'PAGER': '/bin/cat', 22:37:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:45 INFO - 'PWD': '/builds/slave/test', 22:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:45 INFO - 'SHELL': '/bin/bash', 22:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:37:45 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:45 INFO - 'USER': 'cltbld', 22:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:45 INFO - 'XPC_FLAGS': '0x0', 22:37:45 INFO - 'XPC_SERVICE_NAME': '0', 22:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:45 INFO - Downloading/unpacking blobuploader==1.2.4 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:47 INFO - Downloading blobuploader-1.2.4.tar.gz 22:37:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:37:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:37:47 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 22:37:47 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 22:37:47 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 22:37:47 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 22:37:47 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 22:37:47 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 22:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:37:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:48 INFO - Downloading docopt-0.6.1.tar.gz 22:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:37:49 INFO - Installing collected packages: blobuploader, requests, docopt 22:37:49 INFO - Running setup.py install for blobuploader 22:37:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:37:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:37:49 INFO - Running setup.py install for requests 22:37:49 INFO - Running setup.py install for docopt 22:37:49 INFO - Successfully installed blobuploader requests docopt 22:37:49 INFO - Cleaning up... 22:37:49 INFO - Return code: 0 22:37:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:49 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 0x101cbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7f25a1c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, '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.eIXdam6JlK/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.FWb7YWC0t4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37:49 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 22:37:49 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 22:37:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:37:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:49 INFO - 'HOME': '/Users/cltbld', 22:37:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:49 INFO - 'LOGNAME': 'cltbld', 22:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:49 INFO - 'MOZ_NO_REMOTE': '1', 22:37:49 INFO - 'NO_EM_RESTART': '1', 22:37:49 INFO - 'PAGER': '/bin/cat', 22:37:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:49 INFO - 'PWD': '/builds/slave/test', 22:37:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:49 INFO - 'SHELL': '/bin/bash', 22:37:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:37:49 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:37:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:49 INFO - 'USER': 'cltbld', 22:37:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:49 INFO - 'XPC_FLAGS': '0x0', 22:37:49 INFO - 'XPC_SERVICE_NAME': '0', 22:37:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:37:50 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-xOXWed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:37:50 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-YlAYwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:37:50 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-QE_1Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:37:50 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-gZWdwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:37:50 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-yhwaCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:37:50 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-XrfON1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:37:50 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-f48LIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:37:50 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-zSFvVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:37:50 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-xthwwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:37:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-KqaTfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:37:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-CCTE5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:37:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Aj3kMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:37:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-FJgj89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:37:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-bIMV1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:37:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-gSkW4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:37:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-ocI6iQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:37:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-UgAqdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:37:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:37:51 INFO - Running setup.py install for manifestparser 22:37:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:37:52 INFO - Running setup.py install for mozcrash 22:37:52 INFO - Running setup.py install for mozdebug 22:37:52 INFO - Running setup.py install for mozdevice 22:37:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:37:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:37:52 INFO - Running setup.py install for mozfile 22:37:52 INFO - Running setup.py install for mozhttpd 22:37:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:37:52 INFO - Running setup.py install for mozinfo 22:37:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:37:52 INFO - Running setup.py install for mozInstall 22:37:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:37:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:37:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:37:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:37:53 INFO - Running setup.py install for mozleak 22:37:53 INFO - Running setup.py install for mozlog 22:37:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:37:53 INFO - Running setup.py install for moznetwork 22:37:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:37:53 INFO - Running setup.py install for mozprocess 22:37:53 INFO - Running setup.py install for mozprofile 22:37:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:37:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:37:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:37:53 INFO - Running setup.py install for mozrunner 22:37:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:37:54 INFO - Running setup.py install for mozscreenshot 22:37:54 INFO - Running setup.py install for moztest 22:37:54 INFO - Running setup.py install for mozversion 22:37:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:37:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:37:54 INFO - Cleaning up... 22:37:54 INFO - Return code: 0 22:37:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:37: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')]} 22:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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')]} 22:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37: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 0x101cbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7f25a1c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, '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.eIXdam6JlK/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.FWb7YWC0t4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37: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 22:37: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 22:37:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:37:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:54 INFO - 'HOME': '/Users/cltbld', 22:37:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:54 INFO - 'LOGNAME': 'cltbld', 22:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:54 INFO - 'MOZ_NO_REMOTE': '1', 22:37:54 INFO - 'NO_EM_RESTART': '1', 22:37:54 INFO - 'PAGER': '/bin/cat', 22:37:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:54 INFO - 'PWD': '/builds/slave/test', 22:37:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:54 INFO - 'SHELL': '/bin/bash', 22:37:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:37:54 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:37:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:54 INFO - 'USER': 'cltbld', 22:37:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:54 INFO - 'XPC_FLAGS': '0x0', 22:37:54 INFO - 'XPC_SERVICE_NAME': '0', 22:37:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:37:54 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-03DT1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:37:54 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)) 22:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:37:54 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-KALQsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:37:54 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)) 22:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:37:54 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-jXmPT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:37: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)) 22:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:37:55 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-iw8BfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:37: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)) 22:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:37:55 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-NdNxjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:37: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)) 22:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:37:55 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-jrfGJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:37: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)) 22:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:37:55 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-C_E9Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:37: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)) 22:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:37:55 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-f8jFvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:37: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)) 22:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:37:55 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-OccIJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:37:55 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)) 22:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:37:55 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-N1fPv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:37:55 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)) 22:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:37:55 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-5hz7pQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:37:55 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)) 22:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:37:55 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-MYu3C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:37: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)) 22:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:37:56 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-gDVyjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:37: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)) 22:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:37:56 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-2p21to-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:37: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)) 22:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:37:56 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Lrk9PI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:37: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)) 22:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:37:56 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-_Z25n5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:37: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)) 22:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:37:56 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-YXeKNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37: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)) 22:37:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37: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 22:37:58 INFO - Downloading blessings-1.6.tar.gz 22:37:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:37:59 INFO - Installing collected packages: blessings 22:37:59 INFO - Running setup.py install for blessings 22:37:59 INFO - Successfully installed blessings 22:37:59 INFO - Cleaning up... 22:37:59 INFO - Return code: 0 22:37:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:37:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:37:59 INFO - Reading from file tmpfile_stdout 22:37:59 INFO - Current package versions: 22:37:59 INFO - blessings == 1.6 22:37:59 INFO - blobuploader == 1.2.4 22:37:59 INFO - docopt == 0.6.1 22:37:59 INFO - functools32 == 3.2.3-2 22:37:59 INFO - jsonschema == 2.5.1 22:37:59 INFO - manifestparser == 1.1 22:37:59 INFO - mozInstall == 1.12 22:37:59 INFO - mozcrash == 0.17 22:37:59 INFO - mozdebug == 0.1 22:37:59 INFO - mozdevice == 0.48 22:37:59 INFO - mozfile == 1.2 22:37:59 INFO - mozhttpd == 0.7 22:37:59 INFO - mozinfo == 0.9 22:37:59 INFO - mozleak == 0.1 22:37:59 INFO - mozlog == 3.2 22:37:59 INFO - moznetwork == 0.27 22:37:59 INFO - mozprocess == 0.22 22:37:59 INFO - mozprofile == 0.28 22:37:59 INFO - mozrunner == 6.11 22:37:59 INFO - mozscreenshot == 0.1 22:37:59 INFO - mozsystemmonitor == 0.3 22:37:59 INFO - moztest == 0.7 22:37:59 INFO - mozversion == 1.4 22:37:59 INFO - psutil == 3.1.1 22:37:59 INFO - requests == 1.2.3 22:37:59 INFO - wsgiref == 0.1.2 22:37:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:37:59 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')]} 22:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:59 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')]} 22:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:59 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 0x101cbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7f25a1c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, '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.eIXdam6JlK/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.FWb7YWC0t4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37:59 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 22:37:59 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 22:37:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:37:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:59 INFO - 'HOME': '/Users/cltbld', 22:37:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:59 INFO - 'LOGNAME': 'cltbld', 22:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:59 INFO - 'MOZ_NO_REMOTE': '1', 22:37:59 INFO - 'NO_EM_RESTART': '1', 22:37:59 INFO - 'PAGER': '/bin/cat', 22:37:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:59 INFO - 'PWD': '/builds/slave/test', 22:37:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:59 INFO - 'SHELL': '/bin/bash', 22:37:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:37:59 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:37:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:59 INFO - 'USER': 'cltbld', 22:37:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:59 INFO - 'XPC_FLAGS': '0x0', 22:37:59 INFO - 'XPC_SERVICE_NAME': '0', 22:37:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:37:59 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)) 22:37:59 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)) 22:37:59 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)) 22:37:59 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)) 22:37:59 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)) 22:37:59 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)) 22:37:59 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)) 22:37:59 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)) 22:37:59 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)) 22:37:59 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)) 22:37:59 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)) 22:37:59 INFO - Cleaning up... 22:37:59 INFO - Return code: 0 22:37:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:37:59 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')]} 22:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:37:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:59 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')]} 22:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:37:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:37:59 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 0x101cbda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d52618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7f25a1c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, '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.eIXdam6JlK/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.FWb7YWC0t4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 22:37:59 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 22:37:59 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 22:37:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:37:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:37:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:37:59 INFO - 'HOME': '/Users/cltbld', 22:37:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:37:59 INFO - 'LOGNAME': 'cltbld', 22:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:37:59 INFO - 'MOZ_NO_REMOTE': '1', 22:37:59 INFO - 'NO_EM_RESTART': '1', 22:37:59 INFO - 'PAGER': '/bin/cat', 22:37:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:37:59 INFO - 'PWD': '/builds/slave/test', 22:37:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:37:59 INFO - 'SHELL': '/bin/bash', 22:37:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:37:59 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:37:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:37:59 INFO - 'USER': 'cltbld', 22:37:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:37:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:37:59 INFO - 'XPC_FLAGS': '0x0', 22:37:59 INFO - 'XPC_SERVICE_NAME': '0', 22:37:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:38:00 INFO - Cleaning up... 22:38:00 INFO - Return code: 0 22:38:00 INFO - Running post-action listener: _resource_record_post_action 22:38:00 INFO - Running post-action listener: _start_resource_monitoring 22:38:00 INFO - Starting resource monitoring. 22:38:00 INFO - ##### 22:38:00 INFO - ##### Finished create-virtualenv step (success) 22:38:00 INFO - ##### 22:38:00 INFO - ##### 22:38:00 INFO - ##### Running install step. 22:38:00 INFO - ##### 22:38:00 INFO - Running pre-action listener: _resource_record_pre_action 22:38:00 INFO - Running main action method: install 22:38:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:38:00 INFO - Reading from file tmpfile_stdout 22:38:00 INFO - Detecting whether we're running mozinstall >=1.0... 22:38:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:38:00 INFO - Reading from file tmpfile_stdout 22:38:00 INFO - Output received: 22:38:00 INFO - Usage: mozinstall [options] installer 22:38:00 INFO - Options: 22:38:00 INFO - -h, --help show this help message and exit 22:38:00 INFO - -d DEST, --destination=DEST 22:38:00 INFO - Directory to install application into. [default: 22:38:00 INFO - "/builds/slave/test"] 22:38:00 INFO - --app=APP Application being installed. [default: firefox] 22:38:00 INFO - mkdir: /builds/slave/test/build/application 22:38: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'] 22:38: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 22:38:35 INFO - Reading from file tmpfile_stdout 22:38:35 INFO - Output received: 22:38:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:38:35 INFO - Running post-action listener: _resource_record_post_action 22:38:35 INFO - ##### 22:38:35 INFO - ##### Finished install step (success) 22:38:35 INFO - ##### 22:38:35 INFO - ##### 22:38:35 INFO - ##### Running run-tests step. 22:38:35 INFO - ##### 22:38:35 INFO - Running pre-action listener: _resource_record_pre_action 22:38:35 INFO - Running main action method: run_tests 22:38:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:38:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:38:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:38:35 INFO - 2016-07-05 22:38:35.226 screenresolution[1928:11940] starting screenresolution argv=screenresolution get 22:38:35 INFO - 2016-07-05 22:38:35.229 screenresolution[1928:11940] Display 0: 1600x1200x32@60 22:38:35 INFO - 2016-07-05 22:38:35.235 screenresolution[1929:11942] starting screenresolution argv=screenresolution list 22:38:35 INFO - Available Modes on Display 0 22:38:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:38:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:38:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:38:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:38:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:38:35 INFO - Intel Iris: 22:38:35 INFO - Chipset Model: Intel Iris 22:38:35 INFO - Type: GPU 22:38:35 INFO - Bus: Built-In 22:38:35 INFO - VRAM (Dynamic, Max): 1536 MB 22:38:35 INFO - Vendor: Intel (0x8086) 22:38:35 INFO - Device ID: 0x0a2e 22:38:35 INFO - Revision ID: 0x0009 22:38:35 INFO - Displays: 22:38:35 INFO - Display: 22:38:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:38:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:38:35 INFO - Display Serial Number: 1600x1200 60 22:38:35 INFO - Main Display: Yes 22:38:35 INFO - Mirror: Off 22:38:35 INFO - Online: Yes 22:38:35 INFO - Rotation: Supported 22:38:35 INFO - Return code: 0 22:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:38:35 INFO - Python 2.7.3 22:38:35 INFO - Return code: 0 22:38:35 INFO - grabbing minidump binary from tooltool 22:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:38:35 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 0x101c21d30>, '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 0x101986db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c19b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:38:35 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 22:38:35 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 22:38:35 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 22:38:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:38:35 INFO - Return code: 0 22:38:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:38:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:38:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:38:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:38:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:38:35 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/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 22:38:35 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/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0304 --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 22:38:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIXdam6JlK/Render', 22:38:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:38:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:38:35 INFO - 'HOME': '/Users/cltbld', 22:38:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:38:35 INFO - 'LOGNAME': 'cltbld', 22:38:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:38:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:38:35 INFO - 'MOZ_NO_REMOTE': '1', 22:38:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:38:35 INFO - 'NO_EM_RESTART': '1', 22:38:35 INFO - 'PAGER': '/bin/cat', 22:38:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:38:35 INFO - 'PWD': '/builds/slave/test', 22:38:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:38:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:38:35 INFO - 'SHELL': '/bin/bash', 22:38:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWb7YWC0t4/Listeners', 22:38:35 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 22:38:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:38:35 INFO - 'USER': 'cltbld', 22:38:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:38:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:38:35 INFO - 'XPC_FLAGS': '0x0', 22:38:35 INFO - 'XPC_SERVICE_NAME': '0', 22:38:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:38:35 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/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 22:38:35 INFO - mozversion application_buildid: 20160705205623 22:38:35 INFO - mozversion application_changeset: cec1ab33c0d6bdc2638e0e76566a5009f033d949 22:38:35 INFO - mozversion application_display_name: Nightly 22:38:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:38:35 INFO - mozversion application_name: Firefox 22:38:35 INFO - mozversion application_remotingname: firefox 22:38:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:38:35 INFO - mozversion application_vendor: Mozilla 22:38:35 INFO - mozversion application_version: 50.0a1 22:38:35 INFO - mozversion platform_buildid: 20160705205623 22:38:35 INFO - mozversion platform_changeset: cec1ab33c0d6bdc2638e0e76566a5009f033d949 22:38:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:38:35 INFO - mozversion platform_version: 50.0a1 22:38:35 INFO - using testdate: 1467783515 22:38:35 INFO - actual date: 1467783515 22:38:35 INFO - starting webserver on 'localhost:49668' 22:38:35 INFO - SUITE-START | Running 2 tests 22:38:35 INFO - TEST-START | dromaeo_css 22:38:35 INFO - operating with platform_type : mac_ 22:38:35 INFO - Initialising browser for dromaeo_css test... 22:38:35 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpHqQ_yo/profile http://localhost:49668/getInfo.html) 22:38:37 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 22:38:37 INFO - PROCESS | 1939 | colorDepth:24 22:38:37 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 22:38:37 INFO - PROCESS | 1939 | __metrics 22:38:38 INFO - PROCESS | 1939 | 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" 22:38:38 INFO - PROCESS | 1939 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:38:38 INFO - PROCESS | 1939 | ************************* 22:38:38 INFO - PROCESS | 1939 | A coding exception was thrown in a Promise resolution callback. 22:38:38 INFO - PROCESS | 1939 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 22:38:38 INFO - PROCESS | 1939 | 22:38:38 ERROR - PROCESS | 1939 | Full message: TypeError: win is null 22:38:38 INFO - PROCESS | 1939 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 22:38:38 INFO - PROCESS | 1939 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 22:38:38 INFO - PROCESS | 1939 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 22:38:38 INFO - PROCESS | 1939 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 22:38:38 INFO - PROCESS | 1939 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 22:38:38 INFO - PROCESS | 1939 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 22:38:38 INFO - PROCESS | 1939 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 22:38:38 INFO - PROCESS | 1939 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 22:38:38 INFO - PROCESS | 1939 | 22:38:38 INFO - PROCESS | 1939 | ************************* 22:38:38 INFO - TEST-INFO | 1939: exit 0 22:38:38 INFO - Browser initialized. 22:38:38 INFO - Running cycle 1/1 for dromaeo_css test... 22:38:38 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpHqQ_yo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:38:50 INFO - PROCESS | 1941 | RSS: Main: 266534912 22:38:50 INFO - PROCESS | 1941 | 22:39:41 INFO - PROCESS | 1941 | 1467783581972 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] 22:41:53 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49668/tests/dromaeo/cssquery-ext.html) 22:41:54 INFO - PROCESS | 1941 | RSS: Main: 391393280 22:41:54 INFO - PROCESS | 1941 | 22:44:58 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-ext.html (next: http://localhost:49668/tests/dromaeo/cssquery-jquery.html) 22:44:58 INFO - PROCESS | 1941 | RSS: Main: 398364672 22:44:58 INFO - PROCESS | 1941 | 22:48:01 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49668/tests/dromaeo/cssquery-mootools.html) 22:48:01 INFO - PROCESS | 1941 | RSS: Main: 393117696 22:48:01 INFO - PROCESS | 1941 | 22:51:04 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49668/tests/dromaeo/cssquery-prototype.html) 22:51:04 INFO - PROCESS | 1941 | RSS: Main: 393965568 22:51:04 INFO - PROCESS | 1941 | 22:54:07 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49668/tests/dromaeo/cssquery-yui.html) 22:54:08 INFO - PROCESS | 1941 | RSS: Main: 366129152 22:54:08 INFO - PROCESS | 1941 | 22:57:11 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49668/tests/dromaeo/cssquery-yui.html (next: http://localhost:49668/tests/dromaeo/cssquery-dojo.html) 22:57:12 INFO - PROCESS | 1941 | RSS: Main: 405426176 22:57:12 INFO - PROCESS | 1941 | 22:57:12 INFO - PROCESS | 1941 | __start_tp_report 22:57:12 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 22:57:12 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 22:57:12 INFO - PROCESS | 1941 | |i|pagename|runs| 22:57:12 INFO - PROCESS | 1941 | |0;dojo.html;2832.6317578714766;3363.81499017554;3370.6123795763497;3404.6254911959663;3429.5884493860726;4672.275797251432;5698.316202055754;5765.1928729977835;5798.159266906301;5803.767849286029;5719.513841323482;5754.050581654022;5809.244798176242;5813.128030795383;5849.327327357355;5197.922031169535;5230.973845130788;5266.973665131688;5283.656562323436;5326.360836699601;5363.758630861621;5404.756785944623;5409.513143817056;5413.431589683086;5471.124620060791;4388.407564978751;5177.37871455424;5261.86845328866;5267.446918073604;5309;5294;5309.495597918191;5316.069687804657;5316.494932981361;5323.787048518055;4381.408509851193;5228.241904923802;5252.684838909678;5296.523312901819;5309.256704061396;5708.743106560215;5717.885642287131;5934.881302373971;5943;6002.54980876436;6150.200473938404;6208.317085120633;6208.441240288395;6231.813045608639;6232.563720539519;6149.569530132892;6162.784302549389;6204.81385558434;6213;6238.625682459067;5812.82561523155;5849.444302791228;5856.765729370863;5873.882522349614;5938.346781853993;5353.2237825515085;6332.018537126753;6340.492760579143;6343.27052388972;6347.746090156435;6110.938890611126;6116.174316467309;6200.038993955944;6229.626222426669;6231.3145553989025;6129.63387754696;6201.720922558496;6202.906956395657;6230.6884655767935;6250.874982500324;6194.039923811726;6215.12988181655;6234.563580549318;6236.968815155986;6238.407351301619;6098.390160983955;6185.84535386619;6214.844628884223;6219.782307619212;6240.157578726905;4954.727489988013;4957;5006.274090256965;5012.899742005139;5028.547430731179;4404.713693609905;4459.910801783946;4460.174867649495;4465.754383508938;4496.392986946786;5902.144189092557;5910.290765108214;5915.319738230072;5932.495737862244;5937.139114828412;6096.116063170815;6145.262568491809;6190.721417536221;6253.6560489172625;6264.906026409608;4806.663533552688;5820.417958204231;5827;5840.561957853178;5870.354261031283;4744.95255047452;5384.380796208407;5473.343198816088;5479;5500.367457742331;6221.47117495018;6264.655443950626;6284.962981108066;6320.241571011508;6406.903896441557;6360.77737279193;6363.236411630634;6367.586106903036;6385.233771947396;6385.808425747235;6361.936380636134;6396.808095757135;6405.935940640534;6406.23125224976;6423.807285781434;6128;6139.5228034422325;6195.070739389128;6219.067139929047;6253;6307.085472606354;6325.146105275913;6333.6516491591565;6336.683165841781;6357.23713154406;6149.815505534841;6166.136740856374;6177.104319873503;6204.689765511796;6219.004959206505;5069.797208111634;5523.2819733436;5660.29246344207;5930.347661757117;6167.5374346925055;5952.970235148797;5958.821235362946;5961.701914904324;5973.701314934323;6024.518038556993;5953.91069133972;6192.318844926965;6204.13142160089;6206.503479721701;6231.40801623845;5844.298684157929;5890.558208134321;5924.200232968494;5941.049432090845;5966.254218222723 22:57:12 INFO - PROCESS | 1941 | |1;ext.html;479.23083451061046;531.7474199755114;541.072061414674;543.5298466826194;555.872149405637;30294.09117726466;41794.95512612182;43413.56586434135;43592.564074359245;43744.12511749771;44136;44421;44560.33159502602;44576.777116114456;44630.776846115805;8844.469331840079;9652.613895444192;9826.50867456628;9838.754031149207;9845.36005159666;8850.5574721264;11993.280403175837;12237;12259.632211033639;12445.875541244563;12101.576444824434;12138.332391718452;12177.086718496104;12198.878011219907;12209.938950305235;8892.555372231363;11906.34515101669;12054.276743395381;12120.636380908543;12153.69615759613;11951.521939122425;12044.81932771009;12060.336681482602;12063.034957203403;12079.879201207963;42134.78932605348;42608.360874586906;42659.933501662395;42683.786581066895;42688;3484.5992710838323;3527.2416430467315;3666.578343490506;3677.485152078711;3792.5448946126367;26410.867945660342;32131.678683213104;33087.3382532348;33104;33218.83390583032;3269.0356344878082;3332.9333413331597;3337.8831740889195;3339.081752518053;3354.010566882751;383104;383138;383905;385707;386417.0679146614;63125;63544;63576;63579.36420635734;63607;66566.66716666483;66635.0004749929;66710.66644666843;66734;66769.33230667631;15164.165970871709;16806.32774689023;17074.829251707324;17079;17087.31650733957;11748.353840538852;12125.696857578454;12875.163114397534;13136;13151.736965260641;16207.594810129842;16964;17272.740908886375;17291.827081729272;17326.913365433345;6417.582857114273;6458.257300410513;6509.544331896722;6512.316206798313;6554;891.6968230801428;997.9351342162736;1004.5278719002057;1023.5956797065118;1088.4068182840372;26058.34854128624;30845;31184.688153118634;31280.843595781877;31788.046358609277;1096.5908807182732;1100.1187968573668;1152.9654110376703;1166.2361153444508;1210.189173253922;15213.847861521464;15935.203239838194;16384.918075409547;16660.666786664442;16776.58058548546;595478;693042;698928;701325;701553;61909.69045154745;62074.379256207765;62168.37831621626;62180;62233.066504002476;1257.4090177616506;1380.5375199308344;1389.4303335632342;1417.6030711400824;1420.9573712788633;31650.683493165696;32010;32125.03624891257;32896;32960.01119966405;51489.22766158436;58807.411925881905;60211.78517503733;60598.69700651469;64179;603440;610629;614774;614848;616063;24417.755822441548;27019.59470607903;27157.86421067882;27529.587056193774;27630.44739105247;48398;48492;49033.01933961372;50021.249681254085;50631.240531392796;39065.609343906195;40130.59869401269;41329.1734165321;41790.10463273526;42950.92622684458;41404;41506;41972.58027419809;42071.57928420677;42347.7882610585;40289.395659065725;40844.79577602093;40874.79562602168;40949.59050409458;41117.383239250834;33078;35427.64572354243;35465.33397904162;35923;35965.64034359623;26171.73828261693;26280.73719262783;26569.468610627293;26752.33119172036;27147.86426067857 22:57:12 INFO - PROCESS | 1941 | |2;jquery.html;364.8011833550715;380.82101412336203;393.349007392765;393.5729733239437;419.05294035479807;15570.299336529855;16462.83537164628;21631.567368652657;21681.4579635509;22309.44226394341;21475.926203689833;21787.67318490224;22147.667784983238;22893;22894.313170604866;5954.345022047572;5957.52339812815;6137.20216371871;6155;6174.722137503801;4244.978775106136;4463.553644635543;6085.330613632497;6195.845103872417;6516.413522782948;6792.932070679304;6869.420033392323;6881.724731010753;6883.414909732679;6904.516683832134;6595.604263744167;6906.723731050777;6914.308569143096;6915.481338899553;6921.723131074751;3794.5636251831115;4045.6965727570255;5860.560457965669;5969.134475501071;5980.581359304851;24092.87953560221;24204.515909681708;27123.45753084927;27676;28575.85712071447;2052.79472052795;2295.7015587973456;2497.4131079196372;2644.502834464296;3056.51095824667;7785.260400261966;10215.029572190704;10266.69199923996;10300.330478518872;10501.63244286454;1837.6875931091747;1906.1993962535769;1908.8759230650103;1917.1660327757331;1956.0806420982115;130812;131871.34064329616;131909;133431;134429;11706.590269340531;11776.411179440993;11801.645950621494;11811.88188118125;11922.10584206188;14734.263286835616;14824.555263342118;14855.702885942437;14868.851311486964;14908.70182596342;8722.34582406322;8754.69358572447;8754.737357879147;8780.824383512421;8838.29293656506;8737.30101591871;8740.956295218482;8769.693060742971;8852.645894164161;8859.778505537413;4578.885527861764;4586.633069354443;4590.517995610479;4614;4693.48371679115;1682.0328311221097;1898.506388339042;1925.594316149219;1949;1986.0536674059215;716.39822549058;810.2464707821782;826.972643227474;876.8553188723916;929.4284015330524;19031.333903313316;21736.891315543322;23019.769802302097;23059.73171475585;23105.3068407948;936.8782058332373;962.5668449197889;976.097110173087;991.7520619845038;1038.9636362727267;11393.373364464867;11898.405079745979;11929.70175745596;11944.104192185621;12242;485017;599330;606968;608102;630393;101034.98965010257;121506;138063.92904106446;139098;141207.29396352952;941.9811603767886;967.511406739592;977.8435450327913;978.3836183204536;1010.9747256318651;19519;20169.39491815248;20179.79820201779;20226.797732022493;20296.79703202949;48967;51677.48322516727;52069.47930520646;52541.7372913133;54724;77501.2249877494;78772.4245515075;78844.60577697075;79064.60467697625;79698;7616.162222155669;7828.725994590162;8319.126491718282;8394.622241999003;8498.617562209593;11084.556617735523;13300.068995170632;13354.732905342033;13632.250226237653;13729.176249425067;11654.30074195551;11687.298762074302;11690;11794.997425218953;11987.820182697442;11497.137714671266;11507.654770356903;11613.76772464563;11758.294502329887;11785.292882427082;11091.445427728742;11682.299062056303;11882.465289062186;11955.581554645545;12072.637820865388;8190.140035296208;8212.507249565046;8244.793880153044;8486.321094312316;8563.486190828571;11122.721931951766;11499.54001839917;11618.244814087395;11700;11818.46816893259 22:57:12 INFO - PROCESS | 1941 | |3;mootools.html;1502.6393665520272;1564.623131644153;1764.3560100563298;1929.1608150454563;1935.7386752788377;8689.174528419799;8981;9188.908110918888;9190.632374705012;9211.907880921197;7904.209579042093;8181.877271840927;8182.468139570924;8287.502749835;8956.10438956104;2137.9786202137934;2177.7060096886953;2314.2247347138727;2390.95218095638;2398.8920498577518;2374.394529395002;2399.1722855614785;2400.6519054737055;2404.5311164322966;2405.326508577601;1995.5609765851489;2345.2026311054237;2395.1138078910826;2398.1126983016306;2400.2799160251857;2064.0711679744027;2096.6854971754283;2284.543091381722;2357.422431504275;2359.4927090675587;2320.582295186866;2384.71383433989;2418.6734790803193;2421.055788242587;2427.6965379327585;9024.63901443948;9080.545972701404;9092.499912504809;9102.226310763594;9120.315976301805;5291.656042357235;5417.6478528895495;5470.56235501159;5476.726163691799;5486.753096110645;3512.3150985557827;3601.981990090059;3673.944890826613;3678.282734866702;3686.7419280650365;1042.7705904700954;1063.7712891728238;1070.4968664727567;1070.6252811515985;1074.4788777442934;12091.697707557294;12132.636020919386;12160.087993400442;12171.14801963863;12176.756464870654;4749.168895443284;4767.523247675274;4780.888273469599;4878.414590249122;4880.341153944243;10201.234907381977;10248.64129755455;10256.948715256376;10262.948685256675;10266.589336426608;5408.702521361284;5422.295101636762;5425.050616142238;5426.593005524602;5427.972860135753;4913.63147763912;4935.284383764334;4955.380577427822;4961.70229786214;5020.046191223656;3701.204241088179;3873.5158105236846;3958.960410395917;4068.8372465101065;4108.98659656772;1271.1864406779669;1301.3883474766844;1305.6213698027466;1316.789313709821;1317.3742472325569;866.8309679612415;919.8482250428738;943.5706753427174;950.8257302231739;999.3404353126901;5547.750351234205;5985.221921150222;6074.240719910012;6121.938780612137;6210.378962103844;897.5467388968659;915.5879854065697;924.9445033298024;981.9950900245595;1024.4877561219391;4009.8396064157105;4104.0970986382945;4141.544430112685;4149.722461337536;4188.958110418917;10491.422971736627;10880.891191087987;11013.669589912315;11068.557257709763;11081.944590276997;10983.61557345505;10996.725081872857;11000.559977600966;11012.284201526874;11017;7077.5399599025895;8317.708880189286;8333.499990000499;8407.117252688502;8418.452800567944;5575;5635.098384258499;5691.430856914441;5697.8860422790485;5874;10071.34536255141;10119.704474938279;10284.920083391435;10308.484575771032;10314.43270620093;10179.338343007681;10503.47482625881;10514.947425262824;10516.369017858953;10535.262531622713;6609.966950165219;7080.858382832417;7677.846443071219;7807.336376408069;7815;7994.0407151142235;8412.242898139197;8764.035956044703;8767.868481972902;8798.780030499289;7788.260115289038;7922.603869806602;7982.8802567960365;8124.918750812416;8210.753677389688;7721.806954826175;7854.293113619801;7859.17478664732;7890;7955.880661789962;7050.118735158106;7063.611501367269;7135.072440582691;7144.821379465555;7152.785416437515;5113.233015047624;5310.707911064774;5368.543673787772;5400.05499037659;5458.918116228339;5315.176147697113;5429.429909859566;5500.1749737539685;5630.352509461464;5638.605297629127 22:57:12 INFO - PROCESS | 1941 | |4;prototype.html;303.2645833852907;310.19349690803915;310.35601127660107;317.769617027655;321.145752298885;5377.085895397787;5379.892402151954;5385.895891342275;5405.945940540593;5411.756470958807;5272.736363181844;5355.6786592804365;5363.570914326854;5370.91943620846;5379.950909572635;3381.442062059757;3831.3678243089857;3887.494625698655;4006.699497537682;4108.52751933527;4013.6588389986887;4057.2899742545083;4064.329385651364;4120.175964807036;4121.505419349683;4073.6130067643526;4073.8612228579937;4095.1195492969005;4116.723815617153;4148.917021659565;3180.254300673149;3898.629630185142;4095.7337773044655;4104.199681062194;4117.300058989979;2909.6615556843876;3244.837758112085;3539.787612743218;3555.3955827509385;3592.7125829933543;4896.926546101811;5096.286519887181;5111.769970351306;5124.538791508745;5174.689518628894;4132.40080188371;4519.502854685982;4539.591436770706;4630.467496237942;4665.976670116627;5057.002484018829;5196.610254230948;5524.944750552483;5526.889462210774;5532.944670553324;3925.3326934421216;3934.4688467056867;3941.5861334559745;3943.842246310144;3947.881563553098;4838.371011768483;5258.894822103536;5336.67979921206;5496;5540.722963851832;3985.561588225293;3991.3856424042297;3999.4800675911947;4031.576684448091;4043.2722110020477;3415.6432061877226;3439.518467414564;4079.632833044981;4094.4267802507675;4102.0310251079645;4160.1471698301775;4494.797734101973;4673.766311684471;4688.671792974459;4700.788464519105;5390.353157621111;5557.471695283749;5573.5819813514145;5626.690532020778;5639.295088113986;1189.976200476003;1378.1248906943963;1388.3266615691382;1390.1311680199874;1391.9652008699863;722.1839321566702;754.8414832885046;852.808118173406;866.9639780462287;867.9801233550679;4894.608431325485;5027.874303142451;5052.646314758005;5186.066508028515;5211.749180196725;5041.193409054534;5257.421683614799;5283.709395983181;5700.743466544015;5728.742206600713;4404.480454243281;4925.375355266887;5145.871353216125;5179.404368497596;5318.149096144675;2403.1015497756925;2831.3346363604537;2973.5985641938496;2996.7003299670296;3032.0752170587743;5021.6735912165595;5927.703614819328;5927.733252003584;5933.673647949404;5933.821985340447;5610.903556109042;5695.430456954271;5817.156512305776;5841.2990441146485;5870;5873.236479257669;5911;5919.200907877467;5934.715134673095;5937.82326455353;4749.976250118727;5243.2472951700665;5548.889022219452;5673.8297851064535;5710.714464276853;5272.868178295574;5354.223637572608;5581.6930068846605;5626.521745651666;5657.415923541415;4756.027482823236;5843.503302219189;5855.414458554111;5910.970445147746;5911.615744976562;3407.16524451511;3778.641029102341;3911.1786524830104;3924.4113382992778;3963.544192417909;4986.628677113823;5639.295088113986;5660.358496036268;5718.885622287447;5779.537636989115;4440.533743956838;4876.317315575751;5317.893642127213;5544.390117087037;5573.498385145356;4653.744044077608;5184.020319375344;5503.1847133757965;5522.646951496909;5594.664320140804;4680.5085466025575;4772.689775164603;4778.593819525379;5347.705876176692;5366.463353664759;5074.923876141787;5306.9203961941375;5448.754806033818;5550.75021624036;5583.022970979981;5440.075187218262;5449.3460784706085;5450.345958485007;5479.479449452296;5520.5709487825225 22:57:12 INFO - PROCESS | 1941 | |5;yui.html;753.8793792993122;773.6441237030965;787.7715462515871;828.5360198288956;867.6963062927975;17622.118894055293;18219.36232231872;18223.453296401098;18271.99504027278;18285.26858925643;16976.15119244039;17065.658686826257;17153.91423042886;17200.311987520483;17302.48092557222;738.2174894611729;918.623364420586;942.0908822985817;946.1012038563358;946.7491114854569;758.3137260779004;761.2958013837178;764.5909438450431;765.0322342237074;765.0780809124991;952.0431965874321;974.6832279509188;985.1281615770024;988.4020167798486;997.7256254530026;966.7776411425376;978;978.8629591857145;978.8923218445965;980.9950950245275;577.4629594477135;719.061624579923;730.6602429870082;731.0678884422404;732.4872589187537;15683;15785;15798.526044218692;15804.288807003712;15815.209239538091;14434.350454229585;14866.108033518023;14877.925610371987;14928.62678433037;15009;12227.877721222763;13004.479820807157;13429.328533573282;13594.592162235149;13688.726225475535;4682.204025315705;4748.9525104748855;4889.217725163992;4933.975330123327;4992.500749925015;120039.79960200374;121419;122019.3899030508;122092;122419;11508.827367589325;11513.539458421737;11649.301041937511;12039.337836418907;12149.453274602663;22726.545469090524;22935.770642293697;23920.282391527933;23990;24053.879730601235;8028.638711258008;8060.516369017878;8215.835683286301;8231.382646301432;8238;4361.0841723237845;4383.6054755071555;4491.3487544306545;4567.3377360282575;4649.256119020941;3580.588232353313;3699.5560532735703;3702;3704.407294832814;3707.277080969185;662.7415308029872;675.2943174382782;682.0996284903979;683.7641013850214;683.9213490448662;622.5587764325571;625.8591816841265;627.2222444169197;651.8598501322239;665.1785045468843;7637.465377423527;8429.915700842914;9123.863142052875;9150.679726209684;9189.90810091904;621.7606221604715;622.5610944284268;631.5515983651567;638.4828289085856;655.3872129558782;3970.861019864291;4008.6993475489453;4157.6695457453325;4464.1071785643;4511.068918556214;162036;169711;173130;175699;175806.12096939434;147407;149259.2537037308;149567.25216373848;155201;155514;124488.75511244952;127506;131332;131685;135189;7663.233676632292;7905.56519391439;8640.524771137647;8650.30797536183;8666.956665216634;66612.66774664576;69017;69140;69454;70241.64879175571;85849;90585;104825.95174048161;109178;110953.44523277332;11869.162585020333;11916.821247681119;11967;12108.697282568006;12169.81745273804;12232.449539770554;12264.693382665504;12694.111412201426;12805.423755930819;12968.93515532454;11544.01875840563;11813.114016448973;11828.940855295668;12206.694832629255;12221.256009675439;9041.820134675965;11810.704732381759;13752.968527360688;13874.861251387358;14047.719045619235;13283.203007819562;13663.590092297247;13711.245881476614;14128.222947737973;14240.430382784987;10701.785964280827;10839.13286937059;11174;11525.423728813357;11689.649310520697;11214.719632008939;11424.714382140513;11512.730804681989;11564.363959982282;11593.24643898144 22:57:12 INFO - PROCESS | 1941 | __end_tp_report 22:57:12 INFO - PROCESS | 1941 | __start_cc_report 22:57:12 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1288 22:57:12 INFO - PROCESS | 1941 | __end_cc_report 22:57:12 INFO - PROCESS | 1941 | __startTimestamp1467784632183__endTimestamp 22:57:12 INFO - PROCESS | 1941 | 22:57:12 INFO - PROCESS | 1941 | ------- Summary: start ------- 22:57:12 INFO - PROCESS | 1941 | Number of tests: 6 22:57:12 INFO - PROCESS | 1941 | 22:57:12 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5752.78 Median:5943.00 stddev:671.89 (11.3%) stddev-sans-first:633.70 22:57:12 INFO - PROCESS | 1941 | Values: 2832.6 3363.8 3370.6 3404.6 3429.6 4672.3 5698.3 5765.2 5798.2 5803.8 5719.5 5754.1 5809.2 5813.1 5849.3 5197.9 5231.0 5267.0 5283.7 5326.4 5363.8 5404.8 5409.5 5413.4 5471.1 4388.4 5177.4 5261.9 5267.4 5309.0 5294.0 5309.5 5316.1 5316.5 5323.8 4381.4 5228.2 5252.7 5296.5 5309.3 5708.7 5717.9 5934.9 5943.0 6002.5 6150.2 6208.3 6208.4 6231.8 6232.6 6149.6 6162.8 6204.8 6213.0 6238.6 5812.8 5849.4 5856.8 5873.9 5938.3 5353.2 6332.0 6340.5 6343.3 6347.7 6110.9 6116.2 6200.0 6229.6 6231.3 6129.6 6201.7 6202.9 6230.7 6250.9 6194.0 6215.1 6234.6 6237.0 6238.4 6098.4 6185.8 6214.8 6219.8 6240.2 4954.7 4957.0 5006.3 5012.9 5028.5 4404.7 4459.9 4460.2 4465.8 4496.4 5902.1 5910.3 5915.3 5932.5 5937.1 6096.1 6145.3 6190.7 6253.7 6264.9 4806.7 5820.4 5827.0 5840.6 5870.4 4745.0 5384.4 5473.3 5479.0 5500.4 6221.5 6264.7 6285.0 6320.2 6406.9 6360.8 6363.2 6367.6 6385.2 6385.8 6361.9 6396.8 6405.9 6406.2 6423.8 6128.0 6139.5 6195.1 6219.1 6253.0 6307.1 6325.1 6333.7 6336.7 6357.2 6149.8 6166.1 6177.1 6204.7 6219.0 5069.8 5523.3 5660.3 5930.3 6167.5 5953.0 5958.8 5961.7 5973.7 6024.5 5953.9 6192.3 6204.1 6206.5 6231.4 5844.3 5890.6 5924.2 5941.0 5966.3 22:57:12 INFO - PROCESS | 1941 | 22:57:12 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:70717.83 Median:3735.02 stddev:153173.66 (4101.0%) stddev-sans-first:153512.57 22:57:12 INFO - PROCESS | 1941 | Values: 479.2 531.7 541.1 543.5 555.9 30294.1 41795.0 43413.6 43592.6 43744.1 44136.0 44421.0 44560.3 44576.8 44630.8 8844.5 9652.6 9826.5 9838.8 9845.4 8850.6 11993.3 12237.0 12259.6 12445.9 12101.6 12138.3 12177.1 12198.9 12209.9 8892.6 11906.3 12054.3 12120.6 12153.7 11951.5 12044.8 12060.3 12063.0 12079.9 42134.8 42608.4 42659.9 42683.8 42688.0 3484.6 3527.2 3666.6 3677.5 3792.5 26410.9 32131.7 33087.3 33104.0 33218.8 3269.0 3332.9 3337.9 3339.1 3354.0 383104.0 383138.0 383905.0 385707.0 386417.1 63125.0 63544.0 63576.0 63579.4 63607.0 66566.7 66635.0 66710.7 66734.0 66769.3 15164.2 16806.3 17074.8 17079.0 17087.3 11748.4 12125.7 12875.2 13136.0 13151.7 16207.6 16964.0 17272.7 17291.8 17326.9 6417.6 6458.3 6509.5 6512.3 6554.0 891.7 997.9 1004.5 1023.6 1088.4 26058.3 30845.0 31184.7 31280.8 31788.0 1096.6 1100.1 1153.0 1166.2 1210.2 15213.8 15935.2 16384.9 16660.7 16776.6 595478.0 693042.0 698928.0 701325.0 701553.0 61909.7 62074.4 62168.4 62180.0 62233.1 1257.4 1380.5 1389.4 1417.6 1421.0 31650.7 32010.0 32125.0 32896.0 32960.0 51489.2 58807.4 60211.8 60598.7 64179.0 603440.0 610629.0 614774.0 614848.0 616063.0 24417.8 27019.6 27157.9 27529.6 27630.4 48398.0 48492.0 49033.0 50021.2 50631.2 39065.6 40130.6 41329.2 41790.1 42950.9 41404.0 41506.0 41972.6 42071.6 42347.8 40289.4 40844.8 40874.8 40949.6 41117.4 33078.0 35427.6 35465.3 35923.0 35965.6 26171.7 26280.7 26569.5 26752.3 27147.9 22:57:12 INFO - PROCESS | 1941 | 22:57:12 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:35393.73 Median:2570.96 stddev:98642.05 (3836.8%) stddev-sans-first:98883.70 22:57:12 INFO - PROCESS | 1941 | Values: 364.8 380.8 393.3 393.6 419.1 15570.3 16462.8 21631.6 21681.5 22309.4 21475.9 21787.7 22147.7 22893.0 22894.3 5954.3 5957.5 6137.2 6155.0 6174.7 4245.0 4463.6 6085.3 6195.8 6516.4 6792.9 6869.4 6881.7 6883.4 6904.5 6595.6 6906.7 6914.3 6915.5 6921.7 3794.6 4045.7 5860.6 5969.1 5980.6 24092.9 24204.5 27123.5 27676.0 28575.9 2052.8 2295.7 2497.4 2644.5 3056.5 7785.3 10215.0 10266.7 10300.3 10501.6 1837.7 1906.2 1908.9 1917.2 1956.1 130812.0 131871.3 131909.0 133431.0 134429.0 11706.6 11776.4 11801.6 11811.9 11922.1 14734.3 14824.6 14855.7 14868.9 14908.7 8722.3 8754.7 8754.7 8780.8 8838.3 8737.3 8741.0 8769.7 8852.6 8859.8 4578.9 4586.6 4590.5 4614.0 4693.5 1682.0 1898.5 1925.6 1949.0 1986.1 716.4 810.2 827.0 876.9 929.4 19031.3 21736.9 23019.8 23059.7 23105.3 936.9 962.6 976.1 991.8 1039.0 11393.4 11898.4 11929.7 11944.1 12242.0 485017.0 599330.0 606968.0 608102.0 630393.0 101035.0 121506.0 138063.9 139098.0 141207.3 942.0 967.5 977.8 978.4 1011.0 19519.0 20169.4 20179.8 20226.8 20296.8 48967.0 51677.5 52069.5 52541.7 54724.0 77501.2 78772.4 78844.6 79064.6 79698.0 7616.2 7828.7 8319.1 8394.6 8498.6 11084.6 13300.1 13354.7 13632.3 13729.2 11654.3 11687.3 11690.0 11795.0 11987.8 11497.1 11507.7 11613.8 11758.3 11785.3 11091.4 11682.3 11882.5 11955.6 12072.6 8190.1 8212.5 8244.8 8486.3 8563.5 11122.7 11499.5 11618.2 11700.0 11818.5 22:57:12 INFO - PROCESS | 1941 | 22:57:12 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:5857.55 Median:4896.99 stddev:3297.68 (67.3%) stddev-sans-first:3290.69 22:57:12 INFO - PROCESS | 1941 | Values: 1502.6 1564.6 1764.4 1929.2 1935.7 8689.2 8981.0 9188.9 9190.6 9211.9 7904.2 8181.9 8182.5 8287.5 8956.1 2138.0 2177.7 2314.2 2391.0 2398.9 2374.4 2399.2 2400.7 2404.5 2405.3 1995.6 2345.2 2395.1 2398.1 2400.3 2064.1 2096.7 2284.5 2357.4 2359.5 2320.6 2384.7 2418.7 2421.1 2427.7 9024.6 9080.5 9092.5 9102.2 9120.3 5291.7 5417.6 5470.6 5476.7 5486.8 3512.3 3602.0 3673.9 3678.3 3686.7 1042.8 1063.8 1070.5 1070.6 1074.5 12091.7 12132.6 12160.1 12171.1 12176.8 4749.2 4767.5 4780.9 4878.4 4880.3 10201.2 10248.6 10256.9 10262.9 10266.6 5408.7 5422.3 5425.1 5426.6 5428.0 4913.6 4935.3 4955.4 4961.7 5020.0 3701.2 3873.5 3959.0 4068.8 4109.0 1271.2 1301.4 1305.6 1316.8 1317.4 866.8 919.8 943.6 950.8 999.3 5547.8 5985.2 6074.2 6121.9 6210.4 897.5 915.6 924.9 982.0 1024.5 4009.8 4104.1 4141.5 4149.7 4189.0 10491.4 10880.9 11013.7 11068.6 11081.9 10983.6 10996.7 11000.6 11012.3 11017.0 7077.5 8317.7 8333.5 8407.1 8418.5 5575.0 5635.1 5691.4 5697.9 5874.0 10071.3 10119.7 10284.9 10308.5 10314.4 10179.3 10503.5 10514.9 10516.4 10535.3 6610.0 7080.9 7677.8 7807.3 7815.0 7994.0 8412.2 8764.0 8767.9 8798.8 7788.3 7922.6 7982.9 8124.9 8210.8 7721.8 7854.3 7859.2 7890.0 7955.9 7050.1 7063.6 7135.1 7144.8 7152.8 5113.2 5310.7 5368.5 5400.1 5458.9 5315.2 5429.4 5500.2 5630.4 5638.6 22:57:12 INFO - PROCESS | 1941 | 22:57:12 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:4514.83 Median:5104.03 stddev:1375.98 (27.0%) stddev-sans-first:1343.03 22:57:12 INFO - PROCESS | 1941 | Values: 303.3 310.2 310.4 317.8 321.1 5377.1 5379.9 5385.9 5405.9 5411.8 5272.7 5355.7 5363.6 5370.9 5380.0 3381.4 3831.4 3887.5 4006.7 4108.5 4013.7 4057.3 4064.3 4120.2 4121.5 4073.6 4073.9 4095.1 4116.7 4148.9 3180.3 3898.6 4095.7 4104.2 4117.3 2909.7 3244.8 3539.8 3555.4 3592.7 4896.9 5096.3 5111.8 5124.5 5174.7 4132.4 4519.5 4539.6 4630.5 4666.0 5057.0 5196.6 5524.9 5526.9 5532.9 3925.3 3934.5 3941.6 3943.8 3947.9 4838.4 5258.9 5336.7 5496.0 5540.7 3985.6 3991.4 3999.5 4031.6 4043.3 3415.6 3439.5 4079.6 4094.4 4102.0 4160.1 4494.8 4673.8 4688.7 4700.8 5390.4 5557.5 5573.6 5626.7 5639.3 1190.0 1378.1 1388.3 1390.1 1392.0 722.2 754.8 852.8 867.0 868.0 4894.6 5027.9 5052.6 5186.1 5211.7 5041.2 5257.4 5283.7 5700.7 5728.7 4404.5 4925.4 5145.9 5179.4 5318.1 2403.1 2831.3 2973.6 2996.7 3032.1 5021.7 5927.7 5927.7 5933.7 5933.8 5610.9 5695.4 5817.2 5841.3 5870.0 5873.2 5911.0 5919.2 5934.7 5937.8 4750.0 5243.2 5548.9 5673.8 5710.7 5272.9 5354.2 5581.7 5626.5 5657.4 4756.0 5843.5 5855.4 5911.0 5911.6 3407.2 3778.6 3911.2 3924.4 3963.5 4986.6 5639.3 5660.4 5718.9 5779.5 4440.5 4876.3 5317.9 5544.4 5573.5 4653.7 5184.0 5503.2 5522.6 5594.7 4680.5 4772.7 4778.6 5347.7 5366.5 5074.9 5306.9 5448.8 5550.8 5583.0 5440.1 5449.3 5450.3 5479.5 5520.6 22:57:12 INFO - PROCESS | 1941 | 22:57:12 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:27577.31 Median:24021.94 stddev:45720.13 (190.3%) stddev-sans-first:45804.02 22:57:12 INFO - PROCESS | 1941 | Values: 753.9 773.6 787.8 828.5 867.7 17622.1 18219.4 18223.5 18272.0 18285.3 16976.2 17065.7 17153.9 17200.3 17302.5 738.2 918.6 942.1 946.1 946.7 758.3 761.3 764.6 765.0 765.1 952.0 974.7 985.1 988.4 997.7 966.8 978.0 978.9 978.9 981.0 577.5 719.1 730.7 731.1 732.5 15683.0 15785.0 15798.5 15804.3 15815.2 14434.4 14866.1 14877.9 14928.6 15009.0 12227.9 13004.5 13429.3 13594.6 13688.7 4682.2 4749.0 4889.2 4934.0 4992.5 120039.8 121419.0 122019.4 122092.0 122419.0 11508.8 11513.5 11649.3 12039.3 12149.5 22726.5 22935.8 23920.3 23990.0 24053.9 8028.6 8060.5 8215.8 8231.4 8238.0 4361.1 4383.6 4491.3 4567.3 4649.3 3580.6 3699.6 3702.0 3704.4 3707.3 662.7 675.3 682.1 683.8 683.9 622.6 625.9 627.2 651.9 665.2 7637.5 8429.9 9123.9 9150.7 9189.9 621.8 622.6 631.6 638.5 655.4 3970.9 4008.7 4157.7 4464.1 4511.1 162036.0 169711.0 173130.0 175699.0 175806.1 147407.0 149259.3 149567.3 155201.0 155514.0 124488.8 127506.0 131332.0 131685.0 135189.0 7663.2 7905.6 8640.5 8650.3 8667.0 66612.7 69017.0 69140.0 69454.0 70241.6 85849.0 90585.0 104826.0 109178.0 110953.4 11869.2 11916.8 11967.0 12108.7 12169.8 12232.4 12264.7 12694.1 12805.4 12968.9 11544.0 11813.1 11828.9 12206.7 12221.3 9041.8 11810.7 13753.0 13874.9 14047.7 13283.2 13663.6 13711.2 14128.2 14240.4 10701.8 10839.1 11174.0 11525.4 11689.6 11214.7 11424.7 11512.7 11564.4 11593.2 22:57:12 INFO - PROCESS | 1941 | -------- Summary: end -------- 22:57:12 INFO - PROCESS | 1941 | 22:57:12 INFO - TEST-INFO | 1941: exit 0 22:57:12 INFO - TEST-OK | dromaeo_css | took 1117005ms 22:57:12 INFO - TEST-START | kraken 22:57:12 INFO - operating with platform_type : mac_ 22:57:12 INFO - Initialising browser for kraken test... 22:57:12 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpj1PWkf/profile http://localhost:49668/getInfo.html) 22:57:14 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 22:57:14 INFO - PROCESS | 1944 | colorDepth:24 22:57:14 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 22:57:14 INFO - PROCESS | 1944 | __metrics 22:57:15 INFO - PROCESS | 1944 | 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" 22:57:15 INFO - PROCESS | 1944 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:57:15 INFO - PROCESS | 1944 | ************************* 22:57:15 INFO - PROCESS | 1944 | A coding exception was thrown in a Promise resolution callback. 22:57:15 INFO - PROCESS | 1944 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 22:57:15 INFO - PROCESS | 1944 | 22:57:15 ERROR - PROCESS | 1944 | Full message: TypeError: win is null 22:57:15 INFO - PROCESS | 1944 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 22:57:15 INFO - PROCESS | 1944 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 22:57:15 INFO - PROCESS | 1944 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 22:57:15 INFO - PROCESS | 1944 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 22:57:15 INFO - PROCESS | 1944 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 22:57:15 INFO - PROCESS | 1944 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 22:57:15 INFO - PROCESS | 1944 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 22:57:15 INFO - PROCESS | 1944 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 22:57:15 INFO - PROCESS | 1944 | 22:57:15 INFO - PROCESS | 1944 | ************************* 22:57:15 INFO - TEST-INFO | 1944: exit 0 22:57:15 INFO - Browser initialized. 22:57:15 INFO - Running cycle 1/1 for kraken test... 22:57:15 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpj1PWkf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:57:27 INFO - PROCESS | 1945 | RSS: Main: 264593408 22:57:27 INFO - PROCESS | 1945 | 22:57:30 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49668/tests/kraken/driver.html?testName=audio-beat-detection) 22:57:31 INFO - PROCESS | 1945 | RSS: Main: 311955456 22:57:31 INFO - PROCESS | 1945 | 22:57:34 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49668/tests/kraken/driver.html?testName=audio-dft) 22:57:34 INFO - PROCESS | 1945 | RSS: Main: 321548288 22:57:34 INFO - PROCESS | 1945 | 22:57:38 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49668/tests/kraken/driver.html?testName=audio-fft) 22:57:38 INFO - PROCESS | 1945 | RSS: Main: 410308608 22:57:38 INFO - PROCESS | 1945 | 22:57:40 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49668/tests/kraken/driver.html?testName=audio-oscillator) 22:57:41 INFO - PROCESS | 1945 | RSS: Main: 308666368 22:57:41 INFO - PROCESS | 1945 | 22:57:43 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49668/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:57:44 INFO - PROCESS | 1945 | RSS: Main: 312332288 22:57:44 INFO - PROCESS | 1945 | 22:57:47 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49668/tests/kraken/driver.html?testName=imaging-darkroom) 22:57:47 INFO - PROCESS | 1945 | RSS: Main: 349966336 22:57:47 INFO - PROCESS | 1945 | 22:57:50 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49668/tests/kraken/driver.html?testName=imaging-desaturate) 22:57:51 INFO - PROCESS | 1945 | RSS: Main: 352743424 22:57:51 INFO - PROCESS | 1945 | 22:57:53 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49668/tests/kraken/driver.html?testName=json-parse-financial) 22:57:54 INFO - PROCESS | 1945 | RSS: Main: 347832320 22:57:54 INFO - PROCESS | 1945 | 22:57:56 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49668/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:57:56 INFO - PROCESS | 1945 | RSS: Main: 329191424 22:57:56 INFO - PROCESS | 1945 | 22:57:58 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49668/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:57:59 INFO - PROCESS | 1945 | RSS: Main: 335646720 22:57:59 INFO - PROCESS | 1945 | 22:58:01 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49668/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:58:02 INFO - PROCESS | 1945 | RSS: Main: 332992512 22:58:02 INFO - PROCESS | 1945 | 22:58:04 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49668/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:58:05 INFO - PROCESS | 1945 | RSS: Main: 342036480 22:58:05 INFO - PROCESS | 1945 | 22:58:08 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49668/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:58:09 INFO - PROCESS | 1945 | RSS: Main: 343506944 22:58:09 INFO - PROCESS | 1945 | 22:58:10 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49668/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49668/tests/kraken/driver.html?testName=ai-astar) 22:58:11 INFO - PROCESS | 1945 | RSS: Main: 386854912 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | __start_tp_report 22:58:11 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 22:58:11 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 22:58:11 INFO - PROCESS | 1945 | |i|pagename|runs| 22:58:11 INFO - PROCESS | 1945 | |0;ai-astar;101;136;97;151;168;158;113;112;113;113 22:58:11 INFO - PROCESS | 1945 | |1;audio-beat-detection;141;109;106;153;107;106;128;89;159;137 22:58:11 INFO - PROCESS | 1945 | |2;audio-dft;185;184;182;184;185;183;180;184;186;183 22:58:11 INFO - PROCESS | 1945 | |3;audio-fft;79;75;58;108;60;57;60;61;127;61 22:58:11 INFO - PROCESS | 1945 | |4;audio-oscillator;82;92;95;96;101;98;95;69;68;122 22:58:11 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;87;85;88;85;87;84;88;84;87;83 22:58:11 INFO - PROCESS | 1945 | |6;imaging-darkroom;93;92;93;93;101;121;92;93;93;92 22:58:11 INFO - PROCESS | 1945 | |7;imaging-desaturate;84;85;84;84;86;86;86;85;86;86 22:58:11 INFO - PROCESS | 1945 | |8;json-parse-financial;52;55;54;54;54;54;54;55;53;52 22:58:11 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;68;73;79;76;71;71;69;70;71;72 22:58:11 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;83;85;83;82;85;80;84;81;82;85 22:58:11 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;135;127;134;136;132;133;134;135;178;136 22:58:11 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;165;163;161;162;160;163;222;203;162;158 22:58:11 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;66;66;64;67;65;67;65;66;64;65 22:58:11 INFO - PROCESS | 1945 | __end_tp_report 22:58:11 INFO - PROCESS | 1945 | __start_cc_report 22:58:11 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,2104 22:58:11 INFO - PROCESS | 1945 | __end_cc_report 22:58:11 INFO - PROCESS | 1945 | __startTimestamp1467784691005__endTimestamp 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | ------- Summary: start ------- 22:58:11 INFO - PROCESS | 1945 | Number of tests: 14 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:126.20 Median:143.50 stddev:25.13 (17.5%) stddev-sans-first:24.94 22:58:11 INFO - PROCESS | 1945 | Values: 101.0 136.0 97.0 151.0 168.0 158.0 113.0 112.0 113.0 113.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:139.00 stddev:23.39 (16.8%) stddev-sans-first:23.94 22:58:11 INFO - PROCESS | 1945 | Values: 141.0 109.0 106.0 153.0 107.0 106.0 128.0 89.0 159.0 137.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.71 (0.9%) stddev-sans-first:1.74 22:58:11 INFO - PROCESS | 1945 | Values: 185.0 184.0 182.0 184.0 185.0 183.0 180.0 184.0 186.0 183.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:74.60 Median:60.50 stddev:24.18 (40.0%) stddev-sans-first:25.60 22:58:11 INFO - PROCESS | 1945 | Values: 79.0 75.0 58.0 108.0 60.0 57.0 60.0 61.0 127.0 61.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:93.50 stddev:15.83 (16.9%) stddev-sans-first:16.39 22:58:11 INFO - PROCESS | 1945 | Values: 82.0 92.0 95.0 96.0 101.0 98.0 95.0 69.0 68.0 122.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.81 (2.1%) stddev-sans-first:1.87 22:58:11 INFO - PROCESS | 1945 | Values: 87.0 85.0 88.0 85.0 87.0 84.0 88.0 84.0 87.0 83.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:96.30 Median:93.00 stddev:9.08 (9.8%) stddev-sans-first:9.55 22:58:11 INFO - PROCESS | 1945 | Values: 93.0 92.0 93.0 93.0 101.0 121.0 92.0 93.0 93.0 92.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:0.92 (1.1%) stddev-sans-first:0.87 22:58:11 INFO - PROCESS | 1945 | Values: 84.0 85.0 84.0 84.0 86.0 86.0 86.0 85.0 86.0 86.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.06 (2.0%) stddev-sans-first:0.93 22:58:11 INFO - PROCESS | 1945 | Values: 52.0 55.0 54.0 54.0 54.0 54.0 54.0 55.0 53.0 52.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:71.50 stddev:3.30 (4.6%) stddev-sans-first:3.17 22:58:11 INFO - PROCESS | 1945 | Values: 68.0 73.0 79.0 76.0 71.0 71.0 69.0 70.0 71.0 72.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:1.76 (2.1%) stddev-sans-first:1.87 22:58:11 INFO - PROCESS | 1945 | Values: 83.0 85.0 83.0 82.0 85.0 80.0 84.0 81.0 82.0 85.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:135.00 stddev:14.30 (10.6%) stddev-sans-first:15.12 22:58:11 INFO - PROCESS | 1945 | Values: 135.0 127.0 134.0 136.0 132.0 133.0 134.0 135.0 178.0 136.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:163.00 stddev:21.94 (13.5%) stddev-sans-first:23.13 22:58:11 INFO - PROCESS | 1945 | Values: 165.0 163.0 161.0 162.0 160.0 163.0 222.0 203.0 162.0 158.0 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.08 (1.6%) stddev-sans-first:1.13 22:58:11 INFO - PROCESS | 1945 | Values: 66.0 66.0 64.0 67.0 65.0 67.0 65.0 66.0 64.0 65.0 22:58:11 INFO - PROCESS | 1945 | -------- Summary: end -------- 22:58:11 INFO - PROCESS | 1945 | 22:58:11 INFO - TEST-INFO | 1945: exit 0 22:58:11 INFO - TEST-OK | kraken | took 59026ms 22:58:11 INFO - SUITE-END | took 1176s 22:58:12 INFO - Completed test suite (00:19:36) 22:58:12 INFO - javascript benchmark 22:58:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2832.6317578714766, 3363.81499017554, 3370.6123795763497, 3404.6254911959663, 3429.5884493860726, 4672.275797251432, 5698.316202055754, 5765.1928729977835, 5798.159266906301, 5803.767849286029, 5719.513841323482, 5754.050581654022, 5809.244798176242, 5813.128030795383, 5849.327327357355, 5197.922031169535, 5230.973845130788, 5266.973665131688, 5283.656562323436, 5326.360836699601, 5363.758630861621, 5404.756785944623, 5409.513143817056, 5413.431589683086, 5471.124620060791, 4388.407564978751, 5177.37871455424, 5261.86845328866, 5267.446918073604, 5309.0, 5294.0, 5309.495597918191, 5316.069687804657, 5316.494932981361, 5323.787048518055, 4381.408509851193, 5228.241904923802, 5252.684838909678, 5296.523312901819, 5309.256704061396, 5708.743106560215, 5717.885642287131, 5934.881302373971, 5943.0, 6002.54980876436, 6150.200473938404, 6208.317085120633, 6208.441240288395, 6231.813045608639, 6232.563720539519, 6149.569530132892, 6162.784302549389, 6204.81385558434, 6213.0, 6238.625682459067, 5812.82561523155, 5849.444302791228, 5856.765729370863, 5873.882522349614, 5938.346781853993, 5353.2237825515085, 6332.018537126753, 6340.492760579143, 6343.27052388972, 6347.746090156435, 6110.938890611126, 6116.174316467309, 6200.038993955944, 6229.626222426669, 6231.3145553989025, 6129.63387754696, 6201.720922558496, 6202.906956395657, 6230.6884655767935, 6250.874982500324, 6194.039923811726, 6215.12988181655, 6234.563580549318, 6236.968815155986, 6238.407351301619, 6098.390160983955, 6185.84535386619, 6214.844628884223, 6219.782307619212, 6240.157578726905, 4954.727489988013, 4957.0, 5006.274090256965, 5012.899742005139, 5028.547430731179, 4404.713693609905, 4459.910801783946, 4460.174867649495, 4465.754383508938, 4496.392986946786, 5902.144189092557, 5910.290765108214, 5915.319738230072, 5932.495737862244, 5937.139114828412, 6096.116063170815, 6145.262568491809, 6190.721417536221, 6253.6560489172625, 6264.906026409608, 4806.663533552688, 5820.417958204231, 5827.0, 5840.561957853178, 5870.354261031283, 4744.95255047452, 5384.380796208407, 5473.343198816088, 5479.0, 5500.367457742331, 6221.47117495018, 6264.655443950626, 6284.962981108066, 6320.241571011508, 6406.903896441557, 6360.77737279193, 6363.236411630634, 6367.586106903036, 6385.233771947396, 6385.808425747235, 6361.936380636134, 6396.808095757135, 6405.935940640534, 6406.23125224976, 6423.807285781434, 6128.0, 6139.5228034422325, 6195.070739389128, 6219.067139929047, 6253.0, 6307.085472606354, 6325.146105275913, 6333.6516491591565, 6336.683165841781, 6357.23713154406, 6149.815505534841, 6166.136740856374, 6177.104319873503, 6204.689765511796, 6219.004959206505, 5069.797208111634, 5523.2819733436, 5660.29246344207, 5930.347661757117, 6167.5374346925055, 5952.970235148797, 5958.821235362946, 5961.701914904324, 5973.701314934323, 6024.518038556993, 5953.91069133972, 6192.318844926965, 6204.13142160089, 6206.503479721701, 6231.40801623845, 5844.298684157929, 5890.558208134321, 5924.200232968494, 5941.049432090845, 5966.254218222723], "value": 5709.205026295767, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.23083451061046, 531.7474199755114, 541.072061414674, 543.5298466826194, 555.872149405637, 30294.09117726466, 41794.95512612182, 43413.56586434135, 43592.564074359245, 43744.12511749771, 44136.0, 44421.0, 44560.33159502602, 44576.777116114456, 44630.776846115805, 8844.469331840079, 9652.613895444192, 9826.50867456628, 9838.754031149207, 9845.36005159666, 8850.5574721264, 11993.280403175837, 12237.0, 12259.632211033639, 12445.875541244563, 12101.576444824434, 12138.332391718452, 12177.086718496104, 12198.878011219907, 12209.938950305235, 8892.555372231363, 11906.34515101669, 12054.276743395381, 12120.636380908543, 12153.69615759613, 11951.521939122425, 12044.81932771009, 12060.336681482602, 12063.034957203403, 12079.879201207963, 42134.78932605348, 42608.360874586906, 42659.933501662395, 42683.786581066895, 42688.0, 3484.5992710838323, 3527.2416430467315, 3666.578343490506, 3677.485152078711, 3792.5448946126367, 26410.867945660342, 32131.678683213104, 33087.3382532348, 33104.0, 33218.83390583032, 3269.0356344878082, 3332.9333413331597, 3337.8831740889195, 3339.081752518053, 3354.010566882751, 383104.0, 383138.0, 383905.0, 385707.0, 386417.0679146614, 63125.0, 63544.0, 63576.0, 63579.36420635734, 63607.0, 66566.66716666483, 66635.0004749929, 66710.66644666843, 66734.0, 66769.33230667631, 15164.165970871709, 16806.32774689023, 17074.829251707324, 17079.0, 17087.31650733957, 11748.353840538852, 12125.696857578454, 12875.163114397534, 13136.0, 13151.736965260641, 16207.594810129842, 16964.0, 17272.740908886375, 17291.827081729272, 17326.913365433345, 6417.582857114273, 6458.257300410513, 6509.544331896722, 6512.316206798313, 6554.0, 891.6968230801428, 997.9351342162736, 1004.5278719002057, 1023.5956797065118, 1088.4068182840372, 26058.34854128624, 30845.0, 31184.688153118634, 31280.843595781877, 31788.046358609277, 1096.5908807182732, 1100.1187968573668, 1152.9654110376703, 1166.2361153444508, 1210.189173253922, 15213.847861521464, 15935.203239838194, 16384.918075409547, 16660.666786664442, 16776.58058548546, 595478.0, 693042.0, 698928.0, 701325.0, 701553.0, 61909.69045154745, 62074.379256207765, 62168.37831621626, 62180.0, 62233.066504002476, 1257.4090177616506, 1380.5375199308344, 1389.4303335632342, 1417.6030711400824, 1420.9573712788633, 31650.683493165696, 32010.0, 32125.03624891257, 32896.0, 32960.01119966405, 51489.22766158436, 58807.411925881905, 60211.78517503733, 60598.69700651469, 64179.0, 603440.0, 610629.0, 614774.0, 614848.0, 616063.0, 24417.755822441548, 27019.59470607903, 27157.86421067882, 27529.587056193774, 27630.44739105247, 48398.0, 48492.0, 49033.01933961372, 50021.249681254085, 50631.240531392796, 39065.609343906195, 40130.59869401269, 41329.1734165321, 41790.10463273526, 42950.92622684458, 41404.0, 41506.0, 41972.58027419809, 42071.57928420677, 42347.7882610585, 40289.395659065725, 40844.79577602093, 40874.79562602168, 40949.59050409458, 41117.383239250834, 33078.0, 35427.64572354243, 35465.33397904162, 35923.0, 35965.64034359623, 26171.73828261693, 26280.73719262783, 26569.468610627293, 26752.33119172036, 27147.86426067857], "value": 20939.047448210396, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.8011833550715, 380.82101412336203, 393.349007392765, 393.5729733239437, 419.05294035479807, 15570.299336529855, 16462.83537164628, 21631.567368652657, 21681.4579635509, 22309.44226394341, 21475.926203689833, 21787.67318490224, 22147.667784983238, 22893.0, 22894.313170604866, 5954.345022047572, 5957.52339812815, 6137.20216371871, 6155.0, 6174.722137503801, 4244.978775106136, 4463.553644635543, 6085.330613632497, 6195.845103872417, 6516.413522782948, 6792.932070679304, 6869.420033392323, 6881.724731010753, 6883.414909732679, 6904.516683832134, 6595.604263744167, 6906.723731050777, 6914.308569143096, 6915.481338899553, 6921.723131074751, 3794.5636251831115, 4045.6965727570255, 5860.560457965669, 5969.134475501071, 5980.581359304851, 24092.87953560221, 24204.515909681708, 27123.45753084927, 27676.0, 28575.85712071447, 2052.79472052795, 2295.7015587973456, 2497.4131079196372, 2644.502834464296, 3056.51095824667, 7785.260400261966, 10215.029572190704, 10266.69199923996, 10300.330478518872, 10501.63244286454, 1837.6875931091747, 1906.1993962535769, 1908.8759230650103, 1917.1660327757331, 1956.0806420982115, 130812.0, 131871.34064329616, 131909.0, 133431.0, 134429.0, 11706.590269340531, 11776.411179440993, 11801.645950621494, 11811.88188118125, 11922.10584206188, 14734.263286835616, 14824.555263342118, 14855.702885942437, 14868.851311486964, 14908.70182596342, 8722.34582406322, 8754.69358572447, 8754.737357879147, 8780.824383512421, 8838.29293656506, 8737.30101591871, 8740.956295218482, 8769.693060742971, 8852.645894164161, 8859.778505537413, 4578.885527861764, 4586.633069354443, 4590.517995610479, 4614.0, 4693.48371679115, 1682.0328311221097, 1898.506388339042, 1925.594316149219, 1949.0, 1986.0536674059215, 716.39822549058, 810.2464707821782, 826.972643227474, 876.8553188723916, 929.4284015330524, 19031.333903313316, 21736.891315543322, 23019.769802302097, 23059.73171475585, 23105.3068407948, 936.8782058332373, 962.5668449197889, 976.097110173087, 991.7520619845038, 1038.9636362727267, 11393.373364464867, 11898.405079745979, 11929.70175745596, 11944.104192185621, 12242.0, 485017.0, 599330.0, 606968.0, 608102.0, 630393.0, 101034.98965010257, 121506.0, 138063.92904106446, 139098.0, 141207.29396352952, 941.9811603767886, 967.511406739592, 977.8435450327913, 978.3836183204536, 1010.9747256318651, 19519.0, 20169.39491815248, 20179.79820201779, 20226.797732022493, 20296.79703202949, 48967.0, 51677.48322516727, 52069.47930520646, 52541.7372913133, 54724.0, 77501.2249877494, 78772.4245515075, 78844.60577697075, 79064.60467697625, 79698.0, 7616.162222155669, 7828.725994590162, 8319.126491718282, 8394.622241999003, 8498.617562209593, 11084.556617735523, 13300.068995170632, 13354.732905342033, 13632.250226237653, 13729.176249425067, 11654.30074195551, 11687.298762074302, 11690.0, 11794.997425218953, 11987.820182697442, 11497.137714671266, 11507.654770356903, 11613.76772464563, 11758.294502329887, 11785.292882427082, 11091.445427728742, 11682.299062056303, 11882.465289062186, 11955.581554645545, 12072.637820865388, 8190.140035296208, 8212.507249565046, 8244.793880153044, 8486.321094312316, 8563.486190828571, 11122.721931951766, 11499.54001839917, 11618.244814087395, 11700.0, 11818.46816893259], "value": 9839.12501753049, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1502.6393665520272, 1564.623131644153, 1764.3560100563298, 1929.1608150454563, 1935.7386752788377, 8689.174528419799, 8981.0, 9188.908110918888, 9190.632374705012, 9211.907880921197, 7904.209579042093, 8181.877271840927, 8182.468139570924, 8287.502749835, 8956.10438956104, 2137.9786202137934, 2177.7060096886953, 2314.2247347138727, 2390.95218095638, 2398.8920498577518, 2374.394529395002, 2399.1722855614785, 2400.6519054737055, 2404.5311164322966, 2405.326508577601, 1995.5609765851489, 2345.2026311054237, 2395.1138078910826, 2398.1126983016306, 2400.2799160251857, 2064.0711679744027, 2096.6854971754283, 2284.543091381722, 2357.422431504275, 2359.4927090675587, 2320.582295186866, 2384.71383433989, 2418.6734790803193, 2421.055788242587, 2427.6965379327585, 9024.63901443948, 9080.545972701404, 9092.499912504809, 9102.226310763594, 9120.315976301805, 5291.656042357235, 5417.6478528895495, 5470.56235501159, 5476.726163691799, 5486.753096110645, 3512.3150985557827, 3601.981990090059, 3673.944890826613, 3678.282734866702, 3686.7419280650365, 1042.7705904700954, 1063.7712891728238, 1070.4968664727567, 1070.6252811515985, 1074.4788777442934, 12091.697707557294, 12132.636020919386, 12160.087993400442, 12171.14801963863, 12176.756464870654, 4749.168895443284, 4767.523247675274, 4780.888273469599, 4878.414590249122, 4880.341153944243, 10201.234907381977, 10248.64129755455, 10256.948715256376, 10262.948685256675, 10266.589336426608, 5408.702521361284, 5422.295101636762, 5425.050616142238, 5426.593005524602, 5427.972860135753, 4913.63147763912, 4935.284383764334, 4955.380577427822, 4961.70229786214, 5020.046191223656, 3701.204241088179, 3873.5158105236846, 3958.960410395917, 4068.8372465101065, 4108.98659656772, 1271.1864406779669, 1301.3883474766844, 1305.6213698027466, 1316.789313709821, 1317.3742472325569, 866.8309679612415, 919.8482250428738, 943.5706753427174, 950.8257302231739, 999.3404353126901, 5547.750351234205, 5985.221921150222, 6074.240719910012, 6121.938780612137, 6210.378962103844, 897.5467388968659, 915.5879854065697, 924.9445033298024, 981.9950900245595, 1024.4877561219391, 4009.8396064157105, 4104.0970986382945, 4141.544430112685, 4149.722461337536, 4188.958110418917, 10491.422971736627, 10880.891191087987, 11013.669589912315, 11068.557257709763, 11081.944590276997, 10983.61557345505, 10996.725081872857, 11000.559977600966, 11012.284201526874, 11017.0, 7077.5399599025895, 8317.708880189286, 8333.499990000499, 8407.117252688502, 8418.452800567944, 5575.0, 5635.098384258499, 5691.430856914441, 5697.8860422790485, 5874.0, 10071.34536255141, 10119.704474938279, 10284.920083391435, 10308.484575771032, 10314.43270620093, 10179.338343007681, 10503.47482625881, 10514.947425262824, 10516.369017858953, 10535.262531622713, 6609.966950165219, 7080.858382832417, 7677.846443071219, 7807.336376408069, 7815.0, 7994.0407151142235, 8412.242898139197, 8764.035956044703, 8767.868481972902, 8798.780030499289, 7788.260115289038, 7922.603869806602, 7982.8802567960365, 8124.918750812416, 8210.753677389688, 7721.806954826175, 7854.293113619801, 7859.17478664732, 7890.0, 7955.880661789962, 7050.118735158106, 7063.611501367269, 7135.072440582691, 7144.821379465555, 7152.785416437515, 5113.233015047624, 5310.707911064774, 5368.543673787772, 5400.05499037659, 5458.918116228339, 5315.176147697113, 5429.429909859566, 5500.1749737539685, 5630.352509461464, 5638.605297629127], "value": 4694.084705062261, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.2645833852907, 310.19349690803915, 310.35601127660107, 317.769617027655, 321.145752298885, 5377.085895397787, 5379.892402151954, 5385.895891342275, 5405.945940540593, 5411.756470958807, 5272.736363181844, 5355.6786592804365, 5363.570914326854, 5370.91943620846, 5379.950909572635, 3381.442062059757, 3831.3678243089857, 3887.494625698655, 4006.699497537682, 4108.52751933527, 4013.6588389986887, 4057.2899742545083, 4064.329385651364, 4120.175964807036, 4121.505419349683, 4073.6130067643526, 4073.8612228579937, 4095.1195492969005, 4116.723815617153, 4148.917021659565, 3180.254300673149, 3898.629630185142, 4095.7337773044655, 4104.199681062194, 4117.300058989979, 2909.6615556843876, 3244.837758112085, 3539.787612743218, 3555.3955827509385, 3592.7125829933543, 4896.926546101811, 5096.286519887181, 5111.769970351306, 5124.538791508745, 5174.689518628894, 4132.40080188371, 4519.502854685982, 4539.591436770706, 4630.467496237942, 4665.976670116627, 5057.002484018829, 5196.610254230948, 5524.944750552483, 5526.889462210774, 5532.944670553324, 3925.3326934421216, 3934.4688467056867, 3941.5861334559745, 3943.842246310144, 3947.881563553098, 4838.371011768483, 5258.894822103536, 5336.67979921206, 5496.0, 5540.722963851832, 3985.561588225293, 3991.3856424042297, 3999.4800675911947, 4031.576684448091, 4043.2722110020477, 3415.6432061877226, 3439.518467414564, 4079.632833044981, 4094.4267802507675, 4102.0310251079645, 4160.1471698301775, 4494.797734101973, 4673.766311684471, 4688.671792974459, 4700.788464519105, 5390.353157621111, 5557.471695283749, 5573.5819813514145, 5626.690532020778, 5639.295088113986, 1189.976200476003, 1378.1248906943963, 1388.3266615691382, 1390.1311680199874, 1391.9652008699863, 722.1839321566702, 754.8414832885046, 852.808118173406, 866.9639780462287, 867.9801233550679, 4894.608431325485, 5027.874303142451, 5052.646314758005, 5186.066508028515, 5211.749180196725, 5041.193409054534, 5257.421683614799, 5283.709395983181, 5700.743466544015, 5728.742206600713, 4404.480454243281, 4925.375355266887, 5145.871353216125, 5179.404368497596, 5318.149096144675, 2403.1015497756925, 2831.3346363604537, 2973.5985641938496, 2996.7003299670296, 3032.0752170587743, 5021.6735912165595, 5927.703614819328, 5927.733252003584, 5933.673647949404, 5933.821985340447, 5610.903556109042, 5695.430456954271, 5817.156512305776, 5841.2990441146485, 5870.0, 5873.236479257669, 5911.0, 5919.200907877467, 5934.715134673095, 5937.82326455353, 4749.976250118727, 5243.2472951700665, 5548.889022219452, 5673.8297851064535, 5710.714464276853, 5272.868178295574, 5354.223637572608, 5581.6930068846605, 5626.521745651666, 5657.415923541415, 4756.027482823236, 5843.503302219189, 5855.414458554111, 5910.970445147746, 5911.615744976562, 3407.16524451511, 3778.641029102341, 3911.1786524830104, 3924.4113382992778, 3963.544192417909, 4986.628677113823, 5639.295088113986, 5660.358496036268, 5718.885622287447, 5779.537636989115, 4440.533743956838, 4876.317315575751, 5317.893642127213, 5544.390117087037, 5573.498385145356, 4653.744044077608, 5184.020319375344, 5503.1847133757965, 5522.646951496909, 5594.664320140804, 4680.5085466025575, 4772.689775164603, 4778.593819525379, 5347.705876176692, 5366.463353664759, 5074.923876141787, 5306.9203961941375, 5448.754806033818, 5550.75021624036, 5583.022970979981, 5440.075187218262, 5449.3460784706085, 5450.345958485007, 5479.479449452296, 5520.5709487825225], "value": 4071.4177111873732, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.8793792993122, 773.6441237030965, 787.7715462515871, 828.5360198288956, 867.6963062927975, 17622.118894055293, 18219.36232231872, 18223.453296401098, 18271.99504027278, 18285.26858925643, 16976.15119244039, 17065.658686826257, 17153.91423042886, 17200.311987520483, 17302.48092557222, 738.2174894611729, 918.623364420586, 942.0908822985817, 946.1012038563358, 946.7491114854569, 758.3137260779004, 761.2958013837178, 764.5909438450431, 765.0322342237074, 765.0780809124991, 952.0431965874321, 974.6832279509188, 985.1281615770024, 988.4020167798486, 997.7256254530026, 966.7776411425376, 978.0, 978.8629591857145, 978.8923218445965, 980.9950950245275, 577.4629594477135, 719.061624579923, 730.6602429870082, 731.0678884422404, 732.4872589187537, 15683.0, 15785.0, 15798.526044218692, 15804.288807003712, 15815.209239538091, 14434.350454229585, 14866.108033518023, 14877.925610371987, 14928.62678433037, 15009.0, 12227.877721222763, 13004.479820807157, 13429.328533573282, 13594.592162235149, 13688.726225475535, 4682.204025315705, 4748.9525104748855, 4889.217725163992, 4933.975330123327, 4992.500749925015, 120039.79960200374, 121419.0, 122019.3899030508, 122092.0, 122419.0, 11508.827367589325, 11513.539458421737, 11649.301041937511, 12039.337836418907, 12149.453274602663, 22726.545469090524, 22935.770642293697, 23920.282391527933, 23990.0, 24053.879730601235, 8028.638711258008, 8060.516369017878, 8215.835683286301, 8231.382646301432, 8238.0, 4361.0841723237845, 4383.6054755071555, 4491.3487544306545, 4567.3377360282575, 4649.256119020941, 3580.588232353313, 3699.5560532735703, 3702.0, 3704.407294832814, 3707.277080969185, 662.7415308029872, 675.2943174382782, 682.0996284903979, 683.7641013850214, 683.9213490448662, 622.5587764325571, 625.8591816841265, 627.2222444169197, 651.8598501322239, 665.1785045468843, 7637.465377423527, 8429.915700842914, 9123.863142052875, 9150.679726209684, 9189.90810091904, 621.7606221604715, 622.5610944284268, 631.5515983651567, 638.4828289085856, 655.3872129558782, 3970.861019864291, 4008.6993475489453, 4157.6695457453325, 4464.1071785643, 4511.068918556214, 162036.0, 169711.0, 173130.0, 175699.0, 175806.12096939434, 147407.0, 149259.2537037308, 149567.25216373848, 155201.0, 155514.0, 124488.75511244952, 127506.0, 131332.0, 131685.0, 135189.0, 7663.233676632292, 7905.56519391439, 8640.524771137647, 8650.30797536183, 8666.956665216634, 66612.66774664576, 69017.0, 69140.0, 69454.0, 70241.64879175571, 85849.0, 90585.0, 104825.95174048161, 109178.0, 110953.44523277332, 11869.162585020333, 11916.821247681119, 11967.0, 12108.697282568006, 12169.81745273804, 12232.449539770554, 12264.693382665504, 12694.111412201426, 12805.423755930819, 12968.93515532454, 11544.01875840563, 11813.114016448973, 11828.940855295668, 12206.694832629255, 12221.256009675439, 9041.820134675965, 11810.704732381759, 13752.968527360688, 13874.861251387358, 14047.719045619235, 13283.203007819562, 13663.590092297247, 13711.245881476614, 14128.222947737973, 14240.430382784987, 10701.785964280827, 10839.13286937059, 11174.0, 11525.423728813357, 11689.649310520697, 11214.719632008939, 11424.714382140513, 11512.730804681989, 11564.363959982282, 11593.24643898144], "value": 8140.94247704309, "unit": "score"}], "name": "dromaeo_css", "value": 7535.024351032866, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 136.0, 97.0, 151.0, 168.0, 158.0, 113.0, 112.0, 113.0, 113.0], "value": 126.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 106.0, 153.0, 107.0, 106.0, 128.0, 89.0, 159.0, 137.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 182.0, 184.0, 185.0, 183.0, 180.0, 184.0, 186.0, 183.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 58.0, 108.0, 60.0, 57.0, 60.0, 61.0, 127.0, 61.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 92.0, 95.0, 96.0, 101.0, 98.0, 95.0, 69.0, 68.0, 122.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 87.0, 84.0, 88.0, 84.0, 87.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 101.0, 121.0, 92.0, 93.0, 93.0, 92.0], "value": 96.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 86.0, 86.0, 86.0, 85.0, 86.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0, 53.0, 52.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 79.0, 76.0, 71.0, 71.0, 69.0, 70.0, 71.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 83.0, 82.0, 85.0, 80.0, 84.0, 81.0, 82.0, 85.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 127.0, 134.0, 136.0, 132.0, 133.0, 134.0, 135.0, 178.0, 136.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 161.0, 162.0, 160.0, 163.0, 222.0, 203.0, 162.0, 158.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 64.0, 67.0, 65.0, 67.0, 65.0, 66.0, 64.0, 65.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1451.1, "alertThreshold": 2.0}]} 22:58:12 INFO - Return code: 0 22:58:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:58:12 ERROR - # TBPL SUCCESS # 22:58:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:58:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:58:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:58:12 INFO - 2016-07-05 22:58:12.336 screenresolution[1949:14021] starting screenresolution argv=screenresolution get 22:58:12 INFO - 2016-07-05 22:58:12.339 screenresolution[1949:14021] Display 0: 1600x1200x32@60 22:58:12 INFO - 2016-07-05 22:58:12.345 screenresolution[1950:14025] starting screenresolution argv=screenresolution list 22:58:12 INFO - Available Modes on Display 0 22:58:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:58:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:58:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:58:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:58:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:58:12 INFO - Intel Iris: 22:58:12 INFO - Chipset Model: Intel Iris 22:58:12 INFO - Type: GPU 22:58:12 INFO - Bus: Built-In 22:58:12 INFO - VRAM (Dynamic, Max): 1536 MB 22:58:12 INFO - Vendor: Intel (0x8086) 22:58:12 INFO - Device ID: 0x0a2e 22:58:12 INFO - Revision ID: 0x0009 22:58:12 INFO - Displays: 22:58:12 INFO - Display: 22:58:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:58:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:58:12 INFO - Display Serial Number: 1600x1200 60 22:58:12 INFO - Main Display: Yes 22:58:12 INFO - Mirror: Off 22:58:12 INFO - Online: Yes 22:58:12 INFO - Rotation: Supported 22:58:12 INFO - Return code: 0 22:58:12 INFO - Running post-action listener: _resource_record_post_action 22:58:12 INFO - ##### 22:58:12 INFO - ##### Finished run-tests step (success) 22:58:12 INFO - ##### 22:58:12 INFO - Running post-run listener: _resource_record_post_run 22:58:12 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 49152; Write bytes: 704812544; Read time: 1; Write time: 1916 22:58:12 INFO - TinderboxPrint: CPU usage
53.5% 22:58:12 INFO - TinderboxPrint: I/O read bytes / time
49,152 / 1 22:58:12 INFO - TinderboxPrint: I/O write bytes / time
704,812,544 / 1,916 22:58:12 INFO - TinderboxPrint: CPU idle
2,252.5 (46.5%) 22:58:12 INFO - TinderboxPrint: CPU system
966.3 (19.9%) 22:58:12 INFO - TinderboxPrint: CPU user
1,626.9 (33.6%) 22:58:12 INFO - TinderboxPrint: Swap in / out
406,691,840 / 0 22:58:12 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 383736320; Write bytes: 379897856; Read time: 28564; Write time: 746 22:58:12 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 45056; Write bytes: 294350336; Read time: 1; Write time: 1098 22:58:13 INFO - Running post-run listener: _upload_blobber_files 22:58:13 INFO - Blob upload gear active. 22:58:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:58: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 22:58: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'] 22:58: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 22:58:13 INFO - (blobuploader) - INFO - Open directory for files ... 22:58:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:58:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:58:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:58:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:58:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:58:14 INFO - (blobuploader) - INFO - Done attempting. 22:58:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:58:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:58:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:58:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:58:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:58:15 INFO - (blobuploader) - INFO - Done attempting. 22:58:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:58:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:58:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:58:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:58:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:58:17 INFO - (blobuploader) - INFO - Done attempting. 22:58:17 INFO - (blobuploader) - INFO - Iteration through files over. 22:58:17 INFO - Return code: 0 22:58:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:58:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:58:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/73de5245fd3a2584cee2534884833a8902d628cc4e76c74fa08ae8f33177353801c6f940cc9e27103eb0b82ebadb2958ac64dbdb0ec76a9e28cb0a2f7baea4d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46afea3d72a964a45f657f8d6076c972af415fe2d7579dfd870704bca3cb3fd049c2390af7a1a7ce3a835a2b468bf53bf139ef22213b93a5f36de07461bdedbe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9bc7d32c1dd0b2408cf319d82bb7750b4f2b898ffaa6d723ea97cd3b5bf33aca402bb267fe30792cafc3c907661b081634c76ed1ddc225fd0261676237f609c4"} 22:58:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:58:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:58:17 INFO - Contents: 22:58:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/73de5245fd3a2584cee2534884833a8902d628cc4e76c74fa08ae8f33177353801c6f940cc9e27103eb0b82ebadb2958ac64dbdb0ec76a9e28cb0a2f7baea4d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46afea3d72a964a45f657f8d6076c972af415fe2d7579dfd870704bca3cb3fd049c2390af7a1a7ce3a835a2b468bf53bf139ef22213b93a5f36de07461bdedbe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9bc7d32c1dd0b2408cf319d82bb7750b4f2b898ffaa6d723ea97cd3b5bf33aca402bb267fe30792cafc3c907661b081634c76ed1ddc225fd0261676237f609c4"} 22:58:17 INFO - Running post-run listener: copy_logs_to_upload_dir 22:58:17 INFO - Copying logs to upload dir... 22:58:17 INFO - mkdir: /builds/slave/test/build/upload/logs 22:58:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.549959 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-07-05 22:58:17.534309) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 22:58:17.537385) ========= 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.eIXdam6JlK/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.FWb7YWC0t4/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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/73de5245fd3a2584cee2534884833a8902d628cc4e76c74fa08ae8f33177353801c6f940cc9e27103eb0b82ebadb2958ac64dbdb0ec76a9e28cb0a2f7baea4d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46afea3d72a964a45f657f8d6076c972af415fe2d7579dfd870704bca3cb3fd049c2390af7a1a7ce3a835a2b468bf53bf139ef22213b93a5f36de07461bdedbe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9bc7d32c1dd0b2408cf319d82bb7750b4f2b898ffaa6d723ea97cd3b5bf33aca402bb267fe30792cafc3c907661b081634c76ed1ddc225fd0261676237f609c4"} build_url:https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011287 build_url: 'https://queue.taskcluster.net/v1/task/bYxOBAJjTYOkIaKCMoeHZQ/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/73de5245fd3a2584cee2534884833a8902d628cc4e76c74fa08ae8f33177353801c6f940cc9e27103eb0b82ebadb2958ac64dbdb0ec76a9e28cb0a2f7baea4d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46afea3d72a964a45f657f8d6076c972af415fe2d7579dfd870704bca3cb3fd049c2390af7a1a7ce3a835a2b468bf53bf139ef22213b93a5f36de07461bdedbe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9bc7d32c1dd0b2408cf319d82bb7750b4f2b898ffaa6d723ea97cd3b5bf33aca402bb267fe30792cafc3c907661b081634c76ed1ddc225fd0261676237f609c4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 22:58:17.569819) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 22:58:17.570142) ========= 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.eIXdam6JlK/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.FWb7YWC0t4/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.005227 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 22:58:17.618815) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 22:58:17.619135) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 22:58:17.619458) ========= ========= Total master_lag: 0.17 =========