builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0342 starttime: 1468599114.05 results: success (0) buildid: 20160714094242 builduid: 5eac837765964f8393ec98e43c8f70d5 revision: 0968b5fb96974b862d6901f9250644d8e478a30f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:54.045403) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:54.046314) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:54.046582) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:54.072500) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:54.072748) ========= 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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-15 09:11:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.8M=0.001s 2016-07-15 09:11:54 (17.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163102 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:54.258131) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:54.258434) ========= 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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.040871 ========= master_lag: 0.17 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:54.472345) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 09:11:54.472646) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0968b5fb96974b862d6901f9250644d8e478a30f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0968b5fb96974b862d6901f9250644d8e478a30f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oBKAyF8ATB/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.IEn4p8W2wX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-15 09:11:54,551 truncating revision to first 12 chars 2016-07-15 09:11:54,552 Setting DEBUG logging. 2016-07-15 09:11:54,552 attempt 1/10 2016-07-15 09:11:54,552 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0968b5fb9697?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 09:11:55,654 unpacking tar archive at: mozilla-beta-0968b5fb9697/testing/mozharness/ program finished with exit code 0 elapsedTime=1.358547 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 09:11:55.853577) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:55.853932) ========= script_repo_revision: 0968b5fb96974b862d6901f9250644d8e478a30f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:55.854376) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:55.854671) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 09:11:55.872683) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-15 09:11:55.873005) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oBKAyF8ATB/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.IEn4p8W2wX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:11:56 INFO - MultiFileLogger online at 20160715 09:11:56 in /builds/slave/test 09:11:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:11:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:11:56 INFO - {'append_to_log': False, 09:11:56 INFO - 'base_work_dir': '/builds/slave/test', 09:11:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:11:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:11:56 INFO - 'branch': 'Mozilla-Beta', 09:11:56 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:56 INFO - 'config_files': ('talos/mac_config.py',), 09:11:56 INFO - 'default_actions': ('clobber', 09:11:56 INFO - 'read-buildbot-config', 09:11:56 INFO - 'download-and-extract', 09:11:56 INFO - 'populate-webroot', 09:11:56 INFO - 'create-virtualenv', 09:11:56 INFO - 'install', 09:11:56 INFO - 'run-tests'), 09:11:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:56 INFO - 'download_minidump_stackwalk': True, 09:11:56 INFO - 'download_symbols': 'ondemand', 09:11:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:56 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:56 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:56 INFO - 'installer_path': 'installer.exe', 09:11:56 INFO - 'log_level': 'info', 09:11:56 INFO - 'log_name': 'talos', 09:11:56 INFO - 'log_to_console': True, 09:11:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:11:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:11:56 INFO - 'opt_config_files': (), 09:11:56 INFO - 'pip_index': False, 09:11:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:11:56 INFO - 'cmd': ('bash', 09:11:56 INFO - '-c', 09:11:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:11:56 INFO - 'enabled': True, 09:11:56 INFO - 'halt_on_failure': False, 09:11:56 INFO - 'name': 'check_screen_resolution'},), 09:11:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:11:56 INFO - 'cmd': ('bash', 09:11:56 INFO - '-c', 09:11:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:11:56 INFO - 'enabled': True, 09:11:56 INFO - 'halt_on_failure': False, 09:11:56 INFO - 'name': 'check_screen_resolution'},), 09:11:56 INFO - 'run_cmd_checks_enabled': True, 09:11:56 INFO - 'sps_profile': False, 09:11:56 INFO - 'sps_profile_interval': 0, 09:11:56 INFO - 'suite': 'dromaeojs', 09:11:56 INFO - 'system_bits': '32', 09:11:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:56 INFO - 'title': 't-yosemite-r7-0342', 09:11:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:56 INFO - 'use_talos_json': True, 09:11:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:11:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:56 INFO - 'work_dir': 'build'} 09:11:56 INFO - ##### 09:11:56 INFO - ##### Running clobber step. 09:11:56 INFO - ##### 09:11:56 INFO - Running pre-action listener: _resource_record_pre_action 09:11:56 INFO - Running main action method: clobber 09:11:56 INFO - rmtree: /builds/slave/test/build 09:11:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:11:57 INFO - Running post-action listener: _resource_record_post_action 09:11:57 INFO - ##### 09:11:57 INFO - ##### Running read-buildbot-config step. 09:11:57 INFO - ##### 09:11:57 INFO - Running pre-action listener: _resource_record_pre_action 09:11:57 INFO - Running main action method: read_buildbot_config 09:11:57 INFO - Using buildbot properties: 09:11:57 INFO - { 09:11:57 INFO - "project": "", 09:11:57 INFO - "product": "firefox", 09:11:57 INFO - "script_repo_revision": "production", 09:11:57 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:11:57 INFO - "repository": "", 09:11:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:11:57 INFO - "buildid": "20160714094242", 09:11:57 INFO - "pgo_build": "False", 09:11:57 INFO - "basedir": "/builds/slave/test", 09:11:57 INFO - "buildnumber": 0, 09:11:57 INFO - "slavename": "t-yosemite-r7-0342", 09:11:57 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:11:57 INFO - "platform": "yosemite_r7", 09:11:57 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:11:57 INFO - "branch": "mozilla-beta", 09:11:57 INFO - "repo_path": "releases/mozilla-beta", 09:11:57 INFO - "revision": "0968b5fb96974b862d6901f9250644d8e478a30f", 09:11:57 INFO - "stage_platform": "macosx64", 09:11:57 INFO - "builduid": "5eac837765964f8393ec98e43c8f70d5", 09:11:57 INFO - "slavebuilddir": "test" 09:11:57 INFO - } 09:11:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 09:11:57 INFO - Running post-action listener: _resource_record_post_action 09:11:57 INFO - ##### 09:11:57 INFO - ##### Running download-and-extract step. 09:11:57 INFO - ##### 09:11:57 INFO - Running pre-action listener: _resource_record_pre_action 09:11:57 INFO - Running main action method: download_and_extract 09:11:57 INFO - mkdir: /builds/slave/test/build/tests 09:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:57 INFO - https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:11:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:11:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:11:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:11:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 09:11:58 INFO - Downloaded 1165 bytes. 09:11:58 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:11:58 INFO - Using the following test package requirements: 09:11:58 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:11:58 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:11:58 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 09:11:58 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:11:58 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 09:11:58 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:11:58 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:11:58 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 09:11:58 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:11:58 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:11:58 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 09:11:58 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:11:58 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 09:11:58 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:11:58 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 09:11:58 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:11:58 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 09:11:58 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 09:11:58 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')]} 09:11:58 INFO - https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:11:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:11:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:11:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:11:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:12:12 INFO - Downloaded 18314842 bytes. 09:12:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:12:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:12:15 INFO - Return code: 0 09:12:15 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:12:15 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')]} 09:12:15 INFO - https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:12:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:12:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:12:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:12:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:12:17 INFO - Downloaded 18314842 bytes. 09:12:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:12:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:12:20 INFO - Return code: 0 09:12: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')]} 09:12:20 INFO - https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:12:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:12:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:12:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 09:12:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 09:12:22 INFO - Downloaded 10840145 bytes. 09:12:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:12:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:12:22 INFO - Return code: 0 09:12: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')]} 09:12:22 INFO - https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 09:12:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:12:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:12:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 09:12:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 09:12:25 INFO - Downloaded 86191200 bytes. 09:12:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:12:25 INFO - mkdir: /builds/slave/test/properties 09:12:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:12:25 INFO - Writing to file /builds/slave/test/properties/build_url 09:12:25 INFO - Contents: 09:12:25 INFO - build_url:https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:12:27 INFO - Running post-action listener: _resource_record_post_action 09:12:27 INFO - Running post-action listener: set_extra_try_arguments 09:12:27 INFO - ##### 09:12:27 INFO - ##### Running populate-webroot step. 09:12:27 INFO - ##### 09:12:27 INFO - Running pre-action listener: _resource_record_pre_action 09:12:27 INFO - Running main action method: populate_webroot 09:12:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:27 INFO - '--tppagecycles', 09:12:27 INFO - '7'], 09:12:27 INFO - 'tests': ['tsvgm']}}, 09:12:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:12:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:12:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:12:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:12:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:27 INFO - 'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:27 INFO - 'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['damp', 'tps']}, 09:12:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:27 INFO - 'tests': ['damp', 'tps']}, 09:12:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['dromaeo_dom']}, 09:12:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['a11yr', 09:12:27 INFO - 'ts_paint', 09:12:27 INFO - 'tpaint', 09:12:27 INFO - 'sessionrestore', 09:12:27 INFO - 'sessionrestore_no_auto_restore', 09:12:27 INFO - 'tabpaint']}, 09:12:27 INFO - 'other-e10s': {'tests': ['a11yr', 09:12:27 INFO - 'ts_paint', 09:12:27 INFO - 'tpaint', 09:12:27 INFO - 'sessionrestore', 09:12:27 INFO - 'sessionrestore_no_auto_restore', 09:12:27 INFO - 'tabpaint']}, 09:12:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:12:27 INFO - 'ts_paint', 09:12:27 INFO - 'tpaint', 09:12:27 INFO - 'sessionrestore', 09:12:27 INFO - 'sessionrestore_no_auto_restore', 09:12:27 INFO - 'tabpaint']}, 09:12:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:12:27 INFO - 'ts_paint', 09:12:27 INFO - 'tpaint', 09:12:27 INFO - 'sessionrestore', 09:12:27 INFO - 'sessionrestore_no_auto_restore', 09:12:27 INFO - 'tabpaint']}, 09:12:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['a11yr', 09:12:27 INFO - 'ts_paint', 09:12:27 INFO - 'tpaint', 09:12:27 INFO - 'sessionrestore', 09:12:27 INFO - 'sessionrestore_no_auto_restore', 09:12:27 INFO - 'tabpaint']}, 09:12:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['a11yr', 09:12:27 INFO - 'ts_paint', 09:12:27 INFO - 'tpaint', 09:12:27 INFO - 'sessionrestore', 09:12:27 INFO - 'sessionrestore_no_auto_restore', 09:12:27 INFO - 'tabpaint']}, 09:12:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['tsvgx', 09:12:27 INFO - 'tsvgr_opacity', 09:12:27 INFO - 'tart', 09:12:27 INFO - 'tscrollx', 09:12:27 INFO - 'cart']}, 09:12:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:12:27 INFO - 'tsvgr_opacity', 09:12:27 INFO - 'tart', 09:12:27 INFO - 'tscrollx', 09:12:27 INFO - 'cart']}, 09:12:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:27 INFO - 'talos_options': ['--disable-e10s'], 09:12:27 INFO - 'tests': ['tp5o']}, 09:12:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:27 INFO - 'tests': ['tp5o']}, 09:12:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:27 INFO - 'talos_options': ['--disable-e10s', 09:12:27 INFO - '--xperf_path', 09:12:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:27 INFO - 'tests': ['tp5n']}, 09:12:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:27 INFO - 'talos_options': ['--xperf_path', 09:12:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:27 INFO - 'tests': ['tp5n']}}, 09:12:27 INFO - 'talos.zip': {'path': '', 09:12:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:27 INFO - Running post-action listener: _resource_record_post_action 09:12:27 INFO - ##### 09:12:27 INFO - ##### Running create-virtualenv step. 09:12:27 INFO - ##### 09:12:27 INFO - Running pre-action listener: _resource_record_pre_action 09:12:27 INFO - Running main action method: create_virtualenv 09:12:27 INFO - Creating virtualenv /builds/slave/test/build/venv 09:12:27 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 09:12:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:12:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:27 INFO - Using real prefix '/tools/python27' 09:12:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:12:27 INFO - Installing distribute.............................................................................................................................................................................................done. 09:12:30 INFO - Installing pip.................done. 09:12:30 INFO - Return code: 0 09:12:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:30 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 0x10b4fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b50d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb23ca89e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, '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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:12:30 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 09:12:30 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 09:12:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:12:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:30 INFO - 'HOME': '/Users/cltbld', 09:12:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:30 INFO - 'LOGNAME': 'cltbld', 09:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:30 INFO - 'MOZ_NO_REMOTE': '1', 09:12:30 INFO - 'NO_EM_RESTART': '1', 09:12:30 INFO - 'PAGER': '/bin/cat', 09:12:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:30 INFO - 'PWD': '/builds/slave/test', 09:12:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:30 INFO - 'SHELL': '/bin/bash', 09:12:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:12:30 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:12:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:30 INFO - 'USER': 'cltbld', 09:12:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:30 INFO - 'XPC_FLAGS': '0x0', 09:12:30 INFO - 'XPC_SERVICE_NAME': '0', 09:12:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:31 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 09:12:31 INFO - Cleaning up... 09:12:31 INFO - Return code: 0 09:12:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b4fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b50d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb23ca89e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, '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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:12:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:12:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:12:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:31 INFO - 'HOME': '/Users/cltbld', 09:12:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:31 INFO - 'LOGNAME': 'cltbld', 09:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:31 INFO - 'MOZ_NO_REMOTE': '1', 09:12:31 INFO - 'NO_EM_RESTART': '1', 09:12:31 INFO - 'PAGER': '/bin/cat', 09:12:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:31 INFO - 'PWD': '/builds/slave/test', 09:12:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:31 INFO - 'SHELL': '/bin/bash', 09:12:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:12:31 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:12:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:31 INFO - 'USER': 'cltbld', 09:12:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:31 INFO - 'XPC_FLAGS': '0x0', 09:12:31 INFO - 'XPC_SERVICE_NAME': '0', 09:12:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:31 INFO - Downloading/unpacking psutil>=0.7.1 09:12:31 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 09:12:31 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 09:12:31 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 09:12:31 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 09:12:31 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 09:12:31 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 09:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:12:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:34 INFO - Installing collected packages: psutil 09:12:34 INFO - Running setup.py install for psutil 09:12:34 INFO - building 'psutil._psutil_osx' extension 09:12:34 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 09:12:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:12:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:12:35 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 09:12:35 INFO - building 'psutil._psutil_posix' extension 09:12:35 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 09:12:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:12:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:12:35 INFO - ^ 09:12:35 INFO - 1 warning generated. 09:12:35 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 09:12:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:35 INFO - Successfully installed psutil 09:12:35 INFO - Cleaning up... 09:12:35 INFO - Return code: 0 09:12:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:35 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 0x10b4fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b50d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb23ca89e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, '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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:12:35 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 09:12:35 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 09:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:35 INFO - 'HOME': '/Users/cltbld', 09:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:35 INFO - 'LOGNAME': 'cltbld', 09:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:35 INFO - 'MOZ_NO_REMOTE': '1', 09:12:35 INFO - 'NO_EM_RESTART': '1', 09:12:35 INFO - 'PAGER': '/bin/cat', 09:12:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:35 INFO - 'PWD': '/builds/slave/test', 09:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:35 INFO - 'SHELL': '/bin/bash', 09:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:12:35 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:35 INFO - 'USER': 'cltbld', 09:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:35 INFO - 'XPC_FLAGS': '0x0', 09:12:35 INFO - 'XPC_SERVICE_NAME': '0', 09:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:12:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:12:38 INFO - Installing collected packages: mozsystemmonitor 09:12:38 INFO - Running setup.py install for mozsystemmonitor 09:12:38 INFO - Successfully installed mozsystemmonitor 09:12:38 INFO - Cleaning up... 09:12:38 INFO - Return code: 0 09:12:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b4fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b50d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb23ca89e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, '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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:12:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:12:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:12:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:12:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:38 INFO - 'HOME': '/Users/cltbld', 09:12:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:38 INFO - 'LOGNAME': 'cltbld', 09:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:38 INFO - 'MOZ_NO_REMOTE': '1', 09:12:38 INFO - 'NO_EM_RESTART': '1', 09:12:38 INFO - 'PAGER': '/bin/cat', 09:12:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:38 INFO - 'PWD': '/builds/slave/test', 09:12:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:38 INFO - 'SHELL': '/bin/bash', 09:12:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:12:38 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:12:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:38 INFO - 'USER': 'cltbld', 09:12:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:38 INFO - 'XPC_FLAGS': '0x0', 09:12:38 INFO - 'XPC_SERVICE_NAME': '0', 09:12:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:38 INFO - Downloading/unpacking blobuploader==1.2.4 09:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:41 INFO - Downloading blobuploader-1.2.4.tar.gz 09:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:12:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:12:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:42 INFO - Downloading docopt-0.6.1.tar.gz 09:12:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:12:42 INFO - Installing collected packages: blobuploader, requests, docopt 09:12:42 INFO - Running setup.py install for blobuploader 09:12:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:12:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:12:42 INFO - Running setup.py install for requests 09:12:43 INFO - Running setup.py install for docopt 09:12:43 INFO - Successfully installed blobuploader requests docopt 09:12:43 INFO - Cleaning up... 09:12:43 INFO - Return code: 0 09:12:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:43 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 0x10b4fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b50d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb23ca89e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, '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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:12:43 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 09:12:43 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 09:12:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:12:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:43 INFO - 'HOME': '/Users/cltbld', 09:12:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:43 INFO - 'LOGNAME': 'cltbld', 09:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:43 INFO - 'MOZ_NO_REMOTE': '1', 09:12:43 INFO - 'NO_EM_RESTART': '1', 09:12:43 INFO - 'PAGER': '/bin/cat', 09:12:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:43 INFO - 'PWD': '/builds/slave/test', 09:12:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:43 INFO - 'SHELL': '/bin/bash', 09:12:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:12:43 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:12:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:43 INFO - 'USER': 'cltbld', 09:12:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:43 INFO - 'XPC_FLAGS': '0x0', 09:12:43 INFO - 'XPC_SERVICE_NAME': '0', 09:12:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-WZwFTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ZcqlZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-25dn8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-xQ4Tb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-D3xJJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-p7XoZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-CQXblW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-oN4TGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-6BNnkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-7XBYZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-wt3a69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-bwoj0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-pEWmGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-On1FX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-nrax6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-KmZvre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-_Xa_sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:12:45 INFO - Running setup.py install for manifestparser 09:12:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:12:45 INFO - Running setup.py install for mozcrash 09:12:45 INFO - Running setup.py install for mozdebug 09:12:45 INFO - Running setup.py install for mozdevice 09:12:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:12:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:12:46 INFO - Running setup.py install for mozfile 09:12:46 INFO - Running setup.py install for mozhttpd 09:12:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:12:46 INFO - Running setup.py install for mozinfo 09:12:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:12:46 INFO - Running setup.py install for mozInstall 09:12:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:12:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:12:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:12:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:12:46 INFO - Running setup.py install for mozleak 09:12:46 INFO - Running setup.py install for mozlog 09:12:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:12:46 INFO - Running setup.py install for moznetwork 09:12:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:12:47 INFO - Running setup.py install for mozprocess 09:12:47 INFO - Running setup.py install for mozprofile 09:12:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:12:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:12:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:12:47 INFO - Running setup.py install for mozrunner 09:12:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:12:47 INFO - Running setup.py install for mozscreenshot 09:12:47 INFO - Running setup.py install for moztest 09:12:47 INFO - Running setup.py install for mozversion 09:12:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:12:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:12:47 INFO - Cleaning up... 09:12:48 INFO - Return code: 0 09:12:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b50d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb23ca89e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, '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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:12:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:12:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:48 INFO - 'HOME': '/Users/cltbld', 09:12:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:48 INFO - 'LOGNAME': 'cltbld', 09:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:48 INFO - 'MOZ_NO_REMOTE': '1', 09:12:48 INFO - 'NO_EM_RESTART': '1', 09:12:48 INFO - 'PAGER': '/bin/cat', 09:12:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:48 INFO - 'PWD': '/builds/slave/test', 09:12:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:48 INFO - 'SHELL': '/bin/bash', 09:12:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:12:48 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:12:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:48 INFO - 'USER': 'cltbld', 09:12:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:48 INFO - 'XPC_FLAGS': '0x0', 09:12:48 INFO - 'XPC_SERVICE_NAME': '0', 09:12:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-GfiQJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:48 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)) 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-3CMYzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:48 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)) 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-AWCft_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:48 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)) 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-fjW9Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:48 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)) 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-QMdPK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:48 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)) 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-feY0hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:48 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)) 09:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-xCZCuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-bzGHNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-yYkDtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-MeBYPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:49 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)) 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-4p86DN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:49 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)) 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-TOZZSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:49 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)) 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-fCX0JQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:49 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)) 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-_MR2gD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:49 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)) 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-I6hlc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:49 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)) 09:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-BHmk7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-2xBoUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:12:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:12:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:12:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:12:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12:52 INFO - Downloading blessings-1.6.tar.gz 09:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:12:52 INFO - Installing collected packages: blessings 09:12:52 INFO - Running setup.py install for blessings 09:12:52 INFO - Successfully installed blessings 09:12:52 INFO - Cleaning up... 09:12:52 INFO - Return code: 0 09:12:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:12:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:53 INFO - Reading from file tmpfile_stdout 09:12:53 INFO - Current package versions: 09:12:53 INFO - blessings == 1.6 09:12:53 INFO - blobuploader == 1.2.4 09:12:53 INFO - docopt == 0.6.1 09:12:53 INFO - manifestparser == 1.1 09:12:53 INFO - mozInstall == 1.12 09:12:53 INFO - mozcrash == 0.17 09:12:53 INFO - mozdebug == 0.1 09:12:53 INFO - mozdevice == 0.48 09:12:53 INFO - mozfile == 1.2 09:12:53 INFO - mozhttpd == 0.7 09:12:53 INFO - mozinfo == 0.9 09:12:53 INFO - mozleak == 0.1 09:12:53 INFO - mozlog == 3.1 09:12:53 INFO - moznetwork == 0.27 09:12:53 INFO - mozprocess == 0.22 09:12:53 INFO - mozprofile == 0.28 09:12:53 INFO - mozrunner == 6.11 09:12:53 INFO - mozscreenshot == 0.1 09:12:53 INFO - mozsystemmonitor == 0.0 09:12:53 INFO - moztest == 0.7 09:12:53 INFO - mozversion == 1.4 09:12:53 INFO - psutil == 3.1.1 09:12:53 INFO - requests == 1.2.3 09:12:53 INFO - wsgiref == 0.1.2 09:12:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b50d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb23ca89e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, '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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:12:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:12:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:12:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:53 INFO - 'HOME': '/Users/cltbld', 09:12:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:53 INFO - 'LOGNAME': 'cltbld', 09:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:53 INFO - 'MOZ_NO_REMOTE': '1', 09:12:53 INFO - 'NO_EM_RESTART': '1', 09:12:53 INFO - 'PAGER': '/bin/cat', 09:12:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:53 INFO - 'PWD': '/builds/slave/test', 09:12:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:53 INFO - 'SHELL': '/bin/bash', 09:12:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:12:53 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:12:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:53 INFO - 'USER': 'cltbld', 09:12:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:53 INFO - 'XPC_FLAGS': '0x0', 09:12:53 INFO - 'XPC_SERVICE_NAME': '0', 09:12:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 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)) 09:12:53 INFO - Cleaning up... 09:12:53 INFO - Return code: 0 09:12:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:53 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 0x10b4fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b50d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb23ca89e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, '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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:12:53 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 09:12:53 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 09:12:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:12:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:53 INFO - 'HOME': '/Users/cltbld', 09:12:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:53 INFO - 'LOGNAME': 'cltbld', 09:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:53 INFO - 'MOZ_NO_REMOTE': '1', 09:12:53 INFO - 'NO_EM_RESTART': '1', 09:12:53 INFO - 'PAGER': '/bin/cat', 09:12:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:53 INFO - 'PWD': '/builds/slave/test', 09:12:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:53 INFO - 'SHELL': '/bin/bash', 09:12:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:12:53 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:12:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:53 INFO - 'USER': 'cltbld', 09:12:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:53 INFO - 'XPC_FLAGS': '0x0', 09:12:53 INFO - 'XPC_SERVICE_NAME': '0', 09:12:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:53 INFO - Downloading/unpacking jsonschema 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:12:56 INFO - Downloading/unpacking functools32 (from jsonschema) 09:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:56 INFO - Downloading functools32-3.2.3-2.tar.gz 09:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:12:56 INFO - warning: no files found matching '*.txt' 09:12:56 INFO - no previously-included directories found matching 'build' 09:12:56 INFO - no previously-included directories found matching 'dist' 09:12:56 INFO - no previously-included directories found matching '.git*' 09:12:56 INFO - Installing collected packages: jsonschema, functools32 09:12:56 INFO - Running setup.py install for functools32 09:12:56 INFO - warning: no files found matching '*.txt' 09:12:56 INFO - no previously-included directories found matching 'build' 09:12:56 INFO - no previously-included directories found matching 'dist' 09:12:56 INFO - no previously-included directories found matching '.git*' 09:12:56 INFO - Successfully installed jsonschema functools32 09:12:56 INFO - Cleaning up... 09:12:57 INFO - Return code: 0 09:12:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b50d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb23ca89e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, '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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 09:12:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:12:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:12:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:57 INFO - 'HOME': '/Users/cltbld', 09:12:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:57 INFO - 'LOGNAME': 'cltbld', 09:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:57 INFO - 'MOZ_NO_REMOTE': '1', 09:12:57 INFO - 'NO_EM_RESTART': '1', 09:12:57 INFO - 'PAGER': '/bin/cat', 09:12:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:57 INFO - 'PWD': '/builds/slave/test', 09:12:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:57 INFO - 'SHELL': '/bin/bash', 09:12:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:12:57 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:12:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:57 INFO - 'USER': 'cltbld', 09:12:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:57 INFO - 'XPC_FLAGS': '0x0', 09:12:57 INFO - 'XPC_SERVICE_NAME': '0', 09:12:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:57 INFO - Downloading/unpacking flake8 09:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:13:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:13:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:13:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:01 INFO - no previously-included directories found matching 'docs/_build' 09:13:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:13:01 INFO - Running setup.py install for pep8 09:13:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:01 INFO - no previously-included directories found matching 'docs/_build' 09:13:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:13:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:13:01 INFO - Cleaning up... 09:13:01 INFO - Return code: 0 09:13:01 INFO - Running post-action listener: _resource_record_post_action 09:13:01 INFO - Running post-action listener: _start_resource_monitoring 09:13:01 INFO - Starting resource monitoring. 09:13:01 INFO - ##### 09:13:01 INFO - ##### Running install step. 09:13:01 INFO - ##### 09:13:01 INFO - Running pre-action listener: _resource_record_pre_action 09:13:01 INFO - Running main action method: install 09:13:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:02 INFO - Reading from file tmpfile_stdout 09:13:02 INFO - Detecting whether we're running mozinstall >=1.0... 09:13:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:13:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:13:02 INFO - Reading from file tmpfile_stdout 09:13:02 INFO - Output received: 09:13:02 INFO - Usage: mozinstall [options] installer 09:13:02 INFO - Options: 09:13:02 INFO - -h, --help show this help message and exit 09:13:02 INFO - -d DEST, --destination=DEST 09:13:02 INFO - Directory to install application into. [default: 09:13:02 INFO - "/builds/slave/test"] 09:13:02 INFO - --app=APP Application being installed. [default: firefox] 09:13:02 INFO - mkdir: /builds/slave/test/build/application 09:13:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:13:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:13:24 INFO - Reading from file tmpfile_stdout 09:13:24 INFO - Output received: 09:13:24 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:13:24 INFO - Running post-action listener: _resource_record_post_action 09:13:24 INFO - ##### 09:13:24 INFO - ##### Running run-tests step. 09:13:24 INFO - ##### 09:13:24 INFO - Running pre-action listener: _resource_record_pre_action 09:13:24 INFO - Running main action method: run_tests 09:13:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:13:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:13:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:13:24 INFO - 2016-07-15 09:13:24.458 screenresolution[1657:10971] starting screenresolution argv=screenresolution get 09:13:24 INFO - 2016-07-15 09:13:24.460 screenresolution[1657:10971] Display 0: 1600x1200x32@60 09:13:24 INFO - 2016-07-15 09:13:24.466 screenresolution[1658:10974] starting screenresolution argv=screenresolution list 09:13:24 INFO - Available Modes on Display 0 09:13:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:13:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:13:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:13:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:13:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:13:24 INFO - Intel Iris: 09:13:24 INFO - Chipset Model: Intel Iris 09:13:24 INFO - Type: GPU 09:13:24 INFO - Bus: Built-In 09:13:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:13:24 INFO - Vendor: Intel (0x8086) 09:13:24 INFO - Device ID: 0x0a2e 09:13:24 INFO - Revision ID: 0x0009 09:13:24 INFO - Displays: 09:13:24 INFO - Display: 09:13:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:13:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:13:24 INFO - Display Serial Number: 1600x1200 60 09:13:24 INFO - Main Display: Yes 09:13:24 INFO - Mirror: Off 09:13:24 INFO - Online: Yes 09:13:24 INFO - Rotation: Supported 09:13:24 INFO - Return code: 0 09:13:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:13:24 INFO - Python 2.7.3 09:13:24 INFO - Return code: 0 09:13:24 INFO - grabbing minidump binary from tooltool 09:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:24 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 0x10b39ce00>, '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 0x10b205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b397b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:13:24 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 09:13:24 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 09:13:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:13:24 INFO - Return code: 0 09:13:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:13:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:13:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:13:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:13:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:13:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:13:25 INFO - Return code: 0 09:13:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0342 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:13:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBKAyF8ATB/Render', 09:13:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:25 INFO - 'HOME': '/Users/cltbld', 09:13:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:25 INFO - 'LOGNAME': 'cltbld', 09:13:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:13:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:25 INFO - 'MOZ_NO_REMOTE': '1', 09:13:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:13:25 INFO - 'NO_EM_RESTART': '1', 09:13:25 INFO - 'PAGER': '/bin/cat', 09:13:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:25 INFO - 'PWD': '/builds/slave/test', 09:13:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:13:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:25 INFO - 'SHELL': '/bin/bash', 09:13:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IEn4p8W2wX/Listeners', 09:13:25 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 09:13:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:25 INFO - 'USER': 'cltbld', 09:13:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:25 INFO - 'XPC_FLAGS': '0x0', 09:13:25 INFO - 'XPC_SERVICE_NAME': '0', 09:13:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:13:26 INFO - mozversion application_buildid: 20160714094242 09:13:26 INFO - mozversion application_changeset: 0968b5fb96974b862d6901f9250644d8e478a30f 09:13:26 INFO - mozversion application_display_name: Firefox 09:13:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:13:26 INFO - mozversion application_name: Firefox 09:13:26 INFO - mozversion application_remotingname: firefox 09:13:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:13:26 INFO - mozversion application_vendor: Mozilla 09:13:26 INFO - mozversion application_version: 48.0 09:13:26 INFO - mozversion platform_buildid: 20160714094242 09:13:26 INFO - mozversion platform_changeset: 0968b5fb96974b862d6901f9250644d8e478a30f 09:13:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:13:26 INFO - mozversion platform_version: 48.0 09:13:26 INFO - using testdate: 1468599206 09:13:26 INFO - actual date: 1468599206 09:13:26 INFO - starting webserver on 'localhost:49291' 09:13:26 INFO - SUITE-START | Running 2 tests 09:13:26 INFO - TEST-START | dromaeo_css 09:13:26 INFO - operating with platform_type : mac_ 09:13:26 INFO - Initialising browser for dromaeo_css test... 09:13:26 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpkI2WNy/profile http://localhost:49291/getInfo.html) 09:13:28 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 09:13:28 INFO - PROCESS | 1676 | colorDepth:24 09:13:28 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 09:13:28 INFO - PROCESS | 1676 | __metrics 09:13:28 INFO - PROCESS | 1676 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:13:28 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:13:29 INFO - TEST-INFO | 1676: exit 0 09:13:29 INFO - Browser initialized. 09:13:29 INFO - Running cycle 1/1 for dromaeo_css test... 09:13:29 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpkI2WNy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:41 INFO - PROCESS | 1680 | RSS: Main: 160088064 09:13:41 INFO - PROCESS | 1680 | 09:14:32 INFO - PROCESS | 1680 | 1468599272514 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] 09:16:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49291/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49291/tests/dromaeo/cssquery-ext.html) 09:16:43 INFO - PROCESS | 1680 | RSS: Main: 288706560 09:16:43 INFO - PROCESS | 1680 | 09:19:46 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49291/tests/dromaeo/cssquery-ext.html (next: http://localhost:49291/tests/dromaeo/cssquery-jquery.html) 09:19:47 INFO - PROCESS | 1680 | RSS: Main: 302006272 09:19:47 INFO - PROCESS | 1680 | 09:22:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49291/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49291/tests/dromaeo/cssquery-mootools.html) 09:22:49 INFO - PROCESS | 1680 | RSS: Main: 308920320 09:22:49 INFO - PROCESS | 1680 | 09:25:51 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49291/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49291/tests/dromaeo/cssquery-prototype.html) 09:25:52 INFO - PROCESS | 1680 | RSS: Main: 307200000 09:25:52 INFO - PROCESS | 1680 | 09:28:55 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49291/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49291/tests/dromaeo/cssquery-yui.html) 09:28:56 INFO - PROCESS | 1680 | RSS: Main: 268709888 09:28:56 INFO - PROCESS | 1680 | 09:31:59 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49291/tests/dromaeo/cssquery-yui.html (next: http://localhost:49291/tests/dromaeo/cssquery-dojo.html) 09:31:59 INFO - PROCESS | 1680 | RSS: Main: 315334656 09:31:59 INFO - PROCESS | 1680 | 09:31:59 INFO - PROCESS | 1680 | __start_tp_report 09:31:59 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 09:31:59 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 09:31:59 INFO - PROCESS | 1680 | |i|pagename|runs| 09:31:59 INFO - PROCESS | 1680 | |0;dojo.html;3539.6283390244025;3618.240169564392;3638.6725194732467;3664.1755604988866;3731.832067556961;6036.034234522477;6083.452489275965;6098.9695051524795;6100.847478813032;6104.084387341901;6062.605930614518;6083.300420451649;6109.725062372193;6111.908321375183;6123.050927106306;5301.761420736057;5415;5415.268938693275;5441.673499590037;5492.917606235909;5437.374701909292;5473.425290344516;5483.561315094783;5491.890162196753;5495.835124946258;5353.5984801139875;5393.19102134679;5419.485148910847;5424.891502169954;5480.342358916935;5463.590230732711;5470.835874923759;5493.368262649807;5494.670319780786;5533.3083364579425;5318.73406329682;5325.920111198335;5353.839384818461;5406.405295417501;5436.238926550286;6003.909941350883;6023.307319658252;6064.06007068905;6075.574709770318;6088.817335479943;6333.493320534346;6370.9681451592905;6383.202099737533;6390.9774436090465;6399.488040956712;6328.462080723144;6382.521310901655;6460.741570337181;6467.191601049869;6469.870602587969;6127.325994140641;6128.479079278267;6144.38556144382;6151.108089327023;6151.292601350857;6557.803265901935;6565.540412171198;6575.704093315812;6585.703643336062;6591.736330546724;6161.7535298588455;6230.252369715663;6270.59241149333;6298.716557754912;6316;6431.292557818636;6439.452646525004;6442.452391546771;6447.161868957006;6457.3865482779065;6241.314964068791;6318;6322.778702745474;6323.747050117944;6331.05034244867;6161.075838624242;6181.011038233861;6197.5351848611535;6251.687415629201;6311.431971122621;5035.697858128524;5089.236614507853;5101.081805275012;5103.362079740033;5104.668196567211;4603.838865639686;4618.237990731492;4632.096741135513;4632.953670463319;4654.930176047362;6069.180660610849;6142.815715528542;6150.077488376779;6154.015357542861;6163.537734669918;6320.683965801783;6372.808815735535;6455.419012288822;6471.320511346335;6471.9676401618635;6120.142398366865;6141;6148.78479253224;6149.046897730859;6175.320714721377;5592.86017849557;5655.80204692842;5678.346990096109;5679.091345384802;5686.5735069869925;6490.805275841638;6591.340865913467;6598.967005165136;6601.207855057424;6704.497162712816;6327;6382.904256436064;6389.201349831271;6394.776182833764;6397.328280530477;6639.634820084942;6683.532152749261;6684.097646817811;6693.1298931138645;6694.230163531256;6248.4064013918605;6316.7473301069285;6353.523485738496;6374.107624932606;6378.840528986627;6194.38056194377;6211.9689401554515;6271.592346497463;6280.874382512233;6283.37166283368;5257.4742525747115;6270.278917924497;6312.337204593451;6313.43179113882;6315.621062736251;5186.429492755869;6109.755609775737;6202.782902598387;6281.811545653638;6295.496360291074;6140.785072522441;6143.078538219303;6146.78486252979;6152.769446110885;6155.322914479314;5195.818146364859;6270.717817698123;6283;6285.654289013965;6288.842778930563;6120.660465672209;6140.9030504681905;6154.076888466766;6159.876802463836;6210.534209934182 09:31:59 INFO - PROCESS | 1680 | |1;ext.html;534.369444056014;537.1084000559073;563.9125935480001;565.7312776431193;591.8757061017186;31803.840980795092;32304.8693295735;32323.191920202007;38252;39285;42079.57920420795;42512.57487425125;42513.787431062876;42553.36169957445;42723.931901702475;9612.471314077722;9840.065193806577;9841.852372214422;9844.950775246114;9846.753831154207;9184;9660.758481037996;11873.465694043789;12204.572839950604;12239.938800305987;11849.466773995147;11947.7610447791;11959.461824217931;11960.940195299056;11962.82055769164;11787.941060294686;11978.161528692995;11978.341191234522;11985.28088314704;11992.820107698304;11745.11911606633;11888.643340699793;11895.04839612829;11898.881011189864;11900.464479098462;40851.591484085075;41407.58592414067;41494.79252603718;41500.54748083833;41526.792366037975;3285.6385797562248;3356.916077098092;3427.0404286799735;3510.1926556891804;3994.1013272013856;25595.872020639963;27087.322816929536;29259;29464;29743.553846692317;2649.5893136563864;2674.358154043035;2680.6917204521533;2733.1937078561873;2753.8898444062215;391443;443929;452140;456854;457012.7149364265;38671.613283867366;39374.80312598419;39423;72259.55480890458;73063.63468182732;38909.80545097313;38939.80530097331;39172;39209;39258.41112383317;16180.75728864068;16338.019718816671;16352.345906163859;16355.100469474055;16426.342946282253;12897.54858579964;12933.935330323287;13556;13627.795583066261;13719.657008574866;7450.590217537979;7692.923070769332;8727.869081963776;8799.780005499913;8859.158379953895;3622.5109610202285;3652.5251717276583;3657.762245454037;3679.2089700714473;3715.535558055243;798.1738900238225;1026.2662196529368;1102.913630074376;1158.8261760735788;1198.7183011992174;29306.26734331616;29381;29834.10497685073;30024.54963175554;30208;1101.2896681640298;1156.2195518025298;1161.4773351991637;1198.6335577441723;1285.460106755165;13754.449822007007;15473.458428954933;15632.4528641497;15758.921205393899;16056.197190140447;625930.8703456454;629971;635408;636026;636896;61665;62076;62126;62188;62308.376916231166;1336.2116351352554;1361.9240799768072;1442.0698649371097;1586.0483709774044;1691.4840973502953;28145.43709125847;29732.851335743184;29745.553816692332;29815.956441524442;30331.39337213199;46024.07951840878;46464.53535464602;46466;46530.30204546867;46575.30137048015;357290;361764.19117904245;362462.1876890599;362776;578587;28118;28306;28620.14139575816;28624.57063144013;28948;43273;43336.133277334906;43339.56660433482;43354;43737.78131109324;34737;36013;36214.55141794299;36222.8188859054;36668;30365.848170759004;34573.48139777956;35366.64633353634;36050.45924311085;36158.4576231362;36513.63486365102;36904.44643330298;37219;37795.81102094582;37842.81078594589;32536.674633253366;33371.83198588135;33585;33614.99155025353;33633.495497568045;24563.87718061398;25159.49681006406;25418;25478.872605637596;25635.871820640776 09:31:59 INFO - PROCESS | 1680 | |2;jquery.html;347.8782426150847;356.1879204996286;408.84668249406474;418.20332267031296;423.6695377605467;16263.91868040661;19195;20629.690554641693;21301.467463313424;21342.146314147434;19232.51918702033;21490.785092149075;21617.56764864702;23064.42338941527;23103.84480775963;5748.741306641211;5803.593748437611;6077.3314935357075;6080.118382834508;6080.179175811275;5843.328017278003;5959.195508606348;5975.163477113208;5988.131720900488;6184.752609895599;6154.415330543591;6177.629342239457;6182.072689096626;6185.907211391833;6186.412290832363;5849.415058494138;6144.662043587601;6146.231721034871;6149.692515374213;6158.6612736299485;5927.911081333784;5939.970300148515;5944.019236825919;5946.7026648667825;5953.2856057272975;14084;24741.6288755667;24787.752122478723;25983.090591829194;26087.347816304555;2131.850770446054;2253.143805353955;2285.954280914389;2318.7681231876845;2416.9758302416926;9093.31800114994;9723.756906077404;10273.434961077137;10972.945135274273;14196.006279560435;1781.403229917979;1832.7709036370454;1837.595728939631;1889.4992826900882;1909.5703466719876;109656.90343096547;112688;113629;122509.54980900421;127369;9833.508324583885;11776.52893884252;11797.174197806244;11961.521539138512;12112.878871211351;14182.078164919247;14745.631359216106;14867.959242853112;15554.533363999099;15558.611034724223;9071.228945539571;9167.12912273333;9221.769455763526;9244.722658320261;9287.48918809472;9199.954000229956;9241.491717955443;9248.352615316997;9277.721668349961;9344.486053267136;3832.2718683450057;4727.479977202505;4739.573438390561;4756.405449318835;4763.833265835679;1984.8761824847563;2033.5627840014467;2060.093558834108;2071.637463443921;2072.813446789796;372.6677129262896;377.6167190301846;884.964601415949;1052.7473406382412;1089.8147314956477;11655.941720291514;11994.820077698841;12008.879911200776;12148.574799881962;12165.878341216652;452.81434611809;854.5513605357237;955.0783493928253;967.9661211857692;1011.6206422591529;10736.785264294671;11281;11317.943410282896;11344.659660210207;11352.375619341015;516817;641361;648552;658568;665954;139350.6064939358;140622;142184;143258.41853953735;146216.07567848795;977.1596427072711;1055.8257887448638;1103.172620534599;1121.243160866412;1126.5550107707409;16176.757348639545;17927.641447171245;19691.507712307306;19741.901290493457;19795.703064454334;51094.74452627713;51752.22371664353;52507.212391814915;52620.73689631528;54078.72960635171;77987;79932.60033699794;81977;82488.17511824805;84186;8210.302124319262;8443.36674749384;8473.322134229124;8541.736439843571;8771.649134034822;9541;10868;12452.377381131088;13354.732905342033;13600.52398166099;10824.458777060958;11767.470463829319;11988.220765650205;12027.398630068286;12079.51681932713;10998.835017474585;11335.093192544742;11379.54481820718;11490.827637585277;11850;9425.481598512148;11883.227590206609;12025.4588543514;12110.636680899586;12160.45277962476;8491.702790402307;8581.613827377907;8634.481931084156;8638.95517768162;8907.153820387053;10298.17614590846;11797.88202117968;11931.926126648643;12016.33910134951;12039.217450865666 09:31:59 INFO - PROCESS | 1680 | |3;mootools.html;1493.8132733408324;1619.5303362025013;1643.030611938956;1942.4561122885589;2031.029619181946;8267.91732082679;8877.156670116337;9019.278457723383;9019.729408117766;9037.141471560191;8831.735047948556;8902.287816974642;9003.5048072356;9017;9025.18773310402;2317.5249073939885;2544.9236522904343;2571.7299683533242;2577.445849142434;2578.600316950876;2344.4842134730416;2563.9743602564017;2564.653771740819;2565.179142674345;2572.5498037843395;2538.66997290352;2556.118139241968;2578.0976658169584;2580.0324878170686;2580.4710034442896;2526.3684078980255;2559.8592077435733;2575.188815523104;2578.4714133602756;2578.8839502222445;2528.115159694111;2557.4373637799654;2565.204786516186;2567.961480577793;2571.3443072016703;8881.378303518757;9162.496062716547;9203.217726493189;9206.171444569954;9210.125038121369;5637;5791.623544469596;5792.768289268424;5793.102069179284;5793.304803423574;3057.755379569629;3588.4796704477967;3686.4286035664513;3725.515682961198;3735.6077611850637;1043.6242952537082;1056.8256237720766;1059.7827445373757;1061.3631820907472;1061.686802393296;12395;12493.250404975639;12649;12684.556040538537;12686;4831.613470922318;4835.20219163834;4841.346418233564;4844.491328410537;4900.5099490051425;9764.707058788248;9764.804703906024;9769.413835169913;9771.657991970247;9782.119609235204;5602.775888964477;5634.52106570946;5648.293963254593;5650.152477128381;5662.2639056922335;4554.476235232991;4683.718976861399;5033.748312584429;5210.7915683373;5257.553107985864;3987.860424885115;4024.1750441159493;4046.0289530513055;4098.7950602469755;4100.91798164035;1252.2611659121162;1278.0159277356383;1280.6670265730977;1285.4022879361141;1291.031726205346;936.3539157981107;957.3537861943161;959.3428501476511;961.1013702188476;1007.7842646649951;6061.272647282354;6077.696115194222;6161.260648722182;6226.5641405102115;6283.277423096311;863.330918538138;956.4309236004566;980.460746589373;988.5452691761726;1043.7495001199657;3921.529416469985;4058.1680755445063;4064.695147863863;4085.59144085595;4105.096878686684;10920.781584368428;11314.660560183207;11401.973822356027;11423.428828558539;11450.94274528622;11172.385518796382;11174.994250517493;11240.775184496264;11249.437528123724;11261.943690281496;8190.566650836199;8532.274756645755;8698.521581312962;8749.387542871938;8755.124487551322;6001.2498437695285;6002.129691194839;6147.047207682816;6164.167837341902;6206.751729930752;9585.520723963635;9715.36850104741;10209.74475638115;10219.38683678952;10221.029002244775;10084.69745907624;10321.793564128526;10323.690289291333;10357.326773759682;10497.212709047004;6705.966470167618;7060.682269297791;7251.347378735939;7625.8856117157175;7773.261540153676;8501.914980850359;8531.189536993976;8596.613152408281;8602;8605.096464871349;7476.5514069156025;7603.429742769204;7668.616569171408;7678.155402905788;7702.499337543042;7390.5196162249285;7406.14829294638;7439.8140046499275;7551.5091519053285;7555.017847679766;7194.4244460442;7295.817604559928;7334.596597187206;7337.743178988923;7344.8898266524975;4653.9301910470695;4752.857414277577;5040.722760248222;5092.898142037065;5144.511271429208;5094.2103973884105;5160.896782064413;5162.612804039787;5231.503007214308;5392.703401312965 09:31:59 INFO - PROCESS | 1680 | |4;prototype.html;325.2486755594577;333.0740541295199;342.55467891740733;343.1301650316448;345.77697385186553;6552.901706474407;6630.701618427171;6702.363275488827;6715.294894036129;6724.798256052314;6378.425941665261;6515.393842463017;6608.405243528082;6624.701888415021;6681.699323530441;4933.235348520967;4933.358663373757;4937.456879743234;4946.307516947631;4957.578605818491;4746.833860814872;4900.852974410774;4932.950670493285;4940.851774446773;4946.307516947631;4804.063207674505;4883;4888.315635810989;4901.1177987962155;4915.188993816034;4862.878428039292;4873.951260487385;4908.092002979461;4909.484504127051;4914.434839993411;3583.62371950944;4171.728837625575;4226.197022565702;4227.682923780729;4238.512571054337;6348.206474190726;6445.548811583144;6456.838579035561;6470.288268290485;6477.319881412431;4703.2239680452685;5660.886782264372;5863.5309175265875;5879.412058794129;5897.823065308047;7047.330503602149;7066.893996590055;7073.221945585981;7078.716851325941;7113.53762005468;4591.724496530219;4771.928421073687;4775.856724298276;4788.209945359012;4793.352897358882;5792.391798861143;6603.943369060928;6845.965770171167;7460.03019607453;7461.664225109829;4854.660173787872;4865.6107511399005;4869.391326084239;4870.659053866196;4875.463698993108;4775.5224477552665;4947.505249475095;4950.752462376939;4952.851414457572;4957.900841983213;4899.726071221508;5753.942460575341;5825.1844741736195;5880.2943646761805;5903.704814759245;7046.788596342118;7086.043384143178;7103.360697537247;7107.147142342848;7126.750563730245;1574.889757716949;1699.3882202407124;1732.480255923218;1734.8525375342986;1736.2100244388773;985.8669079674296;1049.6063976008998;1054.641421916552;1061.867266591676;1081.1242893256488;6609.4798196415095;6815.965920170367;6824.249332573413;6853.246142924374;6866.210385805596;7429.777106686808;7434.405247580181;7448.739294124788;7471.589062601609;7483.775486735407;6788.287229840895;6788.59268443895;6789.219239787389;6795.898061529081;6813.216480104752;3740.4389341598976;3869.5356557213313;3902.70729695274;3914.6672532835028;3926.450296958428;7693.307602315818;7705.190954949761;7720.884186737204;7769.417293702995;7795.610219489003;7594.696212151563;7598.924010759933;7616.885746713804;7625.961870190725;7635.694572217049;6151.323354431009;7728.806779830436;7751.0311211098615;7753.806154846174;7768.53388796674;7180.342008731924;7384.446166537424;7407.518511296748;7412.444066695128;7416.592087435243;7244.565326080452;7251.311125443074;7264;7276.818079548054;7278.452541610394;7738.883916741141;7752.612369381621;7758.3017528422715;7762.68949242024;7769.417293702882;4684.203685373426;4731.053789242096;4738.241881298975;4738.31294462308;4741.193997020584;6994.650267486707;7367.447441441806;7389.778306650809;7406.44451666138;7416.9258307418395;7114;7143.357097861218;7143.571385716874;7151.42788576923;7155.785326440215;7124.465665075244;7127.2516385778745;7133.500654954103;7153.141623005273;7154.177269613853;6969.965150174419;6972.79081627552;6976.930230697831;6984.371406573433;6994.860102798018;5521.365043020104;6846.623435711083;7098.183708873546;7145.035420218204;7176.282371762782;6902.378785909293;6940.444764418935;6942.618156001468;6944.756933507303;6949.895751563832 09:31:59 INFO - PROCESS | 1680 | |5;yui.html;681.9950337280245;692.0760784352889;704.7357241034612;704.7850405626285;738.3207449146786;15381.461648842293;15598.53204403867;15615.375384984602;15623;15625.062496250233;13751.312434378266;13847.584572462816;13875.514356997508;13944.511942082028;14050.929745351285;664.9867002659968;791.2008871040233;942.820864035831;947.8483442649177;950.6150009246231;938.8544775559765;963.0946909904702;963.6675347005307;964.7346979580636;964.8311545479548;998.5207100591721;1004.8944860789585;1004.8995100489929;1005.3063386263491;1006.6577363696342;813.686730608717;997.3517213811008;1001.0640051551823;1001.7645853224486;1022.4172221833558;640.452413186728;758.1660173808822;760.8972788673514;761.5849362097615;761.9390448764085;15300.158491282975;15367.615809604738;15435.30541125652;15438;15441.150736709476;12721.87278127216;13108.672283192902;13124.737505249841;13143.408546615425;13198.538051168163;10125.64560240388;10161.18710503158;10242.027007434282;10368.222383321281;10370.844437333446;3499.580050393969;3577.141486043331;3587.515685382466;3656.104254457675;3694.8152592370275;80296.19703803003;81899.18100818976;81979;82287.7656835148;82348.58825705833;8342.582870856555;9421.764455888657;9756.756081098029;9823.263255255884;10145.69562913099;21765.673514897288;22455.55088898213;22459.101635934705;22470.988805503464;22491.88754056219;7780.844383112306;7781;8021.598920053974;8183.71357002502;8300.792480188044;4241.34259189826;4272.6154646082;4291.871243862689;4302.935455968163;4489.371487991683;3731.365667836474;3790.5830358660523;3795.791231482295;3796.867109651149;3799.6580307772433;688.4561196654587;692.5879101934341;707.098449476921;708.6633848921721;724.8151721311032;507.58885302904764;566.60620868496;601.52479541162;605.1588292273743;606.3451472409787;8024.277814996678;8174.632141553644;8309.916900831035;8358.331333493306;8608;543.1337017457149;543.7553101104519;557.5400294756761;599.7271241585156;621.2296752027454;3779.640934111255;3882.437046628223;3910.589388114264;4012.3179059559943;4086.203190377907;86370;89336.6599501008;90119;90163.54918225367;90350;76799;78334;78408.60795695985;78534.607326963;78929.81605275926;67180.32819671839;69554.65222673955;69799.95300070503;70125.59748804894;71044.64477677578;7641.355666859916;7734.458587898793;7776.339011183886;7855.33229675484;7901.920980790348;48478.272825908345;49077;49219.50780492149;49457.50542494673;51567;62904.37095628985;64433.67783161054;64515.35484645094;65058;65173;11089;11183.664490065312;11235.66293011211;11430.942845285721;12009.279443233434;11459.598914037968;11908.99822074748;12062.215955962834;12184.57353992606;12637.052221083271;11208.495617697386;11682.70793230176;11751.588694395656;11953.342566158944;11954.282743035446;11427.828582571434;11583.884161158281;11611.709707257387;11742;11805.586804461802;11221.775564488828;11455.541778328774;11521.193516453768;11716.472758726048;11723.882761172279;9628.71113866585;9875.456849873326;9893.604255829974;9911.058449447291;10277.84583231267;9674.393675465864;10110.617490086332;10114.747131321776;10165.977171908547;10284.691459256233 09:31:59 INFO - PROCESS | 1680 | __end_tp_report 09:31:59 INFO - PROCESS | 1680 | __start_cc_report 09:31:59 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1077 09:31:59 INFO - PROCESS | 1680 | __end_cc_report 09:31:59 INFO - PROCESS | 1680 | __startTimestamp1468600319267__endTimestamp 09:31:59 INFO - PROCESS | 1680 | 09:31:59 INFO - PROCESS | 1680 | ------- Summary: start ------- 09:31:59 INFO - PROCESS | 1680 | Number of tests: 6 09:31:59 INFO - PROCESS | 1680 | 09:31:59 INFO - PROCESS | 1680 | [#0] dojo.html Cycles:165 Average:5956.58 Median:6154.02 stddev:630.84 (10.3%) stddev-sans-first:603.61 09:31:59 INFO - PROCESS | 1680 | Values: 3539.6 3618.2 3638.7 3664.2 3731.8 6036.0 6083.5 6099.0 6100.8 6104.1 6062.6 6083.3 6109.7 6111.9 6123.1 5301.8 5415.0 5415.3 5441.7 5492.9 5437.4 5473.4 5483.6 5491.9 5495.8 5353.6 5393.2 5419.5 5424.9 5480.3 5463.6 5470.8 5493.4 5494.7 5533.3 5318.7 5325.9 5353.8 5406.4 5436.2 6003.9 6023.3 6064.1 6075.6 6088.8 6333.5 6371.0 6383.2 6391.0 6399.5 6328.5 6382.5 6460.7 6467.2 6469.9 6127.3 6128.5 6144.4 6151.1 6151.3 6557.8 6565.5 6575.7 6585.7 6591.7 6161.8 6230.3 6270.6 6298.7 6316.0 6431.3 6439.5 6442.5 6447.2 6457.4 6241.3 6318.0 6322.8 6323.7 6331.1 6161.1 6181.0 6197.5 6251.7 6311.4 5035.7 5089.2 5101.1 5103.4 5104.7 4603.8 4618.2 4632.1 4633.0 4654.9 6069.2 6142.8 6150.1 6154.0 6163.5 6320.7 6372.8 6455.4 6471.3 6472.0 6120.1 6141.0 6148.8 6149.0 6175.3 5592.9 5655.8 5678.3 5679.1 5686.6 6490.8 6591.3 6599.0 6601.2 6704.5 6327.0 6382.9 6389.2 6394.8 6397.3 6639.6 6683.5 6684.1 6693.1 6694.2 6248.4 6316.7 6353.5 6374.1 6378.8 6194.4 6212.0 6271.6 6280.9 6283.4 5257.5 6270.3 6312.3 6313.4 6315.6 5186.4 6109.8 6202.8 6281.8 6295.5 6140.8 6143.1 6146.8 6152.8 6155.3 5195.8 6270.7 6283.0 6285.7 6288.8 6120.7 6140.9 6154.1 6159.9 6210.5 09:31:59 INFO - PROCESS | 1680 | 09:31:59 INFO - PROCESS | 1680 | [#1] ext.html Cycles:180 Average:62397.61 Median:19123.60 stddev:135127.37 (706.6%) stddev-sans-first:135426.61 09:31:59 INFO - PROCESS | 1680 | Values: 534.4 537.1 563.9 565.7 591.9 31803.8 32304.9 32323.2 38252.0 39285.0 42079.6 42512.6 42513.8 42553.4 42723.9 9612.5 9840.1 9841.9 9845.0 9846.8 9184.0 9660.8 11873.5 12204.6 12239.9 11849.5 11947.8 11959.5 11960.9 11962.8 11787.9 11978.2 11978.3 11985.3 11992.8 11745.1 11888.6 11895.0 11898.9 11900.5 40851.6 41407.6 41494.8 41500.5 41526.8 3285.6 3356.9 3427.0 3510.2 3994.1 25595.9 27087.3 29259.0 29464.0 29743.6 2649.6 2674.4 2680.7 2733.2 2753.9 391443.0 443929.0 452140.0 456854.0 457012.7 38671.6 39374.8 39423.0 72259.6 73063.6 38909.8 38939.8 39172.0 39209.0 39258.4 16180.8 16338.0 16352.3 16355.1 16426.3 12897.5 12933.9 13556.0 13627.8 13719.7 7450.6 7692.9 8727.9 8799.8 8859.2 3622.5 3652.5 3657.8 3679.2 3715.5 798.2 1026.3 1102.9 1158.8 1198.7 29306.3 29381.0 29834.1 30024.5 30208.0 1101.3 1156.2 1161.5 1198.6 1285.5 13754.4 15473.5 15632.5 15758.9 16056.2 625930.9 629971.0 635408.0 636026.0 636896.0 61665.0 62076.0 62126.0 62188.0 62308.4 1336.2 1361.9 1442.1 1586.0 1691.5 28145.4 29732.9 29745.6 29816.0 30331.4 46024.1 46464.5 46466.0 46530.3 46575.3 357290.0 361764.2 362462.2 362776.0 578587.0 28118.0 28306.0 28620.1 28624.6 28948.0 43273.0 43336.1 43339.6 43354.0 43737.8 34737.0 36013.0 36214.6 36222.8 36668.0 30365.8 34573.5 35366.6 36050.5 36158.5 36513.6 36904.4 37219.0 37795.8 37842.8 32536.7 33371.8 33585.0 33615.0 33633.5 24563.9 25159.5 25418.0 25478.9 25635.9 09:31:59 INFO - PROCESS | 1680 | 09:31:59 INFO - PROCESS | 1680 | [#2] jquery.html Cycles:180 Average:36140.93 Median:12711.31 stddev:105090.03 (826.7%) stddev-sans-first:105350.47 09:31:59 INFO - PROCESS | 1680 | Values: 347.9 356.2 408.8 418.2 423.7 16263.9 19195.0 20629.7 21301.5 21342.1 19232.5 21490.8 21617.6 23064.4 23103.8 5748.7 5803.6 6077.3 6080.1 6080.2 5843.3 5959.2 5975.2 5988.1 6184.8 6154.4 6177.6 6182.1 6185.9 6186.4 5849.4 6144.7 6146.2 6149.7 6158.7 5927.9 5940.0 5944.0 5946.7 5953.3 14084.0 24741.6 24787.8 25983.1 26087.3 2131.9 2253.1 2286.0 2318.8 2417.0 9093.3 9723.8 10273.4 10972.9 14196.0 1781.4 1832.8 1837.6 1889.5 1909.6 109656.9 112688.0 113629.0 122509.5 127369.0 9833.5 11776.5 11797.2 11961.5 12112.9 14182.1 14745.6 14868.0 15554.5 15558.6 9071.2 9167.1 9221.8 9244.7 9287.5 9200.0 9241.5 9248.4 9277.7 9344.5 3832.3 4727.5 4739.6 4756.4 4763.8 1984.9 2033.6 2060.1 2071.6 2072.8 372.7 377.6 885.0 1052.7 1089.8 11655.9 11994.8 12008.9 12148.6 12165.9 452.8 854.6 955.1 968.0 1011.6 10736.8 11281.0 11317.9 11344.7 11352.4 516817.0 641361.0 648552.0 658568.0 665954.0 139350.6 140622.0 142184.0 143258.4 146216.1 977.2 1055.8 1103.2 1121.2 1126.6 16176.8 17927.6 19691.5 19741.9 19795.7 51094.7 51752.2 52507.2 52620.7 54078.7 77987.0 79932.6 81977.0 82488.2 84186.0 8210.3 8443.4 8473.3 8541.7 8771.6 9541.0 10868.0 12452.4 13354.7 13600.5 10824.5 11767.5 11988.2 12027.4 12079.5 10998.8 11335.1 11379.5 11490.8 11850.0 9425.5 11883.2 12025.5 12110.6 12160.5 8491.7 8581.6 8634.5 8639.0 8907.2 10298.2 11797.9 11931.9 12016.3 12039.2 09:31:59 INFO - PROCESS | 1680 | 09:31:59 INFO - PROCESS | 1680 | [#3] mootools.html Cycles:180 Average:5915.41 Median:5066.81 stddev:3297.10 (65.1%) stddev-sans-first:3289.60 09:31:59 INFO - PROCESS | 1680 | Values: 1493.8 1619.5 1643.0 1942.5 2031.0 8267.9 8877.2 9019.3 9019.7 9037.1 8831.7 8902.3 9003.5 9017.0 9025.2 2317.5 2544.9 2571.7 2577.4 2578.6 2344.5 2564.0 2564.7 2565.2 2572.5 2538.7 2556.1 2578.1 2580.0 2580.5 2526.4 2559.9 2575.2 2578.5 2578.9 2528.1 2557.4 2565.2 2568.0 2571.3 8881.4 9162.5 9203.2 9206.2 9210.1 5637.0 5791.6 5792.8 5793.1 5793.3 3057.8 3588.5 3686.4 3725.5 3735.6 1043.6 1056.8 1059.8 1061.4 1061.7 12395.0 12493.3 12649.0 12684.6 12686.0 4831.6 4835.2 4841.3 4844.5 4900.5 9764.7 9764.8 9769.4 9771.7 9782.1 5602.8 5634.5 5648.3 5650.2 5662.3 4554.5 4683.7 5033.7 5210.8 5257.6 3987.9 4024.2 4046.0 4098.8 4100.9 1252.3 1278.0 1280.7 1285.4 1291.0 936.4 957.4 959.3 961.1 1007.8 6061.3 6077.7 6161.3 6226.6 6283.3 863.3 956.4 980.5 988.5 1043.7 3921.5 4058.2 4064.7 4085.6 4105.1 10920.8 11314.7 11402.0 11423.4 11450.9 11172.4 11175.0 11240.8 11249.4 11261.9 8190.6 8532.3 8698.5 8749.4 8755.1 6001.2 6002.1 6147.0 6164.2 6206.8 9585.5 9715.4 10209.7 10219.4 10221.0 10084.7 10321.8 10323.7 10357.3 10497.2 6706.0 7060.7 7251.3 7625.9 7773.3 8501.9 8531.2 8596.6 8602.0 8605.1 7476.6 7603.4 7668.6 7678.2 7702.5 7390.5 7406.1 7439.8 7551.5 7555.0 7194.4 7295.8 7334.6 7337.7 7344.9 4653.9 4752.9 5040.7 5092.9 5144.5 5094.2 5160.9 5162.6 5231.5 5392.7 09:31:59 INFO - PROCESS | 1680 | 09:31:59 INFO - PROCESS | 1680 | [#4] prototype.html Cycles:180 Average:5841.62 Median:6692.03 stddev:1854.93 (27.7%) stddev-sans-first:1813.34 09:31:59 INFO - PROCESS | 1680 | Values: 325.2 333.1 342.6 343.1 345.8 6552.9 6630.7 6702.4 6715.3 6724.8 6378.4 6515.4 6608.4 6624.7 6681.7 4933.2 4933.4 4937.5 4946.3 4957.6 4746.8 4900.9 4933.0 4940.9 4946.3 4804.1 4883.0 4888.3 4901.1 4915.2 4862.9 4874.0 4908.1 4909.5 4914.4 3583.6 4171.7 4226.2 4227.7 4238.5 6348.2 6445.5 6456.8 6470.3 6477.3 4703.2 5660.9 5863.5 5879.4 5897.8 7047.3 7066.9 7073.2 7078.7 7113.5 4591.7 4771.9 4775.9 4788.2 4793.4 5792.4 6603.9 6846.0 7460.0 7461.7 4854.7 4865.6 4869.4 4870.7 4875.5 4775.5 4947.5 4950.8 4952.9 4957.9 4899.7 5753.9 5825.2 5880.3 5903.7 7046.8 7086.0 7103.4 7107.1 7126.8 1574.9 1699.4 1732.5 1734.9 1736.2 985.9 1049.6 1054.6 1061.9 1081.1 6609.5 6816.0 6824.2 6853.2 6866.2 7429.8 7434.4 7448.7 7471.6 7483.8 6788.3 6788.6 6789.2 6795.9 6813.2 3740.4 3869.5 3902.7 3914.7 3926.5 7693.3 7705.2 7720.9 7769.4 7795.6 7594.7 7598.9 7616.9 7626.0 7635.7 6151.3 7728.8 7751.0 7753.8 7768.5 7180.3 7384.4 7407.5 7412.4 7416.6 7244.6 7251.3 7264.0 7276.8 7278.5 7738.9 7752.6 7758.3 7762.7 7769.4 4684.2 4731.1 4738.2 4738.3 4741.2 6994.7 7367.4 7389.8 7406.4 7416.9 7114.0 7143.4 7143.6 7151.4 7155.8 7124.5 7127.3 7133.5 7153.1 7154.2 6970.0 6972.8 6976.9 6984.4 6994.9 5521.4 6846.6 7098.2 7145.0 7176.3 6902.4 6940.4 6942.6 6944.8 6949.9 09:31:59 INFO - PROCESS | 1680 | 09:31:59 INFO - PROCESS | 1680 | [#5] yui.html Cycles:180 Average:18273.98 Median:49338.51 stddev:25277.68 (51.2%) stddev-sans-first:25314.07 09:31:59 INFO - PROCESS | 1680 | Values: 682.0 692.1 704.7 704.8 738.3 15381.5 15598.5 15615.4 15623.0 15625.1 13751.3 13847.6 13875.5 13944.5 14050.9 665.0 791.2 942.8 947.8 950.6 938.9 963.1 963.7 964.7 964.8 998.5 1004.9 1004.9 1005.3 1006.7 813.7 997.4 1001.1 1001.8 1022.4 640.5 758.2 760.9 761.6 761.9 15300.2 15367.6 15435.3 15438.0 15441.2 12721.9 13108.7 13124.7 13143.4 13198.5 10125.6 10161.2 10242.0 10368.2 10370.8 3499.6 3577.1 3587.5 3656.1 3694.8 80296.2 81899.2 81979.0 82287.8 82348.6 8342.6 9421.8 9756.8 9823.3 10145.7 21765.7 22455.6 22459.1 22471.0 22491.9 7780.8 7781.0 8021.6 8183.7 8300.8 4241.3 4272.6 4291.9 4302.9 4489.4 3731.4 3790.6 3795.8 3796.9 3799.7 688.5 692.6 707.1 708.7 724.8 507.6 566.6 601.5 605.2 606.3 8024.3 8174.6 8309.9 8358.3 8608.0 543.1 543.8 557.5 599.7 621.2 3779.6 3882.4 3910.6 4012.3 4086.2 86370.0 89336.7 90119.0 90163.5 90350.0 76799.0 78334.0 78408.6 78534.6 78929.8 67180.3 69554.7 69800.0 70125.6 71044.6 7641.4 7734.5 7776.3 7855.3 7901.9 48478.3 49077.0 49219.5 49457.5 51567.0 62904.4 64433.7 64515.4 65058.0 65173.0 11089.0 11183.7 11235.7 11430.9 12009.3 11459.6 11909.0 12062.2 12184.6 12637.1 11208.5 11682.7 11751.6 11953.3 11954.3 11427.8 11583.9 11611.7 11742.0 11805.6 11221.8 11455.5 11521.2 11716.5 11723.9 9628.7 9875.5 9893.6 9911.1 10277.8 9674.4 10110.6 10114.7 10166.0 10284.7 09:31:59 INFO - PROCESS | 1680 | -------- Summary: end -------- 09:31:59 INFO - PROCESS | 1680 | 09:31:59 INFO - TEST-INFO | 1680: exit 0 09:31:59 INFO - TEST-OK | dromaeo_css | took 1113647ms 09:31:59 INFO - TEST-START | kraken 09:31:59 INFO - operating with platform_type : mac_ 09:31:59 INFO - Initialising browser for kraken test... 09:31:59 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmp5ON9Lm/profile http://localhost:49291/getInfo.html) 09:32:01 INFO - PROCESS | 1686 | __metrics Screen width/height:1600/1200 09:32:01 INFO - PROCESS | 1686 | colorDepth:24 09:32:01 INFO - PROCESS | 1686 | Browser inner width/height: 1024/689 09:32:01 INFO - PROCESS | 1686 | __metrics 09:32:01 INFO - PROCESS | 1686 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:32:01 ERROR - PROCESS | 1686 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:32:02 INFO - TEST-INFO | 1686: exit 0 09:32:02 INFO - Browser initialized. 09:32:02 INFO - Running cycle 1/1 for kraken test... 09:32:02 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmp5ON9Lm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:14 INFO - PROCESS | 1689 | RSS: Main: 160436224 09:32:14 INFO - PROCESS | 1689 | 09:32:17 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49291/tests/kraken/driver.html?testName=audio-beat-detection) 09:32:17 INFO - PROCESS | 1689 | RSS: Main: 223899648 09:32:17 INFO - PROCESS | 1689 | 09:32:20 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49291/tests/kraken/driver.html?testName=audio-dft) 09:32:21 INFO - PROCESS | 1689 | RSS: Main: 241938432 09:32:21 INFO - PROCESS | 1689 | 09:32:24 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49291/tests/kraken/driver.html?testName=audio-fft) 09:32:25 INFO - PROCESS | 1689 | RSS: Main: 325832704 09:32:25 INFO - PROCESS | 1689 | 09:32:27 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49291/tests/kraken/driver.html?testName=audio-oscillator) 09:32:27 INFO - PROCESS | 1689 | RSS: Main: 230461440 09:32:27 INFO - PROCESS | 1689 | 09:32:29 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49291/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:32:30 INFO - PROCESS | 1689 | RSS: Main: 231079936 09:32:30 INFO - PROCESS | 1689 | 09:32:33 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49291/tests/kraken/driver.html?testName=imaging-darkroom) 09:32:33 INFO - PROCESS | 1689 | RSS: Main: 270606336 09:32:33 INFO - PROCESS | 1689 | 09:32:36 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49291/tests/kraken/driver.html?testName=imaging-desaturate) 09:32:37 INFO - PROCESS | 1689 | RSS: Main: 270610432 09:32:37 INFO - PROCESS | 1689 | 09:32:39 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49291/tests/kraken/driver.html?testName=json-parse-financial) 09:32:40 INFO - PROCESS | 1689 | RSS: Main: 268378112 09:32:40 INFO - PROCESS | 1689 | 09:32:42 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49291/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:32:42 INFO - PROCESS | 1689 | RSS: Main: 259891200 09:32:42 INFO - PROCESS | 1689 | 09:32:44 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49291/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:32:45 INFO - PROCESS | 1689 | RSS: Main: 267468800 09:32:45 INFO - PROCESS | 1689 | 09:32:47 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49291/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:32:48 INFO - PROCESS | 1689 | RSS: Main: 271454208 09:32:48 INFO - PROCESS | 1689 | 09:32:50 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49291/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:32:51 INFO - PROCESS | 1689 | RSS: Main: 278769664 09:32:51 INFO - PROCESS | 1689 | 09:32:54 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49291/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:32:54 INFO - PROCESS | 1689 | RSS: Main: 275644416 09:32:54 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49291/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49291/tests/kraken/driver.html?testName=ai-astar) 09:32:56 INFO - PROCESS | 1689 | RSS: Main: 336728064 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | __start_tp_report 09:32:56 INFO - PROCESS | 1689 | _x_x_mozilla_page_load 09:32:56 INFO - PROCESS | 1689 | _x_x_mozilla_page_load_details 09:32:56 INFO - PROCESS | 1689 | |i|pagename|runs| 09:32:56 INFO - PROCESS | 1689 | |0;ai-astar;112;113;119;112;113;112;113;113;112;134 09:32:56 INFO - PROCESS | 1689 | |1;audio-beat-detection;150;119;141;181;146;170;125;139;207;142 09:32:56 INFO - PROCESS | 1689 | |2;audio-dft;184;186;186;183;185;184;184;186;184;185 09:32:56 INFO - PROCESS | 1689 | |3;audio-fft;69;108;105;186;57;55;56;64;131;56 09:32:56 INFO - PROCESS | 1689 | |4;audio-oscillator;91;91;141;78;78;82;137;100;78;122 09:32:56 INFO - PROCESS | 1689 | |5;imaging-gaussian-blur;89;86;90;86;88;85;89;87;89;85 09:32:56 INFO - PROCESS | 1689 | |6;imaging-darkroom;93;94;94;95;93;94;95;95;94;94 09:32:56 INFO - PROCESS | 1689 | |7;imaging-desaturate;86;83;85;92;102;84;85;85;84;85 09:32:56 INFO - PROCESS | 1689 | |8;json-parse-financial;62;61;58;63;88;94;92;98;61;58 09:32:56 INFO - PROCESS | 1689 | |9;json-stringify-tinderbox;61;62;64;75;75;70;73;96;94;96 09:32:56 INFO - PROCESS | 1689 | |10;stanford-crypto-aes;80;67;75;95;103;103;105;76;81;82 09:32:56 INFO - PROCESS | 1689 | |11;stanford-crypto-ccm;126;126;126;126;124;141;137;131;131;138 09:32:56 INFO - PROCESS | 1689 | |12;stanford-crypto-pbkdf2;162;169;167;162;165;162;165;163;162;160 09:32:56 INFO - PROCESS | 1689 | |13;stanford-crypto-sha256-iterative;65;69;67;66;66;66;67;65;66;64 09:32:56 INFO - PROCESS | 1689 | __end_tp_report 09:32:56 INFO - PROCESS | 1689 | __start_cc_report 09:32:56 INFO - PROCESS | 1689 | _x_x_mozilla_cycle_collect,1897 09:32:56 INFO - PROCESS | 1689 | __end_cc_report 09:32:56 INFO - PROCESS | 1689 | __startTimestamp1468600376923__endTimestamp 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | ------- Summary: start ------- 09:32:56 INFO - PROCESS | 1689 | Number of tests: 14 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#0] ai-astar Cycles:10 Average:115.30 Median:113.00 stddev:6.90 (6.1%) stddev-sans-first:7.21 09:32:56 INFO - PROCESS | 1689 | Values: 112.0 113.0 119.0 112.0 113.0 112.0 113.0 113.0 112.0 134.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#1] audio-beat-detection Cycles:10 Average:152.00 Median:148.00 stddev:26.75 (18.1%) stddev-sans-first:28.36 09:32:56 INFO - PROCESS | 1689 | Values: 150.0 119.0 141.0 181.0 146.0 170.0 125.0 139.0 207.0 142.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.06 (0.6%) stddev-sans-first:1.09 09:32:56 INFO - PROCESS | 1689 | Values: 184.0 186.0 186.0 183.0 185.0 184.0 184.0 186.0 184.0 185.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#3] audio-fft Cycles:10 Average:88.70 Median:56.00 stddev:43.68 (78.0%) stddev-sans-first:45.75 09:32:56 INFO - PROCESS | 1689 | Values: 69.0 108.0 105.0 186.0 57.0 55.0 56.0 64.0 131.0 56.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#4] audio-oscillator Cycles:10 Average:99.80 Median:78.00 stddev:24.66 (31.6%) stddev-sans-first:25.95 09:32:56 INFO - PROCESS | 1689 | Values: 91.0 91.0 141.0 78.0 78.0 82.0 137.0 100.0 78.0 122.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.50 stddev:1.84 (2.1%) stddev-sans-first:1.86 09:32:56 INFO - PROCESS | 1689 | Values: 89.0 86.0 90.0 86.0 88.0 85.0 89.0 87.0 89.0 85.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 09:32:56 INFO - PROCESS | 1689 | Values: 93.0 94.0 94.0 95.0 93.0 94.0 95.0 95.0 94.0 94.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#7] imaging-desaturate Cycles:10 Average:87.10 Median:85.00 stddev:5.78 (6.8%) stddev-sans-first:6.12 09:32:56 INFO - PROCESS | 1689 | Values: 86.0 83.0 85.0 92.0 102.0 84.0 85.0 85.0 84.0 85.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#8] json-parse-financial Cycles:10 Average:73.50 Median:75.50 stddev:17.02 (22.5%) stddev-sans-first:17.54 09:32:56 INFO - PROCESS | 1689 | Values: 62.0 61.0 58.0 63.0 88.0 94.0 92.0 98.0 61.0 58.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#9] json-stringify-tinderbox Cycles:10 Average:76.60 Median:75.00 stddev:13.87 (18.5%) stddev-sans-first:13.52 09:32:56 INFO - PROCESS | 1689 | Values: 61.0 62.0 64.0 75.0 75.0 70.0 73.0 96.0 94.0 96.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:78.00 stddev:13.64 (17.5%) stddev-sans-first:14.25 09:32:56 INFO - PROCESS | 1689 | Values: 80.0 67.0 75.0 95.0 103.0 103.0 105.0 76.0 81.0 82.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#11] stanford-crypto-ccm Cycles:10 Average:130.60 Median:131.00 stddev:6.08 (4.6%) stddev-sans-first:6.21 09:32:56 INFO - PROCESS | 1689 | Values: 126.0 126.0 126.0 126.0 124.0 141.0 137.0 131.0 131.0 138.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.70 Median:164.00 stddev:2.75 (1.7%) stddev-sans-first:2.85 09:32:56 INFO - PROCESS | 1689 | Values: 162.0 169.0 167.0 162.0 165.0 162.0 165.0 163.0 162.0 160.0 09:32:56 INFO - PROCESS | 1689 | 09:32:56 INFO - PROCESS | 1689 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:1.37 (2.1%) stddev-sans-first:1.39 09:32:56 INFO - PROCESS | 1689 | Values: 65.0 69.0 67.0 66.0 66.0 66.0 67.0 65.0 66.0 64.0 09:32:56 INFO - PROCESS | 1689 | -------- Summary: end -------- 09:32:56 INFO - PROCESS | 1689 | 09:32:57 INFO - TEST-INFO | 1689: exit 0 09:32:57 INFO - TEST-OK | kraken | took 57905ms 09:32:57 INFO - SUITE-END | took 1171s 09:32:58 INFO - Completed test suite (00:19:31) 09:32:58 INFO - javascript benchmark 09:32:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3539.6283390244025, 3618.240169564392, 3638.6725194732467, 3664.1755604988866, 3731.832067556961, 6036.034234522477, 6083.452489275965, 6098.9695051524795, 6100.847478813032, 6104.084387341901, 6062.605930614518, 6083.300420451649, 6109.725062372193, 6111.908321375183, 6123.050927106306, 5301.761420736057, 5415.0, 5415.268938693275, 5441.673499590037, 5492.917606235909, 5437.374701909292, 5473.425290344516, 5483.561315094783, 5491.890162196753, 5495.835124946258, 5353.5984801139875, 5393.19102134679, 5419.485148910847, 5424.891502169954, 5480.342358916935, 5463.590230732711, 5470.835874923759, 5493.368262649807, 5494.670319780786, 5533.3083364579425, 5318.73406329682, 5325.920111198335, 5353.839384818461, 5406.405295417501, 5436.238926550286, 6003.909941350883, 6023.307319658252, 6064.06007068905, 6075.574709770318, 6088.817335479943, 6333.493320534346, 6370.9681451592905, 6383.202099737533, 6390.9774436090465, 6399.488040956712, 6328.462080723144, 6382.521310901655, 6460.741570337181, 6467.191601049869, 6469.870602587969, 6127.325994140641, 6128.479079278267, 6144.38556144382, 6151.108089327023, 6151.292601350857, 6557.803265901935, 6565.540412171198, 6575.704093315812, 6585.703643336062, 6591.736330546724, 6161.7535298588455, 6230.252369715663, 6270.59241149333, 6298.716557754912, 6316.0, 6431.292557818636, 6439.452646525004, 6442.452391546771, 6447.161868957006, 6457.3865482779065, 6241.314964068791, 6318.0, 6322.778702745474, 6323.747050117944, 6331.05034244867, 6161.075838624242, 6181.011038233861, 6197.5351848611535, 6251.687415629201, 6311.431971122621, 5035.697858128524, 5089.236614507853, 5101.081805275012, 5103.362079740033, 5104.668196567211, 4603.838865639686, 4618.237990731492, 4632.096741135513, 4632.953670463319, 4654.930176047362, 6069.180660610849, 6142.815715528542, 6150.077488376779, 6154.015357542861, 6163.537734669918, 6320.683965801783, 6372.808815735535, 6455.419012288822, 6471.320511346335, 6471.9676401618635, 6120.142398366865, 6141.0, 6148.78479253224, 6149.046897730859, 6175.320714721377, 5592.86017849557, 5655.80204692842, 5678.346990096109, 5679.091345384802, 5686.5735069869925, 6490.805275841638, 6591.340865913467, 6598.967005165136, 6601.207855057424, 6704.497162712816, 6327.0, 6382.904256436064, 6389.201349831271, 6394.776182833764, 6397.328280530477, 6639.634820084942, 6683.532152749261, 6684.097646817811, 6693.1298931138645, 6694.230163531256, 6248.4064013918605, 6316.7473301069285, 6353.523485738496, 6374.107624932606, 6378.840528986627, 6194.38056194377, 6211.9689401554515, 6271.592346497463, 6280.874382512233, 6283.37166283368, 5257.4742525747115, 6270.278917924497, 6312.337204593451, 6313.43179113882, 6315.621062736251, 5186.429492755869, 6109.755609775737, 6202.782902598387, 6281.811545653638, 6295.496360291074, 6140.785072522441, 6143.078538219303, 6146.78486252979, 6152.769446110885, 6155.322914479314, 5195.818146364859, 6270.717817698123, 6283.0, 6285.654289013965, 6288.842778930563, 6120.660465672209, 6140.9030504681905, 6154.076888466766, 6159.876802463836, 6210.534209934182], "value": 5919.094666098883, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.369444056014, 537.1084000559073, 563.9125935480001, 565.7312776431193, 591.8757061017186, 31803.840980795092, 32304.8693295735, 32323.191920202007, 38252.0, 39285.0, 42079.57920420795, 42512.57487425125, 42513.787431062876, 42553.36169957445, 42723.931901702475, 9612.471314077722, 9840.065193806577, 9841.852372214422, 9844.950775246114, 9846.753831154207, 9184.0, 9660.758481037996, 11873.465694043789, 12204.572839950604, 12239.938800305987, 11849.466773995147, 11947.7610447791, 11959.461824217931, 11960.940195299056, 11962.82055769164, 11787.941060294686, 11978.161528692995, 11978.341191234522, 11985.28088314704, 11992.820107698304, 11745.11911606633, 11888.643340699793, 11895.04839612829, 11898.881011189864, 11900.464479098462, 40851.591484085075, 41407.58592414067, 41494.79252603718, 41500.54748083833, 41526.792366037975, 3285.6385797562248, 3356.916077098092, 3427.0404286799735, 3510.1926556891804, 3994.1013272013856, 25595.872020639963, 27087.322816929536, 29259.0, 29464.0, 29743.553846692317, 2649.5893136563864, 2674.358154043035, 2680.6917204521533, 2733.1937078561873, 2753.8898444062215, 391443.0, 443929.0, 452140.0, 456854.0, 457012.7149364265, 38671.613283867366, 39374.80312598419, 39423.0, 72259.55480890458, 73063.63468182732, 38909.80545097313, 38939.80530097331, 39172.0, 39209.0, 39258.41112383317, 16180.75728864068, 16338.019718816671, 16352.345906163859, 16355.100469474055, 16426.342946282253, 12897.54858579964, 12933.935330323287, 13556.0, 13627.795583066261, 13719.657008574866, 7450.590217537979, 7692.923070769332, 8727.869081963776, 8799.780005499913, 8859.158379953895, 3622.5109610202285, 3652.5251717276583, 3657.762245454037, 3679.2089700714473, 3715.535558055243, 798.1738900238225, 1026.2662196529368, 1102.913630074376, 1158.8261760735788, 1198.7183011992174, 29306.26734331616, 29381.0, 29834.10497685073, 30024.54963175554, 30208.0, 1101.2896681640298, 1156.2195518025298, 1161.4773351991637, 1198.6335577441723, 1285.460106755165, 13754.449822007007, 15473.458428954933, 15632.4528641497, 15758.921205393899, 16056.197190140447, 625930.8703456454, 629971.0, 635408.0, 636026.0, 636896.0, 61665.0, 62076.0, 62126.0, 62188.0, 62308.376916231166, 1336.2116351352554, 1361.9240799768072, 1442.0698649371097, 1586.0483709774044, 1691.4840973502953, 28145.43709125847, 29732.851335743184, 29745.553816692332, 29815.956441524442, 30331.39337213199, 46024.07951840878, 46464.53535464602, 46466.0, 46530.30204546867, 46575.30137048015, 357290.0, 361764.19117904245, 362462.1876890599, 362776.0, 578587.0, 28118.0, 28306.0, 28620.14139575816, 28624.57063144013, 28948.0, 43273.0, 43336.133277334906, 43339.56660433482, 43354.0, 43737.78131109324, 34737.0, 36013.0, 36214.55141794299, 36222.8188859054, 36668.0, 30365.848170759004, 34573.48139777956, 35366.64633353634, 36050.45924311085, 36158.4576231362, 36513.63486365102, 36904.44643330298, 37219.0, 37795.81102094582, 37842.81078594589, 32536.674633253366, 33371.83198588135, 33585.0, 33614.99155025353, 33633.495497568045, 24563.87718061398, 25159.49681006406, 25418.0, 25478.872605637596, 25635.871820640776], "value": 18898.92032992657, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.8782426150847, 356.1879204996286, 408.84668249406474, 418.20332267031296, 423.6695377605467, 16263.91868040661, 19195.0, 20629.690554641693, 21301.467463313424, 21342.146314147434, 19232.51918702033, 21490.785092149075, 21617.56764864702, 23064.42338941527, 23103.84480775963, 5748.741306641211, 5803.593748437611, 6077.3314935357075, 6080.118382834508, 6080.179175811275, 5843.328017278003, 5959.195508606348, 5975.163477113208, 5988.131720900488, 6184.752609895599, 6154.415330543591, 6177.629342239457, 6182.072689096626, 6185.907211391833, 6186.412290832363, 5849.415058494138, 6144.662043587601, 6146.231721034871, 6149.692515374213, 6158.6612736299485, 5927.911081333784, 5939.970300148515, 5944.019236825919, 5946.7026648667825, 5953.2856057272975, 14084.0, 24741.6288755667, 24787.752122478723, 25983.090591829194, 26087.347816304555, 2131.850770446054, 2253.143805353955, 2285.954280914389, 2318.7681231876845, 2416.9758302416926, 9093.31800114994, 9723.756906077404, 10273.434961077137, 10972.945135274273, 14196.006279560435, 1781.403229917979, 1832.7709036370454, 1837.595728939631, 1889.4992826900882, 1909.5703466719876, 109656.90343096547, 112688.0, 113629.0, 122509.54980900421, 127369.0, 9833.508324583885, 11776.52893884252, 11797.174197806244, 11961.521539138512, 12112.878871211351, 14182.078164919247, 14745.631359216106, 14867.959242853112, 15554.533363999099, 15558.611034724223, 9071.228945539571, 9167.12912273333, 9221.769455763526, 9244.722658320261, 9287.48918809472, 9199.954000229956, 9241.491717955443, 9248.352615316997, 9277.721668349961, 9344.486053267136, 3832.2718683450057, 4727.479977202505, 4739.573438390561, 4756.405449318835, 4763.833265835679, 1984.8761824847563, 2033.5627840014467, 2060.093558834108, 2071.637463443921, 2072.813446789796, 372.6677129262896, 377.6167190301846, 884.964601415949, 1052.7473406382412, 1089.8147314956477, 11655.941720291514, 11994.820077698841, 12008.879911200776, 12148.574799881962, 12165.878341216652, 452.81434611809, 854.5513605357237, 955.0783493928253, 967.9661211857692, 1011.6206422591529, 10736.785264294671, 11281.0, 11317.943410282896, 11344.659660210207, 11352.375619341015, 516817.0, 641361.0, 648552.0, 658568.0, 665954.0, 139350.6064939358, 140622.0, 142184.0, 143258.41853953735, 146216.07567848795, 977.1596427072711, 1055.8257887448638, 1103.172620534599, 1121.243160866412, 1126.5550107707409, 16176.757348639545, 17927.641447171245, 19691.507712307306, 19741.901290493457, 19795.703064454334, 51094.74452627713, 51752.22371664353, 52507.212391814915, 52620.73689631528, 54078.72960635171, 77987.0, 79932.60033699794, 81977.0, 82488.17511824805, 84186.0, 8210.302124319262, 8443.36674749384, 8473.322134229124, 8541.736439843571, 8771.649134034822, 9541.0, 10868.0, 12452.377381131088, 13354.732905342033, 13600.52398166099, 10824.458777060958, 11767.470463829319, 11988.220765650205, 12027.398630068286, 12079.51681932713, 10998.835017474585, 11335.093192544742, 11379.54481820718, 11490.827637585277, 11850.0, 9425.481598512148, 11883.227590206609, 12025.4588543514, 12110.636680899586, 12160.45277962476, 8491.702790402307, 8581.613827377907, 8634.481931084156, 8638.95517768162, 8907.153820387053, 10298.17614590846, 11797.88202117968, 11931.926126648643, 12016.33910134951, 12039.217450865666], "value": 9607.89215025408, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1493.8132733408324, 1619.5303362025013, 1643.030611938956, 1942.4561122885589, 2031.029619181946, 8267.91732082679, 8877.156670116337, 9019.278457723383, 9019.729408117766, 9037.141471560191, 8831.735047948556, 8902.287816974642, 9003.5048072356, 9017.0, 9025.18773310402, 2317.5249073939885, 2544.9236522904343, 2571.7299683533242, 2577.445849142434, 2578.600316950876, 2344.4842134730416, 2563.9743602564017, 2564.653771740819, 2565.179142674345, 2572.5498037843395, 2538.66997290352, 2556.118139241968, 2578.0976658169584, 2580.0324878170686, 2580.4710034442896, 2526.3684078980255, 2559.8592077435733, 2575.188815523104, 2578.4714133602756, 2578.8839502222445, 2528.115159694111, 2557.4373637799654, 2565.204786516186, 2567.961480577793, 2571.3443072016703, 8881.378303518757, 9162.496062716547, 9203.217726493189, 9206.171444569954, 9210.125038121369, 5637.0, 5791.623544469596, 5792.768289268424, 5793.102069179284, 5793.304803423574, 3057.755379569629, 3588.4796704477967, 3686.4286035664513, 3725.515682961198, 3735.6077611850637, 1043.6242952537082, 1056.8256237720766, 1059.7827445373757, 1061.3631820907472, 1061.686802393296, 12395.0, 12493.250404975639, 12649.0, 12684.556040538537, 12686.0, 4831.613470922318, 4835.20219163834, 4841.346418233564, 4844.491328410537, 4900.5099490051425, 9764.707058788248, 9764.804703906024, 9769.413835169913, 9771.657991970247, 9782.119609235204, 5602.775888964477, 5634.52106570946, 5648.293963254593, 5650.152477128381, 5662.2639056922335, 4554.476235232991, 4683.718976861399, 5033.748312584429, 5210.7915683373, 5257.553107985864, 3987.860424885115, 4024.1750441159493, 4046.0289530513055, 4098.7950602469755, 4100.91798164035, 1252.2611659121162, 1278.0159277356383, 1280.6670265730977, 1285.4022879361141, 1291.031726205346, 936.3539157981107, 957.3537861943161, 959.3428501476511, 961.1013702188476, 1007.7842646649951, 6061.272647282354, 6077.696115194222, 6161.260648722182, 6226.5641405102115, 6283.277423096311, 863.330918538138, 956.4309236004566, 980.460746589373, 988.5452691761726, 1043.7495001199657, 3921.529416469985, 4058.1680755445063, 4064.695147863863, 4085.59144085595, 4105.096878686684, 10920.781584368428, 11314.660560183207, 11401.973822356027, 11423.428828558539, 11450.94274528622, 11172.385518796382, 11174.994250517493, 11240.775184496264, 11249.437528123724, 11261.943690281496, 8190.566650836199, 8532.274756645755, 8698.521581312962, 8749.387542871938, 8755.124487551322, 6001.2498437695285, 6002.129691194839, 6147.047207682816, 6164.167837341902, 6206.751729930752, 9585.520723963635, 9715.36850104741, 10209.74475638115, 10219.38683678952, 10221.029002244775, 10084.69745907624, 10321.793564128526, 10323.690289291333, 10357.326773759682, 10497.212709047004, 6705.966470167618, 7060.682269297791, 7251.347378735939, 7625.8856117157175, 7773.261540153676, 8501.914980850359, 8531.189536993976, 8596.613152408281, 8602.0, 8605.096464871349, 7476.5514069156025, 7603.429742769204, 7668.616569171408, 7678.155402905788, 7702.499337543042, 7390.5196162249285, 7406.14829294638, 7439.8140046499275, 7551.5091519053285, 7555.017847679766, 7194.4244460442, 7295.817604559928, 7334.596597187206, 7337.743178988923, 7344.8898266524975, 4653.9301910470695, 4752.857414277577, 5040.722760248222, 5092.898142037065, 5144.511271429208, 5094.2103973884105, 5160.896782064413, 5162.612804039787, 5231.503007214308, 5392.703401312965], "value": 4770.627801420263, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.2486755594577, 333.0740541295199, 342.55467891740733, 343.1301650316448, 345.77697385186553, 6552.901706474407, 6630.701618427171, 6702.363275488827, 6715.294894036129, 6724.798256052314, 6378.425941665261, 6515.393842463017, 6608.405243528082, 6624.701888415021, 6681.699323530441, 4933.235348520967, 4933.358663373757, 4937.456879743234, 4946.307516947631, 4957.578605818491, 4746.833860814872, 4900.852974410774, 4932.950670493285, 4940.851774446773, 4946.307516947631, 4804.063207674505, 4883.0, 4888.315635810989, 4901.1177987962155, 4915.188993816034, 4862.878428039292, 4873.951260487385, 4908.092002979461, 4909.484504127051, 4914.434839993411, 3583.62371950944, 4171.728837625575, 4226.197022565702, 4227.682923780729, 4238.512571054337, 6348.206474190726, 6445.548811583144, 6456.838579035561, 6470.288268290485, 6477.319881412431, 4703.2239680452685, 5660.886782264372, 5863.5309175265875, 5879.412058794129, 5897.823065308047, 7047.330503602149, 7066.893996590055, 7073.221945585981, 7078.716851325941, 7113.53762005468, 4591.724496530219, 4771.928421073687, 4775.856724298276, 4788.209945359012, 4793.352897358882, 5792.391798861143, 6603.943369060928, 6845.965770171167, 7460.03019607453, 7461.664225109829, 4854.660173787872, 4865.6107511399005, 4869.391326084239, 4870.659053866196, 4875.463698993108, 4775.5224477552665, 4947.505249475095, 4950.752462376939, 4952.851414457572, 4957.900841983213, 4899.726071221508, 5753.942460575341, 5825.1844741736195, 5880.2943646761805, 5903.704814759245, 7046.788596342118, 7086.043384143178, 7103.360697537247, 7107.147142342848, 7126.750563730245, 1574.889757716949, 1699.3882202407124, 1732.480255923218, 1734.8525375342986, 1736.2100244388773, 985.8669079674296, 1049.6063976008998, 1054.641421916552, 1061.867266591676, 1081.1242893256488, 6609.4798196415095, 6815.965920170367, 6824.249332573413, 6853.246142924374, 6866.210385805596, 7429.777106686808, 7434.405247580181, 7448.739294124788, 7471.589062601609, 7483.775486735407, 6788.287229840895, 6788.59268443895, 6789.219239787389, 6795.898061529081, 6813.216480104752, 3740.4389341598976, 3869.5356557213313, 3902.70729695274, 3914.6672532835028, 3926.450296958428, 7693.307602315818, 7705.190954949761, 7720.884186737204, 7769.417293702995, 7795.610219489003, 7594.696212151563, 7598.924010759933, 7616.885746713804, 7625.961870190725, 7635.694572217049, 6151.323354431009, 7728.806779830436, 7751.0311211098615, 7753.806154846174, 7768.53388796674, 7180.342008731924, 7384.446166537424, 7407.518511296748, 7412.444066695128, 7416.592087435243, 7244.565326080452, 7251.311125443074, 7264.0, 7276.818079548054, 7278.452541610394, 7738.883916741141, 7752.612369381621, 7758.3017528422715, 7762.68949242024, 7769.417293702882, 4684.203685373426, 4731.053789242096, 4738.241881298975, 4738.31294462308, 4741.193997020584, 6994.650267486707, 7367.447441441806, 7389.778306650809, 7406.44451666138, 7416.9258307418395, 7114.0, 7143.357097861218, 7143.571385716874, 7151.42788576923, 7155.785326440215, 7124.465665075244, 7127.2516385778745, 7133.500654954103, 7153.141623005273, 7154.177269613853, 6969.965150174419, 6972.79081627552, 6976.930230697831, 6984.371406573433, 6994.860102798018, 5521.365043020104, 6846.623435711083, 7098.183708873546, 7145.035420218204, 7176.282371762782, 6902.378785909293, 6940.444764418935, 6942.618156001468, 6944.756933507303, 6949.895751563832], "value": 5220.38037433644, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.9950337280245, 692.0760784352889, 704.7357241034612, 704.7850405626285, 738.3207449146786, 15381.461648842293, 15598.53204403867, 15615.375384984602, 15623.0, 15625.062496250233, 13751.312434378266, 13847.584572462816, 13875.514356997508, 13944.511942082028, 14050.929745351285, 664.9867002659968, 791.2008871040233, 942.820864035831, 947.8483442649177, 950.6150009246231, 938.8544775559765, 963.0946909904702, 963.6675347005307, 964.7346979580636, 964.8311545479548, 998.5207100591721, 1004.8944860789585, 1004.8995100489929, 1005.3063386263491, 1006.6577363696342, 813.686730608717, 997.3517213811008, 1001.0640051551823, 1001.7645853224486, 1022.4172221833558, 640.452413186728, 758.1660173808822, 760.8972788673514, 761.5849362097615, 761.9390448764085, 15300.158491282975, 15367.615809604738, 15435.30541125652, 15438.0, 15441.150736709476, 12721.87278127216, 13108.672283192902, 13124.737505249841, 13143.408546615425, 13198.538051168163, 10125.64560240388, 10161.18710503158, 10242.027007434282, 10368.222383321281, 10370.844437333446, 3499.580050393969, 3577.141486043331, 3587.515685382466, 3656.104254457675, 3694.8152592370275, 80296.19703803003, 81899.18100818976, 81979.0, 82287.7656835148, 82348.58825705833, 8342.582870856555, 9421.764455888657, 9756.756081098029, 9823.263255255884, 10145.69562913099, 21765.673514897288, 22455.55088898213, 22459.101635934705, 22470.988805503464, 22491.88754056219, 7780.844383112306, 7781.0, 8021.598920053974, 8183.71357002502, 8300.792480188044, 4241.34259189826, 4272.6154646082, 4291.871243862689, 4302.935455968163, 4489.371487991683, 3731.365667836474, 3790.5830358660523, 3795.791231482295, 3796.867109651149, 3799.6580307772433, 688.4561196654587, 692.5879101934341, 707.098449476921, 708.6633848921721, 724.8151721311032, 507.58885302904764, 566.60620868496, 601.52479541162, 605.1588292273743, 606.3451472409787, 8024.277814996678, 8174.632141553644, 8309.916900831035, 8358.331333493306, 8608.0, 543.1337017457149, 543.7553101104519, 557.5400294756761, 599.7271241585156, 621.2296752027454, 3779.640934111255, 3882.437046628223, 3910.589388114264, 4012.3179059559943, 4086.203190377907, 86370.0, 89336.6599501008, 90119.0, 90163.54918225367, 90350.0, 76799.0, 78334.0, 78408.60795695985, 78534.607326963, 78929.81605275926, 67180.32819671839, 69554.65222673955, 69799.95300070503, 70125.59748804894, 71044.64477677578, 7641.355666859916, 7734.458587898793, 7776.339011183886, 7855.33229675484, 7901.920980790348, 48478.272825908345, 49077.0, 49219.50780492149, 49457.50542494673, 51567.0, 62904.37095628985, 64433.67783161054, 64515.35484645094, 65058.0, 65173.0, 11089.0, 11183.664490065312, 11235.66293011211, 11430.942845285721, 12009.279443233434, 11459.598914037968, 11908.99822074748, 12062.215955962834, 12184.57353992606, 12637.052221083271, 11208.495617697386, 11682.70793230176, 11751.588694395656, 11953.342566158944, 11954.282743035446, 11427.828582571434, 11583.884161158281, 11611.709707257387, 11742.0, 11805.586804461802, 11221.775564488828, 11455.541778328774, 11521.193516453768, 11716.472758726048, 11723.882761172279, 9628.71113866585, 9875.456849873326, 9893.604255829974, 9911.058449447291, 10277.84583231267, 9674.393675465864, 10110.617490086332, 10114.747131321776, 10165.977171908547, 10284.691459256233], "value": 7025.395226049707, "unit": "score"}], "name": "dromaeo_css", "value": 7569.206654460222, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 119.0, 112.0, 113.0, 112.0, 113.0, 113.0, 112.0, 134.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 119.0, 141.0, 181.0, 146.0, 170.0, 125.0, 139.0, 207.0, 142.0], "value": 152.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 186.0, 183.0, 185.0, 184.0, 184.0, 186.0, 184.0, 185.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 108.0, 105.0, 186.0, 57.0, 55.0, 56.0, 64.0, 131.0, 56.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 141.0, 78.0, 78.0, 82.0, 137.0, 100.0, 78.0, 122.0], "value": 99.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 86.0, 88.0, 85.0, 89.0, 87.0, 89.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 95.0, 93.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 92.0, 102.0, 84.0, 85.0, 85.0, 84.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 58.0, 63.0, 88.0, 94.0, 92.0, 98.0, 61.0, 58.0], "value": 73.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 64.0, 75.0, 75.0, 70.0, 73.0, 96.0, 94.0, 96.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 67.0, 75.0, 95.0, 103.0, 103.0, 105.0, 76.0, 81.0, 82.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 126.0, 126.0, 126.0, 124.0, 141.0, 137.0, 131.0, 131.0, 138.0], "value": 130.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 169.0, 167.0, 162.0, 165.0, 162.0, 165.0, 163.0, 162.0, 160.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 67.0, 66.0, 66.0, 66.0, 67.0, 65.0, 66.0, 64.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1506.3, "alertThreshold": 2.0}]} 09:32:58 INFO - Return code: 0 09:32:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:32:58 ERROR - # TBPL SUCCESS # 09:32:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:32:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:32:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:32:58 INFO - 2016-07-15 09:32:58.258 screenresolution[1693:12983] starting screenresolution argv=screenresolution get 09:32:58 INFO - 2016-07-15 09:32:58.261 screenresolution[1693:12983] Display 0: 1600x1200x32@60 09:32:58 INFO - 2016-07-15 09:32:58.267 screenresolution[1694:12986] starting screenresolution argv=screenresolution list 09:32:58 INFO - Available Modes on Display 0 09:32:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:32:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:32:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:32:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:32:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:32:58 INFO - Intel Iris: 09:32:58 INFO - Chipset Model: Intel Iris 09:32:58 INFO - Type: GPU 09:32:58 INFO - Bus: Built-In 09:32:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:32:58 INFO - Vendor: Intel (0x8086) 09:32:58 INFO - Device ID: 0x0a2e 09:32:58 INFO - Revision ID: 0x0009 09:32:58 INFO - Displays: 09:32:58 INFO - Display: 09:32:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:32:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:32:58 INFO - Display Serial Number: 1600x1200 60 09:32:58 INFO - Main Display: Yes 09:32:58 INFO - Mirror: Off 09:32:58 INFO - Online: Yes 09:32:58 INFO - Rotation: Supported 09:32:58 INFO - Return code: 0 09:32:58 INFO - Running post-action listener: _resource_record_post_action 09:32:58 INFO - Running post-run listener: _resource_record_post_run 09:32:59 INFO - Total resource usage - Wall time: 1196s; CPU: 52.0%; Read bytes: 31596032; Write bytes: 494674944; Read time: 197; Write time: 1534 09:32:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 189235712; Write bytes: 186157056; Read time: 17244; Write time: 422 09:32:59 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 29810176; Write bytes: 297724928; Read time: 181; Write time: 1077 09:32:59 INFO - Running post-run listener: _upload_blobber_files 09:32:59 INFO - Blob upload gear active. 09:32:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:32:59 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 09:32:59 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:32:59 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:32:59 INFO - (blobuploader) - INFO - Open directory for files ... 09:32:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:32:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:33:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:01 INFO - (blobuploader) - INFO - Done attempting. 09:33:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:33:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:33:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:02 INFO - (blobuploader) - INFO - Done attempting. 09:33:02 INFO - (blobuploader) - INFO - Iteration through files over. 09:33:02 INFO - Return code: 0 09:33:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:33:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:33:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fa85262de97ca203d6e22b6bce5fb929f29b3b5316e9ba2d194058ad78643ce64afa5576494e993c3a211835e8ff56ff1080808b47a33ccbe01c06af155dec8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5bb27a033791e09831aeda3a3dd4fc1c88090f0ce34959eaca79c9cc62cca2d74645a78f0b45b53f32eb0a77906117b87fce374d388db573f2d9b6b0c42954d0"} 09:33:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:33:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:33:02 INFO - Contents: 09:33:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fa85262de97ca203d6e22b6bce5fb929f29b3b5316e9ba2d194058ad78643ce64afa5576494e993c3a211835e8ff56ff1080808b47a33ccbe01c06af155dec8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5bb27a033791e09831aeda3a3dd4fc1c88090f0ce34959eaca79c9cc62cca2d74645a78f0b45b53f32eb0a77906117b87fce374d388db573f2d9b6b0c42954d0"} 09:33:02 INFO - Running post-run listener: copy_logs_to_upload_dir 09:33:02 INFO - Copying logs to upload dir... 09:33:02 INFO - mkdir: /builds/slave/test/build/upload/logs 09:33:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.617677 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-15 09:33:02.547756) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 09:33:02.551334) ========= 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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fa85262de97ca203d6e22b6bce5fb929f29b3b5316e9ba2d194058ad78643ce64afa5576494e993c3a211835e8ff56ff1080808b47a33ccbe01c06af155dec8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5bb27a033791e09831aeda3a3dd4fc1c88090f0ce34959eaca79c9cc62cca2d74645a78f0b45b53f32eb0a77906117b87fce374d388db573f2d9b6b0c42954d0"} build_url:https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011091 build_url: 'https://queue.taskcluster.net/v1/task/TcgGz8s_RH2jCtNbYMLHJQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fa85262de97ca203d6e22b6bce5fb929f29b3b5316e9ba2d194058ad78643ce64afa5576494e993c3a211835e8ff56ff1080808b47a33ccbe01c06af155dec8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5bb27a033791e09831aeda3a3dd4fc1c88090f0ce34959eaca79c9cc62cca2d74645a78f0b45b53f32eb0a77906117b87fce374d388db573f2d9b6b0c42954d0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 09:33:02.585528) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:33:02.585983) ========= 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.oBKAyF8ATB/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.IEn4p8W2wX/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005131 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:33:02.637803) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 09:33:02.638440) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 09:33:02.639156) ========= ========= Total master_lag: 0.34 =========