builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0171 starttime: 1461586936.91 results: success (0) buildid: 20160425030548 builduid: ca65c932a4c54b728705a7503ea01b0d revision: 1c6385ae1fe7e37d8f23f958ce14582f07af729e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:16.907985) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:16.908380) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:16.908646) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:16.931329) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:16.931591) ========= 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.WDFRiKfFRp/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.R5xejp42QT/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-25 05:22:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-04-25 05:22:17 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102556 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:17.052544) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:17.052850) ========= 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.WDFRiKfFRp/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.R5xejp42QT/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.019949 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:17.101144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 05:22:17.103513) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1c6385ae1fe7e37d8f23f958ce14582f07af729e --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 1c6385ae1fe7e37d8f23f958ce14582f07af729e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WDFRiKfFRp/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.R5xejp42QT/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-25 05:22:17,182 truncating revision to first 12 chars 2016-04-25 05:22:17,182 Setting DEBUG logging. 2016-04-25 05:22:17,182 attempt 1/10 2016-04-25 05:22:17,182 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1c6385ae1fe7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 05:22:18,213 unpacking tar archive at: mozilla-central-1c6385ae1fe7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.282963 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 05:22:18.402880) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:18.403175) ========= script_repo_revision: 1c6385ae1fe7e37d8f23f958ce14582f07af729e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:18.403554) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:18.403825) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 05:22:18.424969) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-25 05:22:18.425351) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.WDFRiKfFRp/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.R5xejp42QT/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:22:18 INFO - MultiFileLogger online at 20160425 05:22:18 in /builds/slave/test 05:22:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:22:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:22:18 INFO - {'append_to_log': False, 05:22:18 INFO - 'base_work_dir': '/builds/slave/test', 05:22:18 INFO - 'blob_upload_branch': 'Firefox', 05:22:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:22:18 INFO - 'branch': 'Firefox', 05:22:18 INFO - 'buildbot_json_path': 'buildprops.json', 05:22:18 INFO - 'config_files': ('talos/mac_config.py',), 05:22:18 INFO - 'default_actions': ('clobber', 05:22:18 INFO - 'read-buildbot-config', 05:22:18 INFO - 'download-and-extract', 05:22:18 INFO - 'populate-webroot', 05:22:18 INFO - 'create-virtualenv', 05:22:18 INFO - 'install', 05:22:18 INFO - 'run-tests'), 05:22:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:22:18 INFO - 'download_minidump_stackwalk': True, 05:22:18 INFO - 'download_symbols': 'ondemand', 05:22:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:22:18 INFO - 'tooltool.py': '/tools/tooltool.py', 05:22:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:22:18 INFO - '/tools/misc-python/virtualenv.py')}, 05:22:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:22:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:22:18 INFO - 'installer_path': 'installer.exe', 05:22:18 INFO - 'log_level': 'info', 05:22:18 INFO - 'log_name': 'talos', 05:22:18 INFO - 'log_to_console': True, 05:22:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:22:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:22:18 INFO - 'opt_config_files': (), 05:22:18 INFO - 'pip_index': False, 05:22:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:22:18 INFO - 'cmd': ('bash', 05:22:18 INFO - '-c', 05:22:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:22:18 INFO - 'enabled': True, 05:22:18 INFO - 'halt_on_failure': False, 05:22:18 INFO - 'name': 'check_screen_resolution'},), 05:22:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:22:18 INFO - 'cmd': ('bash', 05:22:18 INFO - '-c', 05:22:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:22:18 INFO - 'enabled': True, 05:22:18 INFO - 'halt_on_failure': False, 05:22:18 INFO - 'name': 'check_screen_resolution'},), 05:22:18 INFO - 'run_cmd_checks_enabled': True, 05:22:18 INFO - 'sps_profile': False, 05:22:18 INFO - 'sps_profile_interval': 0, 05:22:18 INFO - 'suite': 'dromaeojs-e10s', 05:22:18 INFO - 'system_bits': '32', 05:22:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:22:18 INFO - 'title': 't-yosemite-r7-0171', 05:22:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:22:18 INFO - 'use_talos_json': True, 05:22:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:22:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:22:18 INFO - 'work_dir': 'build'} 05:22:18 INFO - ##### 05:22:18 INFO - ##### Running clobber step. 05:22:18 INFO - ##### 05:22:18 INFO - Running pre-action listener: _resource_record_pre_action 05:22:18 INFO - Running main action method: clobber 05:22:18 INFO - rmtree: /builds/slave/test/build 05:22:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:22:20 INFO - Running post-action listener: _resource_record_post_action 05:22:20 INFO - ##### 05:22:20 INFO - ##### Running read-buildbot-config step. 05:22:20 INFO - ##### 05:22:20 INFO - Running pre-action listener: _resource_record_pre_action 05:22:20 INFO - Running main action method: read_buildbot_config 05:22:20 INFO - Using buildbot properties: 05:22:20 INFO - { 05:22:20 INFO - "project": "", 05:22:20 INFO - "product": "firefox", 05:22:20 INFO - "script_repo_revision": "production", 05:22:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:22:20 INFO - "repository": "", 05:22:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:22:20 INFO - "buildid": "20160425030548", 05:22:20 INFO - "stage_platform": "macosx64", 05:22:20 INFO - "basedir": "/builds/slave/test", 05:22:20 INFO - "nightly_build": "True", 05:22:20 INFO - "buildnumber": 37, 05:22:20 INFO - "slavename": "t-yosemite-r7-0171", 05:22:20 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:22:20 INFO - "platform": "yosemite_r7", 05:22:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:22:20 INFO - "branch": "mozilla-central", 05:22:20 INFO - "repo_path": "mozilla-central", 05:22:20 INFO - "revision": "1c6385ae1fe7e37d8f23f958ce14582f07af729e", 05:22:20 INFO - "pgo_build": "False", 05:22:20 INFO - "builduid": "ca65c932a4c54b728705a7503ea01b0d", 05:22:20 INFO - "slavebuilddir": "test" 05:22:20 INFO - } 05:22:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:22:20 INFO - Running post-action listener: _resource_record_post_action 05:22:20 INFO - ##### 05:22:20 INFO - ##### Running download-and-extract step. 05:22:20 INFO - ##### 05:22:20 INFO - Running pre-action listener: _resource_record_pre_action 05:22:20 INFO - Running main action method: download_and_extract 05:22:20 INFO - mkdir: /builds/slave/test/build/tests 05:22: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:22:20 INFO - https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:22:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:22:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:22:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/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:22:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/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:22:22 INFO - Downloaded 1199 bytes. 05:22:22 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:22:22 INFO - Using the following test package requirements: 05:22:22 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:22:22 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:22:22 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:22:22 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:22:22 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:22:22 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:22:22 INFO - u'jsshell-mac.zip'], 05:22:22 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:22:22 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:22:22 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:22:22 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:22:22 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:22:22 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:22:22 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:22:22 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:22:22 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:22:22 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:22:22 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:22:22 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:22 INFO - https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:22:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:22:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:22:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/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:22:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/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:22:23 INFO - Downloaded 18111380 bytes. 05:22:23 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:22:23 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:22:26 INFO - Return code: 0 05:22:26 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:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:26 INFO - https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:22:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:22:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:22:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/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:22:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/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:22:27 INFO - Downloaded 18111380 bytes. 05:22:27 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:22:27 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:22:31 INFO - Return code: 0 05:22: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:22:31 INFO - https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:22:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:22:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:22:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/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:22:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/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:22:32 INFO - Downloaded 10840145 bytes. 05:22:32 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:22:32 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:22:32 INFO - Return code: 0 05:22: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:22:32 INFO - https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:22:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:22:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:22:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:22:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K44pmJgLQIKdeyga9YG5Og/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:22:38 INFO - Downloaded 110280394 bytes. 05:22:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:22:38 INFO - mkdir: /builds/slave/test/properties 05:22:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:22:38 INFO - Writing to file /builds/slave/test/properties/build_url 05:22:38 INFO - Contents: 05:22:38 INFO - build_url:https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:22:39 INFO - Running post-action listener: _resource_record_post_action 05:22:39 INFO - Running post-action listener: set_extra_try_arguments 05:22:39 INFO - ##### 05:22:39 INFO - ##### Running populate-webroot step. 05:22:39 INFO - ##### 05:22:39 INFO - Running pre-action listener: _resource_record_pre_action 05:22:39 INFO - Running main action method: populate_webroot 05:22:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:22:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:22:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:22:39 INFO - '--tppagecycles', 05:22:39 INFO - '7'], 05:22:39 INFO - 'tests': ['tsvgm']}}, 05:22:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:22:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:22:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:22:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:22:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:39 INFO - 'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:39 INFO - 'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['damp', 'tps']}, 05:22:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:39 INFO - 'tests': ['damp', 'tps']}, 05:22:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['dromaeo_dom']}, 05:22:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:22:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['a11yr', 05:22:39 INFO - 'ts_paint', 05:22:39 INFO - 'tpaint', 05:22:39 INFO - 'sessionrestore', 05:22:39 INFO - 'sessionrestore_no_auto_restore', 05:22:39 INFO - 'tabpaint']}, 05:22:39 INFO - 'other-e10s': {'tests': ['a11yr', 05:22:39 INFO - 'ts_paint', 05:22:39 INFO - 'tpaint', 05:22:39 INFO - 'sessionrestore', 05:22:39 INFO - 'sessionrestore_no_auto_restore', 05:22:39 INFO - 'tabpaint']}, 05:22:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:22:39 INFO - 'ts_paint', 05:22:39 INFO - 'tpaint', 05:22:39 INFO - 'sessionrestore', 05:22:39 INFO - 'sessionrestore_no_auto_restore', 05:22:39 INFO - 'tabpaint']}, 05:22:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:22:39 INFO - 'ts_paint', 05:22:39 INFO - 'tpaint', 05:22:39 INFO - 'sessionrestore', 05:22:39 INFO - 'sessionrestore_no_auto_restore', 05:22:39 INFO - 'tabpaint']}, 05:22:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['a11yr', 05:22:39 INFO - 'ts_paint', 05:22:39 INFO - 'tpaint', 05:22:39 INFO - 'sessionrestore', 05:22:39 INFO - 'sessionrestore_no_auto_restore', 05:22:39 INFO - 'tabpaint']}, 05:22:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['a11yr', 05:22:39 INFO - 'ts_paint', 05:22:39 INFO - 'tpaint', 05:22:39 INFO - 'sessionrestore', 05:22:39 INFO - 'sessionrestore_no_auto_restore', 05:22:39 INFO - 'tabpaint']}, 05:22:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['tsvgx', 05:22:39 INFO - 'tsvgr_opacity', 05:22:39 INFO - 'tart', 05:22:39 INFO - 'tscrollx', 05:22:39 INFO - 'cart']}, 05:22:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:22:39 INFO - 'tsvgr_opacity', 05:22:39 INFO - 'tart', 05:22:39 INFO - 'tscrollx', 05:22:39 INFO - 'cart']}, 05:22:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:39 INFO - 'talos_options': ['--disable-e10s'], 05:22:39 INFO - 'tests': ['tp5o']}, 05:22:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:39 INFO - 'tests': ['tp5o']}, 05:22:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:39 INFO - 'talos_options': ['--disable-e10s', 05:22:39 INFO - '--xperf_path', 05:22:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:39 INFO - 'tests': ['tp5n']}, 05:22:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:22:39 INFO - 'talos_options': ['--xperf_path', 05:22:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:39 INFO - 'tests': ['tp5n']}}, 05:22:39 INFO - 'talos.zip': {'path': '', 05:22:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:22:39 INFO - Running post-action listener: _resource_record_post_action 05:22:39 INFO - ##### 05:22:39 INFO - ##### Running create-virtualenv step. 05:22:39 INFO - ##### 05:22:39 INFO - Running pre-action listener: _resource_record_pre_action 05:22:39 INFO - Running main action method: create_virtualenv 05:22:39 INFO - Creating virtualenv /builds/slave/test/build/venv 05:22:39 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:22:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:22:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:22:39 INFO - Using real prefix '/tools/python27' 05:22:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:22:40 INFO - Installing distribute.............................................................................................................................................................................................done. 05:22:43 INFO - Installing pip.................done. 05:22:43 INFO - Return code: 0 05:22:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:22:43 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:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:43 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:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:43 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 0x101b9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e94a8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, '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.WDFRiKfFRp/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.R5xejp42QT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:22:43 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:22:43 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:22:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:22:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:43 INFO - 'HOME': '/Users/cltbld', 05:22:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:43 INFO - 'LOGNAME': 'cltbld', 05:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:43 INFO - 'MOZ_NO_REMOTE': '1', 05:22:43 INFO - 'NO_EM_RESTART': '1', 05:22:43 INFO - 'PAGER': '/bin/cat', 05:22:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:43 INFO - 'PWD': '/builds/slave/test', 05:22:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:43 INFO - 'SHELL': '/bin/bash', 05:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:22:43 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:43 INFO - 'USER': 'cltbld', 05:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:43 INFO - 'XPC_FLAGS': '0x0', 05:22:43 INFO - 'XPC_SERVICE_NAME': '0', 05:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:43 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:22:43 INFO - Cleaning up... 05:22:43 INFO - Return code: 0 05:22:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:22:43 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:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:43 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:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:43 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 0x101b9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e94a8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, '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.WDFRiKfFRp/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.R5xejp42QT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:22:43 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:22:43 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:22:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:22:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:43 INFO - 'HOME': '/Users/cltbld', 05:22:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:43 INFO - 'LOGNAME': 'cltbld', 05:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:43 INFO - 'MOZ_NO_REMOTE': '1', 05:22:43 INFO - 'NO_EM_RESTART': '1', 05:22:43 INFO - 'PAGER': '/bin/cat', 05:22:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:43 INFO - 'PWD': '/builds/slave/test', 05:22:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:43 INFO - 'SHELL': '/bin/bash', 05:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:22:43 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:43 INFO - 'USER': 'cltbld', 05:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:43 INFO - 'XPC_FLAGS': '0x0', 05:22:43 INFO - 'XPC_SERVICE_NAME': '0', 05:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:43 INFO - Downloading/unpacking psutil>=0.7.1 05:22:43 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:22:43 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:22:43 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:22:43 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:22:43 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:22:43 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:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:22:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:46 INFO - Installing collected packages: psutil 05:22:46 INFO - Running setup.py install for psutil 05:22:46 INFO - building 'psutil._psutil_osx' extension 05:22:46 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:22:46 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:22:46 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:22:46 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:22:46 INFO - building 'psutil._psutil_posix' extension 05:22:46 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:22:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:22:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:22:46 INFO - ^ 05:22:46 INFO - 1 warning generated. 05:22:46 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:22:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:47 INFO - Successfully installed psutil 05:22:47 INFO - Cleaning up... 05:22:47 INFO - Return code: 0 05:22:47 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:22:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:47 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 0x101b9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e94a8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, '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.WDFRiKfFRp/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.R5xejp42QT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:22:47 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:22:47 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:22:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:22:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:47 INFO - 'HOME': '/Users/cltbld', 05:22:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:47 INFO - 'LOGNAME': 'cltbld', 05:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:47 INFO - 'MOZ_NO_REMOTE': '1', 05:22:47 INFO - 'NO_EM_RESTART': '1', 05:22:47 INFO - 'PAGER': '/bin/cat', 05:22:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:47 INFO - 'PWD': '/builds/slave/test', 05:22:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:47 INFO - 'SHELL': '/bin/bash', 05:22:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:22:47 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:22:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:47 INFO - 'USER': 'cltbld', 05:22:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:47 INFO - 'XPC_FLAGS': '0x0', 05:22:47 INFO - 'XPC_SERVICE_NAME': '0', 05:22:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:22:49 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:22:49 INFO - Installing collected packages: mozsystemmonitor 05:22:49 INFO - Running setup.py install for mozsystemmonitor 05:22:50 INFO - Successfully installed mozsystemmonitor 05:22:50 INFO - Cleaning up... 05:22:50 INFO - Return code: 0 05:22:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:50 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 0x101b9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e94a8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, '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.WDFRiKfFRp/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.R5xejp42QT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:22:50 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:22:50 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:22:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:22:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:50 INFO - 'HOME': '/Users/cltbld', 05:22:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:50 INFO - 'LOGNAME': 'cltbld', 05:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:50 INFO - 'MOZ_NO_REMOTE': '1', 05:22:50 INFO - 'NO_EM_RESTART': '1', 05:22:50 INFO - 'PAGER': '/bin/cat', 05:22:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:50 INFO - 'PWD': '/builds/slave/test', 05:22:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:50 INFO - 'SHELL': '/bin/bash', 05:22:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:22:50 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:22:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:50 INFO - 'USER': 'cltbld', 05:22:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:50 INFO - 'XPC_FLAGS': '0x0', 05:22:50 INFO - 'XPC_SERVICE_NAME': '0', 05:22:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:50 INFO - Downloading/unpacking blobuploader==1.2.4 05:22: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:22: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:22: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:22: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:22: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:22: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:22:52 INFO - Downloading blobuploader-1.2.4.tar.gz 05:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:22:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:22:52 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:22:52 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:22:52 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:22:52 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:22:52 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:22:52 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:22:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:22:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:53 INFO - Downloading docopt-0.6.1.tar.gz 05:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:22:54 INFO - Installing collected packages: blobuploader, requests, docopt 05:22:54 INFO - Running setup.py install for blobuploader 05:22:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:22:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:22:54 INFO - Running setup.py install for requests 05:22:54 INFO - Running setup.py install for docopt 05:22:54 INFO - Successfully installed blobuploader requests docopt 05:22:54 INFO - Cleaning up... 05:22:54 INFO - Return code: 0 05:22:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22: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:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22: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:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:54 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 0x101b9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e94a8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, '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.WDFRiKfFRp/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.R5xejp42QT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:22:54 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:22:54 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:22:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:22:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:54 INFO - 'HOME': '/Users/cltbld', 05:22:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:54 INFO - 'LOGNAME': 'cltbld', 05:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:54 INFO - 'MOZ_NO_REMOTE': '1', 05:22:54 INFO - 'NO_EM_RESTART': '1', 05:22:54 INFO - 'PAGER': '/bin/cat', 05:22:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:54 INFO - 'PWD': '/builds/slave/test', 05:22:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:54 INFO - 'SHELL': '/bin/bash', 05:22:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:22:54 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:22:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:54 INFO - 'USER': 'cltbld', 05:22:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:54 INFO - 'XPC_FLAGS': '0x0', 05:22:54 INFO - 'XPC_SERVICE_NAME': '0', 05:22:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-qocfy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-nYBIT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-5qwSZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-MWlGYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-l8W_um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-tjVcc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-BcT0MZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-x0NAju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-uqL4Ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-fzrxjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-zNRo7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-koq_ZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-iEhRio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-lUHVDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-pU3gA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-hjYABA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-FNVIQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:22:56 INFO - Running setup.py install for manifestparser 05:22:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Running setup.py install for mozcrash 05:22:57 INFO - Running setup.py install for mozdebug 05:22:57 INFO - Running setup.py install for mozdevice 05:22:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Running setup.py install for mozfile 05:22:57 INFO - Running setup.py install for mozhttpd 05:22:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:22:57 INFO - Running setup.py install for mozinfo 05:22:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Running setup.py install for mozInstall 05:22:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Running setup.py install for mozleak 05:22:58 INFO - Running setup.py install for mozlog 05:22:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Running setup.py install for moznetwork 05:22:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Running setup.py install for mozprocess 05:22:58 INFO - Running setup.py install for mozprofile 05:22:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:22:58 INFO - Running setup.py install for mozrunner 05:22:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:22:59 INFO - Running setup.py install for mozscreenshot 05:22:59 INFO - Running setup.py install for moztest 05:22:59 INFO - Running setup.py install for mozversion 05:22:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:22:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:22:59 INFO - Cleaning up... 05:22:59 INFO - Return code: 0 05:22:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22: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:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22: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:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:59 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 0x101b9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e94a8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, '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.WDFRiKfFRp/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.R5xejp42QT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:22:59 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:22:59 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:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:59 INFO - 'HOME': '/Users/cltbld', 05:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:59 INFO - 'LOGNAME': 'cltbld', 05:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:59 INFO - 'MOZ_NO_REMOTE': '1', 05:22:59 INFO - 'NO_EM_RESTART': '1', 05:22:59 INFO - 'PAGER': '/bin/cat', 05:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:59 INFO - 'PWD': '/builds/slave/test', 05:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:59 INFO - 'SHELL': '/bin/bash', 05:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:22:59 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:59 INFO - 'USER': 'cltbld', 05:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:59 INFO - 'XPC_FLAGS': '0x0', 05:22:59 INFO - 'XPC_SERVICE_NAME': '0', 05:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-VzQUyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:59 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:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-BR6hny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:00 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:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-bOhD8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:00 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:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-NEOjrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:00 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:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-RgE9IO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:00 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:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Yy_Oz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:00 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:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-A_7T5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:00 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:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-EGnbz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:00 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:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Acgec9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:00 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:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-GhEbfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:00 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:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-0L53M4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:01 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:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-bCOgRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:01 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:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-rkIV8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:01 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:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-CBdyoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:01 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:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-HA3mpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:01 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:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-e7XHdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:01 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:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-d3eO6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:01 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:23:01 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:23:01 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:23:01 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:23:01 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:23:01 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:23:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:01 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:23:01 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:23:01 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:23:01 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:23:01 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:23:01 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:23:04 INFO - Downloading blessings-1.6.tar.gz 05:23:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:23:04 INFO - Installing collected packages: blessings 05:23:04 INFO - Running setup.py install for blessings 05:23:04 INFO - Successfully installed blessings 05:23:04 INFO - Cleaning up... 05:23:04 INFO - Return code: 0 05:23:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:23:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:04 INFO - Reading from file tmpfile_stdout 05:23:04 INFO - Current package versions: 05:23:04 INFO - blessings == 1.6 05:23:04 INFO - blobuploader == 1.2.4 05:23:04 INFO - docopt == 0.6.1 05:23:04 INFO - manifestparser == 1.1 05:23:04 INFO - mozInstall == 1.12 05:23:04 INFO - mozcrash == 0.17 05:23:04 INFO - mozdebug == 0.1 05:23:04 INFO - mozdevice == 0.48 05:23:04 INFO - mozfile == 1.2 05:23:04 INFO - mozhttpd == 0.7 05:23:04 INFO - mozinfo == 0.9 05:23:04 INFO - mozleak == 0.1 05:23:04 INFO - mozlog == 3.1 05:23:04 INFO - moznetwork == 0.27 05:23:04 INFO - mozprocess == 0.22 05:23:04 INFO - mozprofile == 0.28 05:23:04 INFO - mozrunner == 6.11 05:23:04 INFO - mozscreenshot == 0.1 05:23:04 INFO - mozsystemmonitor == 0.0 05:23:04 INFO - moztest == 0.7 05:23:04 INFO - mozversion == 1.4 05:23:04 INFO - psutil == 3.1.1 05:23:04 INFO - requests == 1.2.3 05:23:04 INFO - wsgiref == 0.1.2 05:23:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:04 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:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:04 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:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:04 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 0x101b9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e94a8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, '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.WDFRiKfFRp/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.R5xejp42QT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:23:04 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:23:04 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:23:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:23:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:04 INFO - 'HOME': '/Users/cltbld', 05:23:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:04 INFO - 'LOGNAME': 'cltbld', 05:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:04 INFO - 'MOZ_NO_REMOTE': '1', 05:23:04 INFO - 'NO_EM_RESTART': '1', 05:23:04 INFO - 'PAGER': '/bin/cat', 05:23:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:04 INFO - 'PWD': '/builds/slave/test', 05:23:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:04 INFO - 'SHELL': '/bin/bash', 05:23:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:23:04 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:23:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:04 INFO - 'USER': 'cltbld', 05:23:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:04 INFO - 'XPC_FLAGS': '0x0', 05:23:04 INFO - 'XPC_SERVICE_NAME': '0', 05:23:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:04 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:23:04 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:23:04 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:23:04 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:23:04 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:23:04 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:23:04 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:23:04 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:23:04 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:23:04 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:23:04 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:23:04 INFO - Cleaning up... 05:23:04 INFO - Return code: 0 05:23:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:23:04 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:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:04 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:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:04 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 0x101b9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e94a8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, '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.WDFRiKfFRp/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.R5xejp42QT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:23:04 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:23:04 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:23:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:23:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:04 INFO - 'HOME': '/Users/cltbld', 05:23:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:04 INFO - 'LOGNAME': 'cltbld', 05:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:04 INFO - 'MOZ_NO_REMOTE': '1', 05:23:04 INFO - 'NO_EM_RESTART': '1', 05:23:04 INFO - 'PAGER': '/bin/cat', 05:23:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:04 INFO - 'PWD': '/builds/slave/test', 05:23:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:04 INFO - 'SHELL': '/bin/bash', 05:23:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:23:04 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:23:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:04 INFO - 'USER': 'cltbld', 05:23:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:04 INFO - 'XPC_FLAGS': '0x0', 05:23:04 INFO - 'XPC_SERVICE_NAME': '0', 05:23:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:05 INFO - Downloading/unpacking jsonschema 05:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:23:07 INFO - Downloading/unpacking functools32 (from jsonschema) 05:23:07 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:23:07 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:23:07 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:23:07 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:23:07 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:23:07 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:23:08 INFO - Downloading functools32-3.2.3-2.tar.gz 05:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:23:08 INFO - warning: no files found matching '*.txt' 05:23:08 INFO - no previously-included directories found matching 'build' 05:23:08 INFO - no previously-included directories found matching 'dist' 05:23:08 INFO - no previously-included directories found matching '.git*' 05:23:08 INFO - Installing collected packages: jsonschema, functools32 05:23:08 INFO - Running setup.py install for functools32 05:23:08 INFO - warning: no files found matching '*.txt' 05:23:08 INFO - no previously-included directories found matching 'build' 05:23:08 INFO - no previously-included directories found matching 'dist' 05:23:08 INFO - no previously-included directories found matching '.git*' 05:23:08 INFO - Successfully installed jsonschema functools32 05:23:08 INFO - Cleaning up... 05:23:08 INFO - Return code: 0 05:23:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:08 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 0x101b9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bac420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e94a8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, '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.WDFRiKfFRp/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.R5xejp42QT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:23:08 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:23:08 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:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:08 INFO - 'HOME': '/Users/cltbld', 05:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:08 INFO - 'LOGNAME': 'cltbld', 05:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:08 INFO - 'MOZ_NO_REMOTE': '1', 05:23:08 INFO - 'NO_EM_RESTART': '1', 05:23:08 INFO - 'PAGER': '/bin/cat', 05:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:08 INFO - 'PWD': '/builds/slave/test', 05:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:08 INFO - 'SHELL': '/bin/bash', 05:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:23:08 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:08 INFO - 'USER': 'cltbld', 05:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:08 INFO - 'XPC_FLAGS': '0x0', 05:23:08 INFO - 'XPC_SERVICE_NAME': '0', 05:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:08 INFO - Downloading/unpacking flake8 05:23:08 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:23:08 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:23:08 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:23:08 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:23:08 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:23:08 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:23:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:23:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:23:11 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:23:11 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:23:11 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:23:11 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:23:11 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:23:11 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:23:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:23:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:23:11 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:23:11 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:23:11 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:23:11 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:23:11 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:23:11 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:23:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:23:12 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:23:12 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:23:12 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:23:12 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:23:12 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:23:12 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:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:23:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:23:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:23:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:23:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:23:12 INFO - no previously-included directories found matching 'docs/_build' 05:23:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:23:12 INFO - Running setup.py install for pep8 05:23:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:23:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:23:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:23:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:23:12 INFO - no previously-included directories found matching 'docs/_build' 05:23:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:23:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:23:12 INFO - Cleaning up... 05:23:12 INFO - Return code: 0 05:23:12 INFO - Running post-action listener: _resource_record_post_action 05:23:12 INFO - Running post-action listener: _start_resource_monitoring 05:23:12 INFO - Starting resource monitoring. 05:23:12 INFO - ##### 05:23:12 INFO - ##### Running install step. 05:23:12 INFO - ##### 05:23:12 INFO - Running pre-action listener: _resource_record_pre_action 05:23:12 INFO - Running main action method: install 05:23:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:13 INFO - Reading from file tmpfile_stdout 05:23:13 INFO - Detecting whether we're running mozinstall >=1.0... 05:23:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:13 INFO - Reading from file tmpfile_stdout 05:23:13 INFO - Output received: 05:23:13 INFO - Usage: mozinstall [options] installer 05:23:13 INFO - Options: 05:23:13 INFO - -h, --help show this help message and exit 05:23:13 INFO - -d DEST, --destination=DEST 05:23:13 INFO - Directory to install application into. [default: 05:23:13 INFO - "/builds/slave/test"] 05:23:13 INFO - --app=APP Application being installed. [default: firefox] 05:23:13 INFO - mkdir: /builds/slave/test/build/application 05:23:13 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:23:13 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:23:47 INFO - Reading from file tmpfile_stdout 05:23:47 INFO - Output received: 05:23:47 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:23:47 INFO - Running post-action listener: _resource_record_post_action 05:23:47 INFO - ##### 05:23:47 INFO - ##### Running run-tests step. 05:23:47 INFO - ##### 05:23:47 INFO - Running pre-action listener: _resource_record_pre_action 05:23:47 INFO - Running main action method: run_tests 05:23:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:23:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:23:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:23:47 INFO - 2016-04-25 05:23:47.061 screenresolution[3234:54135] starting screenresolution argv=screenresolution get 05:23:47 INFO - 2016-04-25 05:23:47.063 screenresolution[3234:54135] Display 0: 1600x1200x32@60 05:23:47 INFO - 2016-04-25 05:23:47.068 screenresolution[3235:54138] starting screenresolution argv=screenresolution list 05:23:47 INFO - Available Modes on Display 0 05:23:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:23:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:23:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:23:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:23:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:23:47 INFO - Intel Iris: 05:23:47 INFO - Chipset Model: Intel Iris 05:23:47 INFO - Type: GPU 05:23:47 INFO - Bus: Built-In 05:23:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:23:47 INFO - Vendor: Intel (0x8086) 05:23:47 INFO - Device ID: 0x0a2e 05:23:47 INFO - Revision ID: 0x0009 05:23:47 INFO - Displays: 05:23:47 INFO - Display: 05:23:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:23:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:23:47 INFO - Display Serial Number: 1600x1200 60 05:23:47 INFO - Main Display: Yes 05:23:47 INFO - Mirror: Off 05:23:47 INFO - Online: Yes 05:23:47 INFO - Rotation: Supported 05:23:47 INFO - Return code: 0 05:23:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:23:47 INFO - Python 2.7.3 05:23:47 INFO - Return code: 0 05:23:47 INFO - grabbing minidump binary from tooltool 05:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:47 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 0x101a3be00>, '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 0x1018a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a36b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:23:47 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:23:47 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:23:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:23:47 INFO - Return code: 0 05:23:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:23:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:23:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:23:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:23:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:23:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:23:48 INFO - Return code: 0 05:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0171 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:23:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDFRiKfFRp/Render', 05:23:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:48 INFO - 'HOME': '/Users/cltbld', 05:23:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:48 INFO - 'LOGNAME': 'cltbld', 05:23:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:23:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:48 INFO - 'MOZ_NO_REMOTE': '1', 05:23:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:23:48 INFO - 'NO_EM_RESTART': '1', 05:23:48 INFO - 'PAGER': '/bin/cat', 05:23:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:48 INFO - 'PWD': '/builds/slave/test', 05:23:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:23:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:48 INFO - 'SHELL': '/bin/bash', 05:23:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5xejp42QT/Listeners', 05:23:48 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:23:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:48 INFO - 'USER': 'cltbld', 05:23:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:48 INFO - 'XPC_FLAGS': '0x0', 05:23:48 INFO - 'XPC_SERVICE_NAME': '0', 05:23:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:23:48 INFO - mozversion application_buildid: 20160425030548 05:23:48 INFO - mozversion application_changeset: 1c6385ae1fe7e37d8f23f958ce14582f07af729e 05:23:48 INFO - mozversion application_display_name: Nightly 05:23:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:23:48 INFO - mozversion application_name: Firefox 05:23:48 INFO - mozversion application_remotingname: firefox 05:23:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:23:48 INFO - mozversion application_vendor: Mozilla 05:23:48 INFO - mozversion application_version: 48.0a1 05:23:48 INFO - mozversion platform_buildid: 20160425030548 05:23:48 INFO - mozversion platform_changeset: 1c6385ae1fe7e37d8f23f958ce14582f07af729e 05:23:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:23:48 INFO - mozversion platform_version: 48.0a1 05:23:48 INFO - using testdate: 1461587028 05:23:48 INFO - actual date: 1461587028 05:23:48 INFO - starting webserver on 'localhost:65342' 05:23:48 INFO - SUITE-START | Running 2 tests 05:23:48 INFO - TEST-START | dromaeo_css 05:23:48 INFO - operating with platform_type : mac_ 05:23:48 INFO - Initialising browser for dromaeo_css test... 05:23:48 INFO - TEST-INFO | started process 3253 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmplMheFD/profile http://localhost:65342/getInfo.html) 05:23:52 INFO - PROCESS | 3253 | __metrics Screen width/height:1600/1200 05:23:52 INFO - PROCESS | 3253 | colorDepth:24 05:23:52 INFO - PROCESS | 3253 | Browser inner width/height: 1024/689 05:23:52 INFO - PROCESS | 3253 | __metrics 05:23:52 ERROR - PROCESS | 3253 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:23:52 INFO - TEST-INFO | 3253: exit 0 05:23:52 INFO - Browser initialized. 05:23:52 INFO - Running cycle 1/1 for dromaeo_css test... 05:23:52 INFO - TEST-INFO | started process 3256 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmplMheFD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:04 INFO - PROCESS | 3256 | RSS: Main: 249794560 05:24:04 INFO - PROCESS | 3256 | 05:24:54 INFO - PROCESS | 3256 | 1461587094611 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:27:04 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:65342/tests/dromaeo/cssquery-dojo.html (next: http://localhost:65342/tests/dromaeo/cssquery-ext.html) 05:27:06 INFO - PROCESS | 3256 | RSS: Main: 261697536 05:27:06 INFO - PROCESS | 3256 | 05:30:08 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:65342/tests/dromaeo/cssquery-ext.html (next: http://localhost:65342/tests/dromaeo/cssquery-jquery.html) 05:30:09 INFO - PROCESS | 3256 | RSS: Main: 274178048 05:30:09 INFO - PROCESS | 3256 | 05:33:10 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:65342/tests/dromaeo/cssquery-jquery.html (next: http://localhost:65342/tests/dromaeo/cssquery-mootools.html) 05:33:11 INFO - PROCESS | 3256 | RSS: Main: 275451904 05:33:11 INFO - PROCESS | 3256 | 05:36:13 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:65342/tests/dromaeo/cssquery-mootools.html (next: http://localhost:65342/tests/dromaeo/cssquery-prototype.html) 05:36:13 INFO - PROCESS | 3256 | RSS: Main: 277164032 05:36:13 INFO - PROCESS | 3256 | 05:39:16 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:65342/tests/dromaeo/cssquery-prototype.html (next: http://localhost:65342/tests/dromaeo/cssquery-yui.html) 05:39:17 INFO - PROCESS | 3256 | RSS: Main: 280051712 05:39:17 INFO - PROCESS | 3256 | 05:42:19 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:65342/tests/dromaeo/cssquery-yui.html (next: http://localhost:65342/tests/dromaeo/cssquery-dojo.html) 05:42:20 INFO - PROCESS | 3256 | RSS: Main: 281534464 05:42:20 INFO - PROCESS | 3256 | 05:42:20 INFO - PROCESS | 3256 | __start_tp_report 05:42:20 INFO - PROCESS | 3256 | _x_x_mozilla_page_load 05:42:20 INFO - PROCESS | 3256 | _x_x_mozilla_page_load_details 05:42:20 INFO - PROCESS | 3256 | |i|pagename|runs| 05:42:20 INFO - PROCESS | 3256 | |0;dojo.html;2865.555838844979;3458.706009989151;3459.152507635629;3496.4930085137657;3531.276088401878;5659.688717120562;5710.057840456325;5711.971440142804;5730.255066841315;5733.598648094635;5788.47903688668;5798.739056742446;5881.147233651129;5912.704364781765;6027.69861506924;5189.974050129754;5252.763625636836;5300.840974770763;5306.655067420625;5345.7594408251725;88.89714303401739;5215;5263.289455923459;5304.867378315535;5313.654612450197;5271.472852714736;5272.604554658396;5287.391949925731;5289.523942845143;5290.285811415448;5283.577313814885;5318.734063296858;5322.2282768998275;5338.905524367496;5353.277307563468;5195.584353251731;5204.401493828221;5245.79016839326;5255.816046438356;5299.655522391071;5606.691631960241;5752.769889204426;5757.395473475267;5769.336526299446;5798.565107616945;6150.292716337589;6189.969050154766;6255.06174073888;6266.592671476339;6287.842803929939;6210.689465526705;6258.593191442542;6262.937370626327;6300.401461861115;6337.968310158466;5976.701164941779;5994;6020.96989515054;6035.849103772353;6071.969640151815;6219.782307619212;6318.494520438354;6350.047492876013;6416;6426.485881129498;5155.819546315861;6129.847850912788;6178;6225.159603453566;6248.937510624835;5123.180291153398;5240.475952404805;6227.910115729729;6296.055591661196;6318.052292156213;5314.7608357624;5368.892622147535;6104.481119104926;6180.81457556274;6234.314225435199;5622.931642987819;5993.0710739835395;6191.164192834;6235.563510554309;6253.312135665074;4574.130915126182;4889.168841296988;4894.535019173173;4956.603471722254;4970.453250142481;4283.250431174532;4424.093060922503;4453.732776033448;4521.299198624219;4600.125976064537;5865.090910908816;5926.7925622603;5929.436703513159;5996.340402555716;6056.788012419544;6228.096925945803;6263.217097862767;6277.905831412532;6286.05709143623;6296.748130074838;5840.415958404125;5877.412258774174;5924.407559244128;6000.609960352563;6049.274087109575;5465.180222966586;5477.643953143033;5491.807786727525;5527.44725527444;5534.695591742412;6482.416582507597;6496.967515162394;6506.479481641552;6520.380563846427;6524.608523488606;6153.953827849186;6213;6236.875262494816;6269.279032911274;6287.056941458635;6460.25707043696;6480.25477070143;6502.512311576556;6505.991571306455;6524.478041756743;6251.312355640966;6263.6241825490615;6264.655443950626;6270.310265870842;6284.4344009039405;6132.477886260695;6180.598261113013;6236.6258024518675;6275.811725648238;6296.968515157395;6166.136740856194;6191.25704915413;6196.256449226122;6199.442050215503;6200.100985357189;5829.15477255787;6119.510439164945;6222.906656400067;6232;6237;4748.335233067304;6061.454469097803;6065.059915713072;6132.20254427538;6146.477549408171;6156.445919867234;6218.906716399167;6223.875522489616;6230.937690623035;6251.499880009679;5910.143029260647;6064.514838812973;6141;6170.969145154425;6178.783742568989 05:42:20 INFO - PROCESS | 3256 | |1;ext.html;518.9091908915939;541.3963430774687;543.9782408703652;565.6408180805188;586.7443670545033;28321.716782832165;38963.41554876675;38976.02559936003;39019;39022.41466378003;33109.83445082777;39807.80096099523;40663.983400415;40664.59335406645;40733.18533629326;8805.031446540876;8958.37291389603;9416.199623032051;9494.952525237364;9630.759231019245;9433.151016408521;9693.903060969405;9701.563429645683;9706.223502119848;9734.123928846402;9605;9679.419234845896;9701.805963880719;9714.854277185812;9722.513874306293;8909.198172164472;9037.774055648595;9616.278779091595;9701.369410988298;9717.65988190417;8907.331950103704;8959.41763785352;9330.346875718702;9392.67125650599;9541.2367010639;34330.31339373199;38941.80529097365;39314;39461.40807887884;39480.60519394798;2588.6634737484196;2937.911862644124;2996.6104406427025;3031.575579418883;3059.2504836315024;22197.889010554845;26343.73656263432;26617;27173.04894328709;27846.860765696245;2624.960625590618;2628.342914271432;2633.2758491415016;2755.4213615140843;2832.9433411331865;348726;350053;350474;351530.2423487866;352213.99999999744;33109.503357449656;33726.49410258799;33930.49104263438;35033.82483087568;35192.29615407729;34943.475847862304;35116.473252901225;35245.82377088149;35287;35308.11729706726;14918.70162596742;15041.097534147986;15160.620984475476;15183.620409489851;15185.316660750294;12641.2415255085;12664.81002784959;12685.302308372944;12737.044721645914;12761.553345633043;8346.916530834735;8375.329973602098;8417.915820841836;8428.662853485914;8447.408681392244;3521.0669172669263;3574.964250357515;3575.0526110580718;3583.480395342661;3611.368010598135;809.7651681012441;850.6809946270149;1021.4126877045728;1062.2723434447428;1078.9946050269698;28990;29323.7067629321;29392.853035735112;29849.10452686423;29875.701242987725;1037.159900480598;1084.4577711144427;1113.218849840249;1142.857142857143;1149.9592868454063;15094.54716358489;15254.084754914518;15276.458860555054;15350.848686348569;15387.38450461969;561325;566885;573401;575118;579125.1043744754;58968.23095307148;59080.40919590835;59349.70325148433;59524.40475595275;59840.70079649661;1426.3937826423728;1458.3728996531381;1469.882409407245;1482.1255459279078;1558.3610719604908;23890.163844265368;26172.8691356542;28702.712972870006;29000.564991524723;29308;50329.245061324844;51153.488465114875;51258.74370628123;51639.48360516347;51899.48100518947;442927;442966;445231;446681;448756;23534.176303829285;26503.204903852915;26575.804088816436;26849;27114.45771084607;45249.77375113103;45275.54724452713;45977.62067137991;46373.536264636925;46469.535304646524;29728.513574320765;36920;37150.44274335833;37575.43636845395;37952.240955181296;36836;36887.81556092203;37139.81430092832;37255.81372093122;37837.24325513529;36677.08307292339;36751.816240919696;37026.814865925495;37391.62608373881;37554.061148471505;25854.61218081768;31725.20686982844;32798.34403311968;32840.835795820865;33023.834880826405;22300.888495557418;24485.632715509637;24540.6318905213;24714.752852471964;24907.003719851728 05:42:20 INFO - PROCESS | 3256 | |2;jquery.html;354.1800731307024;358.46767550187985;404.3005600311461;414.56470705758943;416.47316145076474;16708.830381873275;18894.622107557858;20446.182152713893;20705.689414658773;20786.584268314626;20265.696014559795;20805.895970520163;20871.582568348626;21072.68390974133;21072.840993745336;4931.728754918478;5771.682557459338;5931.08068249422;6147.661878596676;6154.323024467305;5997.190379298782;6035.155078289043;6087.665178415185;6088.147659327676;6094.481969032616;5587.916181257264;5754.02181629122;5828.650280983134;5952.672603006833;6022.578419510636;5673.574481913853;5903.88192236155;5959.821205363846;5974.462298393166;6012.879742405171;5701.6864072476;5750.79872204475;5754.338251101093;5768.74040668171;5809.796657116981;12851.742965140738;13731.725365492634;13779.517716879862;13826.101303415246;13851.238181899991;2044.2436298569476;2156.5686862627535;2246.2362796649054;2293.3005433342823;2318.4783423729696;9812.803743925082;10209.183265338756;10741;14975.176365299903;15333;1689.0625702734976;1731.6969530332267;1752.7984281807628;1797.2092279397023;1804.846588040018;123966;128493.35753321173;128547;129257;131254.3437282817;11545.36500492481;11754.000909922752;11830.704232394259;11883.287002779862;11899.762004759856;11736;12205.877941220475;14565.198914059829;14622.780658290134;14667.92666036663;8623.482591044432;8730.995935467376;8786.209241168197;8824.823503529893;8842.159994800484;8365;8710.869336959951;8738.606762695694;8833.69082082124;8835;4537.251353526697;4659.394278743809;4661.790219440133;4668.85993420198;4702.88242793926;1740.8520275776448;1926.6339395514808;1995.5310502032012;2025.351887396019;2044.396902913644;882.7307671160229;922.377395258198;933.9813203735887;969.5297780576415;1067.3595842494442;19306.806931930783;20157.891315977762;20667.276645317343;21209;21281.78718212829;835.5989125219928;868.5006121480175;958.3004406782948;1002.2683441087901;1060.4273692206277;8857.601407936658;10538.525766340534;10740.838887416696;10779.461026948622;10802.135829133651;559221;563127.3687263157;565719;575988;576450;118845;129427;133178;135464;136081;1079.7570546626946;1080.4975686305906;1106.1869525898458;1108.5621179634;1114.0864491117368;17975.730364044288;18075.548111296797;18189.727154092965;18197.818021819614;18510;39630.80184599059;54953.45046549483;55532.05637802819;55816;57143;79328;79460.60269698614;80866.59566702129;81794;82101;7008.369246767816;7408.148062972611;7861.960690196741;7973.760787176394;8110.878336825071;8906.376553641183;11575.247608905394;12325.198862073936;12587.307698076327;12616.432260548481;9377.874655041438;10725.141988640735;11042.558297668325;11198.664040078811;11240.606578769704;8958.776030599287;9305.720828375159;9780.706578802647;10732.14142868553;10984.670459886216;10327.793444130924;10885.129189664649;11323.660290191307;11374.601888933848;11536.82694759596;7954.920450795649;8023.157568455462;8026.638801254075;8083;8243.83512329762;9892.208623310262;11052.447377630926;11307.491162897524;11318.943405283251;11319.830202546804 05:42:20 INFO - PROCESS | 3256 | |3;mootools.html;1619.9433019844305;1645.3171933647534;1646.440210328488;1956.3446245507755;1977.149825575003;7972.282494575487;8496.49021058736;8604.225619694227;8881.467111973287;8903.198712115909;8583.05586385497;8675.60959756811;8746.475211487299;8777.122287771219;8781.95609021954;2431.19770475743;2464.827462077655;2471.530409222242;2474.232987773787;2489.116363690891;2426.3934016495878;2481.0944005437977;2484.2050543825985;2484.689413823272;2491.215267190838;2107.725995620567;2440.7803297703203;2460.3849037740565;2461.05249478951;2495.089292408267;2132.61612909677;2339.1695947938492;2421.7336093029753;2424.090965887792;2450.5834008218644;2040.7857324891665;2390.8924098415614;2425.563398588253;2435.5494233566847;2437.049550675238;8758.605862736193;8888.155625215593;8919.598618062202;8931.553422328923;8934.374593778439;5227.085260079471;5470.425605311425;5508;5515.751791169407;5540.750666219989;3546.1666508370513;3557.7153827693724;3597.1726502904216;3631.273745250934;3671.596124426311;1040.4433628008978;1041.807265655856;1050.4642632257528;1051.989480105197;1053.3206082077018;11551.306921584648;11698.356590387526;11722.531098756039;11739.647810565782;11741.119416043832;4179.331306990867;4809.0042632307905;4946.258061290763;4956.033573453142;5010.073136469765;9821.263405244636;9983.001699830103;9983.850242246372;9997.350172238926;10029.147522460673;5375.086235340002;5441.102218133963;5465.535429488538;5490.890182196334;5491.313585801775;4464.374987501752;4747.525247475225;4930.852074437773;4965.155923492943;4976.029674213566;4047.4131250968444;4114.692381036628;4146.419501269825;4189.664826813848;4195.559466256061;1345.5425155447194;1395.1768456610648;1395.9092658977338;1399.818023656918;1404.403128670311;752.5033477904557;891.1266958380824;935.9190135393632;942.0673533202217;966.8114717630135;4925.581325587294;5698.430156984351;5747.597668163272;5867.516394384229;5975.850603734942;347.3296537682297;464.8837790552362;470.962323014158;474.30040689982;478.2372116474218;3963.6829053675638;4012.538558065801;4034.616711412411;4084.36692312692;4105.0763578195265;9088.682141089637;10171.033751793568;10348.120409765104;10607.151427885905;10783.08343790771;9451.33840631163;10122.139618132542;10539.789204216026;10579.894201058045;10652.254342196127;8077.35381169505;8214.383921206054;8221.383396245306;8256.463329883538;8301.169883011651;5726.713664316851;5890.7349169288345;5892.410758924073;6115.938840611715;6232.8130156095385;9642.614295428104;9734.561944712363;9744.17174540172;9753.366031207948;9923.206143508647;8369.707060252862;9905.752356191153;9948.35335703206;10092.596296148073;10231.641892533726;6874.346937040973;7319.451041171827;7422.925770742439;7545.849083018199;7581.241875812375;8149.633266502903;8212.589370531568;8245.83508329842;8321.12628174033;8338.749837504885;7225.747098851515;7325.304096110861;7370.557766534025;7466.328030477283;7488.850222995401;7252.41980641558;7258.818529536804;7351.77944661661;7353.374963128194;7369.078865141857;6932.792016239521;7007.509474336952;7105.076340075964;7133.072700548895;7137.464690148364;4206.72656277341;4751.263554149113;4764.142454358249;4925.8029678812445;5120.769565369642;4972.353594032753;5005.898702285492;5081.18701007837;5081.4918508148885;5104.336436263262 05:42:20 INFO - PROCESS | 3256 | |4;prototype.html;292.897485879942;311.4533992842561;321.66868125830405;322.643478955754;330.3525090821989;5393.595480338976;5501.36734275558;5524.425538721465;5537;5576.944230557693;4271.63691086258;4609.617114865544;5281.947180528194;5465.289512363387;5468;3691.280200360931;3986.4219688145204;4098.692598055143;4218.219629368561;4260.126674031816;3231.1437469070715;3688.409854423293;4157.417961485395;4181.393697913801;4194.035371864473;4157.958420415803;4162.229987452331;4180.686448516358;4192.37114432836;4193.832246710143;3350.6481819408855;3942.6845852331744;3946.066427450557;4152.875413737593;4185.790710464465;2897.5943367928294;3562.323158599858;3577.1041348085555;3584.4945123048383;3636.3091012707764;5309.283246761676;5326.481952643478;5328.92006619901;5338.706371149585;5352.678839269656;4322.891927701801;4686.906261874778;4722.291656251556;4725.385699859031;4751.168545504523;4716.646251531149;5194.065068287707;5690.9146362804595;5697.743601537899;5781.942180578182;3551.804650744208;3871.9545722655007;3947.5855035221175;3947.98026009871;3954.0510277533185;5110.773414380522;5621.0444224481935;6025.397460253984;6102.511799056065;6128.479079278267;3633.94549081764;3687.985803903894;3938.4879965604287;4017.718759686794;4039.8586049488126;3843.078460769615;3845.2693988142164;4027.81179552027;4057.5536690963972;4105.445764821711;3778.5843557208686;4317.460317460317;4602.941323495615;4736.928946065812;4738.715677059387;4818.6748644123145;5536.501714845684;5536.7785288588;5821.388754180834;5886.470217680429;1279.820825084489;1384.2248340929111;1466.9853301466849;1479.400842658725;1485.9628509287354;899.4783025844994;943.8678584997969;964.599691128174;984.1978787288338;1006.1548299428515;4849.636277279218;5073;5291.761870715827;5753.619131408432;5787.768489260382;5583.972080139573;6206.534509911775;6466.967665161644;6490.318516555693;6558.967205164039;5504.9449505504435;5610.887782244332;5775.249217601685;5801.506871915934;5836.445537673914;2513.8868750906254;2832.872520736572;3105.6800859634563;3296.4671427786193;3373.68902301924;5560.527355174774;6076.453119219292;6209.813705588749;6687.531872768859;6695.631740254333;5315.787368505294;5743.684097374685;5756.035641644557;5881.970590147107;6594.967025164843;5872.383399742966;6660.870260438559;6711.060451536789;6737.79786606383;6755.9260536455795;5517.820460917835;5867.706614669335;6129.536811701374;6476.546641735222;6486.767514172292;5295.841124766263;5573.693446860299;5966.403359663999;5992.340842507408;6378.011408231546;5032.874178145429;6656.5340426169705;6688.531802773954;6701.832454188684;6710.295418980937;3419.435793094211;4346.456692913386;4404.713693609905;4419.977900110479;4465.771912724027;6438.967805160944;6446.323136070645;6459.354064593503;6478.967605161944;6512.47900167976;5873.647581145145;5905.704714764331;6218.253809542884;6234.9712297470405;6277.623342599459;5153.587712982878;5805.303363596396;5884.764609415746;6187.48406640376;6267.373262673696;4798.616110711205;5390.97304513475;5566.6640006397365;5751.511121554715;6026.246719160105;4891.364122664031;5761.798337058183;5868.911966320423;5893.381194974466;5911.7635294587735;5449.1826226066405;5796.188848408603;5808.776129248307;5811.27359080115;5860.882782344414 05:42:20 INFO - PROCESS | 3256 | |5;yui.html;655.9245686746024;729.9233580474054;739.7706710919615;756.398663062865;757.3562471898883;14422.278886055694;14725.190114543695;14914.850851491496;14924.179170145637;14939.17834519101;13055.281959492224;13166.605001849935;13690.657733556665;13713.0400871939;13713.108647937877;729.7372945739529;927.0219917986539;939.9895112753783;940.8823897012874;940.9482478463682;897.4301318662631;928.6053427293373;932.3566738950103;933.1554942776797;938.352536749644;979.5689896445576;984.1192133040925;986.7286496213527;988.4958671077766;992.8808542974817;953.7424895278306;967.487231767161;983.8425851863703;983.9655612053543;992.8808542974817;732.9340359367629;733.9119305683299;735.4300417176678;740.4705635470665;746.2984794293346;14313.856861431357;14364;14654.780178297335;14677.55967320982;14712;12295.815562766566;12450.688732781662;12473.563425280072;12511.311877846714;12756.80864787029;9659.420434773936;9757.804843903154;10447.111995480393;10672.839907401396;11290.717732056764;3478.89563313101;3734.141147536082;3983.9203215935518;4072.1855628874346;4225.57744225575;68713.31286687119;69846.95229571611;70606.29393706049;71039.93806820454;72186.9171962421;9454;9574.186194173433;9607.183389411979;9610.711678649652;10300.424918619417;15917.52247432579;18288.54278643018;18356.357527486474;18636.627267454576;18731.063446827877;6992.860142797115;7094.53885497441;7110.608916509538;7198.352148306678;7391.243836488004;3892.4939757831303;3972.682185425159;3982.143839074613;3988.940165897514;4010.959890401117;2995.026616349695;3563.376409128444;3613.1509095362567;3615.6745892869767;3634.545681789776;547.7945770336124;550.2653956967413;639.2999665366416;639.8336432527576;666.7533012785301;446.4486359346205;475.98334058308484;518.4659800405589;558.6675927822939;581.282116586016;6053.364396738367;7716.112647045662;7756.069271687434;7792;8056.476329038594;544.4310695323396;549.6427322240576;572.3017918139863;576.8096528145786;584.9122631605209;3258.3971965186042;3561.893143205708;3687.9815600921825;3722.758020728644;3726.32926073304;74688.62655686688;87451;89063.66404503938;89378;91604;71030;73975.63012184904;75281;75749;76424;54459.0939317122;58867;59990.400095998484;61281.999999999105;63021;7172;7280.417566594766;7286.562806231643;7555.659995300334;7589.6205189739185;42986;44489.55510444854;44709;44801.77599111984;44926.77536612296;62152.68923655353;64729.67635161794;64932;65331.346686532524;65640.67179664072;8396.706115285935;9734.416263889469;9900.455474948947;10099.7475063124;10099.89900101019;9245.659379389816;10394.272400931863;10415.791684166123;10590.8940910593;10627.89372106269;9139.223166030695;9561.181267566582;9919.305648604528;10011.124575378943;10061.14480269156;9836.655717049869;9914.355566888129;9929.453880036668;9987.650432234837;10181.490925453549;9045.412048216846;9108.772280693036;9250;9418.858717119112;9692.515374231401;8221.260086592236;8482.776544912893;8544.91455085466;8988.820223595361;9130.95434522823;8229.79425514343;8306.87539686893;8455.112213217524;8509.36179786531;8612.956935215534 05:42:20 INFO - PROCESS | 3256 | __end_tp_report 05:42:20 INFO - PROCESS | 3256 | __start_cc_report 05:42:20 INFO - PROCESS | 3256 | _x_x_mozilla_cycle_collect,413 05:42:20 INFO - PROCESS | 3256 | __end_cc_report 05:42:20 INFO - PROCESS | 3256 | __startTimestamp1461588140104__endTimestamp 05:42:20 INFO - PROCESS | 3256 | 05:42:20 INFO - PROCESS | 3256 | ------- Summary: start ------- 05:42:20 INFO - PROCESS | 3256 | Number of tests: 6 05:42:20 INFO - PROCESS | 3256 | 05:42:20 INFO - PROCESS | 3256 | [#0] dojo.html Cycles:165 Average:5751.84 Median:6056.79 stddev:797.68 (13.2%) stddev-sans-first:767.31 05:42:20 INFO - PROCESS | 3256 | Values: 2865.6 3458.7 3459.2 3496.5 3531.3 5659.7 5710.1 5712.0 5730.3 5733.6 5788.5 5798.7 5881.1 5912.7 6027.7 5190.0 5252.8 5300.8 5306.7 5345.8 88.9 5215.0 5263.3 5304.9 5313.7 5271.5 5272.6 5287.4 5289.5 5290.3 5283.6 5318.7 5322.2 5338.9 5353.3 5195.6 5204.4 5245.8 5255.8 5299.7 5606.7 5752.8 5757.4 5769.3 5798.6 6150.3 6190.0 6255.1 6266.6 6287.8 6210.7 6258.6 6262.9 6300.4 6338.0 5976.7 5994.0 6021.0 6035.8 6072.0 6219.8 6318.5 6350.0 6416.0 6426.5 5155.8 6129.8 6178.0 6225.2 6248.9 5123.2 5240.5 6227.9 6296.1 6318.1 5314.8 5368.9 6104.5 6180.8 6234.3 5622.9 5993.1 6191.2 6235.6 6253.3 4574.1 4889.2 4894.5 4956.6 4970.5 4283.3 4424.1 4453.7 4521.3 4600.1 5865.1 5926.8 5929.4 5996.3 6056.8 6228.1 6263.2 6277.9 6286.1 6296.7 5840.4 5877.4 5924.4 6000.6 6049.3 5465.2 5477.6 5491.8 5527.4 5534.7 6482.4 6497.0 6506.5 6520.4 6524.6 6154.0 6213.0 6236.9 6269.3 6287.1 6460.3 6480.3 6502.5 6506.0 6524.5 6251.3 6263.6 6264.7 6270.3 6284.4 6132.5 6180.6 6236.6 6275.8 6297.0 6166.1 6191.3 6196.3 6199.4 6200.1 5829.2 6119.5 6222.9 6232.0 6237.0 4748.3 6061.5 6065.1 6132.2 6146.5 6156.4 6218.9 6223.9 6230.9 6251.5 5910.1 6064.5 6141.0 6171.0 6178.8 05:42:20 INFO - PROCESS | 3256 | 05:42:20 INFO - PROCESS | 3256 | [#1] ext.html Cycles:180 Average:58158.73 Median:3597.42 stddev:124011.82 (3447.2%) stddev-sans-first:124284.19 05:42:20 INFO - PROCESS | 3256 | Values: 518.9 541.4 544.0 565.6 586.7 28321.7 38963.4 38976.0 39019.0 39022.4 33109.8 39807.8 40664.0 40664.6 40733.2 8805.0 8958.4 9416.2 9495.0 9630.8 9433.2 9693.9 9701.6 9706.2 9734.1 9605.0 9679.4 9701.8 9714.9 9722.5 8909.2 9037.8 9616.3 9701.4 9717.7 8907.3 8959.4 9330.3 9392.7 9541.2 34330.3 38941.8 39314.0 39461.4 39480.6 2588.7 2937.9 2996.6 3031.6 3059.3 22197.9 26343.7 26617.0 27173.0 27846.9 2625.0 2628.3 2633.3 2755.4 2832.9 348726.0 350053.0 350474.0 351530.2 352214.0 33109.5 33726.5 33930.5 35033.8 35192.3 34943.5 35116.5 35245.8 35287.0 35308.1 14918.7 15041.1 15160.6 15183.6 15185.3 12641.2 12664.8 12685.3 12737.0 12761.6 8346.9 8375.3 8417.9 8428.7 8447.4 3521.1 3575.0 3575.1 3583.5 3611.4 809.8 850.7 1021.4 1062.3 1079.0 28990.0 29323.7 29392.9 29849.1 29875.7 1037.2 1084.5 1113.2 1142.9 1150.0 15094.5 15254.1 15276.5 15350.8 15387.4 561325.0 566885.0 573401.0 575118.0 579125.1 58968.2 59080.4 59349.7 59524.4 59840.7 1426.4 1458.4 1469.9 1482.1 1558.4 23890.2 26172.9 28702.7 29000.6 29308.0 50329.2 51153.5 51258.7 51639.5 51899.5 442927.0 442966.0 445231.0 446681.0 448756.0 23534.2 26503.2 26575.8 26849.0 27114.5 45249.8 45275.5 45977.6 46373.5 46469.5 29728.5 36920.0 37150.4 37575.4 37952.2 36836.0 36887.8 37139.8 37255.8 37837.2 36677.1 36751.8 37026.8 37391.6 37554.1 25854.6 31725.2 32798.3 32840.8 33023.8 22300.9 24485.6 24540.6 24714.8 24907.0 05:42:20 INFO - PROCESS | 3256 | 05:42:20 INFO - PROCESS | 3256 | [#2] jquery.html Cycles:180 Average:34213.88 Median:11719.18 stddev:95566.30 (815.5%) stddev-sans-first:95800.57 05:42:20 INFO - PROCESS | 3256 | Values: 354.2 358.5 404.3 414.6 416.5 16708.8 18894.6 20446.2 20705.7 20786.6 20265.7 20805.9 20871.6 21072.7 21072.8 4931.7 5771.7 5931.1 6147.7 6154.3 5997.2 6035.2 6087.7 6088.1 6094.5 5587.9 5754.0 5828.7 5952.7 6022.6 5673.6 5903.9 5959.8 5974.5 6012.9 5701.7 5750.8 5754.3 5768.7 5809.8 12851.7 13731.7 13779.5 13826.1 13851.2 2044.2 2156.6 2246.2 2293.3 2318.5 9812.8 10209.2 10741.0 14975.2 15333.0 1689.1 1731.7 1752.8 1797.2 1804.8 123966.0 128493.4 128547.0 129257.0 131254.3 11545.4 11754.0 11830.7 11883.3 11899.8 11736.0 12205.9 14565.2 14622.8 14667.9 8623.5 8731.0 8786.2 8824.8 8842.2 8365.0 8710.9 8738.6 8833.7 8835.0 4537.3 4659.4 4661.8 4668.9 4702.9 1740.9 1926.6 1995.5 2025.4 2044.4 882.7 922.4 934.0 969.5 1067.4 19306.8 20157.9 20667.3 21209.0 21281.8 835.6 868.5 958.3 1002.3 1060.4 8857.6 10538.5 10740.8 10779.5 10802.1 559221.0 563127.4 565719.0 575988.0 576450.0 118845.0 129427.0 133178.0 135464.0 136081.0 1079.8 1080.5 1106.2 1108.6 1114.1 17975.7 18075.5 18189.7 18197.8 18510.0 39630.8 54953.5 55532.1 55816.0 57143.0 79328.0 79460.6 80866.6 81794.0 82101.0 7008.4 7408.1 7862.0 7973.8 8110.9 8906.4 11575.2 12325.2 12587.3 12616.4 9377.9 10725.1 11042.6 11198.7 11240.6 8958.8 9305.7 9780.7 10732.1 10984.7 10327.8 10885.1 11323.7 11374.6 11536.8 7954.9 8023.2 8026.6 8083.0 8243.8 9892.2 11052.4 11307.5 11318.9 11319.8 05:42:20 INFO - PROCESS | 3256 | 05:42:20 INFO - PROCESS | 3256 | [#3] mootools.html Cycles:180 Average:5683.77 Median:4990.96 stddev:3140.80 (62.9%) stddev-sans-first:3134.77 05:42:20 INFO - PROCESS | 3256 | Values: 1619.9 1645.3 1646.4 1956.3 1977.1 7972.3 8496.5 8604.2 8881.5 8903.2 8583.1 8675.6 8746.5 8777.1 8782.0 2431.2 2464.8 2471.5 2474.2 2489.1 2426.4 2481.1 2484.2 2484.7 2491.2 2107.7 2440.8 2460.4 2461.1 2495.1 2132.6 2339.2 2421.7 2424.1 2450.6 2040.8 2390.9 2425.6 2435.5 2437.0 8758.6 8888.2 8919.6 8931.6 8934.4 5227.1 5470.4 5508.0 5515.8 5540.8 3546.2 3557.7 3597.2 3631.3 3671.6 1040.4 1041.8 1050.5 1052.0 1053.3 11551.3 11698.4 11722.5 11739.6 11741.1 4179.3 4809.0 4946.3 4956.0 5010.1 9821.3 9983.0 9983.9 9997.4 10029.1 5375.1 5441.1 5465.5 5490.9 5491.3 4464.4 4747.5 4930.9 4965.2 4976.0 4047.4 4114.7 4146.4 4189.7 4195.6 1345.5 1395.2 1395.9 1399.8 1404.4 752.5 891.1 935.9 942.1 966.8 4925.6 5698.4 5747.6 5867.5 5975.9 347.3 464.9 471.0 474.3 478.2 3963.7 4012.5 4034.6 4084.4 4105.1 9088.7 10171.0 10348.1 10607.2 10783.1 9451.3 10122.1 10539.8 10579.9 10652.3 8077.4 8214.4 8221.4 8256.5 8301.2 5726.7 5890.7 5892.4 6115.9 6232.8 9642.6 9734.6 9744.2 9753.4 9923.2 8369.7 9905.8 9948.4 10092.6 10231.6 6874.3 7319.5 7422.9 7545.8 7581.2 8149.6 8212.6 8245.8 8321.1 8338.7 7225.7 7325.3 7370.6 7466.3 7488.9 7252.4 7258.8 7351.8 7353.4 7369.1 6932.8 7007.5 7105.1 7133.1 7137.5 4206.7 4751.3 4764.1 4925.8 5120.8 4972.4 5005.9 5081.2 5081.5 5104.3 05:42:20 INFO - PROCESS | 3256 | 05:42:20 INFO - PROCESS | 3256 | [#4] prototype.html Cycles:180 Average:4788.62 Median:5345.69 stddev:1545.25 (28.9%) stddev-sans-first:1512.29 05:42:20 INFO - PROCESS | 3256 | Values: 292.9 311.5 321.7 322.6 330.4 5393.6 5501.4 5524.4 5537.0 5576.9 4271.6 4609.6 5281.9 5465.3 5468.0 3691.3 3986.4 4098.7 4218.2 4260.1 3231.1 3688.4 4157.4 4181.4 4194.0 4158.0 4162.2 4180.7 4192.4 4193.8 3350.6 3942.7 3946.1 4152.9 4185.8 2897.6 3562.3 3577.1 3584.5 3636.3 5309.3 5326.5 5328.9 5338.7 5352.7 4322.9 4686.9 4722.3 4725.4 4751.2 4716.6 5194.1 5690.9 5697.7 5781.9 3551.8 3872.0 3947.6 3948.0 3954.1 5110.8 5621.0 6025.4 6102.5 6128.5 3633.9 3688.0 3938.5 4017.7 4039.9 3843.1 3845.3 4027.8 4057.6 4105.4 3778.6 4317.5 4602.9 4736.9 4738.7 4818.7 5536.5 5536.8 5821.4 5886.5 1279.8 1384.2 1467.0 1479.4 1486.0 899.5 943.9 964.6 984.2 1006.2 4849.6 5073.0 5291.8 5753.6 5787.8 5584.0 6206.5 6467.0 6490.3 6559.0 5504.9 5610.9 5775.2 5801.5 5836.4 2513.9 2832.9 3105.7 3296.5 3373.7 5560.5 6076.5 6209.8 6687.5 6695.6 5315.8 5743.7 5756.0 5882.0 6595.0 5872.4 6660.9 6711.1 6737.8 6755.9 5517.8 5867.7 6129.5 6476.5 6486.8 5295.8 5573.7 5966.4 5992.3 6378.0 5032.9 6656.5 6688.5 6701.8 6710.3 3419.4 4346.5 4404.7 4420.0 4465.8 6439.0 6446.3 6459.4 6479.0 6512.5 5873.6 5905.7 6218.3 6235.0 6277.6 5153.6 5805.3 5884.8 6187.5 6267.4 4798.6 5391.0 5566.7 5751.5 6026.2 4891.4 5761.8 5868.9 5893.4 5911.8 5449.2 5796.2 5808.8 5811.3 5860.9 05:42:20 INFO - PROCESS | 3256 | 05:42:20 INFO - PROCESS | 3256 | [#5] yui.html Cycles:180 Average:16753.63 Median:69280.13 stddev:23493.68 (33.9%) stddev-sans-first:23528.49 05:42:20 INFO - PROCESS | 3256 | Values: 655.9 729.9 739.8 756.4 757.4 14422.3 14725.2 14914.9 14924.2 14939.2 13055.3 13166.6 13690.7 13713.0 13713.1 729.7 927.0 940.0 940.9 940.9 897.4 928.6 932.4 933.2 938.4 979.6 984.1 986.7 988.5 992.9 953.7 967.5 983.8 984.0 992.9 732.9 733.9 735.4 740.5 746.3 14313.9 14364.0 14654.8 14677.6 14712.0 12295.8 12450.7 12473.6 12511.3 12756.8 9659.4 9757.8 10447.1 10672.8 11290.7 3478.9 3734.1 3983.9 4072.2 4225.6 68713.3 69847.0 70606.3 71039.9 72186.9 9454.0 9574.2 9607.2 9610.7 10300.4 15917.5 18288.5 18356.4 18636.6 18731.1 6992.9 7094.5 7110.6 7198.4 7391.2 3892.5 3972.7 3982.1 3988.9 4011.0 2995.0 3563.4 3613.2 3615.7 3634.5 547.8 550.3 639.3 639.8 666.8 446.4 476.0 518.5 558.7 581.3 6053.4 7716.1 7756.1 7792.0 8056.5 544.4 549.6 572.3 576.8 584.9 3258.4 3561.9 3688.0 3722.8 3726.3 74688.6 87451.0 89063.7 89378.0 91604.0 71030.0 73975.6 75281.0 75749.0 76424.0 54459.1 58867.0 59990.4 61282.0 63021.0 7172.0 7280.4 7286.6 7555.7 7589.6 42986.0 44489.6 44709.0 44801.8 44926.8 62152.7 64729.7 64932.0 65331.3 65640.7 8396.7 9734.4 9900.5 10099.7 10099.9 9245.7 10394.3 10415.8 10590.9 10627.9 9139.2 9561.2 9919.3 10011.1 10061.1 9836.7 9914.4 9929.5 9987.7 10181.5 9045.4 9108.8 9250.0 9418.9 9692.5 8221.3 8482.8 8544.9 8988.8 9131.0 8229.8 8306.9 8455.1 8509.4 8613.0 05:42:20 INFO - PROCESS | 3256 | -------- Summary: end -------- 05:42:20 INFO - PROCESS | 3256 | 05:42:20 INFO - TEST-INFO | 3256: exit 0 05:42:20 INFO - TEST-OK | dromaeo_css | took 1111791ms 05:42:20 INFO - TEST-START | kraken 05:42:20 INFO - operating with platform_type : mac_ 05:42:20 INFO - Initialising browser for kraken test... 05:42:20 INFO - TEST-INFO | started process 3261 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpeY4tC8/profile http://localhost:65342/getInfo.html) 05:42:23 INFO - PROCESS | 3261 | __metrics Screen width/height:1600/1200 05:42:23 INFO - PROCESS | 3261 | colorDepth:24 05:42:23 INFO - PROCESS | 3261 | Browser inner width/height: 1024/689 05:42:23 INFO - PROCESS | 3261 | __metrics 05:42:23 ERROR - PROCESS | 3261 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:42:24 INFO - TEST-INFO | 3261: exit 0 05:42:24 INFO - Browser initialized. 05:42:24 INFO - Running cycle 1/1 for kraken test... 05:42:24 INFO - TEST-INFO | started process 3265 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpeY4tC8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:36 INFO - PROCESS | 3265 | RSS: Main: 249487360 05:42:36 INFO - PROCESS | 3265 | 05:42:39 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:65342/tests/kraken/driver.html?testName=audio-beat-detection) 05:42:40 INFO - PROCESS | 3265 | RSS: Main: 261885952 05:42:40 INFO - PROCESS | 3265 | 05:42:42 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:65342/tests/kraken/driver.html?testName=audio-dft) 05:42:43 INFO - PROCESS | 3265 | RSS: Main: 271261696 05:42:43 INFO - PROCESS | 3265 | 05:42:46 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:65342/tests/kraken/driver.html?testName=audio-fft) 05:42:47 INFO - PROCESS | 3265 | RSS: Main: 274468864 05:42:47 INFO - PROCESS | 3265 | 05:42:49 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:65342/tests/kraken/driver.html?testName=audio-oscillator) 05:42:50 INFO - PROCESS | 3265 | RSS: Main: 275959808 05:42:50 INFO - PROCESS | 3265 | 05:42:52 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:65342/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:42:52 INFO - PROCESS | 3265 | RSS: Main: 275800064 05:42:52 INFO - PROCESS | 3265 | 05:42:55 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:65342/tests/kraken/driver.html?testName=imaging-darkroom) 05:42:56 INFO - PROCESS | 3265 | RSS: Main: 276783104 05:42:56 INFO - PROCESS | 3265 | 05:42:59 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:65342/tests/kraken/driver.html?testName=imaging-desaturate) 05:43:00 INFO - PROCESS | 3265 | RSS: Main: 276586496 05:43:00 INFO - PROCESS | 3265 | 05:43:03 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:65342/tests/kraken/driver.html?testName=json-parse-financial) 05:43:03 INFO - PROCESS | 3265 | RSS: Main: 277098496 05:43:03 INFO - PROCESS | 3265 | 05:43:05 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:65342/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:43:05 INFO - PROCESS | 3265 | RSS: Main: 276664320 05:43:05 INFO - PROCESS | 3265 | 05:43:07 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:65342/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:43:08 INFO - PROCESS | 3265 | RSS: Main: 276770816 05:43:08 INFO - PROCESS | 3265 | 05:43:10 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:65342/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:43:11 INFO - PROCESS | 3265 | RSS: Main: 277204992 05:43:11 INFO - PROCESS | 3265 | 05:43:14 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:65342/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:43:15 INFO - PROCESS | 3265 | RSS: Main: 277102592 05:43:15 INFO - PROCESS | 3265 | 05:43:17 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:65342/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:43:18 INFO - PROCESS | 3265 | RSS: Main: 277057536 05:43:18 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | Cycle 1(1): loaded http://localhost:65342/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:65342/tests/kraken/driver.html?testName=ai-astar) 05:43:20 INFO - PROCESS | 3265 | RSS: Main: 278376448 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | __start_tp_report 05:43:20 INFO - PROCESS | 3265 | _x_x_mozilla_page_load 05:43:20 INFO - PROCESS | 3265 | _x_x_mozilla_page_load_details 05:43:20 INFO - PROCESS | 3265 | |i|pagename|runs| 05:43:20 INFO - PROCESS | 3265 | |0;ai-astar;116;109;116;116;118;130;116;116;117;116 05:43:20 INFO - PROCESS | 3265 | |1;audio-beat-detection;168;125;153;196;156;137;172;149;186;113 05:43:20 INFO - PROCESS | 3265 | |2;audio-dft;197;202;215;207;195;197;197;202;255;259 05:43:20 INFO - PROCESS | 3265 | |3;audio-fft;69;91;74;141;61;56;57;68;131;55 05:43:20 INFO - PROCESS | 3265 | |4;audio-oscillator;79;83;137;77;78;88;122;83;82;120 05:43:20 INFO - PROCESS | 3265 | |5;imaging-gaussian-blur;121;132;90;88;91;93;127;88;91;87 05:43:20 INFO - PROCESS | 3265 | |6;imaging-darkroom;113;97;147;148;95;95;97;97;91;139 05:43:20 INFO - PROCESS | 3265 | |7;imaging-desaturate;144;136;92;91;91;87;148;149;91;90 05:43:20 INFO - PROCESS | 3265 | |8;json-parse-financial;61;62;61;61;62;62;62;78;65;61 05:43:20 INFO - PROCESS | 3265 | |9;json-stringify-tinderbox;63;66;67;75;69;72;69;68;66;71 05:43:20 INFO - PROCESS | 3265 | |10;stanford-crypto-aes;85;84;84;98;108;109;83;92;96;85 05:43:20 INFO - PROCESS | 3265 | |11;stanford-crypto-ccm;138;135;137;134;148;155;128;130;189;162 05:43:20 INFO - PROCESS | 3265 | |12;stanford-crypto-pbkdf2;171;170;169;167;165;166;166;218;170;176 05:43:20 INFO - PROCESS | 3265 | |13;stanford-crypto-sha256-iterative;67;84;100;102;105;58;85;99;105;101 05:43:20 INFO - PROCESS | 3265 | __end_tp_report 05:43:20 INFO - PROCESS | 3265 | __start_cc_report 05:43:20 INFO - PROCESS | 3265 | _x_x_mozilla_cycle_collect,1075 05:43:20 INFO - PROCESS | 3265 | __end_cc_report 05:43:20 INFO - PROCESS | 3265 | __startTimestamp1461588200779__endTimestamp 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | ------- Summary: start ------- 05:43:20 INFO - PROCESS | 3265 | Number of tests: 14 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#0] ai-astar Cycles:10 Average:117.00 Median:116.00 stddev:5.16 (4.5%) stddev-sans-first:5.46 05:43:20 INFO - PROCESS | 3265 | Values: 116.0 109.0 116.0 116.0 118.0 130.0 116.0 116.0 117.0 116.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#1] audio-beat-detection Cycles:10 Average:155.50 Median:162.00 stddev:26.09 (16.1%) stddev-sans-first:27.28 05:43:20 INFO - PROCESS | 3265 | Values: 168.0 125.0 153.0 196.0 156.0 137.0 172.0 149.0 186.0 113.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#2] audio-dft Cycles:10 Average:212.60 Median:204.50 stddev:24.16 (11.8%) stddev-sans-first:24.95 05:43:20 INFO - PROCESS | 3265 | Values: 197.0 202.0 215.0 207.0 195.0 197.0 197.0 202.0 255.0 259.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#3] audio-fft Cycles:10 Average:80.30 Median:64.50 stddev:31.33 (48.6%) stddev-sans-first:32.96 05:43:20 INFO - PROCESS | 3265 | Values: 69.0 91.0 74.0 141.0 61.0 56.0 57.0 68.0 131.0 55.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:80.50 stddev:22.34 (27.8%) stddev-sans-first:22.95 05:43:20 INFO - PROCESS | 3265 | Values: 79.0 83.0 137.0 77.0 78.0 88.0 122.0 83.0 82.0 120.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#5] imaging-gaussian-blur Cycles:10 Average:100.80 Median:89.00 stddev:18.12 (20.4%) stddev-sans-first:17.69 05:43:20 INFO - PROCESS | 3265 | Values: 121.0 132.0 90.0 88.0 91.0 93.0 127.0 88.0 91.0 87.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#6] imaging-darkroom Cycles:10 Average:111.90 Median:95.00 stddev:23.44 (24.7%) stddev-sans-first:24.86 05:43:20 INFO - PROCESS | 3265 | Values: 113.0 97.0 147.0 148.0 95.0 95.0 97.0 97.0 91.0 139.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#7] imaging-desaturate Cycles:10 Average:111.90 Median:90.50 stddev:28.08 (31.0%) stddev-sans-first:27.28 05:43:20 INFO - PROCESS | 3265 | Values: 144.0 136.0 92.0 91.0 91.0 87.0 148.0 149.0 91.0 90.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:62.00 stddev:5.23 (8.4%) stddev-sans-first:5.47 05:43:20 INFO - PROCESS | 3265 | Values: 61.0 62.0 61.0 61.0 62.0 62.0 62.0 78.0 65.0 61.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:69.00 stddev:3.44 (5.0%) stddev-sans-first:2.99 05:43:20 INFO - PROCESS | 3265 | Values: 63.0 66.0 67.0 75.0 69.0 72.0 69.0 68.0 66.0 71.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:85.00 stddev:10.01 (11.8%) stddev-sans-first:10.26 05:43:20 INFO - PROCESS | 3265 | Values: 85.0 84.0 84.0 98.0 108.0 109.0 83.0 92.0 96.0 85.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:143.00 stddev:18.79 (13.1%) stddev-sans-first:19.73 05:43:20 INFO - PROCESS | 3265 | Values: 138.0 135.0 137.0 134.0 148.0 155.0 128.0 130.0 189.0 162.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:170.00 stddev:15.86 (9.3%) stddev-sans-first:16.79 05:43:20 INFO - PROCESS | 3265 | Values: 171.0 170.0 169.0 167.0 165.0 166.0 166.0 218.0 170.0 176.0 05:43:20 INFO - PROCESS | 3265 | 05:43:20 INFO - PROCESS | 3265 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:90.60 Median:62.50 stddev:16.69 (26.7%) stddev-sans-first:15.36 05:43:20 INFO - PROCESS | 3265 | Values: 67.0 84.0 100.0 102.0 105.0 58.0 85.0 99.0 105.0 101.0 05:43:20 INFO - PROCESS | 3265 | -------- Summary: end -------- 05:43:20 INFO - PROCESS | 3265 | 05:43:21 INFO - TEST-INFO | 3265: exit 0 05:43:21 INFO - TEST-OK | kraken | took 61032ms 05:43:21 INFO - SUITE-END | took 1172s 05:43:21 INFO - Completed test suite (00:19:33) 05:43:21 INFO - javascript benchmark 05:43:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2865.555838844979, 3458.706009989151, 3459.152507635629, 3496.4930085137657, 3531.276088401878, 5659.688717120562, 5710.057840456325, 5711.971440142804, 5730.255066841315, 5733.598648094635, 5788.47903688668, 5798.739056742446, 5881.147233651129, 5912.704364781765, 6027.69861506924, 5189.974050129754, 5252.763625636836, 5300.840974770763, 5306.655067420625, 5345.7594408251725, 88.89714303401739, 5215.0, 5263.289455923459, 5304.867378315535, 5313.654612450197, 5271.472852714736, 5272.604554658396, 5287.391949925731, 5289.523942845143, 5290.285811415448, 5283.577313814885, 5318.734063296858, 5322.2282768998275, 5338.905524367496, 5353.277307563468, 5195.584353251731, 5204.401493828221, 5245.79016839326, 5255.816046438356, 5299.655522391071, 5606.691631960241, 5752.769889204426, 5757.395473475267, 5769.336526299446, 5798.565107616945, 6150.292716337589, 6189.969050154766, 6255.06174073888, 6266.592671476339, 6287.842803929939, 6210.689465526705, 6258.593191442542, 6262.937370626327, 6300.401461861115, 6337.968310158466, 5976.701164941779, 5994.0, 6020.96989515054, 6035.849103772353, 6071.969640151815, 6219.782307619212, 6318.494520438354, 6350.047492876013, 6416.0, 6426.485881129498, 5155.819546315861, 6129.847850912788, 6178.0, 6225.159603453566, 6248.937510624835, 5123.180291153398, 5240.475952404805, 6227.910115729729, 6296.055591661196, 6318.052292156213, 5314.7608357624, 5368.892622147535, 6104.481119104926, 6180.81457556274, 6234.314225435199, 5622.931642987819, 5993.0710739835395, 6191.164192834, 6235.563510554309, 6253.312135665074, 4574.130915126182, 4889.168841296988, 4894.535019173173, 4956.603471722254, 4970.453250142481, 4283.250431174532, 4424.093060922503, 4453.732776033448, 4521.299198624219, 4600.125976064537, 5865.090910908816, 5926.7925622603, 5929.436703513159, 5996.340402555716, 6056.788012419544, 6228.096925945803, 6263.217097862767, 6277.905831412532, 6286.05709143623, 6296.748130074838, 5840.415958404125, 5877.412258774174, 5924.407559244128, 6000.609960352563, 6049.274087109575, 5465.180222966586, 5477.643953143033, 5491.807786727525, 5527.44725527444, 5534.695591742412, 6482.416582507597, 6496.967515162394, 6506.479481641552, 6520.380563846427, 6524.608523488606, 6153.953827849186, 6213.0, 6236.875262494816, 6269.279032911274, 6287.056941458635, 6460.25707043696, 6480.25477070143, 6502.512311576556, 6505.991571306455, 6524.478041756743, 6251.312355640966, 6263.6241825490615, 6264.655443950626, 6270.310265870842, 6284.4344009039405, 6132.477886260695, 6180.598261113013, 6236.6258024518675, 6275.811725648238, 6296.968515157395, 6166.136740856194, 6191.25704915413, 6196.256449226122, 6199.442050215503, 6200.100985357189, 5829.15477255787, 6119.510439164945, 6222.906656400067, 6232.0, 6237.0, 4748.335233067304, 6061.454469097803, 6065.059915713072, 6132.20254427538, 6146.477549408171, 6156.445919867234, 6218.906716399167, 6223.875522489616, 6230.937690623035, 6251.499880009679, 5910.143029260647, 6064.514838812973, 6141.0, 6170.969145154425, 6178.783742568989], "value": 5703.4354878132435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.9091908915939, 541.3963430774687, 543.9782408703652, 565.6408180805188, 586.7443670545033, 28321.716782832165, 38963.41554876675, 38976.02559936003, 39019.0, 39022.41466378003, 33109.83445082777, 39807.80096099523, 40663.983400415, 40664.59335406645, 40733.18533629326, 8805.031446540876, 8958.37291389603, 9416.199623032051, 9494.952525237364, 9630.759231019245, 9433.151016408521, 9693.903060969405, 9701.563429645683, 9706.223502119848, 9734.123928846402, 9605.0, 9679.419234845896, 9701.805963880719, 9714.854277185812, 9722.513874306293, 8909.198172164472, 9037.774055648595, 9616.278779091595, 9701.369410988298, 9717.65988190417, 8907.331950103704, 8959.41763785352, 9330.346875718702, 9392.67125650599, 9541.2367010639, 34330.31339373199, 38941.80529097365, 39314.0, 39461.40807887884, 39480.60519394798, 2588.6634737484196, 2937.911862644124, 2996.6104406427025, 3031.575579418883, 3059.2504836315024, 22197.889010554845, 26343.73656263432, 26617.0, 27173.04894328709, 27846.860765696245, 2624.960625590618, 2628.342914271432, 2633.2758491415016, 2755.4213615140843, 2832.9433411331865, 348726.0, 350053.0, 350474.0, 351530.2423487866, 352213.99999999744, 33109.503357449656, 33726.49410258799, 33930.49104263438, 35033.82483087568, 35192.29615407729, 34943.475847862304, 35116.473252901225, 35245.82377088149, 35287.0, 35308.11729706726, 14918.70162596742, 15041.097534147986, 15160.620984475476, 15183.620409489851, 15185.316660750294, 12641.2415255085, 12664.81002784959, 12685.302308372944, 12737.044721645914, 12761.553345633043, 8346.916530834735, 8375.329973602098, 8417.915820841836, 8428.662853485914, 8447.408681392244, 3521.0669172669263, 3574.964250357515, 3575.0526110580718, 3583.480395342661, 3611.368010598135, 809.7651681012441, 850.6809946270149, 1021.4126877045728, 1062.2723434447428, 1078.9946050269698, 28990.0, 29323.7067629321, 29392.853035735112, 29849.10452686423, 29875.701242987725, 1037.159900480598, 1084.4577711144427, 1113.218849840249, 1142.857142857143, 1149.9592868454063, 15094.54716358489, 15254.084754914518, 15276.458860555054, 15350.848686348569, 15387.38450461969, 561325.0, 566885.0, 573401.0, 575118.0, 579125.1043744754, 58968.23095307148, 59080.40919590835, 59349.70325148433, 59524.40475595275, 59840.70079649661, 1426.3937826423728, 1458.3728996531381, 1469.882409407245, 1482.1255459279078, 1558.3610719604908, 23890.163844265368, 26172.8691356542, 28702.712972870006, 29000.564991524723, 29308.0, 50329.245061324844, 51153.488465114875, 51258.74370628123, 51639.48360516347, 51899.48100518947, 442927.0, 442966.0, 445231.0, 446681.0, 448756.0, 23534.176303829285, 26503.204903852915, 26575.804088816436, 26849.0, 27114.45771084607, 45249.77375113103, 45275.54724452713, 45977.62067137991, 46373.536264636925, 46469.535304646524, 29728.513574320765, 36920.0, 37150.44274335833, 37575.43636845395, 37952.240955181296, 36836.0, 36887.81556092203, 37139.81430092832, 37255.81372093122, 37837.24325513529, 36677.08307292339, 36751.816240919696, 37026.814865925495, 37391.62608373881, 37554.061148471505, 25854.61218081768, 31725.20686982844, 32798.34403311968, 32840.835795820865, 33023.834880826405, 22300.888495557418, 24485.632715509637, 24540.6318905213, 24714.752852471964, 24907.003719851728], "value": 17653.746318974267, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.1800731307024, 358.46767550187985, 404.3005600311461, 414.56470705758943, 416.47316145076474, 16708.830381873275, 18894.622107557858, 20446.182152713893, 20705.689414658773, 20786.584268314626, 20265.696014559795, 20805.895970520163, 20871.582568348626, 21072.68390974133, 21072.840993745336, 4931.728754918478, 5771.682557459338, 5931.08068249422, 6147.661878596676, 6154.323024467305, 5997.190379298782, 6035.155078289043, 6087.665178415185, 6088.147659327676, 6094.481969032616, 5587.916181257264, 5754.02181629122, 5828.650280983134, 5952.672603006833, 6022.578419510636, 5673.574481913853, 5903.88192236155, 5959.821205363846, 5974.462298393166, 6012.879742405171, 5701.6864072476, 5750.79872204475, 5754.338251101093, 5768.74040668171, 5809.796657116981, 12851.742965140738, 13731.725365492634, 13779.517716879862, 13826.101303415246, 13851.238181899991, 2044.2436298569476, 2156.5686862627535, 2246.2362796649054, 2293.3005433342823, 2318.4783423729696, 9812.803743925082, 10209.183265338756, 10741.0, 14975.176365299903, 15333.0, 1689.0625702734976, 1731.6969530332267, 1752.7984281807628, 1797.2092279397023, 1804.846588040018, 123966.0, 128493.35753321173, 128547.0, 129257.0, 131254.3437282817, 11545.36500492481, 11754.000909922752, 11830.704232394259, 11883.287002779862, 11899.762004759856, 11736.0, 12205.877941220475, 14565.198914059829, 14622.780658290134, 14667.92666036663, 8623.482591044432, 8730.995935467376, 8786.209241168197, 8824.823503529893, 8842.159994800484, 8365.0, 8710.869336959951, 8738.606762695694, 8833.69082082124, 8835.0, 4537.251353526697, 4659.394278743809, 4661.790219440133, 4668.85993420198, 4702.88242793926, 1740.8520275776448, 1926.6339395514808, 1995.5310502032012, 2025.351887396019, 2044.396902913644, 882.7307671160229, 922.377395258198, 933.9813203735887, 969.5297780576415, 1067.3595842494442, 19306.806931930783, 20157.891315977762, 20667.276645317343, 21209.0, 21281.78718212829, 835.5989125219928, 868.5006121480175, 958.3004406782948, 1002.2683441087901, 1060.4273692206277, 8857.601407936658, 10538.525766340534, 10740.838887416696, 10779.461026948622, 10802.135829133651, 559221.0, 563127.3687263157, 565719.0, 575988.0, 576450.0, 118845.0, 129427.0, 133178.0, 135464.0, 136081.0, 1079.7570546626946, 1080.4975686305906, 1106.1869525898458, 1108.5621179634, 1114.0864491117368, 17975.730364044288, 18075.548111296797, 18189.727154092965, 18197.818021819614, 18510.0, 39630.80184599059, 54953.45046549483, 55532.05637802819, 55816.0, 57143.0, 79328.0, 79460.60269698614, 80866.59566702129, 81794.0, 82101.0, 7008.369246767816, 7408.148062972611, 7861.960690196741, 7973.760787176394, 8110.878336825071, 8906.376553641183, 11575.247608905394, 12325.198862073936, 12587.307698076327, 12616.432260548481, 9377.874655041438, 10725.141988640735, 11042.558297668325, 11198.664040078811, 11240.606578769704, 8958.776030599287, 9305.720828375159, 9780.706578802647, 10732.14142868553, 10984.670459886216, 10327.793444130924, 10885.129189664649, 11323.660290191307, 11374.601888933848, 11536.82694759596, 7954.920450795649, 8023.157568455462, 8026.638801254075, 8083.0, 8243.83512329762, 9892.208623310262, 11052.447377630926, 11307.491162897524, 11318.943405283251, 11319.830202546804], "value": 9413.132734565314, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1619.9433019844305, 1645.3171933647534, 1646.440210328488, 1956.3446245507755, 1977.149825575003, 7972.282494575487, 8496.49021058736, 8604.225619694227, 8881.467111973287, 8903.198712115909, 8583.05586385497, 8675.60959756811, 8746.475211487299, 8777.122287771219, 8781.95609021954, 2431.19770475743, 2464.827462077655, 2471.530409222242, 2474.232987773787, 2489.116363690891, 2426.3934016495878, 2481.0944005437977, 2484.2050543825985, 2484.689413823272, 2491.215267190838, 2107.725995620567, 2440.7803297703203, 2460.3849037740565, 2461.05249478951, 2495.089292408267, 2132.61612909677, 2339.1695947938492, 2421.7336093029753, 2424.090965887792, 2450.5834008218644, 2040.7857324891665, 2390.8924098415614, 2425.563398588253, 2435.5494233566847, 2437.049550675238, 8758.605862736193, 8888.155625215593, 8919.598618062202, 8931.553422328923, 8934.374593778439, 5227.085260079471, 5470.425605311425, 5508.0, 5515.751791169407, 5540.750666219989, 3546.1666508370513, 3557.7153827693724, 3597.1726502904216, 3631.273745250934, 3671.596124426311, 1040.4433628008978, 1041.807265655856, 1050.4642632257528, 1051.989480105197, 1053.3206082077018, 11551.306921584648, 11698.356590387526, 11722.531098756039, 11739.647810565782, 11741.119416043832, 4179.331306990867, 4809.0042632307905, 4946.258061290763, 4956.033573453142, 5010.073136469765, 9821.263405244636, 9983.001699830103, 9983.850242246372, 9997.350172238926, 10029.147522460673, 5375.086235340002, 5441.102218133963, 5465.535429488538, 5490.890182196334, 5491.313585801775, 4464.374987501752, 4747.525247475225, 4930.852074437773, 4965.155923492943, 4976.029674213566, 4047.4131250968444, 4114.692381036628, 4146.419501269825, 4189.664826813848, 4195.559466256061, 1345.5425155447194, 1395.1768456610648, 1395.9092658977338, 1399.818023656918, 1404.403128670311, 752.5033477904557, 891.1266958380824, 935.9190135393632, 942.0673533202217, 966.8114717630135, 4925.581325587294, 5698.430156984351, 5747.597668163272, 5867.516394384229, 5975.850603734942, 347.3296537682297, 464.8837790552362, 470.962323014158, 474.30040689982, 478.2372116474218, 3963.6829053675638, 4012.538558065801, 4034.616711412411, 4084.36692312692, 4105.0763578195265, 9088.682141089637, 10171.033751793568, 10348.120409765104, 10607.151427885905, 10783.08343790771, 9451.33840631163, 10122.139618132542, 10539.789204216026, 10579.894201058045, 10652.254342196127, 8077.35381169505, 8214.383921206054, 8221.383396245306, 8256.463329883538, 8301.169883011651, 5726.713664316851, 5890.7349169288345, 5892.410758924073, 6115.938840611715, 6232.8130156095385, 9642.614295428104, 9734.561944712363, 9744.17174540172, 9753.366031207948, 9923.206143508647, 8369.707060252862, 9905.752356191153, 9948.35335703206, 10092.596296148073, 10231.641892533726, 6874.346937040973, 7319.451041171827, 7422.925770742439, 7545.849083018199, 7581.241875812375, 8149.633266502903, 8212.589370531568, 8245.83508329842, 8321.12628174033, 8338.749837504885, 7225.747098851515, 7325.304096110861, 7370.557766534025, 7466.328030477283, 7488.850222995401, 7252.41980641558, 7258.818529536804, 7351.77944661661, 7353.374963128194, 7369.078865141857, 6932.792016239521, 7007.509474336952, 7105.076340075964, 7133.072700548895, 7137.464690148364, 4206.72656277341, 4751.263554149113, 4764.142454358249, 4925.8029678812445, 5120.769565369642, 4972.353594032753, 5005.898702285492, 5081.18701007837, 5081.4918508148885, 5104.336436263262], "value": 4526.7435229485955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.897485879942, 311.4533992842561, 321.66868125830405, 322.643478955754, 330.3525090821989, 5393.595480338976, 5501.36734275558, 5524.425538721465, 5537.0, 5576.944230557693, 4271.63691086258, 4609.617114865544, 5281.947180528194, 5465.289512363387, 5468.0, 3691.280200360931, 3986.4219688145204, 4098.692598055143, 4218.219629368561, 4260.126674031816, 3231.1437469070715, 3688.409854423293, 4157.417961485395, 4181.393697913801, 4194.035371864473, 4157.958420415803, 4162.229987452331, 4180.686448516358, 4192.37114432836, 4193.832246710143, 3350.6481819408855, 3942.6845852331744, 3946.066427450557, 4152.875413737593, 4185.790710464465, 2897.5943367928294, 3562.323158599858, 3577.1041348085555, 3584.4945123048383, 3636.3091012707764, 5309.283246761676, 5326.481952643478, 5328.92006619901, 5338.706371149585, 5352.678839269656, 4322.891927701801, 4686.906261874778, 4722.291656251556, 4725.385699859031, 4751.168545504523, 4716.646251531149, 5194.065068287707, 5690.9146362804595, 5697.743601537899, 5781.942180578182, 3551.804650744208, 3871.9545722655007, 3947.5855035221175, 3947.98026009871, 3954.0510277533185, 5110.773414380522, 5621.0444224481935, 6025.397460253984, 6102.511799056065, 6128.479079278267, 3633.94549081764, 3687.985803903894, 3938.4879965604287, 4017.718759686794, 4039.8586049488126, 3843.078460769615, 3845.2693988142164, 4027.81179552027, 4057.5536690963972, 4105.445764821711, 3778.5843557208686, 4317.460317460317, 4602.941323495615, 4736.928946065812, 4738.715677059387, 4818.6748644123145, 5536.501714845684, 5536.7785288588, 5821.388754180834, 5886.470217680429, 1279.820825084489, 1384.2248340929111, 1466.9853301466849, 1479.400842658725, 1485.9628509287354, 899.4783025844994, 943.8678584997969, 964.599691128174, 984.1978787288338, 1006.1548299428515, 4849.636277279218, 5073.0, 5291.761870715827, 5753.619131408432, 5787.768489260382, 5583.972080139573, 6206.534509911775, 6466.967665161644, 6490.318516555693, 6558.967205164039, 5504.9449505504435, 5610.887782244332, 5775.249217601685, 5801.506871915934, 5836.445537673914, 2513.8868750906254, 2832.872520736572, 3105.6800859634563, 3296.4671427786193, 3373.68902301924, 5560.527355174774, 6076.453119219292, 6209.813705588749, 6687.531872768859, 6695.631740254333, 5315.787368505294, 5743.684097374685, 5756.035641644557, 5881.970590147107, 6594.967025164843, 5872.383399742966, 6660.870260438559, 6711.060451536789, 6737.79786606383, 6755.9260536455795, 5517.820460917835, 5867.706614669335, 6129.536811701374, 6476.546641735222, 6486.767514172292, 5295.841124766263, 5573.693446860299, 5966.403359663999, 5992.340842507408, 6378.011408231546, 5032.874178145429, 6656.5340426169705, 6688.531802773954, 6701.832454188684, 6710.295418980937, 3419.435793094211, 4346.456692913386, 4404.713693609905, 4419.977900110479, 4465.771912724027, 6438.967805160944, 6446.323136070645, 6459.354064593503, 6478.967605161944, 6512.47900167976, 5873.647581145145, 5905.704714764331, 6218.253809542884, 6234.9712297470405, 6277.623342599459, 5153.587712982878, 5805.303363596396, 5884.764609415746, 6187.48406640376, 6267.373262673696, 4798.616110711205, 5390.97304513475, 5566.6640006397365, 5751.511121554715, 6026.246719160105, 4891.364122664031, 5761.798337058183, 5868.911966320423, 5893.381194974466, 5911.7635294587735, 5449.1826226066405, 5796.188848408603, 5808.776129248307, 5811.27359080115, 5860.882782344414], "value": 4303.02017226642, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.9245686746024, 729.9233580474054, 739.7706710919615, 756.398663062865, 757.3562471898883, 14422.278886055694, 14725.190114543695, 14914.850851491496, 14924.179170145637, 14939.17834519101, 13055.281959492224, 13166.605001849935, 13690.657733556665, 13713.0400871939, 13713.108647937877, 729.7372945739529, 927.0219917986539, 939.9895112753783, 940.8823897012874, 940.9482478463682, 897.4301318662631, 928.6053427293373, 932.3566738950103, 933.1554942776797, 938.352536749644, 979.5689896445576, 984.1192133040925, 986.7286496213527, 988.4958671077766, 992.8808542974817, 953.7424895278306, 967.487231767161, 983.8425851863703, 983.9655612053543, 992.8808542974817, 732.9340359367629, 733.9119305683299, 735.4300417176678, 740.4705635470665, 746.2984794293346, 14313.856861431357, 14364.0, 14654.780178297335, 14677.55967320982, 14712.0, 12295.815562766566, 12450.688732781662, 12473.563425280072, 12511.311877846714, 12756.80864787029, 9659.420434773936, 9757.804843903154, 10447.111995480393, 10672.839907401396, 11290.717732056764, 3478.89563313101, 3734.141147536082, 3983.9203215935518, 4072.1855628874346, 4225.57744225575, 68713.31286687119, 69846.95229571611, 70606.29393706049, 71039.93806820454, 72186.9171962421, 9454.0, 9574.186194173433, 9607.183389411979, 9610.711678649652, 10300.424918619417, 15917.52247432579, 18288.54278643018, 18356.357527486474, 18636.627267454576, 18731.063446827877, 6992.860142797115, 7094.53885497441, 7110.608916509538, 7198.352148306678, 7391.243836488004, 3892.4939757831303, 3972.682185425159, 3982.143839074613, 3988.940165897514, 4010.959890401117, 2995.026616349695, 3563.376409128444, 3613.1509095362567, 3615.6745892869767, 3634.545681789776, 547.7945770336124, 550.2653956967413, 639.2999665366416, 639.8336432527576, 666.7533012785301, 446.4486359346205, 475.98334058308484, 518.4659800405589, 558.6675927822939, 581.282116586016, 6053.364396738367, 7716.112647045662, 7756.069271687434, 7792.0, 8056.476329038594, 544.4310695323396, 549.6427322240576, 572.3017918139863, 576.8096528145786, 584.9122631605209, 3258.3971965186042, 3561.893143205708, 3687.9815600921825, 3722.758020728644, 3726.32926073304, 74688.62655686688, 87451.0, 89063.66404503938, 89378.0, 91604.0, 71030.0, 73975.63012184904, 75281.0, 75749.0, 76424.0, 54459.0939317122, 58867.0, 59990.400095998484, 61281.999999999105, 63021.0, 7172.0, 7280.417566594766, 7286.562806231643, 7555.659995300334, 7589.6205189739185, 42986.0, 44489.55510444854, 44709.0, 44801.77599111984, 44926.77536612296, 62152.68923655353, 64729.67635161794, 64932.0, 65331.346686532524, 65640.67179664072, 8396.706115285935, 9734.416263889469, 9900.455474948947, 10099.7475063124, 10099.89900101019, 9245.659379389816, 10394.272400931863, 10415.791684166123, 10590.8940910593, 10627.89372106269, 9139.223166030695, 9561.181267566582, 9919.305648604528, 10011.124575378943, 10061.14480269156, 9836.655717049869, 9914.355566888129, 9929.453880036668, 9987.650432234837, 10181.490925453549, 9045.412048216846, 9108.772280693036, 9250.0, 9418.858717119112, 9692.515374231401, 8221.260086592236, 8482.776544912893, 8544.91455085466, 8988.820223595361, 9130.95434522823, 8229.79425514343, 8306.87539686893, 8455.112213217524, 8509.36179786531, 8612.956935215534], "value": 6510.2614262740235, "unit": "score"}], "name": "dromaeo_css", "value": 7025.0723909735025, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 109.0, 116.0, 116.0, 118.0, 130.0, 116.0, 116.0, 117.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 125.0, 153.0, 196.0, 156.0, 137.0, 172.0, 149.0, 186.0, 113.0], "value": 155.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 202.0, 215.0, 207.0, 195.0, 197.0, 197.0, 202.0, 255.0, 259.0], "value": 212.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 91.0, 74.0, 141.0, 61.0, 56.0, 57.0, 68.0, 131.0, 55.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 83.0, 137.0, 77.0, 78.0, 88.0, 122.0, 83.0, 82.0, 120.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 132.0, 90.0, 88.0, 91.0, 93.0, 127.0, 88.0, 91.0, 87.0], "value": 100.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 97.0, 147.0, 148.0, 95.0, 95.0, 97.0, 97.0, 91.0, 139.0], "value": 111.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 136.0, 92.0, 91.0, 91.0, 87.0, 148.0, 149.0, 91.0, 90.0], "value": 111.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 62.0, 62.0, 62.0, 78.0, 65.0, 61.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 67.0, 75.0, 69.0, 72.0, 69.0, 68.0, 66.0, 71.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 98.0, 108.0, 109.0, 83.0, 92.0, 96.0, 85.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 137.0, 134.0, 148.0, 155.0, 128.0, 130.0, 189.0, 162.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 169.0, 167.0, 165.0, 166.0, 166.0, 218.0, 170.0, 176.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 84.0, 100.0, 102.0, 105.0, 58.0, 85.0, 99.0, 105.0, 101.0], "value": 90.6, "unit": "score"}], "name": "kraken", "value": 1619.3999999999996, "alertThreshold": 2.0}]} 05:43:21 INFO - Return code: 0 05:43:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:43:22 ERROR - # TBPL SUCCESS # 05:43:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:43:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:43:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:43:22 INFO - 2016-04-25 05:43:22.051 screenresolution[3270:56670] starting screenresolution argv=screenresolution get 05:43:22 INFO - 2016-04-25 05:43:22.054 screenresolution[3270:56670] Display 0: 1600x1200x32@60 05:43:22 INFO - 2016-04-25 05:43:22.061 screenresolution[3271:56673] starting screenresolution argv=screenresolution list 05:43:22 INFO - Available Modes on Display 0 05:43:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:43:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:43:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:43:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:43:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:43:22 INFO - Intel Iris: 05:43:22 INFO - Chipset Model: Intel Iris 05:43:22 INFO - Type: GPU 05:43:22 INFO - Bus: Built-In 05:43:22 INFO - VRAM (Dynamic, Max): 1536 MB 05:43:22 INFO - Vendor: Intel (0x8086) 05:43:22 INFO - Device ID: 0x0a2e 05:43:22 INFO - Revision ID: 0x0009 05:43:22 INFO - Displays: 05:43:22 INFO - Display: 05:43:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:43:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:43:22 INFO - Display Serial Number: 1600x1200 60 05:43:22 INFO - Main Display: Yes 05:43:22 INFO - Mirror: Off 05:43:22 INFO - Online: Yes 05:43:22 INFO - Rotation: Supported 05:43:22 INFO - Return code: 0 05:43:22 INFO - Running post-action listener: _resource_record_post_action 05:43:22 INFO - Running post-run listener: _resource_record_post_run 05:43:23 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 925696; Write bytes: 694378496; Read time: 9; Write time: 2084 05:43:23 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 396005888; Write bytes: 397649920; Read time: 29626; Write time: 730 05:43:23 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 925696; Write bytes: 295880704; Read time: 9; Write time: 1351 05:43:23 INFO - Running post-run listener: _upload_blobber_files 05:43:23 INFO - Blob upload gear active. 05:43:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:43:23 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:43:23 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:43:23 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:43:23 INFO - (blobuploader) - INFO - Open directory for files ... 05:43:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:43:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:43:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:24 INFO - (blobuploader) - INFO - Done attempting. 05:43:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:43:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:43:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:25 INFO - (blobuploader) - INFO - Done attempting. 05:43:25 INFO - (blobuploader) - INFO - Iteration through files over. 05:43:25 INFO - Return code: 0 05:43:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:43:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:43:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc8851b6c29bd49f94e8f7ad1fe82d3a3f24617fa0d0e45616333c40ebbae5834c1dce9e80d1ee35625325a8bb206d5c083c03beb29ac405d4ed544667ac21bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/870273e6da8d9f4a7fba3f2ab2ff31ad15485a92b3e29d1d38b8808fe14b187604d4bd976c6f7d3c7b031c975846a38a243b38601a7aa8bd41817952c126d092"} 05:43:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:43:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:43:25 INFO - Contents: 05:43:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc8851b6c29bd49f94e8f7ad1fe82d3a3f24617fa0d0e45616333c40ebbae5834c1dce9e80d1ee35625325a8bb206d5c083c03beb29ac405d4ed544667ac21bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/870273e6da8d9f4a7fba3f2ab2ff31ad15485a92b3e29d1d38b8808fe14b187604d4bd976c6f7d3c7b031c975846a38a243b38601a7aa8bd41817952c126d092"} 05:43:25 INFO - Running post-run listener: copy_logs_to_upload_dir 05:43:25 INFO - Copying logs to upload dir... 05:43:25 INFO - mkdir: /builds/slave/test/build/upload/logs 05:43:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.067381 ========= master_lag: 3.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-25 05:43:28.817308) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-25 05:43:28.820295) ========= 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.WDFRiKfFRp/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.R5xejp42QT/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc8851b6c29bd49f94e8f7ad1fe82d3a3f24617fa0d0e45616333c40ebbae5834c1dce9e80d1ee35625325a8bb206d5c083c03beb29ac405d4ed544667ac21bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/870273e6da8d9f4a7fba3f2ab2ff31ad15485a92b3e29d1d38b8808fe14b187604d4bd976c6f7d3c7b031c975846a38a243b38601a7aa8bd41817952c126d092"} build_url:https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011077 build_url: 'https://queue.taskcluster.net/v1/task/K44pmJgLQIKdeyga9YG5Og/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc8851b6c29bd49f94e8f7ad1fe82d3a3f24617fa0d0e45616333c40ebbae5834c1dce9e80d1ee35625325a8bb206d5c083c03beb29ac405d4ed544667ac21bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/870273e6da8d9f4a7fba3f2ab2ff31ad15485a92b3e29d1d38b8808fe14b187604d4bd976c6f7d3c7b031c975846a38a243b38601a7aa8bd41817952c126d092"}' ========= master_lag: 1.48 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-25 05:43:30.313975) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-04-25 05:43:30.314278) ========= 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.WDFRiKfFRp/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.R5xejp42QT/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.005247 ========= master_lag: 7.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-04-25 05:43:37.423967) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:43:37.424247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:43:37.445395) ========= ========= Total master_lag: 11.97 =========