builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0154 starttime: 1461500036.77 results: success (0) buildid: 20160424030601 builduid: 6c665d98c5ae4206b8a1aabef46f1129 revision: ab8a76ac7b34db20dd3c220c9846826334f3efdc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:56.766611) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:56.767069) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:56.767413) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:57.109569) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:57.109971) ========= 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.hOJRRyb0oD/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.3ALX3lcJg1/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-04-24 05:13:57-- 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% 8.19M=0.001s 2016-04-24 05:13:57 (8.19 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117921 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:57.302982) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:57.303354) ========= 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.hOJRRyb0oD/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.3ALX3lcJg1/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.020175 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:57.416668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-24 05:13:57.417023) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ab8a76ac7b34db20dd3c220c9846826334f3efdc --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 ab8a76ac7b34db20dd3c220c9846826334f3efdc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hOJRRyb0oD/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.3ALX3lcJg1/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-04-24 05:13:57,493 truncating revision to first 12 chars 2016-04-24 05:13:57,494 Setting DEBUG logging. 2016-04-24 05:13:57,494 attempt 1/10 2016-04-24 05:13:57,494 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ab8a76ac7b34?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-24 05:13:58,498 unpacking tar archive at: mozilla-central-ab8a76ac7b34/testing/mozharness/ program finished with exit code 0 elapsedTime=1.254927 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-24 05:13:58.716628) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:58.716957) ========= script_repo_revision: ab8a76ac7b34db20dd3c220c9846826334f3efdc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:58.717341) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:58.717607) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 05:13:58.954849) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-04-24 05:13:58.955111) ========= /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.hOJRRyb0oD/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.3ALX3lcJg1/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:13:59 INFO - MultiFileLogger online at 20160424 05:13:59 in /builds/slave/test 05:13:59 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:13:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:13:59 INFO - {'append_to_log': False, 05:13:59 INFO - 'base_work_dir': '/builds/slave/test', 05:13:59 INFO - 'blob_upload_branch': 'Firefox', 05:13:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:13:59 INFO - 'branch': 'Firefox', 05:13:59 INFO - 'buildbot_json_path': 'buildprops.json', 05:13:59 INFO - 'config_files': ('talos/mac_config.py',), 05:13:59 INFO - 'default_actions': ('clobber', 05:13:59 INFO - 'read-buildbot-config', 05:13:59 INFO - 'download-and-extract', 05:13:59 INFO - 'populate-webroot', 05:13:59 INFO - 'create-virtualenv', 05:13:59 INFO - 'install', 05:13:59 INFO - 'run-tests'), 05:13:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:13:59 INFO - 'download_minidump_stackwalk': True, 05:13:59 INFO - 'download_symbols': 'ondemand', 05:13:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:13:59 INFO - 'tooltool.py': '/tools/tooltool.py', 05:13:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:13:59 INFO - '/tools/misc-python/virtualenv.py')}, 05:13:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:13:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:13:59 INFO - 'installer_path': 'installer.exe', 05:13:59 INFO - 'log_level': 'info', 05:13:59 INFO - 'log_name': 'talos', 05:13:59 INFO - 'log_to_console': True, 05:13:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:13:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:13:59 INFO - 'opt_config_files': (), 05:13:59 INFO - 'pip_index': False, 05:13:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:13:59 INFO - 'cmd': ('bash', 05:13:59 INFO - '-c', 05:13:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:13:59 INFO - 'enabled': True, 05:13:59 INFO - 'halt_on_failure': False, 05:13:59 INFO - 'name': 'check_screen_resolution'},), 05:13:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:13:59 INFO - 'cmd': ('bash', 05:13:59 INFO - '-c', 05:13:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:13:59 INFO - 'enabled': True, 05:13:59 INFO - 'halt_on_failure': False, 05:13:59 INFO - 'name': 'check_screen_resolution'},), 05:13:59 INFO - 'run_cmd_checks_enabled': True, 05:13:59 INFO - 'sps_profile': False, 05:13:59 INFO - 'sps_profile_interval': 0, 05:13:59 INFO - 'suite': 'dromaeojs', 05:13:59 INFO - 'system_bits': '32', 05:13:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:13:59 INFO - 'title': 't-yosemite-r7-0154', 05:13:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:13:59 INFO - 'use_talos_json': True, 05:13:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:13:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:13:59 INFO - 'work_dir': 'build'} 05:13:59 INFO - ##### 05:13:59 INFO - ##### Running clobber step. 05:13:59 INFO - ##### 05:13:59 INFO - Running pre-action listener: _resource_record_pre_action 05:13:59 INFO - Running main action method: clobber 05:13:59 INFO - rmtree: /builds/slave/test/build 05:13:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:13:59 INFO - Running post-action listener: _resource_record_post_action 05:13:59 INFO - ##### 05:13:59 INFO - ##### Running read-buildbot-config step. 05:13:59 INFO - ##### 05:13:59 INFO - Running pre-action listener: _resource_record_pre_action 05:13:59 INFO - Running main action method: read_buildbot_config 05:13:59 INFO - Using buildbot properties: 05:13:59 INFO - { 05:13:59 INFO - "project": "", 05:13:59 INFO - "product": "firefox", 05:13:59 INFO - "script_repo_revision": "production", 05:13:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:13:59 INFO - "repository": "", 05:13:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:13:59 INFO - "buildid": "20160424030601", 05:13:59 INFO - "stage_platform": "macosx64", 05:13:59 INFO - "basedir": "/builds/slave/test", 05:13:59 INFO - "nightly_build": "True", 05:13:59 INFO - "buildnumber": 229, 05:13:59 INFO - "slavename": "t-yosemite-r7-0154", 05:13:59 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:13:59 INFO - "platform": "yosemite_r7", 05:13:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:13:59 INFO - "branch": "mozilla-central", 05:13:59 INFO - "repo_path": "mozilla-central", 05:13:59 INFO - "revision": "ab8a76ac7b34db20dd3c220c9846826334f3efdc", 05:13:59 INFO - "pgo_build": "False", 05:13:59 INFO - "builduid": "6c665d98c5ae4206b8a1aabef46f1129", 05:13:59 INFO - "slavebuilddir": "test" 05:13:59 INFO - } 05:13:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:13:59 INFO - Running post-action listener: _resource_record_post_action 05:13:59 INFO - ##### 05:13:59 INFO - ##### Running download-and-extract step. 05:13:59 INFO - ##### 05:13:59 INFO - Running pre-action listener: _resource_record_pre_action 05:13:59 INFO - Running main action method: download_and_extract 05:13:59 INFO - mkdir: /builds/slave/test/build/tests 05:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:59 INFO - https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:13:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:13:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:13:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:13:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:14:01 INFO - Downloaded 1199 bytes. 05:14:01 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:14:01 INFO - Using the following test package requirements: 05:14:01 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:14:01 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:01 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:14:01 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:01 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:14:01 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:01 INFO - u'jsshell-mac.zip'], 05:14:01 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:01 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:14:01 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:14:01 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:01 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:14:01 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:01 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:14:01 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:01 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:14:01 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:01 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:14:01 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:14:01 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:14:01 INFO - https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:14:08 INFO - Downloaded 18109724 bytes. 05:14:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:14:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:14:11 INFO - Return code: 0 05:14:11 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:14:11 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:14:11 INFO - https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:14:15 INFO - Downloaded 18109724 bytes. 05:14:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:14:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:14:18 INFO - Return code: 0 05:14:18 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:14:18 INFO - https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:14:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:14:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:14:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:14:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:14:20 INFO - Downloaded 10840145 bytes. 05:14:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:14:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:14:20 INFO - Return code: 0 05:14:20 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:14:20 INFO - https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:14:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:14:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:14:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:14:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:14:27 INFO - Downloaded 110237223 bytes. 05:14:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:14:27 INFO - mkdir: /builds/slave/test/properties 05:14:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:14:27 INFO - Writing to file /builds/slave/test/properties/build_url 05:14:27 INFO - Contents: 05:14:27 INFO - build_url:https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:14:28 INFO - Running post-action listener: _resource_record_post_action 05:14:28 INFO - Running post-action listener: set_extra_try_arguments 05:14:28 INFO - ##### 05:14:28 INFO - ##### Running populate-webroot step. 05:14:28 INFO - ##### 05:14:28 INFO - Running pre-action listener: _resource_record_pre_action 05:14:28 INFO - Running main action method: populate_webroot 05:14:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:14:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:14:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:14:28 INFO - '--tppagecycles', 05:14:28 INFO - '7'], 05:14:28 INFO - 'tests': ['tsvgm']}}, 05:14:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:14:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:14:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:14:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:14:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:28 INFO - 'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:28 INFO - 'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['damp', 'tps']}, 05:14:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:28 INFO - 'tests': ['damp', 'tps']}, 05:14:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['dromaeo_dom']}, 05:14:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:14:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['a11yr', 05:14:28 INFO - 'ts_paint', 05:14:28 INFO - 'tpaint', 05:14:28 INFO - 'sessionrestore', 05:14:28 INFO - 'sessionrestore_no_auto_restore', 05:14:28 INFO - 'tabpaint']}, 05:14:28 INFO - 'other-e10s': {'tests': ['a11yr', 05:14:28 INFO - 'ts_paint', 05:14:28 INFO - 'tpaint', 05:14:28 INFO - 'sessionrestore', 05:14:28 INFO - 'sessionrestore_no_auto_restore', 05:14:28 INFO - 'tabpaint']}, 05:14:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:14:28 INFO - 'ts_paint', 05:14:28 INFO - 'tpaint', 05:14:28 INFO - 'sessionrestore', 05:14:28 INFO - 'sessionrestore_no_auto_restore', 05:14:28 INFO - 'tabpaint']}, 05:14:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:14:28 INFO - 'ts_paint', 05:14:28 INFO - 'tpaint', 05:14:28 INFO - 'sessionrestore', 05:14:28 INFO - 'sessionrestore_no_auto_restore', 05:14:28 INFO - 'tabpaint']}, 05:14:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['a11yr', 05:14:28 INFO - 'ts_paint', 05:14:28 INFO - 'tpaint', 05:14:28 INFO - 'sessionrestore', 05:14:28 INFO - 'sessionrestore_no_auto_restore', 05:14:28 INFO - 'tabpaint']}, 05:14:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['a11yr', 05:14:28 INFO - 'ts_paint', 05:14:28 INFO - 'tpaint', 05:14:28 INFO - 'sessionrestore', 05:14:28 INFO - 'sessionrestore_no_auto_restore', 05:14:28 INFO - 'tabpaint']}, 05:14:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['tsvgx', 05:14:28 INFO - 'tsvgr_opacity', 05:14:28 INFO - 'tart', 05:14:28 INFO - 'tscrollx', 05:14:28 INFO - 'cart']}, 05:14:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:14:28 INFO - 'tsvgr_opacity', 05:14:28 INFO - 'tart', 05:14:28 INFO - 'tscrollx', 05:14:28 INFO - 'cart']}, 05:14:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:28 INFO - 'talos_options': ['--disable-e10s'], 05:14:28 INFO - 'tests': ['tp5o']}, 05:14:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:28 INFO - 'tests': ['tp5o']}, 05:14:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:28 INFO - 'talos_options': ['--disable-e10s', 05:14:28 INFO - '--xperf_path', 05:14:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:28 INFO - 'tests': ['tp5n']}, 05:14:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:28 INFO - 'talos_options': ['--xperf_path', 05:14:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:28 INFO - 'tests': ['tp5n']}}, 05:14:28 INFO - 'talos.zip': {'path': '', 05:14:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:14:28 INFO - Running post-action listener: _resource_record_post_action 05:14:28 INFO - ##### 05:14:28 INFO - ##### Running create-virtualenv step. 05:14:28 INFO - ##### 05:14:28 INFO - Running pre-action listener: _resource_record_pre_action 05:14:28 INFO - Running main action method: create_virtualenv 05:14:28 INFO - Creating virtualenv /builds/slave/test/build/venv 05:14:28 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:14:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:14:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:14:28 INFO - Using real prefix '/tools/python27' 05:14:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:14:29 INFO - Installing distribute.............................................................................................................................................................................................done. 05:14:31 INFO - Installing pip.................done. 05:14:31 INFO - Return code: 0 05:14:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:14: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:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14: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:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e8c65f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, '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.hOJRRyb0oD/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.3ALX3lcJg1/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:14:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:14:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:14:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:31 INFO - 'HOME': '/Users/cltbld', 05:14:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:31 INFO - 'LOGNAME': 'cltbld', 05:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:31 INFO - 'MOZ_NO_REMOTE': '1', 05:14:31 INFO - 'NO_EM_RESTART': '1', 05:14:31 INFO - 'PAGER': '/bin/cat', 05:14:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:31 INFO - 'PWD': '/builds/slave/test', 05:14:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:31 INFO - 'SHELL': '/bin/bash', 05:14:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:14:31 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:14:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:31 INFO - 'USER': 'cltbld', 05:14:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:31 INFO - 'XPC_FLAGS': '0x0', 05:14:31 INFO - 'XPC_SERVICE_NAME': '0', 05:14:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:32 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:14:32 INFO - Cleaning up... 05:14:32 INFO - Return code: 0 05:14:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:14:32 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:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:32 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:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:32 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e8c65f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, '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.hOJRRyb0oD/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.3ALX3lcJg1/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:14:32 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>=0.7.1'] in /builds/slave/test/build 05:14:32 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>=0.7.1 05:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:32 INFO - 'HOME': '/Users/cltbld', 05:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:32 INFO - 'LOGNAME': 'cltbld', 05:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:32 INFO - 'MOZ_NO_REMOTE': '1', 05:14:32 INFO - 'NO_EM_RESTART': '1', 05:14:32 INFO - 'PAGER': '/bin/cat', 05:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:32 INFO - 'PWD': '/builds/slave/test', 05:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:32 INFO - 'SHELL': '/bin/bash', 05:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:14:32 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:32 INFO - 'USER': 'cltbld', 05:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:32 INFO - 'XPC_FLAGS': '0x0', 05:14:32 INFO - 'XPC_SERVICE_NAME': '0', 05:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:32 INFO - Downloading/unpacking psutil>=0.7.1 05:14:32 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:14:32 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:14:32 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:14:32 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:14:32 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:14:32 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:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:14:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:35 INFO - Installing collected packages: psutil 05:14:35 INFO - Running setup.py install for psutil 05:14:35 INFO - building 'psutil._psutil_osx' extension 05:14:35 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:14:35 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:14:35 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:14:36 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:14:36 INFO - building 'psutil._psutil_posix' extension 05:14:36 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:14:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:14:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:14:36 INFO - ^ 05:14:36 INFO - 1 warning generated. 05:14:36 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:14:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:36 INFO - Successfully installed psutil 05:14:36 INFO - Cleaning up... 05:14:36 INFO - Return code: 0 05:14:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:36 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e8c65f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, '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.hOJRRyb0oD/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.3ALX3lcJg1/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:14:36 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.0.0'] in /builds/slave/test/build 05:14:36 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.0.0 05:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:36 INFO - 'HOME': '/Users/cltbld', 05:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:36 INFO - 'LOGNAME': 'cltbld', 05:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:36 INFO - 'MOZ_NO_REMOTE': '1', 05:14:36 INFO - 'NO_EM_RESTART': '1', 05:14:36 INFO - 'PAGER': '/bin/cat', 05:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:36 INFO - 'PWD': '/builds/slave/test', 05:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:36 INFO - 'SHELL': '/bin/bash', 05:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:14:36 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:36 INFO - 'USER': 'cltbld', 05:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:36 INFO - 'XPC_FLAGS': '0x0', 05:14:36 INFO - 'XPC_SERVICE_NAME': '0', 05:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:14:36 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:14:36 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:14:36 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:14:36 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:14:36 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:14:36 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:14:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:14:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:14:39 INFO - Installing collected packages: mozsystemmonitor 05:14:39 INFO - Running setup.py install for mozsystemmonitor 05:14:39 INFO - Successfully installed mozsystemmonitor 05:14:39 INFO - Cleaning up... 05:14:39 INFO - Return code: 0 05:14:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:14:39 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:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:39 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:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:39 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 0x10c7e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e8c65f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, '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.hOJRRyb0oD/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.3ALX3lcJg1/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:14:39 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:14:39 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:14:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:14:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:39 INFO - 'HOME': '/Users/cltbld', 05:14:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:39 INFO - 'LOGNAME': 'cltbld', 05:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:39 INFO - 'MOZ_NO_REMOTE': '1', 05:14:39 INFO - 'NO_EM_RESTART': '1', 05:14:39 INFO - 'PAGER': '/bin/cat', 05:14:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:39 INFO - 'PWD': '/builds/slave/test', 05:14:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:39 INFO - 'SHELL': '/bin/bash', 05:14:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:14:39 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:14:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:39 INFO - 'USER': 'cltbld', 05:14:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:39 INFO - 'XPC_FLAGS': '0x0', 05:14:39 INFO - 'XPC_SERVICE_NAME': '0', 05:14:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:39 INFO - Downloading/unpacking blobuploader==1.2.4 05:14:39 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:14:39 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:14:39 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:14:39 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:14:39 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:14:39 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:14:41 INFO - Downloading blobuploader-1.2.4.tar.gz 05:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:14:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:14:42 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:14:42 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:14:42 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:14:42 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:14:42 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:14:42 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:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:14:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:14:42 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:14:42 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:14:42 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:14:42 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:14:42 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:14:42 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:14:43 INFO - Downloading docopt-0.6.1.tar.gz 05:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:14:43 INFO - Installing collected packages: blobuploader, requests, docopt 05:14:43 INFO - Running setup.py install for blobuploader 05:14:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:14:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:14:43 INFO - Running setup.py install for requests 05:14:43 INFO - Running setup.py install for docopt 05:14:43 INFO - Successfully installed blobuploader requests docopt 05:14:43 INFO - Cleaning up... 05:14:44 INFO - Return code: 0 05:14:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:44 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:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:44 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:14:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:44 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 0x10c7e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e8c65f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, '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.hOJRRyb0oD/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.3ALX3lcJg1/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:14:44 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:14:44 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:14:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:14:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:44 INFO - 'HOME': '/Users/cltbld', 05:14:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:44 INFO - 'LOGNAME': 'cltbld', 05:14:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:44 INFO - 'MOZ_NO_REMOTE': '1', 05:14:44 INFO - 'NO_EM_RESTART': '1', 05:14:44 INFO - 'PAGER': '/bin/cat', 05:14:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:44 INFO - 'PWD': '/builds/slave/test', 05:14:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:44 INFO - 'SHELL': '/bin/bash', 05:14:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:14:44 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:14:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:44 INFO - 'USER': 'cltbld', 05:14:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:44 INFO - 'XPC_FLAGS': '0x0', 05:14:44 INFO - 'XPC_SERVICE_NAME': '0', 05:14:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-jLAX5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-BUDKL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-NQ6zSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Jg4Qon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-IRRY4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-bU5IQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:44 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-yR0E3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-XMDxAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Jg4DsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-JKn87m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Uy7kKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-FlUfC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-segCfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-xSbY94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-GkhtiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:45 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-m8ZCf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:46 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Rz8Esq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:14:46 INFO - Running setup.py install for manifestparser 05:14:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:14:46 INFO - Running setup.py install for mozcrash 05:14:46 INFO - Running setup.py install for mozdebug 05:14:46 INFO - Running setup.py install for mozdevice 05:14:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:14:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:14:46 INFO - Running setup.py install for mozfile 05:14:46 INFO - Running setup.py install for mozhttpd 05:14:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:14:46 INFO - Running setup.py install for mozinfo 05:14:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:14:47 INFO - Running setup.py install for mozInstall 05:14:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:14:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:14:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:14:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:14:47 INFO - Running setup.py install for mozleak 05:14:47 INFO - Running setup.py install for mozlog 05:14:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:14:47 INFO - Running setup.py install for moznetwork 05:14:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:14:47 INFO - Running setup.py install for mozprocess 05:14:47 INFO - Running setup.py install for mozprofile 05:14:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:14:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:14:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:14:48 INFO - Running setup.py install for mozrunner 05:14:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:14:48 INFO - Running setup.py install for mozscreenshot 05:14:48 INFO - Running setup.py install for moztest 05:14:48 INFO - Running setup.py install for mozversion 05:14:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:14:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:14:48 INFO - Cleaning up... 05:14:48 INFO - Return code: 0 05:14:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:48 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:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:48 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:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:48 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 0x10c7e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e8c65f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, '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.hOJRRyb0oD/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.3ALX3lcJg1/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:14:48 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:14:48 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:14:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:14:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:48 INFO - 'HOME': '/Users/cltbld', 05:14:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:48 INFO - 'LOGNAME': 'cltbld', 05:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:48 INFO - 'MOZ_NO_REMOTE': '1', 05:14:48 INFO - 'NO_EM_RESTART': '1', 05:14:48 INFO - 'PAGER': '/bin/cat', 05:14:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:48 INFO - 'PWD': '/builds/slave/test', 05:14:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:48 INFO - 'SHELL': '/bin/bash', 05:14:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:14:48 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:14:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:48 INFO - 'USER': 'cltbld', 05:14:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:48 INFO - 'XPC_FLAGS': '0x0', 05:14:48 INFO - 'XPC_SERVICE_NAME': '0', 05:14:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:48 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-GYCZKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:49 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:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-AbhrcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:49 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:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QSNn9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:49 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:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-fPwQoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:49 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:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-02BUF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:49 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:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-aMOgwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:49 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:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ARCqM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:49 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:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-B08GmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:49 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:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-YlbeZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:49 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:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-yJs1de-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-4MJoxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:50 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:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vljizu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Nf_Ytl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:50 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:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-EAg9fR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-0ExB7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:50 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:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-5aIzDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:50 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:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-EKKDrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:50 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:14:50 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:14:50 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:14:50 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:14:50 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:14:50 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:14:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:50 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:14:50 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:14:50 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:14:50 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:14:50 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:14:50 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:14:53 INFO - Downloading blessings-1.6.tar.gz 05:14:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:14:53 INFO - Installing collected packages: blessings 05:14:53 INFO - Running setup.py install for blessings 05:14:53 INFO - Successfully installed blessings 05:14:53 INFO - Cleaning up... 05:14:53 INFO - Return code: 0 05:14:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:14:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:53 INFO - Reading from file tmpfile_stdout 05:14:53 INFO - Current package versions: 05:14:53 INFO - blessings == 1.6 05:14:53 INFO - blobuploader == 1.2.4 05:14:53 INFO - docopt == 0.6.1 05:14:53 INFO - manifestparser == 1.1 05:14:53 INFO - mozInstall == 1.12 05:14:53 INFO - mozcrash == 0.17 05:14:53 INFO - mozdebug == 0.1 05:14:53 INFO - mozdevice == 0.48 05:14:53 INFO - mozfile == 1.2 05:14:53 INFO - mozhttpd == 0.7 05:14:53 INFO - mozinfo == 0.9 05:14:53 INFO - mozleak == 0.1 05:14:53 INFO - mozlog == 3.1 05:14:53 INFO - moznetwork == 0.27 05:14:53 INFO - mozprocess == 0.22 05:14:53 INFO - mozprofile == 0.28 05:14:53 INFO - mozrunner == 6.11 05:14:53 INFO - mozscreenshot == 0.1 05:14:53 INFO - mozsystemmonitor == 0.0 05:14:53 INFO - moztest == 0.7 05:14:53 INFO - mozversion == 1.4 05:14:53 INFO - psutil == 3.1.1 05:14:53 INFO - requests == 1.2.3 05:14:53 INFO - wsgiref == 0.1.2 05:14:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:53 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:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:53 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:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:53 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 0x10c7e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e8c65f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, '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.hOJRRyb0oD/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.3ALX3lcJg1/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:14:53 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:14:53 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:14:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:14:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:53 INFO - 'HOME': '/Users/cltbld', 05:14:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:53 INFO - 'LOGNAME': 'cltbld', 05:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:53 INFO - 'MOZ_NO_REMOTE': '1', 05:14:53 INFO - 'NO_EM_RESTART': '1', 05:14:53 INFO - 'PAGER': '/bin/cat', 05:14:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:53 INFO - 'PWD': '/builds/slave/test', 05:14:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:53 INFO - 'SHELL': '/bin/bash', 05:14:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:14:53 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:14:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:53 INFO - 'USER': 'cltbld', 05:14:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:53 INFO - 'XPC_FLAGS': '0x0', 05:14:53 INFO - 'XPC_SERVICE_NAME': '0', 05:14:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:54 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:14:54 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:14:54 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:14:54 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:14:54 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:14:54 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:14:54 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:14:54 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:14:54 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:14:54 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:14:54 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:14:54 INFO - Cleaning up... 05:14:54 INFO - Return code: 0 05:14:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e8c65f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, '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.hOJRRyb0oD/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.3ALX3lcJg1/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:14:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:54 INFO - 'HOME': '/Users/cltbld', 05:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:54 INFO - 'LOGNAME': 'cltbld', 05:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:54 INFO - 'MOZ_NO_REMOTE': '1', 05:14:54 INFO - 'NO_EM_RESTART': '1', 05:14:54 INFO - 'PAGER': '/bin/cat', 05:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:54 INFO - 'PWD': '/builds/slave/test', 05:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:54 INFO - 'SHELL': '/bin/bash', 05:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:14:54 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:54 INFO - 'USER': 'cltbld', 05:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:54 INFO - 'XPC_FLAGS': '0x0', 05:14:54 INFO - 'XPC_SERVICE_NAME': '0', 05:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:54 INFO - Downloading/unpacking jsonschema 05:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:14:56 INFO - Downloading/unpacking functools32 (from jsonschema) 05:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:57 INFO - Downloading functools32-3.2.3-2.tar.gz 05:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:14:57 INFO - warning: no files found matching '*.txt' 05:14:57 INFO - no previously-included directories found matching 'build' 05:14:57 INFO - no previously-included directories found matching 'dist' 05:14:57 INFO - no previously-included directories found matching '.git*' 05:14:57 INFO - Installing collected packages: jsonschema, functools32 05:14:57 INFO - Running setup.py install for functools32 05:14:57 INFO - warning: no files found matching '*.txt' 05:14:57 INFO - no previously-included directories found matching 'build' 05:14:57 INFO - no previously-included directories found matching 'dist' 05:14:57 INFO - no previously-included directories found matching '.git*' 05:14:57 INFO - Successfully installed jsonschema functools32 05:14:57 INFO - Cleaning up... 05:14:57 INFO - Return code: 0 05:14:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:14: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:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14: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:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:57 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e8c65f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, '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.hOJRRyb0oD/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.3ALX3lcJg1/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:14:57 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', 'flake8'] in /builds/slave/test/build 05:14:57 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 flake8 05:14:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:14:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:57 INFO - 'HOME': '/Users/cltbld', 05:14:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:57 INFO - 'LOGNAME': 'cltbld', 05:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:57 INFO - 'MOZ_NO_REMOTE': '1', 05:14:57 INFO - 'NO_EM_RESTART': '1', 05:14:57 INFO - 'PAGER': '/bin/cat', 05:14:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:57 INFO - 'PWD': '/builds/slave/test', 05:14:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:57 INFO - 'SHELL': '/bin/bash', 05:14:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:14:57 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:14:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:57 INFO - 'USER': 'cltbld', 05:14:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:57 INFO - 'XPC_FLAGS': '0x0', 05:14:57 INFO - 'XPC_SERVICE_NAME': '0', 05:14:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:57 INFO - Downloading/unpacking flake8 05:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:15:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:15:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:15:01 INFO - no previously-included directories found matching 'docs/_build' 05:15:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:15:01 INFO - Running setup.py install for pep8 05:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:15:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:15:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:15:01 INFO - no previously-included directories found matching 'docs/_build' 05:15:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:15:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:15:01 INFO - Cleaning up... 05:15:01 INFO - Return code: 0 05:15:01 INFO - Running post-action listener: _resource_record_post_action 05:15:01 INFO - Running post-action listener: _start_resource_monitoring 05:15:01 INFO - Starting resource monitoring. 05:15:01 INFO - ##### 05:15:01 INFO - ##### Running install step. 05:15:01 INFO - ##### 05:15:01 INFO - Running pre-action listener: _resource_record_pre_action 05:15:01 INFO - Running main action method: install 05:15:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:15:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:15:02 INFO - Reading from file tmpfile_stdout 05:15:02 INFO - Detecting whether we're running mozinstall >=1.0... 05:15:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:15:02 INFO - Reading from file tmpfile_stdout 05:15:02 INFO - Output received: 05:15:02 INFO - Usage: mozinstall [options] installer 05:15:02 INFO - Options: 05:15:02 INFO - -h, --help show this help message and exit 05:15:02 INFO - -d DEST, --destination=DEST 05:15:02 INFO - Directory to install application into. [default: 05:15:02 INFO - "/builds/slave/test"] 05:15:02 INFO - --app=APP Application being installed. [default: firefox] 05:15:02 INFO - mkdir: /builds/slave/test/build/application 05:15:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:15:36 INFO - Reading from file tmpfile_stdout 05:15:36 INFO - Output received: 05:15:36 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:15:36 INFO - Running post-action listener: _resource_record_post_action 05:15:36 INFO - ##### 05:15:36 INFO - ##### Running run-tests step. 05:15:36 INFO - ##### 05:15:36 INFO - Running pre-action listener: _resource_record_pre_action 05:15:36 INFO - Running main action method: run_tests 05:15:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:15:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:15:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:15:36 INFO - 2016-04-24 05:15:36.212 screenresolution[6182:106447] starting screenresolution argv=screenresolution get 05:15:36 INFO - 2016-04-24 05:15:36.215 screenresolution[6182:106447] Display 0: 1600x1200x32@60 05:15:36 INFO - 2016-04-24 05:15:36.221 screenresolution[6183:106449] starting screenresolution argv=screenresolution list 05:15:36 INFO - Available Modes on Display 0 05:15:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:15:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:15:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:15:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:15:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:15:36 INFO - Intel Iris: 05:15:36 INFO - Chipset Model: Intel Iris 05:15:36 INFO - Type: GPU 05:15:36 INFO - Bus: Built-In 05:15:36 INFO - VRAM (Dynamic, Max): 1536 MB 05:15:36 INFO - Vendor: Intel (0x8086) 05:15:36 INFO - Device ID: 0x0a2e 05:15:36 INFO - Revision ID: 0x0009 05:15:36 INFO - Displays: 05:15:36 INFO - Display: 05:15:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:15:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:15:36 INFO - Display Serial Number: 1600x1200 60 05:15:36 INFO - Main Display: Yes 05:15:36 INFO - Mirror: Off 05:15:36 INFO - Online: Yes 05:15:36 INFO - Rotation: Supported 05:15:36 INFO - Return code: 0 05:15:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:15:36 INFO - Python 2.7.3 05:15:36 INFO - Return code: 0 05:15:36 INFO - grabbing minidump binary from tooltool 05:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:36 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c67fe00>, '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 0x10c4a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c67ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:15:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:15:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:15:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:15:36 INFO - Return code: 0 05:15:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:15:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:15:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:15:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:15:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:15:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:15:37 INFO - Return code: 0 05:15:37 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/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.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:15:37 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/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.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:15:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hOJRRyb0oD/Render', 05:15:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:37 INFO - 'HOME': '/Users/cltbld', 05:15:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:37 INFO - 'LOGNAME': 'cltbld', 05:15:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:15:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:37 INFO - 'MOZ_NO_REMOTE': '1', 05:15:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:15:37 INFO - 'NO_EM_RESTART': '1', 05:15:37 INFO - 'PAGER': '/bin/cat', 05:15:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:37 INFO - 'PWD': '/builds/slave/test', 05:15:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:15:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:37 INFO - 'SHELL': '/bin/bash', 05:15:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ALX3lcJg1/Listeners', 05:15:37 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 05:15:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:37 INFO - 'USER': 'cltbld', 05:15:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:37 INFO - 'XPC_FLAGS': '0x0', 05:15:37 INFO - 'XPC_SERVICE_NAME': '0', 05:15:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:37 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/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.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:15:37 INFO - mozversion application_buildid: 20160424030601 05:15:37 INFO - mozversion application_changeset: ab8a76ac7b34db20dd3c220c9846826334f3efdc 05:15:37 INFO - mozversion application_display_name: Nightly 05:15:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:15:37 INFO - mozversion application_name: Firefox 05:15:37 INFO - mozversion application_remotingname: firefox 05:15:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:15:37 INFO - mozversion application_vendor: Mozilla 05:15:37 INFO - mozversion application_version: 48.0a1 05:15:37 INFO - mozversion platform_buildid: 20160424030601 05:15:37 INFO - mozversion platform_changeset: ab8a76ac7b34db20dd3c220c9846826334f3efdc 05:15:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:15:37 INFO - mozversion platform_version: 48.0a1 05:15:37 INFO - using testdate: 1461500137 05:15:37 INFO - actual date: 1461500137 05:15:37 INFO - starting webserver on 'localhost:56578' 05:15:37 INFO - SUITE-START | Running 2 tests 05:15:37 INFO - TEST-START | dromaeo_css 05:15:37 INFO - operating with platform_type : mac_ 05:15:37 INFO - Initialising browser for dromaeo_css test... 05:15:38 INFO - TEST-INFO | started process 6201 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpMoSre7/profile http://localhost:56578/getInfo.html) 05:15:40 INFO - PROCESS | 6201 | __metrics Screen width/height:1600/1200 05:15:40 INFO - PROCESS | 6201 | colorDepth:24 05:15:40 INFO - PROCESS | 6201 | Browser inner width/height: 1024/689 05:15:40 INFO - PROCESS | 6201 | __metrics 05:15:40 INFO - PROCESS | 6201 | 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:15:40 ERROR - PROCESS | 6201 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:15:41 INFO - TEST-INFO | 6201: exit 0 05:15:41 INFO - Browser initialized. 05:15:41 INFO - Running cycle 1/1 for dromaeo_css test... 05:15:41 INFO - TEST-INFO | started process 6205 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpMoSre7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:53 INFO - PROCESS | 6205 | RSS: Main: 239345664 05:15:53 INFO - PROCESS | 6205 | 05:16:44 INFO - PROCESS | 6205 | 1461500204586 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:18:54 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:56578/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56578/tests/dromaeo/cssquery-ext.html) 05:18:56 INFO - PROCESS | 6205 | RSS: Main: 420618240 05:18:56 INFO - PROCESS | 6205 | 05:21:59 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:56578/tests/dromaeo/cssquery-ext.html (next: http://localhost:56578/tests/dromaeo/cssquery-jquery.html) 05:22:00 INFO - PROCESS | 6205 | RSS: Main: 379199488 05:22:00 INFO - PROCESS | 6205 | 05:25:02 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:56578/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56578/tests/dromaeo/cssquery-mootools.html) 05:25:03 INFO - PROCESS | 6205 | RSS: Main: 375922688 05:25:03 INFO - PROCESS | 6205 | 05:28:05 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:56578/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56578/tests/dromaeo/cssquery-prototype.html) 05:28:06 INFO - PROCESS | 6205 | RSS: Main: 376446976 05:28:06 INFO - PROCESS | 6205 | 05:31:09 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:56578/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56578/tests/dromaeo/cssquery-yui.html) 05:31:09 INFO - PROCESS | 6205 | RSS: Main: 354394112 05:31:09 INFO - PROCESS | 6205 | 05:34:12 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:56578/tests/dromaeo/cssquery-yui.html (next: http://localhost:56578/tests/dromaeo/cssquery-dojo.html) 05:34:13 INFO - PROCESS | 6205 | RSS: Main: 384942080 05:34:13 INFO - PROCESS | 6205 | 05:34:13 INFO - PROCESS | 6205 | __start_tp_report 05:34:13 INFO - PROCESS | 6205 | _x_x_mozilla_page_load 05:34:13 INFO - PROCESS | 6205 | _x_x_mozilla_page_load_details 05:34:13 INFO - PROCESS | 6205 | |i|pagename|runs| 05:34:13 INFO - PROCESS | 6205 | |0;dojo.html;3315.585551806024;3366.5455163552915;3383.9661603383965;3385.2721664842056;3430.4511278195478;5523.1163013917785;5604.467575580318;5605;5625.212470254168;5632.802851900185;5653.095504719246;5667.546596272298;5777.971110144454;5795.913061304073;5852.736626851792;5115.820946266881;5141.76862041209;5180.715060671661;5194;5281.419043905167;5267.868303292429;5305.708186049766;5319.946800532003;5334.759935802898;5376.677399356051;5092.668976516552;5157.097507936133;5199.506046925536;5216.973915130438;5251.658642188246;5123.795048198068;5198;5199.090159222121;5232.816851410221;5265.183896496011;5128.282040514331;5139.58883289336;5148.9742551287;5199.740012999335;5257.053730328539;5459.69971651559;5598.272224610815;5600.299962504687;5616.663000219959;5621.184928185431;6132.754689812402;6167.167432396614;6196.752129914843;6216.844578885519;6217.68911554425;6163.167972323724;6251.937480625226;6296.779612713579;6298.149749783812;6300.779472718433;5587.776488940478;5707.400722924116;5732.455416735445;5735.11105778605;5745.2243947067445;6143.051335359052;6247.5939063960695;6259.873222819936;6260.780872669435;6264.2796078451565;6064.514838812885;6084.056971169476;6088.299845517822;6096.908546371808;6100.206973093469;6098.311206366262;6180.5673602848265;6245.188125543741;6262.906056409158;6276.560640755195;6096.254562767794;6103.816885493442;6148.1085242639565;6187.288461826947;6197.287311959182;6168.074788781629;6188.164597779332;6198.163247961557;6208.348123446973;6219.720112594944;4743.335932969387;4841.636877234221;4854.368932038812;4873.878153046131;4927.989762098761;4270.103278311527;4344.304911214191;4409.581089796464;4462.799174037176;4505.864824055284;5618.213450116987;5629.127485239795;5630.465105814941;5636.4927156556105;5659.377468478464;6137.631742095488;6186.350433204539;6242.250929888442;6263;6278.748850045947;4790.0180463005;5429.484199001088;5524.116141417354;5578.693171875505;5623.100303951348;4532.456105267389;5321.707306098202;5323.547498462596;5355.812546560929;5357.464253574612;6143.296949342275;6260.530460215502;6265.404786545271;6266.874662506815;6269.153664255358;5405.081136206933;6147.385261473817;6289.811305660837;6300.842478938063;6315.684215789284;6248.4688801452385;6267.0286105653695;6284.340144284967;6286.277078136073;6290.685465726604;5997.220361352996;6072.240969878765;6121.234845644294;6136.048912418582;6153.969230153821;6140;6173.56785025044;6197.163382943245;6200.627962322275;6212.596181248205;5852.917210316105;5873.442023007979;6085.421884920926;6096.634201947897;6104.725287361991;5938.524918006636;6061.090836374579;6103.389661033861;6111.84720381994;6116.174316467398;5890.558208134493;5901.793437229677;5924.407559244041;5927;5949;5999.970000149971;6050.848728781815;6054.636721796706;6072.726727297194;6098.462484929893;5927.229460170151;5939.554533409925;5949.672767997802;5966.970165149147;5999.670018149044 05:34:13 INFO - PROCESS | 6205 | |1;ext.html;484.8254628333799;510.6195884066371;531.2907268796157;583.667309633509;612.8253447767385;29762;32434.18914527138;38868.416973745414;40140;40419.39370909439;44057.339139912845;44578.77710611442;44594;44690;44786;9662.806743865118;9870.01299870011;9905.158061564776;9907.702768916944;9923.057309555581;8684.001339845903;8983.775405614846;11579.710507237345;11913.166078374517;12001.33992630405;10080.546375413123;11545.422728863565;11590.014848737825;11670.358130302873;11679.824802627967;8644.178803013703;11529.308241505494;11623.593174238946;11632.476538555786;11643.592474263403;11480.885191148065;11902.58340958071;11912.34482103484;11919;11930.164888457812;39396.21207575861;40084.198316033806;40122.19755604901;40169.79915100406;40178;3180.634227063871;3208.1498402923244;3231.579894613709;3236.563063986379;3375.6118046424485;28082.578761318597;28973.855130724212;29204.561931571043;29290.56064159018;29874.40251194986;2674.7325267473097;2739.178246526054;2752.3344809650093;2764.115483045426;2821.50623640862;477157.9999999965;477410;481303;482291.58854205854;482602;66311.33688663148;66736.33263667398;66883;66908.30554486296;66929;69765;72066.55866882633;72159;72245.91631125538;72246;16601.750973735405;16621.168941552874;16872.662546748998;16885.24016419264;16885.986840789345;11755.471003804849;12099.75800484003;12132.5146994121;12809;12912.16070955385;17371.392001279895;17660.646787064186;18387.890397581043;20443.8977805113;20533;6910.13426374876;7955.4033447491665;8110.391720620977;8117.889348769362;8245.752627421187;943.0710749911332;953.9952300238455;961.5961296255589;1108.5898217659517;1202.5730865542607;28878.422431551673;29057;29306.413871722445;29551;29589.816407343464;1094.5676457799284;1123.0454114003032;1238.6717519857245;1271.3528128308233;1276.7893297605976;15572.92213538925;15616.687666246838;15684;15755.97586156896;15912.76130858038;587597.1240287628;638278;650598;650786;651386;59825.70087149536;62314.68842655758;62720.05919911205;62798;62873.68563157155;1349.959501214965;1370.1710465168628;1490.4708828365774;1496.513633069257;1545.5440645009974;28948.986785462406;29028;29184;29568.704312956594;29724.70275297306;49485.75257123692;49679.75160124176;50187.74906125592;50261.74869125631;50267.49732502775;372726;376027;376048;376204;378641;24126.39684007914;26817.329566760985;27796.583051254653;28081.01716439915;28124.71875281221;43566.78216608897;43754;43905.78047109744;44030.55969440265;44242.33636495391;36119.45820812743;36320.27359452743;36382.63617363898;37189.814050930654;37253;35402.29195416129;35646.287074258886;36093.09767255735;36562.26875462529;38308.425373619975;37262;37547.249055019296;37692;38211.42682859815;38419.42370864384;32123.03630891077;32325.51511727279;32703;32938.341233175684;33049.00852974415;21318.360449186548;23294.343087548594;24191.91136398831;24483.51032979295;24731.752682473663 05:34:13 INFO - PROCESS | 6205 | |2;jquery.html;388.71041074399574;391.8648066417086;395.4108378516012;398.28110260978923;407.4682539286232;15253.618659533504;15276.312565934531;17180.140992950364;19870;19890.900545497254;16105.436309729163;16723.581910452245;19559.70660440091;20198.39404817858;20592.588148237028;4926.999999999991;5964.493018093467;6039.5772295939305;6044.791041791637;6050.606710563821;5628.183913332585;5915.556333275;5978;5978.611390259619;5998.9700051499685;5996.760129594811;5998;6004.219451471303;6015.037593984964;6018.699065046752;5912.994790885538;5957.136215248786;5995.760169593233;5996.760129594811;5998.970005149947;5540.9168862466695;5691.9146212806845;5715.514181294595;5722.828315150552;5730.971345143247;12948.417321220568;13798;13812.516561920385;13828.792568111487;13831.654208644764;1990.8108729670657;2000.8199262066485;2038.7349644546262;2048.129544943408;2224.688543603897;8790.120987901222;9586.37688550249;9982.800343993153;10022.398656080659;10105.898941010642;1657.9585510362338;1665.62523432228;1669.5575672446812;1706.7029057916072;1720.2774834569511;100120.49819252717;113868;123141.9999999991;123695.38152309271;125284;11346.659600212008;11532.88467115335;11557.133215008907;11568.88431115695;11656.825147622792;14828.777568336483;14869.182194979163;14887.702245955237;14888;14899.925500372428;6192.690365481708;7705.961470192613;7907.920920790719;8326.916730832614;8497.575121243914;8185.877211841827;8205.302549283258;8266.958665206756;8314.70898518561;8345.082040975487;4436.733795972187;4467.843625473093;4494.617957473652;4508.954910450919;4584.15193189261;1872.1107473949764;1932.318882572171;1938.418474457657;1945.309415157627;1979.475439008664;909.4588719711761;956.5552018311519;983.8868530118984;1006.1900170362782;1033.6950599573145;18864.434066978014;18948.905255473634;19206.61586768257;19249.903750481157;19271.325503607586;842.2798507276314;936.424099179;974.3520558828442;986.7829077602916;1024.2215915903967;8176.427650064557;9894.752631184278;10114;10210.24323432669;10252.23108266883;576725;581265;596078;597869;598392;101678;122663.38668306601;128116;132091;133121;978.4178413843752;979.7158823940978;996.8853581838036;998.7952469743685;1024.9590016399409;14279.928600356932;17346.91326543359;17350.30598776035;17388;17701.38045168413;48532.757336214505;49320;49574.75212624058;49670;49832;70113.64943175252;72817;73991.63004184945;74057;76587;7555.282248186413;7742.690292388466;7861;7876.56678882667;8238.299744521782;11030.779384412428;11894.762104757683;12188.634340969786;12194.146409751233;12446.25322480654;6129.816105516842;9543.665971690958;10290.176785856964;10742.677719668422;10928.836067458835;10020.398776073167;10123.59505619767;10321.380717156995;10330.070293673605;10609.681709548726;10377.896221037994;10546.841797372892;10578.735531611772;10993.780124397626;11010.229283950366;7770.650320735467;8395.790105247417;11188.160887933274;11945.761084778082;11960.342181180118;10030.448325341884;10532.262741608014;10846.837297440386;11183.664490065312;11233.269837460866 05:34:13 INFO - PROCESS | 6205 | |3;mootools.html;1418.4042702065133;1591.8089829220492;1605.839416058394;1855.6195979824138;1981.2075169932034;7949.801254968629;8602.784930376736;8844.027157012739;8844.5577721114;8872.955635221831;8492.53291068991;8731.738047858558;8741.650333986649;8758.91241087589;8780.912190878089;2166.2959538150085;2253.6281513550243;2257.5372048730055;2447.8286519943613;2489.987550062247;2024.3926821953428;2260.1298500077423;2515.4843257132243;2518.5214809186195;2521.470491196851;2414.9396265093337;2460.7416221296776;2467.605183170693;2468.617364308535;2469.8271121021535;2151.601953638582;2419.9516009679796;2427.453822889853;2429.465517586128;2433.598456254716;2384.8688322142275;2467.0501856785086;2467.124170919316;2469.0494159748446;2470.765277298654;8624.094470080614;8706.347023973227;8706.651733930636;8709.172628600272;8733.912660873373;5227.869303267411;5421.403645598981;5446.863828404282;5454;5459.727013649342;3469.1500582357485;3564.3405969895653;3626.655467730561;3646.781193128394;3671.118931456458;1004.2719028704204;1023.4064242739194;1029.3566520924421;1029.5367084811865;1042.7393151712072;11877.930986211282;11881.5841445549;11921.165518413714;11926.46330915111;11927.98612117971;4728.40894888139;4844.224924012141;4850.539198776181;4958.801647934042;4967.230079337708;10081.798364032826;10129.240306977006;10140.13808826258;10141.898581014095;10156.847647285296;5468.179773034077;5474.589405794582;5482.945170548323;5492.038893193675;5522.30971128609;4898.730569818623;4926.9014619707405;4991.675541089818;5010.3737032870895;5042.520960508746;3969.503812023497;4005.2189822984237;4072.1855628874346;4122.690798190148;4138.13099249161;1348.406701051554;1362.0193460708274;1367.8016687580443;1371.8216631837797;1374.29223949666;797.7327595255655;905.1536813079791;914.5838643417229;918.2745630951607;951.900050494702;5879.088741245114;5887.735051922674;6039.214902062791;6041.24484439445;6193.566450348432;451.14958303597956;456.5229335344573;473.09638590292377;474.46622549631667;474.684334917283;3868.574456809749;3968.265870813909;4001.079751657135;4048.2510735513433;4058.6753059755147;10268.383896966207;10555.683329499974;10823.29648572856;10881.727956800985;10895.564177432972;10674.73313167177;10826.404547749791;10827.458627068616;10848.78302433947;10882.564697412174;7695.422843286664;8146.470479418819;8290.927268182953;8294.875576866352;8306.709265175568;5860.912086318624;5980.671063091571;6059.909101363572;6126;6140.078988151813;9731.659391921532;9885.456299903286;9899.65351212704;9969.102780749767;10013.449260290754;8622.870656940026;8820.426672266281;9078.682246121354;9994.450305233282;10111.949440252753;7382.963085184754;7500.549967001997;7533.510321829063;7575.053118360205;7586.810329741801;8071.556064416279;8384.958075209584;8460.280876125598;8463.873041904499;8467.153284671484;7297.233790451926;7396.852062958818;7420.740274090381;7452.329290363893;7548.622568871425;7231.710731570678;7239.131304243525;7290.380317673057;7390.5196162249285;7393.371563417169;6859.2454829969665;7041.436685065285;7049.718011279491;7087.007818905255;7093.82265443368;4768.308595253599;4780.259059845729;4786.7845946931775;4847.903041939212;5057.418396884405;4991.326170966873;5036.84889453317;5046.798128074836;5082.288479612931;5286.074936886106 05:34:13 INFO - PROCESS | 6205 | |4;prototype.html;308.9969100308997;309.6408166526829;316.73710820019386;323.55995845649926;326.31962358482565;4987.501249875011;5193.3248677671945;5602.971985140069;5730.914036289454;5831.620944638601;5456.236126942222;5730.111832665933;5735.541156707463;5744.736158045223;5767.36558978512;3539.469079638062;3653.872114475994;3674.802921458335;4218.7468751874985;4379.781010949456;4321.286987647034;4380.2115619188535;4387.188370151516;4391.978040109795;4410.228210063242;3426.194844211608;4067.6542493888055;4155.522114956773;4200.0339921818;4391.407160033403;4003.5996400360023;4073.4704488416614;4102.876913692594;4354.608085272309;4360.302351623725;3776.7356285060055;3799.2401519696173;3803.486529318562;3815.179736356669;3822.617738226183;4504.684672072956;5212.348456442945;5566.777328906839;5570.888582228374;5577.972110139464;4874.780634871439;5044.940562481883;5051.570616497602;5082.567981721558;5085.771140298695;6019.187409699678;6042.244719410074;6058.909116363214;6079.969600151971;6094.481969032638;4203.285441474956;4205.516365617963;4209.536950935395;4224.9155016899485;4230.894227644303;4809.845637047083;5501.339839219319;5901.527877769811;6348.809535713936;6397.392247736504;4084.223997440477;4243.172581346669;4295.763732994716;4301.956980430155;4302.268614335508;3666.213677604986;4359.629431498295;4371.322684727311;4385.605295523418;4394.912101758011;4157.3415394453295;4419.513853476115;4878.414590249193;4905.3132561441425;4957.418956652411;5033;5608.9439105609235;6079.939200608026;6107.664078475637;6139.938600614026;1437.4896911596443;1481.888858335629;1508.5625168701174;1512.7050225206199;1517.5750789778879;809.2918696140877;888.1606881497053;895.2077411490748;916.2956900165686;1008.3344992305043;4523.163214805271;5568.192612071227;5719.027765279912;5735.397783232784;5750.137479378043;6319.6208227506495;6347.492200623939;6368.363163683594;6369.777057803048;6380.872382552323;5519.751611177507;5665.272092011894;5679.858003549944;5693.4875861172695;5705.343885453143;2970.301979034925;3305.8512366943546;3322.7840190387546;3342.782719123249;3353.195233144028;5132.5876837886135;6452.901808011244;6511.446527045159;6623.205215374186;6624.337566243337;6454.225492940784;6475.190601174853;6490.772822951269;6522.771702990372;6557.409833115043;5675.574331925205;6259.937400625936;6587.50593705474;6614.669266536654;6626.602403855784;6230.794213905505;6307.24313082433;6307.526935479766;6328;6335.3981371769605;6196.907046394398;6234.75060997555;6244.063390491463;6260.843478913063;6263.154474146114;6297.118403423616;6570.8357291068105;6612.8346791330605;6615.122421746363;6626.768063117768;4105.507339119325;4119.661110139157;4135.29699951015;4145.984233862723;4149.294619914682;5224.477552244897;5224.712640804641;5741.555066959791;6288.811335659938;6340.556161068681;5369.040300290444;6111.327753947151;6118.388161183846;6149.292831324276;6150.969245153746;5324.760385782726;6021.488173505126;6106.480949119197;6132.601380910227;6137.815865524041;5092.905025419553;5645.71771411436;5966.582339236212;5994.910076348945;6015.909181756448;6094.939050609437;6100.0849872519475;6113.480354169946;6119.418655227746;6141.07386372153;4967.900641987212;5807.564432667482;5992.490638295793;6002.819915402545;6018.337982821974 05:34:13 INFO - PROCESS | 6205 | |5;yui.html;640.6540468147201;656.2289310060679;696.4080531548182;764.0334976255039;789.1043665439726;14592.197429141394;14909.794394205244;15011.699766004673;15067.397304107822;15077.547673569798;13669;14076.929615351935;14098.506552270672;14139.575812725609;14143.151410915327;711.3064761857199;730.8099894027538;731.2614259597818;892.5849479991803;898.0884402331623;729.0704351951279;731.6780616528737;874.0385575866528;914.0904799724284;916.331078312832;947.9715608531756;952.3714348529971;953.0231512699504;955.4267439536292;956.2971216156154;942.4722155592822;943.235978857121;945.4280160502866;946.3139224062603;949.1410273702276;549.409384911222;675.2639622811159;704.9506534542584;705.7353492440335;706.9081019467436;13089.280089595068;14524.201168935704;14937.70124597502;15017.774733379008;15065.397384104623;12467.875321246853;12589.74820503594;12613.621591352274;12676.619701408972;12786.872131278662;8696.695615653485;9828.115469607697;9927.007299270088;9929.092150306695;10522.000409961041;3445.758796884219;3541.94687079694;3562.8218589070443;3580.856765729368;3665.2669466106886;78156.60921695356;79835.20164798335;80584.19415805825;80920;81363;9479.810403791886;9502.667406640734;9571.234301255883;9836;9964.601415943425;21610.89393015322;21793.346199614036;21810.890945545172;21879;21979.670304945437;7303.817404564928;7411.0736157980255;7439.776806695808;7600.885986710204;7701.075870895528;4014.739041962263;4083.4895638045246;4105.4252404663375;4158.91682166355;4421.159979603929;3427.640097789696;3531.5585551806025;3532.523109380252;3539.1682954505673;3542.0613537412605;642.4410762636443;652.9771457998947;655.1417642887832;657.2244751193638;661.2593894837812;511.872031992002;527.6020486887107;546.912494000958;575.0884847516733;589.0663298671653;7462.440316976248;7826.491278067021;7956.403269754792;8088.555129467818;8145.389095817719;469.76679787170946;519.7479222577047;521.8669239343943;524.8740302327413;592.9644221346647;3535.381308271042;3685.4656074868994;3748.4189950557707;3760.2291530236234;3835.827387767557;62254.0661890072;71844.64077679579;77006.07484812812;80860.59569702114;81848.18151818524;52648.73675631597;52850.73574632179;53637.731811340695;63395.6830215846;68391;59103.40896590979;60075;61550;64341.35658643354;66935;6789.93210067893;7704.152543220353;8116.797080073045;8180.67277308901;8214.219649133323;40718.1856362877;41418.37872431976;42510.787446062575;42606.78696606621;42785.786071069444;58759.412405875395;59871.40128598658;60151.69924150351;60191;60678.39321606728;10859.674209773719;10924.50839712199;11001.119910407308;11029;11341.716457088309;10633.787324253626;12101.152919295564;12289;12312.692182695504;12390.132690711564;9822.361546499455;11473.541058357572;11708.12189085839;11799.410029498662;11828.82256766132;11219.382933938712;11252.549898003988;11310.60412885545;11668.363419661016;12283.69290767738;9711;11326.830097548378;11331.773364532828;11391.031762299966;11695.298282103102;9600.711978640651;9729.027097290214;9731.610735570497;10023.649172278936;10658.179173764618;9726.902730972599;9763;9800.754981125529;9819.852702209615;9852.507374631383 05:34:13 INFO - PROCESS | 6205 | __end_tp_report 05:34:13 INFO - PROCESS | 6205 | __start_cc_report 05:34:13 INFO - PROCESS | 6205 | _x_x_mozilla_cycle_collect,1874 05:34:13 INFO - PROCESS | 6205 | __end_cc_report 05:34:13 INFO - PROCESS | 6205 | __startTimestamp1461501253208__endTimestamp 05:34:13 INFO - PROCESS | 6205 | 05:34:13 INFO - PROCESS | 6205 | ------- Summary: start ------- 05:34:13 INFO - PROCESS | 6205 | Number of tests: 6 05:34:13 INFO - PROCESS | 6205 | 05:34:13 INFO - PROCESS | 6205 | [#0] dojo.html Cycles:165 Average:5695.62 Median:5927.23 stddev:639.71 (10.8%) stddev-sans-first:613.82 05:34:13 INFO - PROCESS | 6205 | Values: 3315.6 3366.5 3384.0 3385.3 3430.5 5523.1 5604.5 5605.0 5625.2 5632.8 5653.1 5667.5 5778.0 5795.9 5852.7 5115.8 5141.8 5180.7 5194.0 5281.4 5267.9 5305.7 5319.9 5334.8 5376.7 5092.7 5157.1 5199.5 5217.0 5251.7 5123.8 5198.0 5199.1 5232.8 5265.2 5128.3 5139.6 5149.0 5199.7 5257.1 5459.7 5598.3 5600.3 5616.7 5621.2 6132.8 6167.2 6196.8 6216.8 6217.7 6163.2 6251.9 6296.8 6298.1 6300.8 5587.8 5707.4 5732.5 5735.1 5745.2 6143.1 6247.6 6259.9 6260.8 6264.3 6064.5 6084.1 6088.3 6096.9 6100.2 6098.3 6180.6 6245.2 6262.9 6276.6 6096.3 6103.8 6148.1 6187.3 6197.3 6168.1 6188.2 6198.2 6208.3 6219.7 4743.3 4841.6 4854.4 4873.9 4928.0 4270.1 4344.3 4409.6 4462.8 4505.9 5618.2 5629.1 5630.5 5636.5 5659.4 6137.6 6186.4 6242.3 6263.0 6278.7 4790.0 5429.5 5524.1 5578.7 5623.1 4532.5 5321.7 5323.5 5355.8 5357.5 6143.3 6260.5 6265.4 6266.9 6269.2 5405.1 6147.4 6289.8 6300.8 6315.7 6248.5 6267.0 6284.3 6286.3 6290.7 5997.2 6072.2 6121.2 6136.0 6154.0 6140.0 6173.6 6197.2 6200.6 6212.6 5852.9 5873.4 6085.4 6096.6 6104.7 5938.5 6061.1 6103.4 6111.8 6116.2 5890.6 5901.8 5924.4 5927.0 5949.0 6000.0 6050.8 6054.6 6072.7 6098.5 5927.2 5939.6 5949.7 5967.0 5999.7 05:34:13 INFO - PROCESS | 6205 | 05:34:13 INFO - PROCESS | 6205 | [#1] ext.html Cycles:180 Average:64591.57 Median:19388.95 stddev:135776.87 (700.3%) stddev-sans-first:136072.45 05:34:13 INFO - PROCESS | 6205 | Values: 484.8 510.6 531.3 583.7 612.8 29762.0 32434.2 38868.4 40140.0 40419.4 44057.3 44578.8 44594.0 44690.0 44786.0 9662.8 9870.0 9905.2 9907.7 9923.1 8684.0 8983.8 11579.7 11913.2 12001.3 10080.5 11545.4 11590.0 11670.4 11679.8 8644.2 11529.3 11623.6 11632.5 11643.6 11480.9 11902.6 11912.3 11919.0 11930.2 39396.2 40084.2 40122.2 40169.8 40178.0 3180.6 3208.1 3231.6 3236.6 3375.6 28082.6 28973.9 29204.6 29290.6 29874.4 2674.7 2739.2 2752.3 2764.1 2821.5 477158.0 477410.0 481303.0 482291.6 482602.0 66311.3 66736.3 66883.0 66908.3 66929.0 69765.0 72066.6 72159.0 72245.9 72246.0 16601.8 16621.2 16872.7 16885.2 16886.0 11755.5 12099.8 12132.5 12809.0 12912.2 17371.4 17660.6 18387.9 20443.9 20533.0 6910.1 7955.4 8110.4 8117.9 8245.8 943.1 954.0 961.6 1108.6 1202.6 28878.4 29057.0 29306.4 29551.0 29589.8 1094.6 1123.0 1238.7 1271.4 1276.8 15572.9 15616.7 15684.0 15756.0 15912.8 587597.1 638278.0 650598.0 650786.0 651386.0 59825.7 62314.7 62720.1 62798.0 62873.7 1350.0 1370.2 1490.5 1496.5 1545.5 28949.0 29028.0 29184.0 29568.7 29724.7 49485.8 49679.8 50187.7 50261.7 50267.5 372726.0 376027.0 376048.0 376204.0 378641.0 24126.4 26817.3 27796.6 28081.0 28124.7 43566.8 43754.0 43905.8 44030.6 44242.3 36119.5 36320.3 36382.6 37189.8 37253.0 35402.3 35646.3 36093.1 36562.3 38308.4 37262.0 37547.2 37692.0 38211.4 38419.4 32123.0 32325.5 32703.0 32938.3 33049.0 21318.4 23294.3 24191.9 24483.5 24731.8 05:34:13 INFO - PROCESS | 6205 | 05:34:13 INFO - PROCESS | 6205 | [#2] jquery.html Cycles:180 Average:33762.14 Median:11118.56 stddev:98510.88 (886.0%) stddev-sans-first:98755.35 05:34:13 INFO - PROCESS | 6205 | Values: 388.7 391.9 395.4 398.3 407.5 15253.6 15276.3 17180.1 19870.0 19890.9 16105.4 16723.6 19559.7 20198.4 20592.6 4927.0 5964.5 6039.6 6044.8 6050.6 5628.2 5915.6 5978.0 5978.6 5999.0 5996.8 5998.0 6004.2 6015.0 6018.7 5913.0 5957.1 5995.8 5996.8 5999.0 5540.9 5691.9 5715.5 5722.8 5731.0 12948.4 13798.0 13812.5 13828.8 13831.7 1990.8 2000.8 2038.7 2048.1 2224.7 8790.1 9586.4 9982.8 10022.4 10105.9 1658.0 1665.6 1669.6 1706.7 1720.3 100120.5 113868.0 123142.0 123695.4 125284.0 11346.7 11532.9 11557.1 11568.9 11656.8 14828.8 14869.2 14887.7 14888.0 14899.9 6192.7 7706.0 7907.9 8326.9 8497.6 8185.9 8205.3 8267.0 8314.7 8345.1 4436.7 4467.8 4494.6 4509.0 4584.2 1872.1 1932.3 1938.4 1945.3 1979.5 909.5 956.6 983.9 1006.2 1033.7 18864.4 18948.9 19206.6 19249.9 19271.3 842.3 936.4 974.4 986.8 1024.2 8176.4 9894.8 10114.0 10210.2 10252.2 576725.0 581265.0 596078.0 597869.0 598392.0 101678.0 122663.4 128116.0 132091.0 133121.0 978.4 979.7 996.9 998.8 1025.0 14279.9 17346.9 17350.3 17388.0 17701.4 48532.8 49320.0 49574.8 49670.0 49832.0 70113.6 72817.0 73991.6 74057.0 76587.0 7555.3 7742.7 7861.0 7876.6 8238.3 11030.8 11894.8 12188.6 12194.1 12446.3 6129.8 9543.7 10290.2 10742.7 10928.8 10020.4 10123.6 10321.4 10330.1 10609.7 10377.9 10546.8 10578.7 10993.8 11010.2 7770.7 8395.8 11188.2 11945.8 11960.3 10030.4 10532.3 10846.8 11183.7 11233.3 05:34:13 INFO - PROCESS | 6205 | 05:34:13 INFO - PROCESS | 6205 | [#3] mootools.html Cycles:180 Average:5731.81 Median:4942.85 stddev:3195.39 (64.6%) stddev-sans-first:3187.91 05:34:13 INFO - PROCESS | 6205 | Values: 1418.4 1591.8 1605.8 1855.6 1981.2 7949.8 8602.8 8844.0 8844.6 8873.0 8492.5 8731.7 8741.7 8758.9 8780.9 2166.3 2253.6 2257.5 2447.8 2490.0 2024.4 2260.1 2515.5 2518.5 2521.5 2414.9 2460.7 2467.6 2468.6 2469.8 2151.6 2420.0 2427.5 2429.5 2433.6 2384.9 2467.1 2467.1 2469.0 2470.8 8624.1 8706.3 8706.7 8709.2 8733.9 5227.9 5421.4 5446.9 5454.0 5459.7 3469.2 3564.3 3626.7 3646.8 3671.1 1004.3 1023.4 1029.4 1029.5 1042.7 11877.9 11881.6 11921.2 11926.5 11928.0 4728.4 4844.2 4850.5 4958.8 4967.2 10081.8 10129.2 10140.1 10141.9 10156.8 5468.2 5474.6 5482.9 5492.0 5522.3 4898.7 4926.9 4991.7 5010.4 5042.5 3969.5 4005.2 4072.2 4122.7 4138.1 1348.4 1362.0 1367.8 1371.8 1374.3 797.7 905.2 914.6 918.3 951.9 5879.1 5887.7 6039.2 6041.2 6193.6 451.1 456.5 473.1 474.5 474.7 3868.6 3968.3 4001.1 4048.3 4058.7 10268.4 10555.7 10823.3 10881.7 10895.6 10674.7 10826.4 10827.5 10848.8 10882.6 7695.4 8146.5 8290.9 8294.9 8306.7 5860.9 5980.7 6059.9 6126.0 6140.1 9731.7 9885.5 9899.7 9969.1 10013.4 8622.9 8820.4 9078.7 9994.5 10111.9 7383.0 7500.5 7533.5 7575.1 7586.8 8071.6 8385.0 8460.3 8463.9 8467.2 7297.2 7396.9 7420.7 7452.3 7548.6 7231.7 7239.1 7290.4 7390.5 7393.4 6859.2 7041.4 7049.7 7087.0 7093.8 4768.3 4780.3 4786.8 4847.9 5057.4 4991.3 5036.8 5046.8 5082.3 5286.1 05:34:13 INFO - PROCESS | 6205 | 05:34:13 INFO - PROCESS | 6205 | [#4] prototype.html Cycles:180 Average:4940.54 Median:5574.43 stddev:1546.69 (27.7%) stddev-sans-first:1511.46 05:34:13 INFO - PROCESS | 6205 | Values: 309.0 309.6 316.7 323.6 326.3 4987.5 5193.3 5603.0 5730.9 5831.6 5456.2 5730.1 5735.5 5744.7 5767.4 3539.5 3653.9 3674.8 4218.7 4379.8 4321.3 4380.2 4387.2 4392.0 4410.2 3426.2 4067.7 4155.5 4200.0 4391.4 4003.6 4073.5 4102.9 4354.6 4360.3 3776.7 3799.2 3803.5 3815.2 3822.6 4504.7 5212.3 5566.8 5570.9 5578.0 4874.8 5044.9 5051.6 5082.6 5085.8 6019.2 6042.2 6058.9 6080.0 6094.5 4203.3 4205.5 4209.5 4224.9 4230.9 4809.8 5501.3 5901.5 6348.8 6397.4 4084.2 4243.2 4295.8 4302.0 4302.3 3666.2 4359.6 4371.3 4385.6 4394.9 4157.3 4419.5 4878.4 4905.3 4957.4 5033.0 5608.9 6079.9 6107.7 6139.9 1437.5 1481.9 1508.6 1512.7 1517.6 809.3 888.2 895.2 916.3 1008.3 4523.2 5568.2 5719.0 5735.4 5750.1 6319.6 6347.5 6368.4 6369.8 6380.9 5519.8 5665.3 5679.9 5693.5 5705.3 2970.3 3305.9 3322.8 3342.8 3353.2 5132.6 6452.9 6511.4 6623.2 6624.3 6454.2 6475.2 6490.8 6522.8 6557.4 5675.6 6259.9 6587.5 6614.7 6626.6 6230.8 6307.2 6307.5 6328.0 6335.4 6196.9 6234.8 6244.1 6260.8 6263.2 6297.1 6570.8 6612.8 6615.1 6626.8 4105.5 4119.7 4135.3 4146.0 4149.3 5224.5 5224.7 5741.6 6288.8 6340.6 5369.0 6111.3 6118.4 6149.3 6151.0 5324.8 6021.5 6106.5 6132.6 6137.8 5092.9 5645.7 5966.6 5994.9 6015.9 6094.9 6100.1 6113.5 6119.4 6141.1 4967.9 5807.6 5992.5 6002.8 6018.3 05:34:13 INFO - PROCESS | 6205 | 05:34:13 INFO - PROCESS | 6205 | [#5] yui.html Cycles:180 Average:16563.39 Median:29848.19 stddev:22060.98 (73.9%) stddev-sans-first:22090.46 05:34:13 INFO - PROCESS | 6205 | Values: 640.7 656.2 696.4 764.0 789.1 14592.2 14909.8 15011.7 15067.4 15077.5 13669.0 14076.9 14098.5 14139.6 14143.2 711.3 730.8 731.3 892.6 898.1 729.1 731.7 874.0 914.1 916.3 948.0 952.4 953.0 955.4 956.3 942.5 943.2 945.4 946.3 949.1 549.4 675.3 705.0 705.7 706.9 13089.3 14524.2 14937.7 15017.8 15065.4 12467.9 12589.7 12613.6 12676.6 12786.9 8696.7 9828.1 9927.0 9929.1 10522.0 3445.8 3541.9 3562.8 3580.9 3665.3 78156.6 79835.2 80584.2 80920.0 81363.0 9479.8 9502.7 9571.2 9836.0 9964.6 21610.9 21793.3 21810.9 21879.0 21979.7 7303.8 7411.1 7439.8 7600.9 7701.1 4014.7 4083.5 4105.4 4158.9 4421.2 3427.6 3531.6 3532.5 3539.2 3542.1 642.4 653.0 655.1 657.2 661.3 511.9 527.6 546.9 575.1 589.1 7462.4 7826.5 7956.4 8088.6 8145.4 469.8 519.7 521.9 524.9 593.0 3535.4 3685.5 3748.4 3760.2 3835.8 62254.1 71844.6 77006.1 80860.6 81848.2 52648.7 52850.7 53637.7 63395.7 68391.0 59103.4 60075.0 61550.0 64341.4 66935.0 6789.9 7704.2 8116.8 8180.7 8214.2 40718.2 41418.4 42510.8 42606.8 42785.8 58759.4 59871.4 60151.7 60191.0 60678.4 10859.7 10924.5 11001.1 11029.0 11341.7 10633.8 12101.2 12289.0 12312.7 12390.1 9822.4 11473.5 11708.1 11799.4 11828.8 11219.4 11252.5 11310.6 11668.4 12283.7 9711.0 11326.8 11331.8 11391.0 11695.3 9600.7 9729.0 9731.6 10023.6 10658.2 9726.9 9763.0 9800.8 9819.9 9852.5 05:34:13 INFO - PROCESS | 6205 | -------- Summary: end -------- 05:34:13 INFO - PROCESS | 6205 | 05:34:13 INFO - TEST-INFO | 6205: exit 0 05:34:13 INFO - TEST-OK | dromaeo_css | took 1115945ms 05:34:13 INFO - TEST-START | kraken 05:34:13 INFO - operating with platform_type : mac_ 05:34:13 INFO - Initialising browser for kraken test... 05:34:13 INFO - TEST-INFO | started process 6208 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpWYOrMO/profile http://localhost:56578/getInfo.html) 05:34:16 INFO - PROCESS | 6208 | __metrics Screen width/height:1600/1200 05:34:16 INFO - PROCESS | 6208 | colorDepth:24 05:34:16 INFO - PROCESS | 6208 | Browser inner width/height: 1024/689 05:34:16 INFO - PROCESS | 6208 | __metrics 05:34:16 INFO - PROCESS | 6208 | 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:34:16 ERROR - PROCESS | 6208 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:34:16 INFO - TEST-INFO | 6208: exit 0 05:34:16 INFO - Browser initialized. 05:34:16 INFO - Running cycle 1/1 for kraken test... 05:34:16 INFO - TEST-INFO | started process 6211 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpWYOrMO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:34:28 INFO - PROCESS | 6211 | RSS: Main: 239116288 05:34:28 INFO - PROCESS | 6211 | 05:34:31 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56578/tests/kraken/driver.html?testName=audio-beat-detection) 05:34:32 INFO - PROCESS | 6211 | RSS: Main: 286441472 05:34:32 INFO - PROCESS | 6211 | 05:34:35 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56578/tests/kraken/driver.html?testName=audio-dft) 05:34:35 INFO - PROCESS | 6211 | RSS: Main: 299569152 05:34:35 INFO - PROCESS | 6211 | 05:34:39 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56578/tests/kraken/driver.html?testName=audio-fft) 05:34:39 INFO - PROCESS | 6211 | RSS: Main: 389345280 05:34:39 INFO - PROCESS | 6211 | 05:34:41 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56578/tests/kraken/driver.html?testName=audio-oscillator) 05:34:42 INFO - PROCESS | 6211 | RSS: Main: 288460800 05:34:42 INFO - PROCESS | 6211 | 05:34:44 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56578/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:34:45 INFO - PROCESS | 6211 | RSS: Main: 292601856 05:34:45 INFO - PROCESS | 6211 | 05:34:47 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56578/tests/kraken/driver.html?testName=imaging-darkroom) 05:34:48 INFO - PROCESS | 6211 | RSS: Main: 331132928 05:34:48 INFO - PROCESS | 6211 | 05:34:51 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56578/tests/kraken/driver.html?testName=imaging-desaturate) 05:34:52 INFO - PROCESS | 6211 | RSS: Main: 331665408 05:34:52 INFO - PROCESS | 6211 | 05:34:54 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56578/tests/kraken/driver.html?testName=json-parse-financial) 05:34:55 INFO - PROCESS | 6211 | RSS: Main: 331112448 05:34:55 INFO - PROCESS | 6211 | 05:34:57 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56578/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:34:57 INFO - PROCESS | 6211 | RSS: Main: 321380352 05:34:57 INFO - PROCESS | 6211 | 05:34:59 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56578/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:35:00 INFO - PROCESS | 6211 | RSS: Main: 327565312 05:35:00 INFO - PROCESS | 6211 | 05:35:02 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56578/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:35:03 INFO - PROCESS | 6211 | RSS: Main: 330010624 05:35:03 INFO - PROCESS | 6211 | 05:35:05 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56578/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:35:06 INFO - PROCESS | 6211 | RSS: Main: 334741504 05:35:06 INFO - PROCESS | 6211 | 05:35:09 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56578/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:35:09 INFO - PROCESS | 6211 | RSS: Main: 332361728 05:35:09 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | Cycle 1(1): loaded http://localhost:56578/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56578/tests/kraken/driver.html?testName=ai-astar) 05:35:11 INFO - PROCESS | 6211 | RSS: Main: 396759040 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | __start_tp_report 05:35:11 INFO - PROCESS | 6211 | _x_x_mozilla_page_load 05:35:11 INFO - PROCESS | 6211 | _x_x_mozilla_page_load_details 05:35:11 INFO - PROCESS | 6211 | |i|pagename|runs| 05:35:11 INFO - PROCESS | 6211 | |0;ai-astar;112;112;106;112;113;114;113;114;108;110 05:35:11 INFO - PROCESS | 6211 | |1;audio-beat-detection;161;123;146;170;120;146;143;120;173;118 05:35:11 INFO - PROCESS | 6211 | |2;audio-dft;187;184;188;185;187;185;182;181;185;189 05:35:11 INFO - PROCESS | 6211 | |3;audio-fft;76;72;91;97;81;85;86;87;87;54 05:35:11 INFO - PROCESS | 6211 | |4;audio-oscillator;96;98;138;77;80;80;127;77;76;129 05:35:11 INFO - PROCESS | 6211 | |5;imaging-gaussian-blur;90;86;90;88;90;90;90;88;89;86 05:35:11 INFO - PROCESS | 6211 | |6;imaging-darkroom;95;107;151;103;95;94;96;95;94;95 05:35:11 INFO - PROCESS | 6211 | |7;imaging-desaturate;86;86;86;84;86;86;87;87;86;87 05:35:11 INFO - PROCESS | 6211 | |8;json-parse-financial;59;59;59;58;61;58;52;66;87;59 05:35:11 INFO - PROCESS | 6211 | |9;json-stringify-tinderbox;66;62;79;70;75;72;66;69;69;69 05:35:11 INFO - PROCESS | 6211 | |10;stanford-crypto-aes;80;82;83;82;80;82;81;81;87;83 05:35:11 INFO - PROCESS | 6211 | |11;stanford-crypto-ccm;143;141;139;137;135;137;145;153;177;137 05:35:11 INFO - PROCESS | 6211 | |12;stanford-crypto-pbkdf2;172;176;171;170;168;171;173;171;170;166 05:35:11 INFO - PROCESS | 6211 | |13;stanford-crypto-sha256-iterative;72;68;69;69;70;69;68;73;73;67 05:35:11 INFO - PROCESS | 6211 | __end_tp_report 05:35:11 INFO - PROCESS | 6211 | __start_cc_report 05:35:11 INFO - PROCESS | 6211 | _x_x_mozilla_cycle_collect,2019 05:35:11 INFO - PROCESS | 6211 | __end_cc_report 05:35:11 INFO - PROCESS | 6211 | __startTimestamp1461501311929__endTimestamp 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | ------- Summary: start ------- 05:35:11 INFO - PROCESS | 6211 | Number of tests: 14 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.50 stddev:2.63 (2.3%) stddev-sans-first:2.78 05:35:11 INFO - PROCESS | 6211 | Values: 112.0 112.0 106.0 112.0 113.0 114.0 113.0 114.0 108.0 110.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:146.00 stddev:21.20 (14.5%) stddev-sans-first:21.34 05:35:11 INFO - PROCESS | 6211 | Values: 161.0 123.0 146.0 170.0 120.0 146.0 143.0 120.0 173.0 118.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#2] audio-dft Cycles:10 Average:185.30 Median:186.00 stddev:2.54 (1.4%) stddev-sans-first:2.62 05:35:11 INFO - PROCESS | 6211 | Values: 187.0 184.0 188.0 185.0 187.0 185.0 182.0 181.0 185.0 189.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#3] audio-fft Cycles:10 Average:81.60 Median:86.50 stddev:12.02 (13.9%) stddev-sans-first:12.58 05:35:11 INFO - PROCESS | 6211 | Values: 76.0 72.0 91.0 97.0 81.0 85.0 86.0 87.0 87.0 54.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:78.50 stddev:24.54 (31.3%) stddev-sans-first:26.02 05:35:11 INFO - PROCESS | 6211 | Values: 96.0 98.0 138.0 77.0 80.0 80.0 127.0 77.0 76.0 129.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:90.00 stddev:1.64 (1.8%) stddev-sans-first:1.67 05:35:11 INFO - PROCESS | 6211 | Values: 90.0 86.0 90.0 88.0 90.0 90.0 90.0 88.0 89.0 86.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:95.00 stddev:17.59 (18.5%) stddev-sans-first:18.45 05:35:11 INFO - PROCESS | 6211 | Values: 95.0 107.0 151.0 103.0 95.0 94.0 96.0 95.0 94.0 95.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.00 stddev:0.88 (1.0%) stddev-sans-first:0.93 05:35:11 INFO - PROCESS | 6211 | Values: 86.0 86.0 86.0 84.0 86.0 86.0 87.0 87.0 86.0 87.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:59.00 stddev:9.48 (16.1%) stddev-sans-first:10.01 05:35:11 INFO - PROCESS | 6211 | Values: 59.0 59.0 59.0 58.0 61.0 58.0 52.0 66.0 87.0 59.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:69.50 stddev:4.81 (6.9%) stddev-sans-first:4.91 05:35:11 INFO - PROCESS | 6211 | Values: 66.0 62.0 79.0 70.0 75.0 72.0 66.0 69.0 69.0 69.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.00 stddev:2.02 (2.5%) stddev-sans-first:2.00 05:35:11 INFO - PROCESS | 6211 | Values: 80.0 82.0 83.0 82.0 80.0 82.0 81.0 81.0 87.0 83.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:142.00 stddev:12.62 (8.9%) stddev-sans-first:13.37 05:35:11 INFO - PROCESS | 6211 | Values: 143.0 141.0 139.0 137.0 135.0 137.0 145.0 153.0 177.0 137.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:171.00 stddev:2.70 (1.6%) stddev-sans-first:2.83 05:35:11 INFO - PROCESS | 6211 | Values: 172.0 176.0 171.0 170.0 168.0 171.0 173.0 171.0 170.0 166.0 05:35:11 INFO - PROCESS | 6211 | 05:35:11 INFO - PROCESS | 6211 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:69.50 stddev:2.15 (3.1%) stddev-sans-first:2.13 05:35:11 INFO - PROCESS | 6211 | Values: 72.0 68.0 69.0 69.0 70.0 69.0 68.0 73.0 73.0 67.0 05:35:11 INFO - PROCESS | 6211 | -------- Summary: end -------- 05:35:11 INFO - PROCESS | 6211 | 05:35:12 INFO - TEST-INFO | 6211: exit 0 05:35:12 INFO - TEST-OK | kraken | took 58931ms 05:35:12 INFO - SUITE-END | took 1174s 05:35:13 INFO - Completed test suite (00:19:35) 05:35:13 INFO - javascript benchmark 05:35:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.585551806024, 3366.5455163552915, 3383.9661603383965, 3385.2721664842056, 3430.4511278195478, 5523.1163013917785, 5604.467575580318, 5605.0, 5625.212470254168, 5632.802851900185, 5653.095504719246, 5667.546596272298, 5777.971110144454, 5795.913061304073, 5852.736626851792, 5115.820946266881, 5141.76862041209, 5180.715060671661, 5194.0, 5281.419043905167, 5267.868303292429, 5305.708186049766, 5319.946800532003, 5334.759935802898, 5376.677399356051, 5092.668976516552, 5157.097507936133, 5199.506046925536, 5216.973915130438, 5251.658642188246, 5123.795048198068, 5198.0, 5199.090159222121, 5232.816851410221, 5265.183896496011, 5128.282040514331, 5139.58883289336, 5148.9742551287, 5199.740012999335, 5257.053730328539, 5459.69971651559, 5598.272224610815, 5600.299962504687, 5616.663000219959, 5621.184928185431, 6132.754689812402, 6167.167432396614, 6196.752129914843, 6216.844578885519, 6217.68911554425, 6163.167972323724, 6251.937480625226, 6296.779612713579, 6298.149749783812, 6300.779472718433, 5587.776488940478, 5707.400722924116, 5732.455416735445, 5735.11105778605, 5745.2243947067445, 6143.051335359052, 6247.5939063960695, 6259.873222819936, 6260.780872669435, 6264.2796078451565, 6064.514838812885, 6084.056971169476, 6088.299845517822, 6096.908546371808, 6100.206973093469, 6098.311206366262, 6180.5673602848265, 6245.188125543741, 6262.906056409158, 6276.560640755195, 6096.254562767794, 6103.816885493442, 6148.1085242639565, 6187.288461826947, 6197.287311959182, 6168.074788781629, 6188.164597779332, 6198.163247961557, 6208.348123446973, 6219.720112594944, 4743.335932969387, 4841.636877234221, 4854.368932038812, 4873.878153046131, 4927.989762098761, 4270.103278311527, 4344.304911214191, 4409.581089796464, 4462.799174037176, 4505.864824055284, 5618.213450116987, 5629.127485239795, 5630.465105814941, 5636.4927156556105, 5659.377468478464, 6137.631742095488, 6186.350433204539, 6242.250929888442, 6263.0, 6278.748850045947, 4790.0180463005, 5429.484199001088, 5524.116141417354, 5578.693171875505, 5623.100303951348, 4532.456105267389, 5321.707306098202, 5323.547498462596, 5355.812546560929, 5357.464253574612, 6143.296949342275, 6260.530460215502, 6265.404786545271, 6266.874662506815, 6269.153664255358, 5405.081136206933, 6147.385261473817, 6289.811305660837, 6300.842478938063, 6315.684215789284, 6248.4688801452385, 6267.0286105653695, 6284.340144284967, 6286.277078136073, 6290.685465726604, 5997.220361352996, 6072.240969878765, 6121.234845644294, 6136.048912418582, 6153.969230153821, 6140.0, 6173.56785025044, 6197.163382943245, 6200.627962322275, 6212.596181248205, 5852.917210316105, 5873.442023007979, 6085.421884920926, 6096.634201947897, 6104.725287361991, 5938.524918006636, 6061.090836374579, 6103.389661033861, 6111.84720381994, 6116.174316467398, 5890.558208134493, 5901.793437229677, 5924.407559244041, 5927.0, 5949.0, 5999.970000149971, 6050.848728781815, 6054.636721796706, 6072.726727297194, 6098.462484929893, 5927.229460170151, 5939.554533409925, 5949.672767997802, 5966.970165149147, 5999.670018149044], "value": 5654.706996080851, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.8254628333799, 510.6195884066371, 531.2907268796157, 583.667309633509, 612.8253447767385, 29762.0, 32434.18914527138, 38868.416973745414, 40140.0, 40419.39370909439, 44057.339139912845, 44578.77710611442, 44594.0, 44690.0, 44786.0, 9662.806743865118, 9870.01299870011, 9905.158061564776, 9907.702768916944, 9923.057309555581, 8684.001339845903, 8983.775405614846, 11579.710507237345, 11913.166078374517, 12001.33992630405, 10080.546375413123, 11545.422728863565, 11590.014848737825, 11670.358130302873, 11679.824802627967, 8644.178803013703, 11529.308241505494, 11623.593174238946, 11632.476538555786, 11643.592474263403, 11480.885191148065, 11902.58340958071, 11912.34482103484, 11919.0, 11930.164888457812, 39396.21207575861, 40084.198316033806, 40122.19755604901, 40169.79915100406, 40178.0, 3180.634227063871, 3208.1498402923244, 3231.579894613709, 3236.563063986379, 3375.6118046424485, 28082.578761318597, 28973.855130724212, 29204.561931571043, 29290.56064159018, 29874.40251194986, 2674.7325267473097, 2739.178246526054, 2752.3344809650093, 2764.115483045426, 2821.50623640862, 477157.9999999965, 477410.0, 481303.0, 482291.58854205854, 482602.0, 66311.33688663148, 66736.33263667398, 66883.0, 66908.30554486296, 66929.0, 69765.0, 72066.55866882633, 72159.0, 72245.91631125538, 72246.0, 16601.750973735405, 16621.168941552874, 16872.662546748998, 16885.24016419264, 16885.986840789345, 11755.471003804849, 12099.75800484003, 12132.5146994121, 12809.0, 12912.16070955385, 17371.392001279895, 17660.646787064186, 18387.890397581043, 20443.8977805113, 20533.0, 6910.13426374876, 7955.4033447491665, 8110.391720620977, 8117.889348769362, 8245.752627421187, 943.0710749911332, 953.9952300238455, 961.5961296255589, 1108.5898217659517, 1202.5730865542607, 28878.422431551673, 29057.0, 29306.413871722445, 29551.0, 29589.816407343464, 1094.5676457799284, 1123.0454114003032, 1238.6717519857245, 1271.3528128308233, 1276.7893297605976, 15572.92213538925, 15616.687666246838, 15684.0, 15755.97586156896, 15912.76130858038, 587597.1240287628, 638278.0, 650598.0, 650786.0, 651386.0, 59825.70087149536, 62314.68842655758, 62720.05919911205, 62798.0, 62873.68563157155, 1349.959501214965, 1370.1710465168628, 1490.4708828365774, 1496.513633069257, 1545.5440645009974, 28948.986785462406, 29028.0, 29184.0, 29568.704312956594, 29724.70275297306, 49485.75257123692, 49679.75160124176, 50187.74906125592, 50261.74869125631, 50267.49732502775, 372726.0, 376027.0, 376048.0, 376204.0, 378641.0, 24126.39684007914, 26817.329566760985, 27796.583051254653, 28081.01716439915, 28124.71875281221, 43566.78216608897, 43754.0, 43905.78047109744, 44030.55969440265, 44242.33636495391, 36119.45820812743, 36320.27359452743, 36382.63617363898, 37189.814050930654, 37253.0, 35402.29195416129, 35646.287074258886, 36093.09767255735, 36562.26875462529, 38308.425373619975, 37262.0, 37547.249055019296, 37692.0, 38211.42682859815, 38419.42370864384, 32123.03630891077, 32325.51511727279, 32703.0, 32938.341233175684, 33049.00852974415, 21318.360449186548, 23294.343087548594, 24191.91136398831, 24483.51032979295, 24731.752682473663], "value": 20106.185064215544, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.71041074399574, 391.8648066417086, 395.4108378516012, 398.28110260978923, 407.4682539286232, 15253.618659533504, 15276.312565934531, 17180.140992950364, 19870.0, 19890.900545497254, 16105.436309729163, 16723.581910452245, 19559.70660440091, 20198.39404817858, 20592.588148237028, 4926.999999999991, 5964.493018093467, 6039.5772295939305, 6044.791041791637, 6050.606710563821, 5628.183913332585, 5915.556333275, 5978.0, 5978.611390259619, 5998.9700051499685, 5996.760129594811, 5998.0, 6004.219451471303, 6015.037593984964, 6018.699065046752, 5912.994790885538, 5957.136215248786, 5995.760169593233, 5996.760129594811, 5998.970005149947, 5540.9168862466695, 5691.9146212806845, 5715.514181294595, 5722.828315150552, 5730.971345143247, 12948.417321220568, 13798.0, 13812.516561920385, 13828.792568111487, 13831.654208644764, 1990.8108729670657, 2000.8199262066485, 2038.7349644546262, 2048.129544943408, 2224.688543603897, 8790.120987901222, 9586.37688550249, 9982.800343993153, 10022.398656080659, 10105.898941010642, 1657.9585510362338, 1665.62523432228, 1669.5575672446812, 1706.7029057916072, 1720.2774834569511, 100120.49819252717, 113868.0, 123141.9999999991, 123695.38152309271, 125284.0, 11346.659600212008, 11532.88467115335, 11557.133215008907, 11568.88431115695, 11656.825147622792, 14828.777568336483, 14869.182194979163, 14887.702245955237, 14888.0, 14899.925500372428, 6192.690365481708, 7705.961470192613, 7907.920920790719, 8326.916730832614, 8497.575121243914, 8185.877211841827, 8205.302549283258, 8266.958665206756, 8314.70898518561, 8345.082040975487, 4436.733795972187, 4467.843625473093, 4494.617957473652, 4508.954910450919, 4584.15193189261, 1872.1107473949764, 1932.318882572171, 1938.418474457657, 1945.309415157627, 1979.475439008664, 909.4588719711761, 956.5552018311519, 983.8868530118984, 1006.1900170362782, 1033.6950599573145, 18864.434066978014, 18948.905255473634, 19206.61586768257, 19249.903750481157, 19271.325503607586, 842.2798507276314, 936.424099179, 974.3520558828442, 986.7829077602916, 1024.2215915903967, 8176.427650064557, 9894.752631184278, 10114.0, 10210.24323432669, 10252.23108266883, 576725.0, 581265.0, 596078.0, 597869.0, 598392.0, 101678.0, 122663.38668306601, 128116.0, 132091.0, 133121.0, 978.4178413843752, 979.7158823940978, 996.8853581838036, 998.7952469743685, 1024.9590016399409, 14279.928600356932, 17346.91326543359, 17350.30598776035, 17388.0, 17701.38045168413, 48532.757336214505, 49320.0, 49574.75212624058, 49670.0, 49832.0, 70113.64943175252, 72817.0, 73991.63004184945, 74057.0, 76587.0, 7555.282248186413, 7742.690292388466, 7861.0, 7876.56678882667, 8238.299744521782, 11030.779384412428, 11894.762104757683, 12188.634340969786, 12194.146409751233, 12446.25322480654, 6129.816105516842, 9543.665971690958, 10290.176785856964, 10742.677719668422, 10928.836067458835, 10020.398776073167, 10123.59505619767, 10321.380717156995, 10330.070293673605, 10609.681709548726, 10377.896221037994, 10546.841797372892, 10578.735531611772, 10993.780124397626, 11010.229283950366, 7770.650320735467, 8395.790105247417, 11188.160887933274, 11945.761084778082, 11960.342181180118, 10030.448325341884, 10532.262741608014, 10846.837297440386, 11183.664490065312, 11233.269837460866], "value": 9136.763155386772, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.4042702065133, 1591.8089829220492, 1605.839416058394, 1855.6195979824138, 1981.2075169932034, 7949.801254968629, 8602.784930376736, 8844.027157012739, 8844.5577721114, 8872.955635221831, 8492.53291068991, 8731.738047858558, 8741.650333986649, 8758.91241087589, 8780.912190878089, 2166.2959538150085, 2253.6281513550243, 2257.5372048730055, 2447.8286519943613, 2489.987550062247, 2024.3926821953428, 2260.1298500077423, 2515.4843257132243, 2518.5214809186195, 2521.470491196851, 2414.9396265093337, 2460.7416221296776, 2467.605183170693, 2468.617364308535, 2469.8271121021535, 2151.601953638582, 2419.9516009679796, 2427.453822889853, 2429.465517586128, 2433.598456254716, 2384.8688322142275, 2467.0501856785086, 2467.124170919316, 2469.0494159748446, 2470.765277298654, 8624.094470080614, 8706.347023973227, 8706.651733930636, 8709.172628600272, 8733.912660873373, 5227.869303267411, 5421.403645598981, 5446.863828404282, 5454.0, 5459.727013649342, 3469.1500582357485, 3564.3405969895653, 3626.655467730561, 3646.781193128394, 3671.118931456458, 1004.2719028704204, 1023.4064242739194, 1029.3566520924421, 1029.5367084811865, 1042.7393151712072, 11877.930986211282, 11881.5841445549, 11921.165518413714, 11926.46330915111, 11927.98612117971, 4728.40894888139, 4844.224924012141, 4850.539198776181, 4958.801647934042, 4967.230079337708, 10081.798364032826, 10129.240306977006, 10140.13808826258, 10141.898581014095, 10156.847647285296, 5468.179773034077, 5474.589405794582, 5482.945170548323, 5492.038893193675, 5522.30971128609, 4898.730569818623, 4926.9014619707405, 4991.675541089818, 5010.3737032870895, 5042.520960508746, 3969.503812023497, 4005.2189822984237, 4072.1855628874346, 4122.690798190148, 4138.13099249161, 1348.406701051554, 1362.0193460708274, 1367.8016687580443, 1371.8216631837797, 1374.29223949666, 797.7327595255655, 905.1536813079791, 914.5838643417229, 918.2745630951607, 951.900050494702, 5879.088741245114, 5887.735051922674, 6039.214902062791, 6041.24484439445, 6193.566450348432, 451.14958303597956, 456.5229335344573, 473.09638590292377, 474.46622549631667, 474.684334917283, 3868.574456809749, 3968.265870813909, 4001.079751657135, 4048.2510735513433, 4058.6753059755147, 10268.383896966207, 10555.683329499974, 10823.29648572856, 10881.727956800985, 10895.564177432972, 10674.73313167177, 10826.404547749791, 10827.458627068616, 10848.78302433947, 10882.564697412174, 7695.422843286664, 8146.470479418819, 8290.927268182953, 8294.875576866352, 8306.709265175568, 5860.912086318624, 5980.671063091571, 6059.909101363572, 6126.0, 6140.078988151813, 9731.659391921532, 9885.456299903286, 9899.65351212704, 9969.102780749767, 10013.449260290754, 8622.870656940026, 8820.426672266281, 9078.682246121354, 9994.450305233282, 10111.949440252753, 7382.963085184754, 7500.549967001997, 7533.510321829063, 7575.053118360205, 7586.810329741801, 8071.556064416279, 8384.958075209584, 8460.280876125598, 8463.873041904499, 8467.153284671484, 7297.233790451926, 7396.852062958818, 7420.740274090381, 7452.329290363893, 7548.622568871425, 7231.710731570678, 7239.131304243525, 7290.380317673057, 7390.5196162249285, 7393.371563417169, 6859.2454829969665, 7041.436685065285, 7049.718011279491, 7087.007818905255, 7093.82265443368, 4768.308595253599, 4780.259059845729, 4786.7845946931775, 4847.903041939212, 5057.418396884405, 4991.326170966873, 5036.84889453317, 5046.798128074836, 5082.288479612931, 5286.074936886106], "value": 4547.68279383868, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.9969100308997, 309.6408166526829, 316.73710820019386, 323.55995845649926, 326.31962358482565, 4987.501249875011, 5193.3248677671945, 5602.971985140069, 5730.914036289454, 5831.620944638601, 5456.236126942222, 5730.111832665933, 5735.541156707463, 5744.736158045223, 5767.36558978512, 3539.469079638062, 3653.872114475994, 3674.802921458335, 4218.7468751874985, 4379.781010949456, 4321.286987647034, 4380.2115619188535, 4387.188370151516, 4391.978040109795, 4410.228210063242, 3426.194844211608, 4067.6542493888055, 4155.522114956773, 4200.0339921818, 4391.407160033403, 4003.5996400360023, 4073.4704488416614, 4102.876913692594, 4354.608085272309, 4360.302351623725, 3776.7356285060055, 3799.2401519696173, 3803.486529318562, 3815.179736356669, 3822.617738226183, 4504.684672072956, 5212.348456442945, 5566.777328906839, 5570.888582228374, 5577.972110139464, 4874.780634871439, 5044.940562481883, 5051.570616497602, 5082.567981721558, 5085.771140298695, 6019.187409699678, 6042.244719410074, 6058.909116363214, 6079.969600151971, 6094.481969032638, 4203.285441474956, 4205.516365617963, 4209.536950935395, 4224.9155016899485, 4230.894227644303, 4809.845637047083, 5501.339839219319, 5901.527877769811, 6348.809535713936, 6397.392247736504, 4084.223997440477, 4243.172581346669, 4295.763732994716, 4301.956980430155, 4302.268614335508, 3666.213677604986, 4359.629431498295, 4371.322684727311, 4385.605295523418, 4394.912101758011, 4157.3415394453295, 4419.513853476115, 4878.414590249193, 4905.3132561441425, 4957.418956652411, 5033.0, 5608.9439105609235, 6079.939200608026, 6107.664078475637, 6139.938600614026, 1437.4896911596443, 1481.888858335629, 1508.5625168701174, 1512.7050225206199, 1517.5750789778879, 809.2918696140877, 888.1606881497053, 895.2077411490748, 916.2956900165686, 1008.3344992305043, 4523.163214805271, 5568.192612071227, 5719.027765279912, 5735.397783232784, 5750.137479378043, 6319.6208227506495, 6347.492200623939, 6368.363163683594, 6369.777057803048, 6380.872382552323, 5519.751611177507, 5665.272092011894, 5679.858003549944, 5693.4875861172695, 5705.343885453143, 2970.301979034925, 3305.8512366943546, 3322.7840190387546, 3342.782719123249, 3353.195233144028, 5132.5876837886135, 6452.901808011244, 6511.446527045159, 6623.205215374186, 6624.337566243337, 6454.225492940784, 6475.190601174853, 6490.772822951269, 6522.771702990372, 6557.409833115043, 5675.574331925205, 6259.937400625936, 6587.50593705474, 6614.669266536654, 6626.602403855784, 6230.794213905505, 6307.24313082433, 6307.526935479766, 6328.0, 6335.3981371769605, 6196.907046394398, 6234.75060997555, 6244.063390491463, 6260.843478913063, 6263.154474146114, 6297.118403423616, 6570.8357291068105, 6612.8346791330605, 6615.122421746363, 6626.768063117768, 4105.507339119325, 4119.661110139157, 4135.29699951015, 4145.984233862723, 4149.294619914682, 5224.477552244897, 5224.712640804641, 5741.555066959791, 6288.811335659938, 6340.556161068681, 5369.040300290444, 6111.327753947151, 6118.388161183846, 6149.292831324276, 6150.969245153746, 5324.760385782726, 6021.488173505126, 6106.480949119197, 6132.601380910227, 6137.815865524041, 5092.905025419553, 5645.71771411436, 5966.582339236212, 5994.910076348945, 6015.909181756448, 6094.939050609437, 6100.0849872519475, 6113.480354169946, 6119.418655227746, 6141.07386372153, 4967.900641987212, 5807.564432667482, 5992.490638295793, 6002.819915402545, 6018.337982821974], "value": 4440.85144790597, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.6540468147201, 656.2289310060679, 696.4080531548182, 764.0334976255039, 789.1043665439726, 14592.197429141394, 14909.794394205244, 15011.699766004673, 15067.397304107822, 15077.547673569798, 13669.0, 14076.929615351935, 14098.506552270672, 14139.575812725609, 14143.151410915327, 711.3064761857199, 730.8099894027538, 731.2614259597818, 892.5849479991803, 898.0884402331623, 729.0704351951279, 731.6780616528737, 874.0385575866528, 914.0904799724284, 916.331078312832, 947.9715608531756, 952.3714348529971, 953.0231512699504, 955.4267439536292, 956.2971216156154, 942.4722155592822, 943.235978857121, 945.4280160502866, 946.3139224062603, 949.1410273702276, 549.409384911222, 675.2639622811159, 704.9506534542584, 705.7353492440335, 706.9081019467436, 13089.280089595068, 14524.201168935704, 14937.70124597502, 15017.774733379008, 15065.397384104623, 12467.875321246853, 12589.74820503594, 12613.621591352274, 12676.619701408972, 12786.872131278662, 8696.695615653485, 9828.115469607697, 9927.007299270088, 9929.092150306695, 10522.000409961041, 3445.758796884219, 3541.94687079694, 3562.8218589070443, 3580.856765729368, 3665.2669466106886, 78156.60921695356, 79835.20164798335, 80584.19415805825, 80920.0, 81363.0, 9479.810403791886, 9502.667406640734, 9571.234301255883, 9836.0, 9964.601415943425, 21610.89393015322, 21793.346199614036, 21810.890945545172, 21879.0, 21979.670304945437, 7303.817404564928, 7411.0736157980255, 7439.776806695808, 7600.885986710204, 7701.075870895528, 4014.739041962263, 4083.4895638045246, 4105.4252404663375, 4158.91682166355, 4421.159979603929, 3427.640097789696, 3531.5585551806025, 3532.523109380252, 3539.1682954505673, 3542.0613537412605, 642.4410762636443, 652.9771457998947, 655.1417642887832, 657.2244751193638, 661.2593894837812, 511.872031992002, 527.6020486887107, 546.912494000958, 575.0884847516733, 589.0663298671653, 7462.440316976248, 7826.491278067021, 7956.403269754792, 8088.555129467818, 8145.389095817719, 469.76679787170946, 519.7479222577047, 521.8669239343943, 524.8740302327413, 592.9644221346647, 3535.381308271042, 3685.4656074868994, 3748.4189950557707, 3760.2291530236234, 3835.827387767557, 62254.0661890072, 71844.64077679579, 77006.07484812812, 80860.59569702114, 81848.18151818524, 52648.73675631597, 52850.73574632179, 53637.731811340695, 63395.6830215846, 68391.0, 59103.40896590979, 60075.0, 61550.0, 64341.35658643354, 66935.0, 6789.93210067893, 7704.152543220353, 8116.797080073045, 8180.67277308901, 8214.219649133323, 40718.1856362877, 41418.37872431976, 42510.787446062575, 42606.78696606621, 42785.786071069444, 58759.412405875395, 59871.40128598658, 60151.69924150351, 60191.0, 60678.39321606728, 10859.674209773719, 10924.50839712199, 11001.119910407308, 11029.0, 11341.716457088309, 10633.787324253626, 12101.152919295564, 12289.0, 12312.692182695504, 12390.132690711564, 9822.361546499455, 11473.541058357572, 11708.12189085839, 11799.410029498662, 11828.82256766132, 11219.382933938712, 11252.549898003988, 11310.60412885545, 11668.363419661016, 12283.69290767738, 9711.0, 11326.830097548378, 11331.773364532828, 11391.031762299966, 11695.298282103102, 9600.711978640651, 9729.027097290214, 9731.610735570497, 10023.649172278936, 10658.179173764618, 9726.902730972599, 9763.0, 9800.754981125529, 9819.852702209615, 9852.507374631383], "value": 6636.22147888844, "unit": "score"}], "name": "dromaeo_css", "value": 7199.329059144463, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 106.0, 112.0, 113.0, 114.0, 113.0, 114.0, 108.0, 110.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 123.0, 146.0, 170.0, 120.0, 146.0, 143.0, 120.0, 173.0, 118.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 188.0, 185.0, 187.0, 185.0, 182.0, 181.0, 185.0, 189.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 91.0, 97.0, 81.0, 85.0, 86.0, 87.0, 87.0, 54.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 138.0, 77.0, 80.0, 80.0, 127.0, 77.0, 76.0, 129.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 88.0, 90.0, 90.0, 90.0, 88.0, 89.0, 86.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 107.0, 151.0, 103.0, 95.0, 94.0, 96.0, 95.0, 94.0, 95.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 84.0, 86.0, 86.0, 87.0, 87.0, 86.0, 87.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 58.0, 61.0, 58.0, 52.0, 66.0, 87.0, 59.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 79.0, 70.0, 75.0, 72.0, 66.0, 69.0, 69.0, 69.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 83.0, 82.0, 80.0, 82.0, 81.0, 81.0, 87.0, 83.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 139.0, 137.0, 135.0, 137.0, 145.0, 153.0, 177.0, 137.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 176.0, 171.0, 170.0, 168.0, 171.0, 173.0, 171.0, 170.0, 166.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 69.0, 69.0, 70.0, 69.0, 68.0, 73.0, 73.0, 67.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1494.0, "alertThreshold": 2.0}]} 05:35:13 INFO - Return code: 0 05:35:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:35:13 ERROR - # TBPL SUCCESS # 05:35:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:35:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:35:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:35:13 INFO - 2016-04-24 05:35:13.244 screenresolution[6215:108425] starting screenresolution argv=screenresolution get 05:35:13 INFO - 2016-04-24 05:35:13.247 screenresolution[6215:108425] Display 0: 1600x1200x32@60 05:35:13 INFO - 2016-04-24 05:35:13.253 screenresolution[6216:108428] starting screenresolution argv=screenresolution list 05:35:13 INFO - Available Modes on Display 0 05:35:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:35:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:35:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:35:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:35:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:35:13 INFO - Intel Iris: 05:35:13 INFO - Chipset Model: Intel Iris 05:35:13 INFO - Type: GPU 05:35:13 INFO - Bus: Built-In 05:35:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:35:13 INFO - Vendor: Intel (0x8086) 05:35:13 INFO - Device ID: 0x0a2e 05:35:13 INFO - Revision ID: 0x0009 05:35:13 INFO - Displays: 05:35:13 INFO - Display: 05:35:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:35:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:35:13 INFO - Display Serial Number: 1600x1200 60 05:35:13 INFO - Main Display: Yes 05:35:13 INFO - Mirror: Off 05:35:13 INFO - Online: Yes 05:35:13 INFO - Rotation: Supported 05:35:13 INFO - Return code: 0 05:35:13 INFO - Running post-action listener: _resource_record_post_action 05:35:13 INFO - Running post-run listener: _resource_record_post_run 05:35:14 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 2493952; Write bytes: 696225280; Read time: 32; Write time: 2117 05:35:14 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 390545920; Write bytes: 384656896; Read time: 29564; Write time: 690 05:35:14 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 2465280; Write bytes: 296247296; Read time: 31; Write time: 1297 05:35:14 INFO - Running post-run listener: _upload_blobber_files 05:35:14 INFO - Blob upload gear active. 05:35:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:35:14 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:35:14 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:35:14 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:35:14 INFO - (blobuploader) - INFO - Open directory for files ... 05:35:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:35:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:35:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:16 INFO - (blobuploader) - INFO - Done attempting. 05:35:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:35:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:35:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:17 INFO - (blobuploader) - INFO - Done attempting. 05:35:17 INFO - (blobuploader) - INFO - Iteration through files over. 05:35:17 INFO - Return code: 0 05:35:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:35:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:35:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdf9782d0484656068ef237325a91b903d11fbbb2014344e9d4cae1ceb824eed851057c98547d277690292fcb42efe69f9e99142f714c3f5d21534b99ee94827", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad35d8059bb906ee69635a62863ac9b8c63c7d65964d165989e914887b77b1a5b3425022482b3b0e329169edd94fdac4ba8fc3aca704c0ce6cc057973a482f18"} 05:35:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:35:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:35:17 INFO - Contents: 05:35:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdf9782d0484656068ef237325a91b903d11fbbb2014344e9d4cae1ceb824eed851057c98547d277690292fcb42efe69f9e99142f714c3f5d21534b99ee94827", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad35d8059bb906ee69635a62863ac9b8c63c7d65964d165989e914887b77b1a5b3425022482b3b0e329169edd94fdac4ba8fc3aca704c0ce6cc057973a482f18"} 05:35:17 INFO - Running post-run listener: copy_logs_to_upload_dir 05:35:17 INFO - Copying logs to upload dir... 05:35:17 INFO - mkdir: /builds/slave/test/build/upload/logs 05:35:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.332469 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-04-24 05:35:17.325658) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 05:35:17.329363) ========= 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.hOJRRyb0oD/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.3ALX3lcJg1/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/cdf9782d0484656068ef237325a91b903d11fbbb2014344e9d4cae1ceb824eed851057c98547d277690292fcb42efe69f9e99142f714c3f5d21534b99ee94827", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad35d8059bb906ee69635a62863ac9b8c63c7d65964d165989e914887b77b1a5b3425022482b3b0e329169edd94fdac4ba8fc3aca704c0ce6cc057973a482f18"} build_url:https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/ZcNjR0O7SsqN_c8b29gV5w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdf9782d0484656068ef237325a91b903d11fbbb2014344e9d4cae1ceb824eed851057c98547d277690292fcb42efe69f9e99142f714c3f5d21534b99ee94827", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad35d8059bb906ee69635a62863ac9b8c63c7d65964d165989e914887b77b1a5b3425022482b3b0e329169edd94fdac4ba8fc3aca704c0ce6cc057973a482f18"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 05:35:17.356980) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 05:35:17.357321) ========= 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.hOJRRyb0oD/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.3ALX3lcJg1/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.005166 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 05:35:17.405933) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 05:35:17.406365) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 05:35:17.406805) ========= ========= Total master_lag: 0.31 =========