builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0154 starttime: 1468413458.44 results: success (0) buildid: 20160713030216 builduid: e4809dcd9077422d806aa716399f9e65 revision: 04821a70c739a00d12e12df651c0989441e22728 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:38.444214) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:38.444659) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:38.444947) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:38.469457) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:38.469829) ========= 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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-13 05:37:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.1M=0.001s 2016-07-13 05:37:38 (12.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123563 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:38.615951) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:38.616278) ========= 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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.027981 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:38.664512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-07-13 05:37:38.664912) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 04821a70c739a00d12e12df651c0989441e22728 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 04821a70c739a00d12e12df651c0989441e22728 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-13 05:37:38,739 truncating revision to first 12 chars 2016-07-13 05:37:38,739 Setting DEBUG logging. 2016-07-13 05:37:38,739 attempt 1/10 2016-07-13 05:37:38,739 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/04821a70c739?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 05:37:40,075 unpacking tar archive at: mozilla-central-04821a70c739/testing/mozharness/ program finished with exit code 0 elapsedTime=1.618731 ========= master_lag: 3.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-07-13 05:37:43.575543) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:43.575906) ========= script_repo_revision: 04821a70c739a00d12e12df651c0989441e22728 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:43.576322) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:43.576627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 05:37:43.674646) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-13 05:37:43.675057) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:37:43 INFO - MultiFileLogger online at 20160713 05:37:43 in /builds/slave/test 05:37:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:37:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:37:43 INFO - {'append_to_log': False, 05:37:43 INFO - 'base_work_dir': '/builds/slave/test', 05:37:43 INFO - 'blob_upload_branch': 'Firefox', 05:37:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:37:43 INFO - 'branch': 'Firefox', 05:37:43 INFO - 'buildbot_json_path': 'buildprops.json', 05:37:43 INFO - 'config_files': ('talos/mac_config.py',), 05:37:43 INFO - 'default_actions': ('clobber', 05:37:43 INFO - 'read-buildbot-config', 05:37:43 INFO - 'download-and-extract', 05:37:43 INFO - 'populate-webroot', 05:37:43 INFO - 'create-virtualenv', 05:37:43 INFO - 'install', 05:37:43 INFO - 'run-tests'), 05:37:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:37:43 INFO - 'download_minidump_stackwalk': True, 05:37:43 INFO - 'download_symbols': 'ondemand', 05:37:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:37:43 INFO - 'tooltool.py': '/tools/tooltool.py', 05:37:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:37:43 INFO - '/tools/misc-python/virtualenv.py')}, 05:37:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:37:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:37:43 INFO - 'installer_path': 'installer.exe', 05:37:43 INFO - 'log_level': 'info', 05:37:43 INFO - 'log_name': 'talos', 05:37:43 INFO - 'log_to_console': True, 05:37:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:37:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:37:43 INFO - 'opt_config_files': (), 05:37:43 INFO - 'pip_index': False, 05:37:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:37:43 INFO - 'cmd': ('bash', 05:37:43 INFO - '-c', 05:37:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:37:43 INFO - 'enabled': True, 05:37:43 INFO - 'halt_on_failure': False, 05:37:43 INFO - 'name': 'check_screen_resolution'},), 05:37:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:37:43 INFO - 'cmd': ('bash', 05:37:43 INFO - '-c', 05:37:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:37:43 INFO - 'enabled': True, 05:37:43 INFO - 'halt_on_failure': False, 05:37:43 INFO - 'name': 'check_screen_resolution'},), 05:37:43 INFO - 'run_cmd_checks_enabled': True, 05:37:43 INFO - 'sps_profile': False, 05:37:43 INFO - 'sps_profile_interval': 0, 05:37:43 INFO - 'suite': 'dromaeojs', 05:37:43 INFO - 'system_bits': '32', 05:37:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:37:43 INFO - 'title': 't-yosemite-r7-0154', 05:37:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:37:43 INFO - 'use_talos_json': True, 05:37:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:37:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:37:43 INFO - 'work_dir': 'build'} 05:37:43 INFO - ##### 05:37:43 INFO - ##### Running clobber step. 05:37:43 INFO - ##### 05:37:43 INFO - Running pre-action listener: _resource_record_pre_action 05:37:43 INFO - Running main action method: clobber 05:37:43 INFO - rmtree: /builds/slave/test/build 05:37:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:37:45 INFO - Running post-action listener: _resource_record_post_action 05:37:45 INFO - ##### 05:37:45 INFO - ##### Finished clobber step (success) 05:37:45 INFO - ##### 05:37:45 INFO - ##### 05:37:45 INFO - ##### Running read-buildbot-config step. 05:37:45 INFO - ##### 05:37:45 INFO - Running pre-action listener: _resource_record_pre_action 05:37:45 INFO - Running main action method: read_buildbot_config 05:37:45 INFO - Using buildbot properties: 05:37:45 INFO - { 05:37:45 INFO - "project": "", 05:37:45 INFO - "product": "firefox", 05:37:45 INFO - "script_repo_revision": "production", 05:37:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:37:45 INFO - "repository": "", 05:37:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:37:45 INFO - "buildid": "20160713030216", 05:37:45 INFO - "stage_platform": "macosx64", 05:37:45 INFO - "basedir": "/builds/slave/test", 05:37:45 INFO - "nightly_build": "True", 05:37:45 INFO - "buildnumber": 10, 05:37:45 INFO - "slavename": "t-yosemite-r7-0154", 05:37:45 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:37:45 INFO - "platform": "yosemite_r7", 05:37:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:37:45 INFO - "branch": "mozilla-central", 05:37:45 INFO - "repo_path": "mozilla-central", 05:37:45 INFO - "revision": "04821a70c739a00d12e12df651c0989441e22728", 05:37:45 INFO - "pgo_build": "False", 05:37:45 INFO - "builduid": "e4809dcd9077422d806aa716399f9e65", 05:37:45 INFO - "slavebuilddir": "test" 05:37:45 INFO - } 05:37:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:37:45 INFO - Running post-action listener: _resource_record_post_action 05:37:45 INFO - ##### 05:37:45 INFO - ##### Finished read-buildbot-config step (success) 05:37:45 INFO - ##### 05:37:45 INFO - ##### 05:37:45 INFO - ##### Running download-and-extract step. 05:37:45 INFO - ##### 05:37:45 INFO - Running pre-action listener: _resource_record_pre_action 05:37:45 INFO - Running main action method: download_and_extract 05:37:45 INFO - mkdir: /builds/slave/test/build/tests 05: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')]} 05:37:45 INFO - https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:37:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:37:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:37:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/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 05:37:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/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 05:37:47 INFO - Downloaded 1199 bytes. 05:37:47 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:37:47 INFO - Using the following test package requirements: 05:37:47 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:37:47 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:37:47 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:37:47 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:37:47 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:37:47 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:37:47 INFO - u'jsshell-mac.zip'], 05:37:47 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:37:47 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:37:47 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:37:47 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:37:47 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:37:47 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:37:47 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:37:47 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:37:47 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:37:47 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:37:47 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:37:47 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:37:47 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')]} 05:37:47 INFO - https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:37:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:37:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:37:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/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 05:37:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/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 05:37:48 INFO - Downloaded 18822953 bytes. 05:37:48 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'] 05:37:48 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 05:37:51 INFO - Return code: 0 05:37:51 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 05:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:37:51 INFO - https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:37:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:37:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:37:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/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 05:37:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/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 05:37:52 INFO - Downloaded 18822953 bytes. 05:37:52 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'] 05:37:52 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 05:37:56 INFO - Return code: 0 05:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:37:56 INFO - https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:37:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:37:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:37:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/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 05:37:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/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 05:37:57 INFO - Downloaded 14403317 bytes. 05:37:57 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'] 05:37:57 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 05:37:57 INFO - Return code: 0 05:37:57 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')]} 05:37:57 INFO - https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:37:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:37:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:37:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:37:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SKUnjT-jSdOK7119QKEdcw/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 05:38:00 INFO - Downloaded 112922130 bytes. 05:38:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:38:00 INFO - mkdir: /builds/slave/test/properties 05:38:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:38:00 INFO - Writing to file /builds/slave/test/properties/build_url 05:38:00 INFO - Contents: 05:38:00 INFO - build_url:https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:38:01 INFO - Running post-action listener: _resource_record_post_action 05:38:01 INFO - Running post-action listener: set_extra_try_arguments 05:38:01 INFO - ##### 05:38:01 INFO - ##### Finished download-and-extract step (success) 05:38:01 INFO - ##### 05:38:01 INFO - ##### 05:38:01 INFO - ##### Running populate-webroot step. 05:38:01 INFO - ##### 05:38:01 INFO - Running pre-action listener: _resource_record_pre_action 05:38:01 INFO - Running main action method: populate_webroot 05:38:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:38:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:38:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:38:01 INFO - '--tppagecycles', 05:38:01 INFO - '7'], 05:38:01 INFO - 'tests': ['tsvgm']}}, 05:38:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:38:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:38:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:38:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:38:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:01 INFO - 'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:38:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:38:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:01 INFO - 'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['damp', 'tps']}, 05:38:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:01 INFO - 'tests': ['damp', 'tps']}, 05:38:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['dromaeo_dom']}, 05:38:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:38:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['basic_compositor_video']}, 05:38:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:38:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['a11yr', 05:38:01 INFO - 'ts_paint', 05:38:01 INFO - 'tpaint', 05:38:01 INFO - 'sessionrestore', 05:38:01 INFO - 'sessionrestore_no_auto_restore', 05:38:01 INFO - 'tabpaint']}, 05:38:01 INFO - 'other-e10s': {'tests': ['a11yr', 05:38:01 INFO - 'ts_paint', 05:38:01 INFO - 'tpaint', 05:38:01 INFO - 'sessionrestore', 05:38:01 INFO - 'sessionrestore_no_auto_restore', 05:38:01 INFO - 'tabpaint']}, 05:38:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:38:01 INFO - 'ts_paint', 05:38:01 INFO - 'tpaint', 05:38:01 INFO - 'sessionrestore', 05:38:01 INFO - 'sessionrestore_no_auto_restore', 05:38:01 INFO - 'tabpaint']}, 05:38:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:38:01 INFO - 'ts_paint', 05:38:01 INFO - 'tpaint', 05:38:01 INFO - 'sessionrestore', 05:38:01 INFO - 'sessionrestore_no_auto_restore', 05:38:01 INFO - 'tabpaint']}, 05:38:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['a11yr', 05:38:01 INFO - 'ts_paint', 05:38:01 INFO - 'tpaint', 05:38:01 INFO - 'sessionrestore', 05:38:01 INFO - 'sessionrestore_no_auto_restore', 05:38:01 INFO - 'tabpaint']}, 05:38:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['a11yr', 05:38:01 INFO - 'ts_paint', 05:38:01 INFO - 'tpaint', 05:38:01 INFO - 'sessionrestore', 05:38:01 INFO - 'sessionrestore_no_auto_restore', 05:38:01 INFO - 'tabpaint']}, 05:38:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['tsvgx', 05:38:01 INFO - 'tsvgr_opacity', 05:38:01 INFO - 'tart', 05:38:01 INFO - 'tscrollx', 05:38:01 INFO - 'cart']}, 05:38:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:38:01 INFO - 'tsvgr_opacity', 05:38:01 INFO - 'tart', 05:38:01 INFO - 'tscrollx', 05:38:01 INFO - 'cart']}, 05:38:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:01 INFO - 'talos_options': ['--disable-e10s'], 05:38:01 INFO - 'tests': ['tp5o']}, 05:38:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:01 INFO - 'tests': ['tp5o']}, 05:38:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:01 INFO - 'talos_options': ['--disable-e10s', 05:38:01 INFO - '--xperf_path', 05:38:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:38:01 INFO - 'tests': ['tp5n']}, 05:38:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:38:01 INFO - 'talos_options': ['--xperf_path', 05:38:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:38:01 INFO - 'tests': ['tp5n']}}, 05:38:01 INFO - 'talos.zip': {'path': '', 05:38:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:38:01 INFO - Running post-action listener: _resource_record_post_action 05:38:01 INFO - ##### 05:38:01 INFO - ##### Finished populate-webroot step (success) 05:38:01 INFO - ##### 05:38:01 INFO - ##### 05:38:01 INFO - ##### Running create-virtualenv step. 05:38:01 INFO - ##### 05:38:01 INFO - Running pre-action listener: _resource_record_pre_action 05:38:01 INFO - Running main action method: create_virtualenv 05:38:01 INFO - Creating virtualenv /builds/slave/test/build/venv 05:38:01 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 05:38:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:38:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:38:01 INFO - Using real prefix '/tools/python27' 05:38:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:38:02 INFO - Installing distribute.............................................................................................................................................................................................done. 05:38:05 INFO - Installing pip.................done. 05:38:05 INFO - Return code: 0 05:38:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:05 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 0x104643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bc45c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, '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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:38:05 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 05:38:05 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 05:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:05 INFO - 'HOME': '/Users/cltbld', 05:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:05 INFO - 'LOGNAME': 'cltbld', 05:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:05 INFO - 'MOZ_NO_REMOTE': '1', 05:38:05 INFO - 'NO_EM_RESTART': '1', 05:38:05 INFO - 'PAGER': '/bin/cat', 05:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:05 INFO - 'PWD': '/builds/slave/test', 05:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:05 INFO - 'SHELL': '/bin/bash', 05:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:38:05 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:05 INFO - 'USER': 'cltbld', 05:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:05 INFO - 'XPC_FLAGS': '0x0', 05:38:05 INFO - 'XPC_SERVICE_NAME': '0', 05:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:05 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 05:38:05 INFO - Cleaning up... 05:38:05 INFO - Return code: 0 05:38:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:05 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 0x104643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bc45c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, '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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:38:05 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 05:38:05 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 05:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:05 INFO - 'HOME': '/Users/cltbld', 05:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:05 INFO - 'LOGNAME': 'cltbld', 05:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:05 INFO - 'MOZ_NO_REMOTE': '1', 05:38:05 INFO - 'NO_EM_RESTART': '1', 05:38:05 INFO - 'PAGER': '/bin/cat', 05:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:05 INFO - 'PWD': '/builds/slave/test', 05:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:05 INFO - 'SHELL': '/bin/bash', 05:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:38:05 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:05 INFO - 'USER': 'cltbld', 05:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:05 INFO - 'XPC_FLAGS': '0x0', 05:38:05 INFO - 'XPC_SERVICE_NAME': '0', 05:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:05 INFO - Downloading/unpacking psutil>=3.1.1 05:38:05 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 05:38:05 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 05:38:05 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 05:38:05 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 05:38:05 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 05:38:05 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 05:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:38:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:38:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:38:08 INFO - Installing collected packages: psutil 05:38:08 INFO - Running setup.py install for psutil 05:38:08 INFO - building 'psutil._psutil_osx' extension 05:38:08 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 05:38:09 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 05:38:09 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 05:38:09 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 05:38:09 INFO - building 'psutil._psutil_posix' extension 05:38:09 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 05:38:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:38:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:38:09 INFO - ^ 05:38:09 INFO - 1 warning generated. 05:38:09 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 05:38:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:38:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:38:09 INFO - Successfully installed psutil 05:38:09 INFO - Cleaning up... 05:38:09 INFO - Return code: 0 05:38:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:09 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 0x104643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bc45c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, '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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:38:09 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 05:38:09 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 05:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:09 INFO - 'HOME': '/Users/cltbld', 05:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:09 INFO - 'LOGNAME': 'cltbld', 05:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:09 INFO - 'MOZ_NO_REMOTE': '1', 05:38:09 INFO - 'NO_EM_RESTART': '1', 05:38:09 INFO - 'PAGER': '/bin/cat', 05:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:09 INFO - 'PWD': '/builds/slave/test', 05:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:09 INFO - 'SHELL': '/bin/bash', 05:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:38:09 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:09 INFO - 'USER': 'cltbld', 05:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:09 INFO - 'XPC_FLAGS': '0x0', 05:38:09 INFO - 'XPC_SERVICE_NAME': '0', 05:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:38:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:38:12 INFO - Installing collected packages: mozsystemmonitor 05:38:12 INFO - Running setup.py install for mozsystemmonitor 05:38:12 INFO - Successfully installed mozsystemmonitor 05:38:12 INFO - Cleaning up... 05:38:12 INFO - Return code: 0 05:38:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:12 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 0x104643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bc45c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, '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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:38:12 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 05:38:12 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 05:38:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:38:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:12 INFO - 'HOME': '/Users/cltbld', 05:38:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:12 INFO - 'LOGNAME': 'cltbld', 05:38:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:12 INFO - 'MOZ_NO_REMOTE': '1', 05:38:12 INFO - 'NO_EM_RESTART': '1', 05:38:12 INFO - 'PAGER': '/bin/cat', 05:38:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:12 INFO - 'PWD': '/builds/slave/test', 05:38:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:12 INFO - 'SHELL': '/bin/bash', 05:38:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:38:12 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:38:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:12 INFO - 'USER': 'cltbld', 05:38:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:12 INFO - 'XPC_FLAGS': '0x0', 05:38:12 INFO - 'XPC_SERVICE_NAME': '0', 05:38:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:13 INFO - Downloading/unpacking jsonschema==2.5.1 05:38:13 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 05:38:13 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 05:38:13 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 05:38:13 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 05:38:13 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 05:38:13 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 05:38:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:38:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:38:15 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 05:38:15 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 05:38:15 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 05:38:15 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 05:38:15 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 05:38:15 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 05:38:15 INFO - Downloading functools32-3.2.3-2.tar.gz 05:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:38:15 INFO - warning: no files found matching '*.txt' 05:38:15 INFO - no previously-included directories found matching 'build' 05:38:15 INFO - no previously-included directories found matching 'dist' 05:38:15 INFO - no previously-included directories found matching '.git*' 05:38:15 INFO - Installing collected packages: jsonschema, functools32 05:38:15 INFO - Running setup.py install for functools32 05:38:16 INFO - warning: no files found matching '*.txt' 05:38:16 INFO - no previously-included directories found matching 'build' 05:38:16 INFO - no previously-included directories found matching 'dist' 05:38:16 INFO - no previously-included directories found matching '.git*' 05:38:16 INFO - Successfully installed jsonschema functools32 05:38:16 INFO - Cleaning up... 05:38:16 INFO - Return code: 0 05:38:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:38:16 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')]} 05:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:16 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')]} 05:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:16 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 0x104643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bc45c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, '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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:38:16 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 05:38:16 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 05:38:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:38:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:16 INFO - 'HOME': '/Users/cltbld', 05:38:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:16 INFO - 'LOGNAME': 'cltbld', 05:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:16 INFO - 'MOZ_NO_REMOTE': '1', 05:38:16 INFO - 'NO_EM_RESTART': '1', 05:38:16 INFO - 'PAGER': '/bin/cat', 05:38:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:16 INFO - 'PWD': '/builds/slave/test', 05:38:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:16 INFO - 'SHELL': '/bin/bash', 05:38:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:38:16 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:38:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:16 INFO - 'USER': 'cltbld', 05:38:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:16 INFO - 'XPC_FLAGS': '0x0', 05:38:16 INFO - 'XPC_SERVICE_NAME': '0', 05:38:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:16 INFO - Downloading/unpacking blobuploader==1.2.4 05:38:16 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 05:38:16 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 05:38:16 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 05:38:16 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 05:38:16 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 05:38:16 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 05:38:18 INFO - Downloading blobuploader-1.2.4.tar.gz 05:38:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:38:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:38:19 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 05:38:19 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 05:38:19 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 05:38:19 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 05:38:19 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 05:38:19 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 05:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:38:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:38:19 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 05:38:19 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 05:38:19 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 05:38:19 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 05:38:19 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 05:38:19 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 05:38:20 INFO - Downloading docopt-0.6.1.tar.gz 05:38:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:38:20 INFO - Installing collected packages: blobuploader, requests, docopt 05:38:20 INFO - Running setup.py install for blobuploader 05:38:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:38:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:38:20 INFO - Running setup.py install for requests 05:38:20 INFO - Running setup.py install for docopt 05:38:21 INFO - Successfully installed blobuploader requests docopt 05:38:21 INFO - Cleaning up... 05:38:21 INFO - Return code: 0 05:38:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:21 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')]} 05:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:21 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')]} 05:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:21 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 0x104643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bc45c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, '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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:38:21 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 05:38:21 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 05:38:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:38:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:21 INFO - 'HOME': '/Users/cltbld', 05:38:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:21 INFO - 'LOGNAME': 'cltbld', 05:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:21 INFO - 'MOZ_NO_REMOTE': '1', 05:38:21 INFO - 'NO_EM_RESTART': '1', 05:38:21 INFO - 'PAGER': '/bin/cat', 05:38:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:21 INFO - 'PWD': '/builds/slave/test', 05:38:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:21 INFO - 'SHELL': '/bin/bash', 05:38:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:38:21 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:38:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:21 INFO - 'USER': 'cltbld', 05:38:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:21 INFO - 'XPC_FLAGS': '0x0', 05:38:21 INFO - 'XPC_SERVICE_NAME': '0', 05:38:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-dR84bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-T2bLQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-p22p6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-FqqMWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-VM64sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-FSuknt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-WjuRba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Pph77c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ES2K8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-To0PZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-hXzMNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-y9PuSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-gJuBow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-aV70PS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:22 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ZJhXAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:23 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-uQYDXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:23 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-km2tV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:38:23 INFO - Running setup.py install for manifestparser 05:38:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:38:23 INFO - Running setup.py install for mozcrash 05:38:23 INFO - Running setup.py install for mozdebug 05:38:23 INFO - Running setup.py install for mozdevice 05:38:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:38:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:38:23 INFO - Running setup.py install for mozfile 05:38:24 INFO - Running setup.py install for mozhttpd 05:38:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:38:24 INFO - Running setup.py install for mozinfo 05:38:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:38:24 INFO - Running setup.py install for mozInstall 05:38:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:38:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:38:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:38:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:38:24 INFO - Running setup.py install for mozleak 05:38:24 INFO - Running setup.py install for mozlog 05:38:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:38:24 INFO - Running setup.py install for moznetwork 05:38:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:38:24 INFO - Running setup.py install for mozprocess 05:38:25 INFO - Running setup.py install for mozprofile 05:38:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:38:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:38:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:38:25 INFO - Running setup.py install for mozrunner 05:38:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:38:25 INFO - Running setup.py install for mozscreenshot 05:38:25 INFO - Running setup.py install for moztest 05:38:25 INFO - Running setup.py install for mozversion 05:38:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:38:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:38:25 INFO - Cleaning up... 05:38:25 INFO - Return code: 0 05:38:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:25 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 0x104643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bc45c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, '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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:38:25 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 05:38:25 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 05:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:25 INFO - 'HOME': '/Users/cltbld', 05:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:25 INFO - 'LOGNAME': 'cltbld', 05:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:25 INFO - 'MOZ_NO_REMOTE': '1', 05:38:25 INFO - 'NO_EM_RESTART': '1', 05:38:25 INFO - 'PAGER': '/bin/cat', 05:38:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:25 INFO - 'PWD': '/builds/slave/test', 05:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:25 INFO - 'SHELL': '/bin/bash', 05:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:38:25 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:25 INFO - 'USER': 'cltbld', 05:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:25 INFO - 'XPC_FLAGS': '0x0', 05:38:25 INFO - 'XPC_SERVICE_NAME': '0', 05:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-K8Llmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:26 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)) 05:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-M49Cta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:26 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)) 05:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-UhGOUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:26 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)) 05:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-3AYXTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:26 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)) 05:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-LkIdVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:26 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)) 05:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-jpXk9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:26 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)) 05:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-c1fhgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:26 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)) 05:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ph4hVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:26 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)) 05:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:26 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ePVDYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:27 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)) 05:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:27 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-dYv6v9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:27 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)) 05:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:27 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vvKQEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:27 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)) 05:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:27 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Zy8gMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:27 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-nhQA33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:27 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)) 05:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:27 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-7iSsm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:27 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Wrvo8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:27 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)) 05:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:27 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-A4TDT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:27 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)) 05:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:27 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-hTB0OT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:27 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)) 05:38:27 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)) 05:38:27 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)) 05:38:27 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)) 05:38:27 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)) 05:38:27 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)) 05:38:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:27 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 05:38:27 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 05:38:27 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 05:38:27 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 05:38:27 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 05:38:27 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 05:38:30 INFO - Downloading blessings-1.6.tar.gz 05:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:38:30 INFO - Installing collected packages: blessings 05:38:30 INFO - Running setup.py install for blessings 05:38:30 INFO - Successfully installed blessings 05:38:30 INFO - Cleaning up... 05:38:30 INFO - Return code: 0 05:38:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:38:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:30 INFO - Reading from file tmpfile_stdout 05:38:30 INFO - Current package versions: 05:38:30 INFO - blessings == 1.6 05:38:30 INFO - blobuploader == 1.2.4 05:38:30 INFO - docopt == 0.6.1 05:38:30 INFO - functools32 == 3.2.3-2 05:38:30 INFO - jsonschema == 2.5.1 05:38:30 INFO - manifestparser == 1.1 05:38:30 INFO - mozInstall == 1.12 05:38:30 INFO - mozcrash == 0.17 05:38:30 INFO - mozdebug == 0.1 05:38:30 INFO - mozdevice == 0.48 05:38:30 INFO - mozfile == 1.2 05:38:30 INFO - mozhttpd == 0.7 05:38:30 INFO - mozinfo == 0.9 05:38:30 INFO - mozleak == 0.1 05:38:30 INFO - mozlog == 3.2 05:38:30 INFO - moznetwork == 0.27 05:38:30 INFO - mozprocess == 0.23 05:38:30 INFO - mozprofile == 0.28 05:38:30 INFO - mozrunner == 6.12 05:38:30 INFO - mozscreenshot == 0.1 05:38:30 INFO - mozsystemmonitor == 0.3 05:38:30 INFO - moztest == 0.7 05:38:30 INFO - mozversion == 1.4 05:38:30 INFO - psutil == 3.1.1 05:38:30 INFO - requests == 1.2.3 05:38:30 INFO - wsgiref == 0.1.2 05:38:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:30 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')]} 05:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:30 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')]} 05:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:30 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 0x104643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bc45c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, '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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:38:30 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 05:38:30 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 05:38:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:38:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:30 INFO - 'HOME': '/Users/cltbld', 05:38:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:30 INFO - 'LOGNAME': 'cltbld', 05:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:30 INFO - 'MOZ_NO_REMOTE': '1', 05:38:30 INFO - 'NO_EM_RESTART': '1', 05:38:30 INFO - 'PAGER': '/bin/cat', 05:38:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:30 INFO - 'PWD': '/builds/slave/test', 05:38:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:30 INFO - 'SHELL': '/bin/bash', 05:38:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:38:30 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:38:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:30 INFO - 'USER': 'cltbld', 05:38:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:30 INFO - 'XPC_FLAGS': '0x0', 05:38:30 INFO - 'XPC_SERVICE_NAME': '0', 05:38:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:31 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)) 05:38:31 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)) 05:38:31 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)) 05:38:31 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)) 05:38:31 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)) 05:38:31 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)) 05:38:31 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)) 05:38:31 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)) 05:38:31 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)) 05:38:31 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)) 05:38:31 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)) 05:38:31 INFO - Cleaning up... 05:38:31 INFO - Return code: 0 05:38:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:38:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bc45c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, '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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 05:38:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:38:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:38:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:38:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:38:31 INFO - 'HOME': '/Users/cltbld', 05:38:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:38:31 INFO - 'LOGNAME': 'cltbld', 05:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:31 INFO - 'MOZ_NO_REMOTE': '1', 05:38:31 INFO - 'NO_EM_RESTART': '1', 05:38:31 INFO - 'PAGER': '/bin/cat', 05:38:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:31 INFO - 'PWD': '/builds/slave/test', 05:38:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:38:31 INFO - 'SHELL': '/bin/bash', 05:38:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:38:31 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:38:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:38:31 INFO - 'USER': 'cltbld', 05:38:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:38:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:31 INFO - 'XPC_FLAGS': '0x0', 05:38:31 INFO - 'XPC_SERVICE_NAME': '0', 05:38:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:38:31 INFO - Cleaning up... 05:38:31 INFO - Return code: 0 05:38:31 INFO - Running post-action listener: _resource_record_post_action 05:38:31 INFO - Running post-action listener: _start_resource_monitoring 05:38:31 INFO - Starting resource monitoring. 05:38:31 INFO - ##### 05:38:31 INFO - ##### Finished create-virtualenv step (success) 05:38:31 INFO - ##### 05:38:31 INFO - ##### 05:38:31 INFO - ##### Running install step. 05:38:31 INFO - ##### 05:38:31 INFO - Running pre-action listener: _resource_record_pre_action 05:38:31 INFO - Running main action method: install 05:38:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:31 INFO - Reading from file tmpfile_stdout 05:38:31 INFO - Detecting whether we're running mozinstall >=1.0... 05:38:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:38:31 INFO - Reading from file tmpfile_stdout 05:38:31 INFO - Output received: 05:38:31 INFO - Usage: mozinstall [options] installer 05:38:31 INFO - Options: 05:38:31 INFO - -h, --help show this help message and exit 05:38:31 INFO - -d DEST, --destination=DEST 05:38:31 INFO - Directory to install application into. [default: 05:38:31 INFO - "/builds/slave/test"] 05:38:31 INFO - --app=APP Application being installed. [default: firefox] 05:38:31 INFO - mkdir: /builds/slave/test/build/application 05:38:31 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'] 05:38:31 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 05:39:06 INFO - Reading from file tmpfile_stdout 05:39:06 INFO - Output received: 05:39:06 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:39:06 INFO - Running post-action listener: _resource_record_post_action 05:39:06 INFO - ##### 05:39:06 INFO - ##### Finished install step (success) 05:39:06 INFO - ##### 05:39:06 INFO - ##### 05:39:06 INFO - ##### Running run-tests step. 05:39:06 INFO - ##### 05:39:06 INFO - Running pre-action listener: _resource_record_pre_action 05:39:06 INFO - Running main action method: run_tests 05:39:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:39:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:39:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:39:06 INFO - 2016-07-13 05:39:06.484 screenresolution[1636:7421] starting screenresolution argv=screenresolution get 05:39:06 INFO - 2016-07-13 05:39:06.487 screenresolution[1636:7421] Display 0: 1600x1200x32@60 05:39:06 INFO - 2016-07-13 05:39:06.493 screenresolution[1637:7423] starting screenresolution argv=screenresolution list 05:39:06 INFO - Available Modes on Display 0 05:39:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:39:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:39:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:39:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:39:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:39:06 INFO - Intel Iris: 05:39:06 INFO - Chipset Model: Intel Iris 05:39:06 INFO - Type: GPU 05:39:06 INFO - Bus: Built-In 05:39:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:39:06 INFO - Vendor: Intel (0x8086) 05:39:06 INFO - Device ID: 0x0a2e 05:39:06 INFO - Revision ID: 0x0009 05:39:06 INFO - Displays: 05:39:06 INFO - Display: 05:39:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:39:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:39:06 INFO - Display Serial Number: 1600x1200 60 05:39:06 INFO - Main Display: Yes 05:39:06 INFO - Mirror: Off 05:39:06 INFO - Online: Yes 05:39:06 INFO - Rotation: Supported 05:39:06 INFO - Return code: 0 05:39:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:39:06 INFO - Python 2.7.3 05:39:06 INFO - Return code: 0 05:39:06 INFO - grabbing minidump binary from tooltool 05:39:06 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')]} 05:39:06 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 0x1045a7d30>, '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 0x10430cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10459fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:39:06 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 05:39:06 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 05:39:06 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 05:39:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:39:06 INFO - Return code: 0 05:39:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:39:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:39:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:39:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:39:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:39:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 05:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --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 05:39:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G0kMqIMIgw/Render', 05:39:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:39:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:39:06 INFO - 'HOME': '/Users/cltbld', 05:39:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:39:06 INFO - 'LOGNAME': 'cltbld', 05:39:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:39:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:39:06 INFO - 'MOZ_NO_REMOTE': '1', 05:39:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:39:06 INFO - 'NO_EM_RESTART': '1', 05:39:06 INFO - 'PAGER': '/bin/cat', 05:39:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:39:06 INFO - 'PWD': '/builds/slave/test', 05:39:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:39:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:39:06 INFO - 'SHELL': '/bin/bash', 05:39:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1R9f8Y2YO2/Listeners', 05:39:06 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:39:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:39:06 INFO - 'USER': 'cltbld', 05:39:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:39:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:39:06 INFO - 'XPC_FLAGS': '0x0', 05:39:06 INFO - 'XPC_SERVICE_NAME': '0', 05:39:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:39:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 05:39:07 INFO - mozversion application_buildid: 20160713030216 05:39:07 INFO - mozversion application_changeset: 04821a70c739a00d12e12df651c0989441e22728 05:39:07 INFO - mozversion application_display_name: Nightly 05:39:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:39:07 INFO - mozversion application_name: Firefox 05:39:07 INFO - mozversion application_remotingname: firefox 05:39:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:39:07 INFO - mozversion application_vendor: Mozilla 05:39:07 INFO - mozversion application_version: 50.0a1 05:39:07 INFO - mozversion platform_buildid: 20160713030216 05:39:07 INFO - mozversion platform_changeset: 04821a70c739a00d12e12df651c0989441e22728 05:39:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:39:07 INFO - mozversion platform_version: 50.0a1 05:39:07 INFO - using testdate: 1468413547 05:39:07 INFO - actual date: 1468413547 05:39:07 INFO - starting webserver on 'localhost:49271' 05:39:07 INFO - SUITE-START | Running 2 tests 05:39:07 INFO - TEST-START | dromaeo_css 05:39:07 INFO - operating with platform_type : mac_ 05:39:07 INFO - Initialising browser for dromaeo_css test... 05:39:07 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpvKKyA5/profile http://localhost:49271/getInfo.html) 05:39:09 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 05:39:09 INFO - PROCESS | 1647 | colorDepth:24 05:39:09 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 05:39:09 INFO - PROCESS | 1647 | __metrics 05:39:10 INFO - PROCESS | 1647 | 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" 05:39:10 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:39:10 INFO - TEST-INFO | 1647: exit 0 05:39:10 INFO - Browser initialized. 05:39:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:39:10 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpvKKyA5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:22 INFO - PROCESS | 1650 | RSS: Main: 243658752 05:39:22 INFO - PROCESS | 1650 | 05:40:14 INFO - PROCESS | 1650 | 1468413614094 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] 05:42:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 05:42:26 INFO - PROCESS | 1650 | RSS: Main: 349769728 05:42:26 INFO - PROCESS | 1650 | 05:45:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 05:45:30 INFO - PROCESS | 1650 | RSS: Main: 381136896 05:45:30 INFO - PROCESS | 1650 | 05:48:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 05:48:33 INFO - PROCESS | 1650 | RSS: Main: 371228672 05:48:33 INFO - PROCESS | 1650 | 05:51:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:51:36 INFO - PROCESS | 1650 | RSS: Main: 371679232 05:51:36 INFO - PROCESS | 1650 | 05:54:39 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:54:40 INFO - PROCESS | 1650 | RSS: Main: 339570688 05:54:40 INFO - PROCESS | 1650 | 05:57:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:57:43 INFO - PROCESS | 1650 | RSS: Main: 385900544 05:57:43 INFO - PROCESS | 1650 | 05:57:43 INFO - PROCESS | 1650 | __start_tp_report 05:57:43 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 05:57:43 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 05:57:43 INFO - PROCESS | 1650 | |i|pagename|runs| 05:57:43 INFO - PROCESS | 1650 | |0;dojo.html;3005.7895947283687;3290.917727056824;3327.9168020799475;3331.2837739885904;3359.815210163443;5512.476314750093;5586;5597.468240517154;5651.858703532409;5668.433156684329;4542.931856022154;5561.499465048145;5654.575906806996;5684.118961560955;5729.026065568853;4297.935530967038;5027.0699920514635;5033.446320904697;5090.541851233388;5105.974470127644;5112.8977420451565;5182.352205974254;5189.455107213745;5197.428282888879;5248.973755131219;4993.825216117455;5050.949490505084;5051.646384753069;5131.589472842183;5149.588032957355;5078.7968481260705;5088.109580823341;5093.18509038552;5098.872528186788;5102.285680004802;5001.249812528114;5063.164577844651;5068.239764035387;5079.517445842639;5131.820386286462;5535.640183388107;5541.974734674058;5571.721413929287;5598.44015598441;5612.130119831432;6112.541559383064;6119.938800611982;6130.509559235251;6138.294096178952;6143.894099062167;6018.247719035121;6053.545984051214;6101.247350682747;6133.69331533426;6163.506919446479;5673.687947162863;5736.913946290809;5746;5750.482456578844;5763.625364351304;5233.581313494911;6210.441060304594;6254.843628909222;6262.968685156545;6279.654618995908;4855.295982082578;5867.911981320283;5939.613925094942;5958.986972214734;6041.697915104227;6134.173184329662;6144.5084393248435;6147.877042459215;6154.846128846814;6168.691565421711;6075.10641903688;6107.603005804608;6121.204243448323;6129.846753831101;6130.938690613037;5886.470217680429;5919.3192782829665;5966.671833049224;6043.788467403708;6066.4540191382985;4776.928346074812;4793.856184314476;4818.518148185153;4820.228763397839;4853.611711063107;3629.9637003630155;4329.783510824509;4343.5439278875265;4384.342348647665;4391.451068616423;5462.453754624586;5587.580931430159;5601.439856014366;5615.213870058195;5656.660600363992;6092.69536523181;6093.238345206849;6109.7387008107025;6114.66235542843;6116.244470650255;5555.3611334697025;5611.382747897728;5617.691026993472;5633.2395126657375;5639.6334238274385;5306;5308.601854860901;5313.0967735485065;5323;5327.520523152857;6135.906433916094;6257.7809776658505;6327.145835312174;6343.3022367538615;6347.365263473708;6103.328633850362;6126.242866754858;6135.04893764628;6138.940218748475;6148.569600127948;6266.373362663697;6300;6320.936790632035;6326.240851097898;6327.3988971047675;5066.493350664904;6089.330173680803;6145;6158.876822463615;6169.598976066542;5014.297998280316;6002.939970600412;6221.160143380585;6233.252009758858;6245.781397651061;5926.140709597171;6006.729697163551;6008.459238668541;6018.127371531016;6055.72749226277;5959.880802384015;5980.5215582754145;5986.102084687332;5997.730102145327;5998.460138587374;5633.88732225345;5714.628549144292;5765.654060756368;5780.653160810365;5824.592278540462;5939.2575928009;6001.3698561652145;6005.249343832021;6011.27864656244;6028.15605815195;5806.44838740319;5814.854628634318;5823.970880145572;5827.621204621686;5835.153902684002 05:57:43 INFO - PROCESS | 1650 | |1;ext.html;523.5052875033095;523.5889826486208;536.8738346488575;551.5439240405328;560.9678192126488;23566.057357705693;29188;30249.546256806138;31552.053438396826;31809.840950795213;40291.19417611646;42967.78516107415;43019.35470967938;43070.353944690854;43155.56844431555;9730.562124704387;9948.104670579647;9955.253355998293;9963.452010139476;9993.850092248622;8728.170823771732;8894.332925030616;9682.70951871445;9831.31180817343;11764.529418823236;11553.826692599618;11972.341521216329;11972.401379931012;11978.341191234478;12009.819852702172;11609.245399049078;11622.418879056013;11665.941670291679;11694.707632309175;11733.178677492579;8645.53025985584;8833.16084971933;8847.73456796297;8982.505962172077;11853.88146118545;39896;40055.7997210015;40067;42680.35979460311;42756.57243427557;3672.9005797489585;3730.0301921500336;3796.1458671798623;3979;4058.208649313385;31774.523382149287;32700.836495817606;33068.17329566756;33090.17274568131;33353.3329333412;3175.857086431093;3288.5724855768835;3340.1482621931495;3344.0302312329395;3373.241020770307;374050;374946;376144;376404;380554.1944580519;59870.401295986485;60178.097328540105;60207.39792602018;60216.698916506015;60239;62703;63290;63473.68263158655;63510;63534.046989295195;16490.257938392802;16870.409535666193;16890;16892.070936098386;16910.239039243264;13009.804852927213;13009.869901301056;13039.608811735663;13052.54316098932;13089.869101309056;11997.700057498458;13722.176669399669;13920.02559820823;14098.647533811736;15767;5890.852728681817;6062.151298818169;6350.714217860207;6487.967560162169;6524.151860258231;837.5812093953024;922.9294018938;977.6773664690635;1021.7241344837001;1070.4915165296577;25960;26511.867440662674;29306.413871722874;30189.466365374763;31154.376912462078;833.204289903141;833.7165363776223;879.1120967822418;941.9717408477757;1059.8394757740261;13796.448142074405;15940.043597384194;15979.920100399657;15993.60015999586;16255.349786008665;693968;696255;699349;702562;705330;59901.70049149814;60069;60182.79634407287;60212.49468763229;60315.39684603186;1408.3732738931078;1413.1309244814365;1446.674498237888;1447.5946734914241;1450.3328468904422;28770.814141234074;29141.41717165687;30749.23126921845;30903.536446953764;31016.0695179145;51250.74374628103;51295.74352128216;51781.74109129581;51813;51839.481605183464;624015;627317;628379;629374;629855;41092.794536027126;41297.587024129374;42065;42401.78799105985;42564.57435425606;48311.033779323516;48528;48971.265431019274;49464.25803612877;50055.49944500509;40131.799341003105;40199.19601607893;40428.79785601053;40871.18257634771;41421.47917427053;39606.80196598999;40319.79840100781;40550.39174412327;40808.97977550585;41283.1743365137;36570.63429365672;36758.08104797402;38781.612183878926;39388.803055984536;39521.60478395179;32537;34270.48594271038;34587.827060865544;34643;34720.82639586786;25593.488130237663;26102.73897261003;26131.216063518124;26373.868130659223;26495.061477542517 05:57:43 INFO - PROCESS | 1650 | |2;jquery.html;346.2037314177392;359.14523434226567;389.5111634898202;391.1551049732577;407.5924075924076;15162.848371516295;15496.22518874056;20628.48428789281;20889.164433422684;20906.37280881576;14868.405263789435;20953;21288.57422851542;21293;21352.25267115651;5438.3473983122085;5590.91613625796;5637.605367624268;5638.097904335307;5767.653940763546;6183.690815459231;6234.345393733638;6251.437370636641;6257.968710156443;6268.059791031336;6251;6251.031090181006;6373.330800265975;6879.27767584404;6881;5394.433584473652;6626.635535045571;6818.249992500821;6851.383375496204;6868.797960356943;6008.48927841134;6050.455459008666;6054.455099041107;6055.455009049207;6057.878842423171;13635.590932272047;13722.588322350346;23356.83215839201;24480.632790508156;25800.483990320277;2077.740282464692;2274.613315736329;2621.30535408117;2937.6327959005125;2961.570572267031;9430.773999380106;10030.348027378197;10118.848217276747;10226.89773102267;10680.305780124345;1713.4345665930214;1887.6507846048526;1890.6502297075085;1921.8270355667917;1966.9606607867763;70336;79997.60001200016;115677.2648410283;118020.22969655463;127191;10116.24128190374;10190.796184076276;11237.38194399316;11456.713582160513;11464.656060318204;14646.560603181922;14750;14755.926220368829;14756.114633122046;14758.409663613334;8545.401821872409;8711;8750.649974001097;8801.911980880237;8845.911540884637;6975.441964642816;8759.080296568896;8788.780280493038;8890.822183556293;8923.41997770143;4486.551344865487;4561.68068235227;4579.313103034505;4601.677882548256;4650.95349046512;1560.6176486760817;1920.3278852401827;1921.9903900480688;1923.086533896416;1923.5960448305732;843.3969711656196;851.0723311590397;877.9341549383822;916.4547094478775;978.1929907826072;21500.139994400048;21999.8713399941;22144.33566992993;22206.66689999651;22295;876.3733930239781;882.7175303902818;928.2805825485303;985.9161971232382;989.5997164012841;10783.83824242637;11006.009459148554;11083.390413527171;11475.139364547525;11475.942620286845;524132;527161;611602;628656;629178;92109.69725756775;131491;140979;141999;146294;749.3405802893528;975.4586204656483;999.1706883287;1013.9188864890791;1015.9085682288629;19465.4160375189;19702.546904920477;19838;20026.299079532146;20056.598868022265;48305;57255.14117288161;57432.13851792143;59777.70111149416;61214;55311;69963.65018174876;70024;79076;85216.57391713002;7956.880646790419;8091.635876385459;8105.594720264081;8407.957960210404;8433.241008309036;9062.81874362496;12611.93694031524;13182.934085329513;13274.402651880882;13522.12106213093;11243.988041076342;11282.94358528202;11445.42772861337;11648.592299269485;11671.066314694974;10958.561657533928;11127.443627818739;11289.7742045157;11352.94323528377;11372.147088968195;9327.393719408217;11151.776964460827;11508.42457877086;11615.419229038684;11775.587854425054;7872.448928574945;8434.746957591282;8440.53577053268;8475.321974241922;8611.052784193858;11206.271592346471;11690.590829320932;11876.406179691154;11877.52489900429;11878.643640690792 05:57:43 INFO - PROCESS | 1650 | |3;mootools.html;1560.797096377471;1585.6353038801076;1778.7331900214965;1901.838343740782;1908.4370110817308;8333.666653333867;8592.35557333201;8752.956235218815;8769.693060742868;8776.956115219431;7656.885146722803;7876.330511906494;7909.327707144886;8008.399370047241;8578.227959483645;2120.8833514156754;2280.4070941555233;2346.9647955280598;2368.4197371643886;2370.265217782493;1932.5844943337179;2331.8834058297102;2332;2354.3172479980785;2357.3399448154546;2097.0877668214375;2363.89362478688;2365.420471984366;2371.0397289097855;2372.632242002492;2135.3380452059832;2158.1583182558816;2158.4603849037817;2287.336672365012;2357.1514254868234;2360.681308023429;2363.0902102690416;2365.47959448921;2368;2368.135630494874;8751.606177722018;8815.074417186168;8822.338324625614;8835.734927952171;8838.779030524223;5144.819931302386;5330.173823057432;5332.626716129873;5333.146696528537;5344.7862085516535;3436.604790449105;3523.277728065766;3608.4226523756074;3616.9095772605633;3623.2572322673786;1073.9087177589836;1076.6339444588878;1085.5277954089918;1093.316677076827;1094.1738987064796;11506.884931150666;11520.963113319842;11526.942365288203;11532.077433805276;11535.134864885167;3256.544083828266;4514;4715.41057367829;4715.575598196178;4723.811047558059;9708.271879608916;9748.31761776683;9749.415035097916;9761.316707830525;9764.804703905882;5251.894962100813;5257.658252213594;5260.579153667697;5268.2097685346735;5270.578353731692;4337.436133302714;4472.642188624967;4819.879503012453;4835.347228124158;4840.6127509799135;3347.9163020924184;3684.3920753075977;3887.980560097238;3950.20995800841;3976.2445135424177;1234.2224398628807;1264.2363902356346;1268.6447794617523;1275.3049587974576;1278.5013844600612;793.3137835772131;825.8843761873343;868.5006121480047;888.8622263549161;1002.4436437777103;6119.785807496716;6125.448709616156;6168;6229;6349.206349206349;971.5725080964353;997.8104160209537;1000.0699349604791;1005.7334806276342;1030.2736570717625;4006.579309172553;4080.9795951020646;4107.794610269475;4117;4160.479940007499;10173.694789156338;10529.84205236922;10611.628592999208;10625.893741062646;10628.574857005788;9964.05341492557;10511.369317840954;10578.841317380246;10582.417967011888;10608.469576521144;8021.759347219593;8078.3133433658795;8249.752507424786;8258.42191046633;8260.215279548433;6037.939620603738;6109.75560977556;6236.563440559117;6279.152314437675;6371;9520.333576649567;9651.758706032124;9953.800923981335;9976.401415915068;10047.14599259042;9772.706818795448;10022.849657255001;10104.898951010397;10139.64511242103;10143.949280253846;6353.237611486466;6994.790156295319;7165.964170179116;7251.709931602677;7555.622218888923;7086.893696594452;8204.548749818818;8414.579271036546;8427.325813934993;8447.788805279672;7228.89156662661;7363.889541656987;7366.226546212571;7479.326860582356;7535.6985720570565;7200.063991681048;7252.746153884589;7337.449691273069;7356.445614349793;7504.812379690333;7041.049458323061;7077.504574679723;7079.893801592877;7080.610566418896;7118.181409138016;4868.245421959602;4982;4989.975050124726;5006.574441172541;5087.592992560661;3971.007248187953;4742.146413645577;4993.176125939324;5127.87180320495;5247.790088396421 05:57:43 INFO - PROCESS | 1650 | |4;prototype.html;265.52868658131814;303.9665636779955;308.22788913771006;319.37721443185785;323.629444286292;5202.361256204293;5332.253484512172;5337.412884582693;5389.51494365507;5434.34787825461;4191.727537710047;4321.178975994567;5147.305113809634;5288.127458969266;5364.532315135033;3722.962770372302;4040.8585699500522;4077.3476243800997;4078.653314468259;4092.731253311468;3717.8512859485586;3937.370020796673;4115.897102572445;4116.9794151029355;4128.649064829493;3151.9913627639166;3851.4607954886337;4053.1893621275717;4059.7970101494957;4118.8970275743195;4046.372812214097;4065.26825171469;4067.755934643901;4070.104576993057;4124.360724087741;3113.6574976752768;3199;3262.9395446479753;3455.6825447570363;3570.0539357070397;4163.333866581333;4525.886852828673;4539.933115717804;4970.583383735654;5357.196420536911;4622.8150873965005;4637.420322459692;4642.466116396624;4677.88305292367;4677.95322046782;4955.677880937764;5306.389765176977;5505.559555235572;5573.6098473106895;5614.747336369873;3596.4245720684567;3842.3275926712713;3859.5754467008605;3861.942070868939;3870.2259548090215;5092.567131793802;5857.920438244447;5918.425484914076;5947.732352044169;5952.9107063394085;3086.3518661080975;3324.7838890472044;3449.7930124192126;3799.2971300308977;3939.1727737175515;3425.9314813703586;3805.486259355007;3968.9206215876097;3992.900177495586;4050.886006348278;4250.978745106255;4708.23341326937;4711.740854253049;4718.9382388962895;4727.503612120678;5336.893262134736;5657.409118377127;5731.077150653196;5759.712014399263;5764.394738552392;1113.4265853085667;1245.3212998915612;1318.4198952460886;1387.8195834541445;1411.470698488067;819.71310041485;874.064750716727;958.9355815045294;966.2028826218258;967.4775621164633;4413.6248418884115;4804.447488538793;5137.147139645266;5143.482672611589;5199.194124910645;5202.557782588447;5450.427705090988;5659.971700141473;5691;5729.226554415184;4192.979035104866;4622.815087396467;5033.7734801934;5062.265971434122;5151.5363617274625;2191.1783081344497;2486.5772818620876;2533.2780157655347;2555.4378036831868;2581.438229870939;4566.74882881438;4623;4694;4959;5004.874878128003;4445.8875540125255;4663.836765713184;5472.808451704172;6055.394460553909;6062.818115456544;5475.397706252229;5560.721963901708;6022.747716233185;6068.969655151696;6075.539406540358;4789.4971028041555;5633.2395126657375;5860.2967643883;5866.853328666817;5885.14570433348;5736.168255602997;5745.339285981999;5794.18881356602;5796.710164491843;5804.158397032491;4660.370849935215;5363.597730170175;5984.311804142405;6033.87932241344;6051.152838602688;3395.2530443302435;3636.3999940009603;3747.494088298154;3868.226354729009;3886.630770076838;4535.591796738177;5106.106431374569;5331.333583302087;5715.257016587983;5748.252727145612;4590.954090459053;4866.48901865299;4946.62900282473;5222.3472065991755;5551.8056868011045;4763.237881938873;5287.788488460418;5380.811671591476;5534.92069046532;5617.354004289559;5512.944870551243;5556.611037227517;5562.527185189304;5573.749181286933;5579.656544251506;4948.727819969792;5308.7172515818565;5646.181303710857;5653.689047102449;5701.515371193329;5271.285288076567;5474.671519708831;5514.200440936122;5529.861753456195;5532.474414930575 05:57:43 INFO - PROCESS | 1650 | |5;yui.html;598.4135547163779;756.1682149635402;780.8789637606174;782.9765107046791;825.5500752090111;14910.329035193416;15660.13869237193;16255.268512916917;17421.390251341207;18030.909845450788;17239.224234909427;17266.482005539823;17412.216450259737;17425.30298788047;17519.82480175198;696.8257935516122;720.6756959368279;861.0313397427893;881.3081730841291;907.8955920069177;884.186548375496;885.2386947225414;889.4040992534984;892.2148509311796;892.830362231174;944.7165850244934;944.7968686732352;947.0340252941995;947.5735918836517;948.5826236455937;725.4957804325995;896.7175339767953;925.1673493855517;935.5415846235296;935.9017303183135;705.3757424679145;707.5153519838926;708.7944496096126;710.0981753173493;710.4174576846988;15310.693786124215;15516.224188790515;15528.456504022304;15544.61138471536;15565.299561519645;13762.862371376259;13829.51596694121;13845.653858653513;13860.306984650828;13933.372998215105;13101.410436530283;13330.200187988752;13520.797188042186;13757.862421375758;13768.518101866486;4491.528389519119;4636.072785442925;4751.667383283171;4772.952270477285;4793.017431426584;111432;112434;112880.43559782149;114056.85943140545;114288;10813.243072984973;10980.890191098146;11111.55553777856;11315.03822175108;11874.940625296818;22920;23296.95163717603;23326;23330.53338933212;23385.532289354116;8101.594920253964;8148.388870834711;8200.26197642203;8376.664933402717;8426.831463370698;4378.10248898975;4475.731456112644;4494.775261236925;4540.296254080623;4694.389729335165;3521.8239088045493;3639.836207370675;3647.7264205184715;3650.8357123929486;3653.6163702811355;671.4460570029647;679.37497502298;681.3016657925666;695.9652017399211;698.2458944340101;561.8061619059498;626.4455956478549;651.9641419721961;653.9640319782362;673.6901025528214;7985.560794156378;8665.263452606472;8935.821283574292;9231.723048308562;9294.767630809283;588.417466707956;603.3272900715671;606.1714782969848;617.7868635320807;637.2575949019433;3298.802071875695;4535.750533720611;4539.6368290536675;4577.771111444415;4655.906881862344;84655;117366;154220.22889885702;160711;164150;99120;113846.72306553823;131376;137195;140329;72688.63655681688;74261;80886;88639;100514.4974275124;9465.574049167943;9482.900546284462;9637.325387222827;9729.027097290214;9792.80414391694;59086.40913590809;59123;63538;65569.67215163894;65713;96943;98602;98994;99946;103375;13164.539241126515;13322.333883305602;13338.666533336744;13496.392662330023;13806.17162970225;14138.43446262138;14148;14268.714625707635;14528.346224419714;14989.77515337249;13742.381592828147;13878.097923634932;13890.930545347208;13891.305434728021;14081.859181408056;13967.371468283749;14001.719965600427;14287.142771433748;14321.785173222203;14439.92780036093;13512.25682587467;13687.589372318846;13791.793123102962;14160.008799383944;14296;10585.52365143555;10920.890791091988;10963.287386319864;11170.944145279222;11380.658580242605;10910.563577456813;11068.059214966817;11279.605213817476;11297.039751621205;11300.547978080785 05:57:43 INFO - PROCESS | 1650 | __end_tp_report 05:57:43 INFO - PROCESS | 1650 | __start_cc_report 05:57:43 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1076 05:57:43 INFO - PROCESS | 1650 | __end_cc_report 05:57:43 INFO - PROCESS | 1650 | __startTimestamp1468414663716__endTimestamp 05:57:43 INFO - PROCESS | 1650 | 05:57:43 INFO - PROCESS | 1650 | ------- Summary: start ------- 05:57:43 INFO - PROCESS | 1650 | Number of tests: 6 05:57:43 INFO - PROCESS | 1650 | 05:57:43 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5605.16 Median:5780.65 stddev:665.16 (11.5%) stddev-sans-first:635.17 05:57:43 INFO - PROCESS | 1650 | Values: 3005.8 3290.9 3327.9 3331.3 3359.8 5512.5 5586.0 5597.5 5651.9 5668.4 4542.9 5561.5 5654.6 5684.1 5729.0 4297.9 5027.1 5033.4 5090.5 5106.0 5112.9 5182.4 5189.5 5197.4 5249.0 4993.8 5050.9 5051.6 5131.6 5149.6 5078.8 5088.1 5093.2 5098.9 5102.3 5001.2 5063.2 5068.2 5079.5 5131.8 5535.6 5542.0 5571.7 5598.4 5612.1 6112.5 6119.9 6130.5 6138.3 6143.9 6018.2 6053.5 6101.2 6133.7 6163.5 5673.7 5736.9 5746.0 5750.5 5763.6 5233.6 6210.4 6254.8 6263.0 6279.7 4855.3 5867.9 5939.6 5959.0 6041.7 6134.2 6144.5 6147.9 6154.8 6168.7 6075.1 6107.6 6121.2 6129.8 6130.9 5886.5 5919.3 5966.7 6043.8 6066.5 4776.9 4793.9 4818.5 4820.2 4853.6 3630.0 4329.8 4343.5 4384.3 4391.5 5462.5 5587.6 5601.4 5615.2 5656.7 6092.7 6093.2 6109.7 6114.7 6116.2 5555.4 5611.4 5617.7 5633.2 5639.6 5306.0 5308.6 5313.1 5323.0 5327.5 6135.9 6257.8 6327.1 6343.3 6347.4 6103.3 6126.2 6135.0 6138.9 6148.6 6266.4 6300.0 6320.9 6326.2 6327.4 5066.5 6089.3 6145.0 6158.9 6169.6 5014.3 6002.9 6221.2 6233.3 6245.8 5926.1 6006.7 6008.5 6018.1 6055.7 5959.9 5980.5 5986.1 5997.7 5998.5 5633.9 5714.6 5765.7 5780.7 5824.6 5939.3 6001.4 6005.2 6011.3 6028.2 5806.4 5814.9 5824.0 5827.6 5835.2 05:57:43 INFO - PROCESS | 1650 | 05:57:43 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:70643.11 Median:39975.90 stddev:156679.31 (391.9%) stddev-sans-first:157030.38 05:57:43 INFO - PROCESS | 1650 | Values: 523.5 523.6 536.9 551.5 561.0 23566.1 29188.0 30249.5 31552.1 31809.8 40291.2 42967.8 43019.4 43070.4 43155.6 9730.6 9948.1 9955.3 9963.5 9993.9 8728.2 8894.3 9682.7 9831.3 11764.5 11553.8 11972.3 11972.4 11978.3 12009.8 11609.2 11622.4 11665.9 11694.7 11733.2 8645.5 8833.2 8847.7 8982.5 11853.9 39896.0 40055.8 40067.0 42680.4 42756.6 3672.9 3730.0 3796.1 3979.0 4058.2 31774.5 32700.8 33068.2 33090.2 33353.3 3175.9 3288.6 3340.1 3344.0 3373.2 374050.0 374946.0 376144.0 376404.0 380554.2 59870.4 60178.1 60207.4 60216.7 60239.0 62703.0 63290.0 63473.7 63510.0 63534.0 16490.3 16870.4 16890.0 16892.1 16910.2 13009.8 13009.9 13039.6 13052.5 13089.9 11997.7 13722.2 13920.0 14098.6 15767.0 5890.9 6062.2 6350.7 6488.0 6524.2 837.6 922.9 977.7 1021.7 1070.5 25960.0 26511.9 29306.4 30189.5 31154.4 833.2 833.7 879.1 942.0 1059.8 13796.4 15940.0 15979.9 15993.6 16255.3 693968.0 696255.0 699349.0 702562.0 705330.0 59901.7 60069.0 60182.8 60212.5 60315.4 1408.4 1413.1 1446.7 1447.6 1450.3 28770.8 29141.4 30749.2 30903.5 31016.1 51250.7 51295.7 51781.7 51813.0 51839.5 624015.0 627317.0 628379.0 629374.0 629855.0 41092.8 41297.6 42065.0 42401.8 42564.6 48311.0 48528.0 48971.3 49464.3 50055.5 40131.8 40199.2 40428.8 40871.2 41421.5 39606.8 40319.8 40550.4 40809.0 41283.2 36570.6 36758.1 38781.6 39388.8 39521.6 32537.0 34270.5 34587.8 34643.0 34720.8 25593.5 26102.7 26131.2 26373.9 26495.1 05:57:43 INFO - PROCESS | 1650 | 05:57:43 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34158.56 Median:1653.89 stddev:97782.50 (5912.3%) stddev-sans-first:98023.84 05:57:43 INFO - PROCESS | 1650 | Values: 346.2 359.1 389.5 391.2 407.6 15162.8 15496.2 20628.5 20889.2 20906.4 14868.4 20953.0 21288.6 21293.0 21352.3 5438.3 5590.9 5637.6 5638.1 5767.7 6183.7 6234.3 6251.4 6258.0 6268.1 6251.0 6251.0 6373.3 6879.3 6881.0 5394.4 6626.6 6818.2 6851.4 6868.8 6008.5 6050.5 6054.5 6055.5 6057.9 13635.6 13722.6 23356.8 24480.6 25800.5 2077.7 2274.6 2621.3 2937.6 2961.6 9430.8 10030.3 10118.8 10226.9 10680.3 1713.4 1887.7 1890.7 1921.8 1967.0 70336.0 79997.6 115677.3 118020.2 127191.0 10116.2 10190.8 11237.4 11456.7 11464.7 14646.6 14750.0 14755.9 14756.1 14758.4 8545.4 8711.0 8750.6 8801.9 8845.9 6975.4 8759.1 8788.8 8890.8 8923.4 4486.6 4561.7 4579.3 4601.7 4651.0 1560.6 1920.3 1922.0 1923.1 1923.6 843.4 851.1 877.9 916.5 978.2 21500.1 21999.9 22144.3 22206.7 22295.0 876.4 882.7 928.3 985.9 989.6 10783.8 11006.0 11083.4 11475.1 11475.9 524132.0 527161.0 611602.0 628656.0 629178.0 92109.7 131491.0 140979.0 141999.0 146294.0 749.3 975.5 999.2 1013.9 1015.9 19465.4 19702.5 19838.0 20026.3 20056.6 48305.0 57255.1 57432.1 59777.7 61214.0 55311.0 69963.7 70024.0 79076.0 85216.6 7956.9 8091.6 8105.6 8408.0 8433.2 9062.8 12611.9 13182.9 13274.4 13522.1 11244.0 11282.9 11445.4 11648.6 11671.1 10958.6 11127.4 11289.8 11352.9 11372.1 9327.4 11151.8 11508.4 11615.4 11775.6 7872.4 8434.7 8440.5 8475.3 8611.1 11206.3 11690.6 11876.4 11877.5 11878.6 05:57:43 INFO - PROCESS | 1650 | 05:57:43 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5649.79 Median:4925.12 stddev:3145.09 (63.9%) stddev-sans-first:3138.90 05:57:43 INFO - PROCESS | 1650 | Values: 1560.8 1585.6 1778.7 1901.8 1908.4 8333.7 8592.4 8753.0 8769.7 8777.0 7656.9 7876.3 7909.3 8008.4 8578.2 2120.9 2280.4 2347.0 2368.4 2370.3 1932.6 2331.9 2332.0 2354.3 2357.3 2097.1 2363.9 2365.4 2371.0 2372.6 2135.3 2158.2 2158.5 2287.3 2357.2 2360.7 2363.1 2365.5 2368.0 2368.1 8751.6 8815.1 8822.3 8835.7 8838.8 5144.8 5330.2 5332.6 5333.1 5344.8 3436.6 3523.3 3608.4 3616.9 3623.3 1073.9 1076.6 1085.5 1093.3 1094.2 11506.9 11521.0 11526.9 11532.1 11535.1 3256.5 4514.0 4715.4 4715.6 4723.8 9708.3 9748.3 9749.4 9761.3 9764.8 5251.9 5257.7 5260.6 5268.2 5270.6 4337.4 4472.6 4819.9 4835.3 4840.6 3347.9 3684.4 3888.0 3950.2 3976.2 1234.2 1264.2 1268.6 1275.3 1278.5 793.3 825.9 868.5 888.9 1002.4 6119.8 6125.4 6168.0 6229.0 6349.2 971.6 997.8 1000.1 1005.7 1030.3 4006.6 4081.0 4107.8 4117.0 4160.5 10173.7 10529.8 10611.6 10625.9 10628.6 9964.1 10511.4 10578.8 10582.4 10608.5 8021.8 8078.3 8249.8 8258.4 8260.2 6037.9 6109.8 6236.6 6279.2 6371.0 9520.3 9651.8 9953.8 9976.4 10047.1 9772.7 10022.8 10104.9 10139.6 10143.9 6353.2 6994.8 7166.0 7251.7 7555.6 7086.9 8204.5 8414.6 8427.3 8447.8 7228.9 7363.9 7366.2 7479.3 7535.7 7200.1 7252.7 7337.4 7356.4 7504.8 7041.0 7077.5 7079.9 7080.6 7118.2 4868.2 4982.0 4990.0 5006.6 5087.6 3971.0 4742.1 4993.2 5127.9 5247.8 05:57:43 INFO - PROCESS | 1650 | 05:57:43 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4457.29 Median:4947.68 stddev:1396.60 (28.2%) stddev-sans-first:1364.62 05:57:43 INFO - PROCESS | 1650 | Values: 265.5 304.0 308.2 319.4 323.6 5202.4 5332.3 5337.4 5389.5 5434.3 4191.7 4321.2 5147.3 5288.1 5364.5 3723.0 4040.9 4077.3 4078.7 4092.7 3717.9 3937.4 4115.9 4117.0 4128.6 3152.0 3851.5 4053.2 4059.8 4118.9 4046.4 4065.3 4067.8 4070.1 4124.4 3113.7 3199.0 3262.9 3455.7 3570.1 4163.3 4525.9 4539.9 4970.6 5357.2 4622.8 4637.4 4642.5 4677.9 4678.0 4955.7 5306.4 5505.6 5573.6 5614.7 3596.4 3842.3 3859.6 3861.9 3870.2 5092.6 5857.9 5918.4 5947.7 5952.9 3086.4 3324.8 3449.8 3799.3 3939.2 3425.9 3805.5 3968.9 3992.9 4050.9 4251.0 4708.2 4711.7 4718.9 4727.5 5336.9 5657.4 5731.1 5759.7 5764.4 1113.4 1245.3 1318.4 1387.8 1411.5 819.7 874.1 958.9 966.2 967.5 4413.6 4804.4 5137.1 5143.5 5199.2 5202.6 5450.4 5660.0 5691.0 5729.2 4193.0 4622.8 5033.8 5062.3 5151.5 2191.2 2486.6 2533.3 2555.4 2581.4 4566.7 4623.0 4694.0 4959.0 5004.9 4445.9 4663.8 5472.8 6055.4 6062.8 5475.4 5560.7 6022.7 6069.0 6075.5 4789.5 5633.2 5860.3 5866.9 5885.1 5736.2 5745.3 5794.2 5796.7 5804.2 4660.4 5363.6 5984.3 6033.9 6051.2 3395.3 3636.4 3747.5 3868.2 3886.6 4535.6 5106.1 5331.3 5715.3 5748.3 4591.0 4866.5 4946.6 5222.3 5551.8 4763.2 5287.8 5380.8 5534.9 5617.4 5512.9 5556.6 5562.5 5573.7 5579.7 4948.7 5308.7 5646.2 5653.7 5701.5 5271.3 5474.7 5514.2 5529.9 5532.5 05:57:43 INFO - PROCESS | 1650 | 05:57:43 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:24252.56 Median:4015.86 stddev:37785.84 (940.9%) stddev-sans-first:37850.09 05:57:43 INFO - PROCESS | 1650 | Values: 598.4 756.2 780.9 783.0 825.6 14910.3 15660.1 16255.3 17421.4 18030.9 17239.2 17266.5 17412.2 17425.3 17519.8 696.8 720.7 861.0 881.3 907.9 884.2 885.2 889.4 892.2 892.8 944.7 944.8 947.0 947.6 948.6 725.5 896.7 925.2 935.5 935.9 705.4 707.5 708.8 710.1 710.4 15310.7 15516.2 15528.5 15544.6 15565.3 13762.9 13829.5 13845.7 13860.3 13933.4 13101.4 13330.2 13520.8 13757.9 13768.5 4491.5 4636.1 4751.7 4773.0 4793.0 111432.0 112434.0 112880.4 114056.9 114288.0 10813.2 10980.9 11111.6 11315.0 11874.9 22920.0 23297.0 23326.0 23330.5 23385.5 8101.6 8148.4 8200.3 8376.7 8426.8 4378.1 4475.7 4494.8 4540.3 4694.4 3521.8 3639.8 3647.7 3650.8 3653.6 671.4 679.4 681.3 696.0 698.2 561.8 626.4 652.0 654.0 673.7 7985.6 8665.3 8935.8 9231.7 9294.8 588.4 603.3 606.2 617.8 637.3 3298.8 4535.8 4539.6 4577.8 4655.9 84655.0 117366.0 154220.2 160711.0 164150.0 99120.0 113846.7 131376.0 137195.0 140329.0 72688.6 74261.0 80886.0 88639.0 100514.5 9465.6 9482.9 9637.3 9729.0 9792.8 59086.4 59123.0 63538.0 65569.7 65713.0 96943.0 98602.0 98994.0 99946.0 103375.0 13164.5 13322.3 13338.7 13496.4 13806.2 14138.4 14148.0 14268.7 14528.3 14989.8 13742.4 13878.1 13890.9 13891.3 14081.9 13967.4 14001.7 14287.1 14321.8 14439.9 13512.3 13687.6 13791.8 14160.0 14296.0 10585.5 10920.9 10963.3 11170.9 11380.7 10910.6 11068.1 11279.6 11297.0 11300.5 05:57:43 INFO - PROCESS | 1650 | -------- Summary: end -------- 05:57:43 INFO - PROCESS | 1650 | 05:57:44 INFO - TEST-INFO | 1650: exit 0 05:57:44 INFO - TEST-OK | dromaeo_css | took 1117244ms 05:57:44 INFO - TEST-START | kraken 05:57:44 INFO - operating with platform_type : mac_ 05:57:44 INFO - Initialising browser for kraken test... 05:57:44 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp45_LBd/profile http://localhost:49271/getInfo.html) 05:57:46 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 05:57:46 INFO - PROCESS | 1659 | colorDepth:24 05:57:46 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 05:57:46 INFO - PROCESS | 1659 | __metrics 05:57:47 INFO - PROCESS | 1659 | 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" 05:57:47 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:57:47 INFO - TEST-INFO | 1659: exit 0 05:57:47 INFO - Browser initialized. 05:57:47 INFO - Running cycle 1/1 for kraken test... 05:57:47 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp45_LBd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:59 INFO - PROCESS | 1661 | RSS: Main: 243617792 05:57:59 INFO - PROCESS | 1661 | 05:58:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:58:03 INFO - PROCESS | 1661 | RSS: Main: 293941248 05:58:03 INFO - PROCESS | 1661 | 05:58:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:58:06 INFO - PROCESS | 1661 | RSS: Main: 304226304 05:58:06 INFO - PROCESS | 1661 | 05:58:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:58:10 INFO - PROCESS | 1661 | RSS: Main: 387362816 05:58:10 INFO - PROCESS | 1661 | 05:58:12 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:58:13 INFO - PROCESS | 1661 | RSS: Main: 289570816 05:58:13 INFO - PROCESS | 1661 | 05:58:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:58:16 INFO - PROCESS | 1661 | RSS: Main: 292745216 05:58:16 INFO - PROCESS | 1661 | 05:58:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:58:19 INFO - PROCESS | 1661 | RSS: Main: 331464704 05:58:19 INFO - PROCESS | 1661 | 05:58:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:58:22 INFO - PROCESS | 1661 | RSS: Main: 332234752 05:58:22 INFO - PROCESS | 1661 | 05:58:25 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:58:26 INFO - PROCESS | 1661 | RSS: Main: 329584640 05:58:26 INFO - PROCESS | 1661 | 05:58:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:58:28 INFO - PROCESS | 1661 | RSS: Main: 311635968 05:58:28 INFO - PROCESS | 1661 | 05:58:30 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:58:31 INFO - PROCESS | 1661 | RSS: Main: 319492096 05:58:31 INFO - PROCESS | 1661 | 05:58:33 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:58:33 INFO - PROCESS | 1661 | RSS: Main: 316854272 05:58:33 INFO - PROCESS | 1661 | 05:58:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:58:37 INFO - PROCESS | 1661 | RSS: Main: 325365760 05:58:37 INFO - PROCESS | 1661 | 05:58:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:58:40 INFO - PROCESS | 1661 | RSS: Main: 328916992 05:58:40 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:58:42 INFO - PROCESS | 1661 | RSS: Main: 372334592 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | __start_tp_report 05:58:42 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 05:58:42 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 05:58:42 INFO - PROCESS | 1661 | |i|pagename|runs| 05:58:42 INFO - PROCESS | 1661 | |0;ai-astar;102;112;112;111;113;112;111;112;110;111 05:58:42 INFO - PROCESS | 1661 | |1;audio-beat-detection;146;107;109;159;106;107;129;107;140;109 05:58:42 INFO - PROCESS | 1661 | |2;audio-dft;181;184;187;183;182;180;181;184;181;185 05:58:42 INFO - PROCESS | 1661 | |3;audio-fft;79;73;59;124;81;82;71;61;83;57 05:58:42 INFO - PROCESS | 1661 | |4;audio-oscillator;82;89;93;70;70;68;97;69;67;100 05:58:42 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;87;85;88;87;135;84;88;85;87;83 05:58:42 INFO - PROCESS | 1661 | |6;imaging-darkroom;93;94;95;93;93;94;97;94;101;93 05:58:42 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;83;85;84;84;85;84;82;85;84 05:58:42 INFO - PROCESS | 1661 | |8;json-parse-financial;57;56;56;56;56;57;55;54;54;56 05:58:42 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;65;71;80;77;71;69;69;72;70;89 05:58:42 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;83;83;82;80;83;80;80;84;84;79 05:58:42 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;136;145;144;135;133;133;137;135;219;150 05:58:42 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;162;163;163;163;162;213;191;163;164;163 05:58:42 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;68;67;67;67;65;65;64;65;66;65 05:58:42 INFO - PROCESS | 1661 | __end_tp_report 05:58:42 INFO - PROCESS | 1661 | __start_cc_report 05:58:42 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2057 05:58:42 INFO - PROCESS | 1661 | __end_cc_report 05:58:42 INFO - PROCESS | 1661 | __startTimestamp1468414722921__endTimestamp 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | ------- Summary: start ------- 05:58:42 INFO - PROCESS | 1661 | Number of tests: 14 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:3.13 (2.8%) stddev-sans-first:0.88 05:58:42 INFO - PROCESS | 1661 | Values: 102.0 112.0 112.0 111.0 113.0 112.0 111.0 112.0 110.0 111.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:121.90 Median:119.00 stddev:19.96 (16.8%) stddev-sans-first:19.18 05:58:42 INFO - PROCESS | 1661 | Values: 146.0 107.0 109.0 159.0 106.0 107.0 129.0 107.0 140.0 109.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.50 stddev:2.20 (1.2%) stddev-sans-first:2.24 05:58:42 INFO - PROCESS | 1661 | Values: 181.0 184.0 187.0 183.0 182.0 180.0 181.0 184.0 181.0 185.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:77.00 Median:76.00 stddev:19.21 (25.3%) stddev-sans-first:20.36 05:58:42 INFO - PROCESS | 1661 | Values: 79.0 73.0 59.0 124.0 81.0 82.0 71.0 61.0 83.0 57.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:76.00 stddev:13.23 (17.4%) stddev-sans-first:14.02 05:58:42 INFO - PROCESS | 1661 | Values: 82.0 89.0 93.0 70.0 70.0 68.0 97.0 69.0 67.0 100.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:90.90 Median:87.00 stddev:15.59 (17.9%) stddev-sans-first:16.47 05:58:42 INFO - PROCESS | 1661 | Values: 87.0 85.0 88.0 87.0 135.0 84.0 88.0 85.0 87.0 83.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:94.00 stddev:2.54 (2.7%) stddev-sans-first:2.62 05:58:42 INFO - PROCESS | 1661 | Values: 93.0 94.0 95.0 93.0 93.0 94.0 97.0 94.0 101.0 93.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.50 stddev:0.99 (1.2%) stddev-sans-first:1.00 05:58:42 INFO - PROCESS | 1661 | Values: 85.0 83.0 85.0 84.0 84.0 85.0 84.0 82.0 85.0 84.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.06 (1.9%) stddev-sans-first:1.01 05:58:42 INFO - PROCESS | 1661 | Values: 57.0 56.0 56.0 56.0 56.0 57.0 55.0 54.0 54.0 56.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:71.50 stddev:6.95 (9.7%) stddev-sans-first:6.69 05:58:42 INFO - PROCESS | 1661 | Values: 65.0 71.0 80.0 77.0 71.0 69.0 69.0 72.0 70.0 89.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:83.00 stddev:1.87 (2.3%) stddev-sans-first:1.94 05:58:42 INFO - PROCESS | 1661 | Values: 83.0 83.0 82.0 80.0 83.0 80.0 80.0 84.0 84.0 79.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:140.50 stddev:26.05 (18.5%) stddev-sans-first:27.34 05:58:42 INFO - PROCESS | 1661 | Values: 136.0 145.0 144.0 135.0 133.0 133.0 137.0 135.0 219.0 150.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:163.00 stddev:17.30 (10.6%) stddev-sans-first:18.06 05:58:42 INFO - PROCESS | 1661 | Values: 162.0 163.0 163.0 163.0 162.0 213.0 191.0 163.0 164.0 163.0 05:58:42 INFO - PROCESS | 1661 | 05:58:42 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.50 stddev:1.29 (1.9%) stddev-sans-first:1.12 05:58:42 INFO - PROCESS | 1661 | Values: 68.0 67.0 67.0 67.0 65.0 65.0 64.0 65.0 66.0 65.0 05:58:42 INFO - PROCESS | 1661 | -------- Summary: end -------- 05:58:42 INFO - PROCESS | 1661 | 05:58:43 INFO - TEST-INFO | 1661: exit 0 05:58:43 INFO - TEST-OK | kraken | took 59418ms 05:58:43 INFO - SUITE-END | took 1176s 05:58:44 INFO - Completed test suite (00:19:37) 05:58:44 INFO - javascript benchmark 05:58:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3005.7895947283687, 3290.917727056824, 3327.9168020799475, 3331.2837739885904, 3359.815210163443, 5512.476314750093, 5586.0, 5597.468240517154, 5651.858703532409, 5668.433156684329, 4542.931856022154, 5561.499465048145, 5654.575906806996, 5684.118961560955, 5729.026065568853, 4297.935530967038, 5027.0699920514635, 5033.446320904697, 5090.541851233388, 5105.974470127644, 5112.8977420451565, 5182.352205974254, 5189.455107213745, 5197.428282888879, 5248.973755131219, 4993.825216117455, 5050.949490505084, 5051.646384753069, 5131.589472842183, 5149.588032957355, 5078.7968481260705, 5088.109580823341, 5093.18509038552, 5098.872528186788, 5102.285680004802, 5001.249812528114, 5063.164577844651, 5068.239764035387, 5079.517445842639, 5131.820386286462, 5535.640183388107, 5541.974734674058, 5571.721413929287, 5598.44015598441, 5612.130119831432, 6112.541559383064, 6119.938800611982, 6130.509559235251, 6138.294096178952, 6143.894099062167, 6018.247719035121, 6053.545984051214, 6101.247350682747, 6133.69331533426, 6163.506919446479, 5673.687947162863, 5736.913946290809, 5746.0, 5750.482456578844, 5763.625364351304, 5233.581313494911, 6210.441060304594, 6254.843628909222, 6262.968685156545, 6279.654618995908, 4855.295982082578, 5867.911981320283, 5939.613925094942, 5958.986972214734, 6041.697915104227, 6134.173184329662, 6144.5084393248435, 6147.877042459215, 6154.846128846814, 6168.691565421711, 6075.10641903688, 6107.603005804608, 6121.204243448323, 6129.846753831101, 6130.938690613037, 5886.470217680429, 5919.3192782829665, 5966.671833049224, 6043.788467403708, 6066.4540191382985, 4776.928346074812, 4793.856184314476, 4818.518148185153, 4820.228763397839, 4853.611711063107, 3629.9637003630155, 4329.783510824509, 4343.5439278875265, 4384.342348647665, 4391.451068616423, 5462.453754624586, 5587.580931430159, 5601.439856014366, 5615.213870058195, 5656.660600363992, 6092.69536523181, 6093.238345206849, 6109.7387008107025, 6114.66235542843, 6116.244470650255, 5555.3611334697025, 5611.382747897728, 5617.691026993472, 5633.2395126657375, 5639.6334238274385, 5306.0, 5308.601854860901, 5313.0967735485065, 5323.0, 5327.520523152857, 6135.906433916094, 6257.7809776658505, 6327.145835312174, 6343.3022367538615, 6347.365263473708, 6103.328633850362, 6126.242866754858, 6135.04893764628, 6138.940218748475, 6148.569600127948, 6266.373362663697, 6300.0, 6320.936790632035, 6326.240851097898, 6327.3988971047675, 5066.493350664904, 6089.330173680803, 6145.0, 6158.876822463615, 6169.598976066542, 5014.297998280316, 6002.939970600412, 6221.160143380585, 6233.252009758858, 6245.781397651061, 5926.140709597171, 6006.729697163551, 6008.459238668541, 6018.127371531016, 6055.72749226277, 5959.880802384015, 5980.5215582754145, 5986.102084687332, 5997.730102145327, 5998.460138587374, 5633.88732225345, 5714.628549144292, 5765.654060756368, 5780.653160810365, 5824.592278540462, 5939.2575928009, 6001.3698561652145, 6005.249343832021, 6011.27864656244, 6028.15605815195, 5806.44838740319, 5814.854628634318, 5823.970880145572, 5827.621204621686, 5835.153902684002], "value": 5562.306608933536, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.5052875033095, 523.5889826486208, 536.8738346488575, 551.5439240405328, 560.9678192126488, 23566.057357705693, 29188.0, 30249.546256806138, 31552.053438396826, 31809.840950795213, 40291.19417611646, 42967.78516107415, 43019.35470967938, 43070.353944690854, 43155.56844431555, 9730.562124704387, 9948.104670579647, 9955.253355998293, 9963.452010139476, 9993.850092248622, 8728.170823771732, 8894.332925030616, 9682.70951871445, 9831.31180817343, 11764.529418823236, 11553.826692599618, 11972.341521216329, 11972.401379931012, 11978.341191234478, 12009.819852702172, 11609.245399049078, 11622.418879056013, 11665.941670291679, 11694.707632309175, 11733.178677492579, 8645.53025985584, 8833.16084971933, 8847.73456796297, 8982.505962172077, 11853.88146118545, 39896.0, 40055.7997210015, 40067.0, 42680.35979460311, 42756.57243427557, 3672.9005797489585, 3730.0301921500336, 3796.1458671798623, 3979.0, 4058.208649313385, 31774.523382149287, 32700.836495817606, 33068.17329566756, 33090.17274568131, 33353.3329333412, 3175.857086431093, 3288.5724855768835, 3340.1482621931495, 3344.0302312329395, 3373.241020770307, 374050.0, 374946.0, 376144.0, 376404.0, 380554.1944580519, 59870.401295986485, 60178.097328540105, 60207.39792602018, 60216.698916506015, 60239.0, 62703.0, 63290.0, 63473.68263158655, 63510.0, 63534.046989295195, 16490.257938392802, 16870.409535666193, 16890.0, 16892.070936098386, 16910.239039243264, 13009.804852927213, 13009.869901301056, 13039.608811735663, 13052.54316098932, 13089.869101309056, 11997.700057498458, 13722.176669399669, 13920.02559820823, 14098.647533811736, 15767.0, 5890.852728681817, 6062.151298818169, 6350.714217860207, 6487.967560162169, 6524.151860258231, 837.5812093953024, 922.9294018938, 977.6773664690635, 1021.7241344837001, 1070.4915165296577, 25960.0, 26511.867440662674, 29306.413871722874, 30189.466365374763, 31154.376912462078, 833.204289903141, 833.7165363776223, 879.1120967822418, 941.9717408477757, 1059.8394757740261, 13796.448142074405, 15940.043597384194, 15979.920100399657, 15993.60015999586, 16255.349786008665, 693968.0, 696255.0, 699349.0, 702562.0, 705330.0, 59901.70049149814, 60069.0, 60182.79634407287, 60212.49468763229, 60315.39684603186, 1408.3732738931078, 1413.1309244814365, 1446.674498237888, 1447.5946734914241, 1450.3328468904422, 28770.814141234074, 29141.41717165687, 30749.23126921845, 30903.536446953764, 31016.0695179145, 51250.74374628103, 51295.74352128216, 51781.74109129581, 51813.0, 51839.481605183464, 624015.0, 627317.0, 628379.0, 629374.0, 629855.0, 41092.794536027126, 41297.587024129374, 42065.0, 42401.78799105985, 42564.57435425606, 48311.033779323516, 48528.0, 48971.265431019274, 49464.25803612877, 50055.49944500509, 40131.799341003105, 40199.19601607893, 40428.79785601053, 40871.18257634771, 41421.47917427053, 39606.80196598999, 40319.79840100781, 40550.39174412327, 40808.97977550585, 41283.1743365137, 36570.63429365672, 36758.08104797402, 38781.612183878926, 39388.803055984536, 39521.60478395179, 32537.0, 34270.48594271038, 34587.827060865544, 34643.0, 34720.82639586786, 25593.488130237663, 26102.73897261003, 26131.216063518124, 26373.868130659223, 26495.061477542517], "value": 20246.01691356103, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.2037314177392, 359.14523434226567, 389.5111634898202, 391.1551049732577, 407.5924075924076, 15162.848371516295, 15496.22518874056, 20628.48428789281, 20889.164433422684, 20906.37280881576, 14868.405263789435, 20953.0, 21288.57422851542, 21293.0, 21352.25267115651, 5438.3473983122085, 5590.91613625796, 5637.605367624268, 5638.097904335307, 5767.653940763546, 6183.690815459231, 6234.345393733638, 6251.437370636641, 6257.968710156443, 6268.059791031336, 6251.0, 6251.031090181006, 6373.330800265975, 6879.27767584404, 6881.0, 5394.433584473652, 6626.635535045571, 6818.249992500821, 6851.383375496204, 6868.797960356943, 6008.48927841134, 6050.455459008666, 6054.455099041107, 6055.455009049207, 6057.878842423171, 13635.590932272047, 13722.588322350346, 23356.83215839201, 24480.632790508156, 25800.483990320277, 2077.740282464692, 2274.613315736329, 2621.30535408117, 2937.6327959005125, 2961.570572267031, 9430.773999380106, 10030.348027378197, 10118.848217276747, 10226.89773102267, 10680.305780124345, 1713.4345665930214, 1887.6507846048526, 1890.6502297075085, 1921.8270355667917, 1966.9606607867763, 70336.0, 79997.60001200016, 115677.2648410283, 118020.22969655463, 127191.0, 10116.24128190374, 10190.796184076276, 11237.38194399316, 11456.713582160513, 11464.656060318204, 14646.560603181922, 14750.0, 14755.926220368829, 14756.114633122046, 14758.409663613334, 8545.401821872409, 8711.0, 8750.649974001097, 8801.911980880237, 8845.911540884637, 6975.441964642816, 8759.080296568896, 8788.780280493038, 8890.822183556293, 8923.41997770143, 4486.551344865487, 4561.68068235227, 4579.313103034505, 4601.677882548256, 4650.95349046512, 1560.6176486760817, 1920.3278852401827, 1921.9903900480688, 1923.086533896416, 1923.5960448305732, 843.3969711656196, 851.0723311590397, 877.9341549383822, 916.4547094478775, 978.1929907826072, 21500.139994400048, 21999.8713399941, 22144.33566992993, 22206.66689999651, 22295.0, 876.3733930239781, 882.7175303902818, 928.2805825485303, 985.9161971232382, 989.5997164012841, 10783.83824242637, 11006.009459148554, 11083.390413527171, 11475.139364547525, 11475.942620286845, 524132.0, 527161.0, 611602.0, 628656.0, 629178.0, 92109.69725756775, 131491.0, 140979.0, 141999.0, 146294.0, 749.3405802893528, 975.4586204656483, 999.1706883287, 1013.9188864890791, 1015.9085682288629, 19465.4160375189, 19702.546904920477, 19838.0, 20026.299079532146, 20056.598868022265, 48305.0, 57255.14117288161, 57432.13851792143, 59777.70111149416, 61214.0, 55311.0, 69963.65018174876, 70024.0, 79076.0, 85216.57391713002, 7956.880646790419, 8091.635876385459, 8105.594720264081, 8407.957960210404, 8433.241008309036, 9062.81874362496, 12611.93694031524, 13182.934085329513, 13274.402651880882, 13522.12106213093, 11243.988041076342, 11282.94358528202, 11445.42772861337, 11648.592299269485, 11671.066314694974, 10958.561657533928, 11127.443627818739, 11289.7742045157, 11352.94323528377, 11372.147088968195, 9327.393719408217, 11151.776964460827, 11508.42457877086, 11615.419229038684, 11775.587854425054, 7872.448928574945, 8434.746957591282, 8440.53577053268, 8475.321974241922, 8611.052784193858, 11206.271592346471, 11690.590829320932, 11876.406179691154, 11877.52489900429, 11878.643640690792], "value": 9586.45636147137, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.797096377471, 1585.6353038801076, 1778.7331900214965, 1901.838343740782, 1908.4370110817308, 8333.666653333867, 8592.35557333201, 8752.956235218815, 8769.693060742868, 8776.956115219431, 7656.885146722803, 7876.330511906494, 7909.327707144886, 8008.399370047241, 8578.227959483645, 2120.8833514156754, 2280.4070941555233, 2346.9647955280598, 2368.4197371643886, 2370.265217782493, 1932.5844943337179, 2331.8834058297102, 2332.0, 2354.3172479980785, 2357.3399448154546, 2097.0877668214375, 2363.89362478688, 2365.420471984366, 2371.0397289097855, 2372.632242002492, 2135.3380452059832, 2158.1583182558816, 2158.4603849037817, 2287.336672365012, 2357.1514254868234, 2360.681308023429, 2363.0902102690416, 2365.47959448921, 2368.0, 2368.135630494874, 8751.606177722018, 8815.074417186168, 8822.338324625614, 8835.734927952171, 8838.779030524223, 5144.819931302386, 5330.173823057432, 5332.626716129873, 5333.146696528537, 5344.7862085516535, 3436.604790449105, 3523.277728065766, 3608.4226523756074, 3616.9095772605633, 3623.2572322673786, 1073.9087177589836, 1076.6339444588878, 1085.5277954089918, 1093.316677076827, 1094.1738987064796, 11506.884931150666, 11520.963113319842, 11526.942365288203, 11532.077433805276, 11535.134864885167, 3256.544083828266, 4514.0, 4715.41057367829, 4715.575598196178, 4723.811047558059, 9708.271879608916, 9748.31761776683, 9749.415035097916, 9761.316707830525, 9764.804703905882, 5251.894962100813, 5257.658252213594, 5260.579153667697, 5268.2097685346735, 5270.578353731692, 4337.436133302714, 4472.642188624967, 4819.879503012453, 4835.347228124158, 4840.6127509799135, 3347.9163020924184, 3684.3920753075977, 3887.980560097238, 3950.20995800841, 3976.2445135424177, 1234.2224398628807, 1264.2363902356346, 1268.6447794617523, 1275.3049587974576, 1278.5013844600612, 793.3137835772131, 825.8843761873343, 868.5006121480047, 888.8622263549161, 1002.4436437777103, 6119.785807496716, 6125.448709616156, 6168.0, 6229.0, 6349.206349206349, 971.5725080964353, 997.8104160209537, 1000.0699349604791, 1005.7334806276342, 1030.2736570717625, 4006.579309172553, 4080.9795951020646, 4107.794610269475, 4117.0, 4160.479940007499, 10173.694789156338, 10529.84205236922, 10611.628592999208, 10625.893741062646, 10628.574857005788, 9964.05341492557, 10511.369317840954, 10578.841317380246, 10582.417967011888, 10608.469576521144, 8021.759347219593, 8078.3133433658795, 8249.752507424786, 8258.42191046633, 8260.215279548433, 6037.939620603738, 6109.75560977556, 6236.563440559117, 6279.152314437675, 6371.0, 9520.333576649567, 9651.758706032124, 9953.800923981335, 9976.401415915068, 10047.14599259042, 9772.706818795448, 10022.849657255001, 10104.898951010397, 10139.64511242103, 10143.949280253846, 6353.237611486466, 6994.790156295319, 7165.964170179116, 7251.709931602677, 7555.622218888923, 7086.893696594452, 8204.548749818818, 8414.579271036546, 8427.325813934993, 8447.788805279672, 7228.89156662661, 7363.889541656987, 7366.226546212571, 7479.326860582356, 7535.6985720570565, 7200.063991681048, 7252.746153884589, 7337.449691273069, 7356.445614349793, 7504.812379690333, 7041.049458323061, 7077.504574679723, 7079.893801592877, 7080.610566418896, 7118.181409138016, 4868.245421959602, 4982.0, 4989.975050124726, 5006.574441172541, 5087.592992560661, 3971.007248187953, 4742.146413645577, 4993.176125939324, 5127.87180320495, 5247.790088396421], "value": 4556.492408150526, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.52868658131814, 303.9665636779955, 308.22788913771006, 319.37721443185785, 323.629444286292, 5202.361256204293, 5332.253484512172, 5337.412884582693, 5389.51494365507, 5434.34787825461, 4191.727537710047, 4321.178975994567, 5147.305113809634, 5288.127458969266, 5364.532315135033, 3722.962770372302, 4040.8585699500522, 4077.3476243800997, 4078.653314468259, 4092.731253311468, 3717.8512859485586, 3937.370020796673, 4115.897102572445, 4116.9794151029355, 4128.649064829493, 3151.9913627639166, 3851.4607954886337, 4053.1893621275717, 4059.7970101494957, 4118.8970275743195, 4046.372812214097, 4065.26825171469, 4067.755934643901, 4070.104576993057, 4124.360724087741, 3113.6574976752768, 3199.0, 3262.9395446479753, 3455.6825447570363, 3570.0539357070397, 4163.333866581333, 4525.886852828673, 4539.933115717804, 4970.583383735654, 5357.196420536911, 4622.8150873965005, 4637.420322459692, 4642.466116396624, 4677.88305292367, 4677.95322046782, 4955.677880937764, 5306.389765176977, 5505.559555235572, 5573.6098473106895, 5614.747336369873, 3596.4245720684567, 3842.3275926712713, 3859.5754467008605, 3861.942070868939, 3870.2259548090215, 5092.567131793802, 5857.920438244447, 5918.425484914076, 5947.732352044169, 5952.9107063394085, 3086.3518661080975, 3324.7838890472044, 3449.7930124192126, 3799.2971300308977, 3939.1727737175515, 3425.9314813703586, 3805.486259355007, 3968.9206215876097, 3992.900177495586, 4050.886006348278, 4250.978745106255, 4708.23341326937, 4711.740854253049, 4718.9382388962895, 4727.503612120678, 5336.893262134736, 5657.409118377127, 5731.077150653196, 5759.712014399263, 5764.394738552392, 1113.4265853085667, 1245.3212998915612, 1318.4198952460886, 1387.8195834541445, 1411.470698488067, 819.71310041485, 874.064750716727, 958.9355815045294, 966.2028826218258, 967.4775621164633, 4413.6248418884115, 4804.447488538793, 5137.147139645266, 5143.482672611589, 5199.194124910645, 5202.557782588447, 5450.427705090988, 5659.971700141473, 5691.0, 5729.226554415184, 4192.979035104866, 4622.815087396467, 5033.7734801934, 5062.265971434122, 5151.5363617274625, 2191.1783081344497, 2486.5772818620876, 2533.2780157655347, 2555.4378036831868, 2581.438229870939, 4566.74882881438, 4623.0, 4694.0, 4959.0, 5004.874878128003, 4445.8875540125255, 4663.836765713184, 5472.808451704172, 6055.394460553909, 6062.818115456544, 5475.397706252229, 5560.721963901708, 6022.747716233185, 6068.969655151696, 6075.539406540358, 4789.4971028041555, 5633.2395126657375, 5860.2967643883, 5866.853328666817, 5885.14570433348, 5736.168255602997, 5745.339285981999, 5794.18881356602, 5796.710164491843, 5804.158397032491, 4660.370849935215, 5363.597730170175, 5984.311804142405, 6033.87932241344, 6051.152838602688, 3395.2530443302435, 3636.3999940009603, 3747.494088298154, 3868.226354729009, 3886.630770076838, 4535.591796738177, 5106.106431374569, 5331.333583302087, 5715.257016587983, 5748.252727145612, 4590.954090459053, 4866.48901865299, 4946.62900282473, 5222.3472065991755, 5551.8056868011045, 4763.237881938873, 5287.788488460418, 5380.811671591476, 5534.92069046532, 5617.354004289559, 5512.944870551243, 5556.611037227517, 5562.527185189304, 5573.749181286933, 5579.656544251506, 4948.727819969792, 5308.7172515818565, 5646.181303710857, 5653.689047102449, 5701.515371193329, 5271.285288076567, 5474.671519708831, 5514.200440936122, 5529.861753456195, 5532.474414930575], "value": 4017.0607153814453, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.4135547163779, 756.1682149635402, 780.8789637606174, 782.9765107046791, 825.5500752090111, 14910.329035193416, 15660.13869237193, 16255.268512916917, 17421.390251341207, 18030.909845450788, 17239.224234909427, 17266.482005539823, 17412.216450259737, 17425.30298788047, 17519.82480175198, 696.8257935516122, 720.6756959368279, 861.0313397427893, 881.3081730841291, 907.8955920069177, 884.186548375496, 885.2386947225414, 889.4040992534984, 892.2148509311796, 892.830362231174, 944.7165850244934, 944.7968686732352, 947.0340252941995, 947.5735918836517, 948.5826236455937, 725.4957804325995, 896.7175339767953, 925.1673493855517, 935.5415846235296, 935.9017303183135, 705.3757424679145, 707.5153519838926, 708.7944496096126, 710.0981753173493, 710.4174576846988, 15310.693786124215, 15516.224188790515, 15528.456504022304, 15544.61138471536, 15565.299561519645, 13762.862371376259, 13829.51596694121, 13845.653858653513, 13860.306984650828, 13933.372998215105, 13101.410436530283, 13330.200187988752, 13520.797188042186, 13757.862421375758, 13768.518101866486, 4491.528389519119, 4636.072785442925, 4751.667383283171, 4772.952270477285, 4793.017431426584, 111432.0, 112434.0, 112880.43559782149, 114056.85943140545, 114288.0, 10813.243072984973, 10980.890191098146, 11111.55553777856, 11315.03822175108, 11874.940625296818, 22920.0, 23296.95163717603, 23326.0, 23330.53338933212, 23385.532289354116, 8101.594920253964, 8148.388870834711, 8200.26197642203, 8376.664933402717, 8426.831463370698, 4378.10248898975, 4475.731456112644, 4494.775261236925, 4540.296254080623, 4694.389729335165, 3521.8239088045493, 3639.836207370675, 3647.7264205184715, 3650.8357123929486, 3653.6163702811355, 671.4460570029647, 679.37497502298, 681.3016657925666, 695.9652017399211, 698.2458944340101, 561.8061619059498, 626.4455956478549, 651.9641419721961, 653.9640319782362, 673.6901025528214, 7985.560794156378, 8665.263452606472, 8935.821283574292, 9231.723048308562, 9294.767630809283, 588.417466707956, 603.3272900715671, 606.1714782969848, 617.7868635320807, 637.2575949019433, 3298.802071875695, 4535.750533720611, 4539.6368290536675, 4577.771111444415, 4655.906881862344, 84655.0, 117366.0, 154220.22889885702, 160711.0, 164150.0, 99120.0, 113846.72306553823, 131376.0, 137195.0, 140329.0, 72688.63655681688, 74261.0, 80886.0, 88639.0, 100514.4974275124, 9465.574049167943, 9482.900546284462, 9637.325387222827, 9729.027097290214, 9792.80414391694, 59086.40913590809, 59123.0, 63538.0, 65569.67215163894, 65713.0, 96943.0, 98602.0, 98994.0, 99946.0, 103375.0, 13164.539241126515, 13322.333883305602, 13338.666533336744, 13496.392662330023, 13806.17162970225, 14138.43446262138, 14148.0, 14268.714625707635, 14528.346224419714, 14989.77515337249, 13742.381592828147, 13878.097923634932, 13890.930545347208, 13891.305434728021, 14081.859181408056, 13967.371468283749, 14001.719965600427, 14287.142771433748, 14321.785173222203, 14439.92780036093, 13512.25682587467, 13687.589372318846, 13791.793123102962, 14160.008799383944, 14296.0, 10585.52365143555, 10920.890791091988, 10963.287386319864, 11170.944145279222, 11380.658580242605, 10910.563577456813, 11068.059214966817, 11279.605213817476, 11297.039751621205, 11300.547978080785], "value": 7966.986216082377, "unit": "score"}], "name": "dromaeo_css", "value": 7348.335565679629, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 112.0, 111.0, 113.0, 112.0, 111.0, 112.0, 110.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 107.0, 109.0, 159.0, 106.0, 107.0, 129.0, 107.0, 140.0, 109.0], "value": 121.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 187.0, 183.0, 182.0, 180.0, 181.0, 184.0, 181.0, 185.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 59.0, 124.0, 81.0, 82.0, 71.0, 61.0, 83.0, 57.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 93.0, 70.0, 70.0, 68.0, 97.0, 69.0, 67.0, 100.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 87.0, 135.0, 84.0, 88.0, 85.0, 87.0, 83.0], "value": 90.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 93.0, 93.0, 94.0, 97.0, 94.0, 101.0, 93.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 84.0, 84.0, 85.0, 84.0, 82.0, 85.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 56.0, 56.0, 57.0, 55.0, 54.0, 54.0, 56.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 80.0, 77.0, 71.0, 69.0, 69.0, 72.0, 70.0, 89.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 80.0, 83.0, 80.0, 80.0, 84.0, 84.0, 79.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 145.0, 144.0, 135.0, 133.0, 133.0, 137.0, 135.0, 219.0, 150.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 163.0, 162.0, 213.0, 191.0, 163.0, 164.0, 163.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 65.0, 65.0, 64.0, 65.0, 66.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1436.6000000000001, "alertThreshold": 2.0}]} 05:58:44 INFO - Return code: 0 05:58:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:58:44 INFO - # TBPL SUCCESS # 05:58:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:58:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:58:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:58:44 INFO - 2016-07-13 05:58:44.223 screenresolution[1665:9613] starting screenresolution argv=screenresolution get 05:58:44 INFO - 2016-07-13 05:58:44.226 screenresolution[1665:9613] Display 0: 1600x1200x32@60 05:58:44 INFO - 2016-07-13 05:58:44.232 screenresolution[1666:9616] starting screenresolution argv=screenresolution list 05:58:44 INFO - Available Modes on Display 0 05:58:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:58:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:58:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:58:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:58:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:58:44 INFO - Intel Iris: 05:58:44 INFO - Chipset Model: Intel Iris 05:58:44 INFO - Type: GPU 05:58:44 INFO - Bus: Built-In 05:58:44 INFO - VRAM (Dynamic, Max): 1536 MB 05:58:44 INFO - Vendor: Intel (0x8086) 05:58:44 INFO - Device ID: 0x0a2e 05:58:44 INFO - Revision ID: 0x0009 05:58:44 INFO - Displays: 05:58:44 INFO - Display: 05:58:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:58:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:58:44 INFO - Display Serial Number: 1600x1200 60 05:58:44 INFO - Main Display: Yes 05:58:44 INFO - Mirror: Off 05:58:44 INFO - Online: Yes 05:58:44 INFO - Rotation: Supported 05:58:44 INFO - Return code: 0 05:58:44 INFO - Running post-action listener: _resource_record_post_action 05:58:44 INFO - ##### 05:58:44 INFO - ##### Finished run-tests step (success) 05:58:44 INFO - ##### 05:58:44 INFO - Running post-run listener: _resource_record_post_run 05:58:44 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 38053376; Write bytes: 705176576; Read time: 391; Write time: 2018 05:58:44 INFO - TinderboxPrint: CPU usage
53.3% 05:58:44 INFO - TinderboxPrint: I/O read bytes / time
38,053,376 / 391 05:58:44 INFO - TinderboxPrint: I/O write bytes / time
705,176,576 / 2,018 05:58:44 INFO - TinderboxPrint: CPU idle
2,266.4 (46.7%) 05:58:44 INFO - TinderboxPrint: CPU system
963.5 (19.9%) 05:58:44 INFO - TinderboxPrint: CPU user
1,619.7 (33.4%) 05:58:44 INFO - TinderboxPrint: Swap in / out
444,645,376 / 0 05:58:44 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 386036224; Write bytes: 381052416; Read time: 29229; Write time: 794 05:58:44 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 37086720; Write bytes: 294747648; Read time: 375; Write time: 1170 05:58:44 INFO - Running post-run listener: _upload_blobber_files 05:58:44 INFO - Blob upload gear active. 05:58:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:58:44 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 05:58:44 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:58:44 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:58:45 INFO - (blobuploader) - INFO - Open directory for files ... 05:58:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:58:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:58:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:46 INFO - (blobuploader) - INFO - Done attempting. 05:58:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:58:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:58:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:47 INFO - (blobuploader) - INFO - Done attempting. 05:58:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:58:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:58:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:49 INFO - (blobuploader) - INFO - Done attempting. 05:58:49 INFO - (blobuploader) - INFO - Iteration through files over. 05:58:49 INFO - Return code: 0 05:58:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:58:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:58:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45e5a20c0c4b5bf2e44c805ea983782eca9387823a924a28b49ea0874fe024ff9fa158018c91314013b93831cd083ba6efdf4adfce759595d2a06c7554871796", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35f7f56a6d2e9f7bb8e4e13c62cfe73211400c07b6157e78288cbbf894091ab0fdaeb31b97a9c20b3026053aae636307dc2665c10ac8bcfef96e659675efa35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2b4a9483073836047ebee3ce2e5366081d8b28b574aaf861c7a2af9556237bcaadb885b91a0dbb66092dbb652368fde6be7afaab946aab3a9c1fabab68f4940"} 05:58:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:58:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:58:49 INFO - Contents: 05:58:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45e5a20c0c4b5bf2e44c805ea983782eca9387823a924a28b49ea0874fe024ff9fa158018c91314013b93831cd083ba6efdf4adfce759595d2a06c7554871796", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35f7f56a6d2e9f7bb8e4e13c62cfe73211400c07b6157e78288cbbf894091ab0fdaeb31b97a9c20b3026053aae636307dc2665c10ac8bcfef96e659675efa35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2b4a9483073836047ebee3ce2e5366081d8b28b574aaf861c7a2af9556237bcaadb885b91a0dbb66092dbb652368fde6be7afaab946aab3a9c1fabab68f4940"} 05:58:49 INFO - Running post-run listener: copy_logs_to_upload_dir 05:58:49 INFO - Copying logs to upload dir... 05:58:49 INFO - mkdir: /builds/slave/test/build/upload/logs 05:58:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.500018 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-13 05:58:49.209775) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 05:58:49.212972) ========= 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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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/Firefox/sha512/45e5a20c0c4b5bf2e44c805ea983782eca9387823a924a28b49ea0874fe024ff9fa158018c91314013b93831cd083ba6efdf4adfce759595d2a06c7554871796", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35f7f56a6d2e9f7bb8e4e13c62cfe73211400c07b6157e78288cbbf894091ab0fdaeb31b97a9c20b3026053aae636307dc2665c10ac8bcfef96e659675efa35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2b4a9483073836047ebee3ce2e5366081d8b28b574aaf861c7a2af9556237bcaadb885b91a0dbb66092dbb652368fde6be7afaab946aab3a9c1fabab68f4940"} build_url:https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010361 build_url: 'https://queue.taskcluster.net/v1/task/SKUnjT-jSdOK7119QKEdcw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45e5a20c0c4b5bf2e44c805ea983782eca9387823a924a28b49ea0874fe024ff9fa158018c91314013b93831cd083ba6efdf4adfce759595d2a06c7554871796", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35f7f56a6d2e9f7bb8e4e13c62cfe73211400c07b6157e78288cbbf894091ab0fdaeb31b97a9c20b3026053aae636307dc2665c10ac8bcfef96e659675efa35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2b4a9483073836047ebee3ce2e5366081d8b28b574aaf861c7a2af9556237bcaadb885b91a0dbb66092dbb652368fde6be7afaab946aab3a9c1fabab68f4940"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 05:58:49.242951) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 05:58:49.243244) ========= 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.G0kMqIMIgw/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.1R9f8Y2YO2/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.004843 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 05:58:49.291112) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 05:58:49.291398) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 05:58:49.291694) ========= ========= Total master_lag: 3.43 =========