builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0164 starttime: 1462537123.09 results: success (0) buildid: 20160506013122 builduid: edd2de8de4ca48969474bf5b1f92b92a revision: b14d8e23323e95c5db3bc4cca69738edeee44302 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:43.088638) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:43.089105) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:43.089383) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:43.108828) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:43.109242) ========= 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.F9n4YlIoPR/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.3qAikAxoOt/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-05-06 05:18:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 187M=0s 2016-05-06 05:18:43 (187 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087046 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:43.214035) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:43.214360) ========= 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.F9n4YlIoPR/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.3qAikAxoOt/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.027605 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:43.262676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 05:18:43.263043) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b14d8e23323e95c5db3bc4cca69738edeee44302 --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-aurora --rev b14d8e23323e95c5db3bc4cca69738edeee44302 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F9n4YlIoPR/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.3qAikAxoOt/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-05-06 05:18:43,339 truncating revision to first 12 chars 2016-05-06 05:18:43,339 Setting DEBUG logging. 2016-05-06 05:18:43,339 attempt 1/10 2016-05-06 05:18:43,339 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b14d8e23323e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 05:18:44,912 unpacking tar archive at: mozilla-aurora-b14d8e23323e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.822342 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 05:18:45.102588) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:45.102910) ========= script_repo_revision: b14d8e23323e95c5db3bc4cca69738edeee44302 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:45.103331) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:45.103627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:45.116931) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 05:18:45.117231) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F9n4YlIoPR/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.3qAikAxoOt/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:18:45 INFO - MultiFileLogger online at 20160506 05:18:45 in /builds/slave/test 05:18:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:18:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:18:45 INFO - {'append_to_log': False, 05:18:45 INFO - 'base_work_dir': '/builds/slave/test', 05:18:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:18:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:18:45 INFO - 'branch': 'Mozilla-Aurora', 05:18:45 INFO - 'buildbot_json_path': 'buildprops.json', 05:18:45 INFO - 'config_files': ('talos/mac_config.py',), 05:18:45 INFO - 'default_actions': ('clobber', 05:18:45 INFO - 'read-buildbot-config', 05:18:45 INFO - 'download-and-extract', 05:18:45 INFO - 'populate-webroot', 05:18:45 INFO - 'create-virtualenv', 05:18:45 INFO - 'install', 05:18:45 INFO - 'run-tests'), 05:18:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:18:45 INFO - 'download_minidump_stackwalk': True, 05:18:45 INFO - 'download_symbols': 'ondemand', 05:18:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:18:45 INFO - 'tooltool.py': '/tools/tooltool.py', 05:18:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:18:45 INFO - '/tools/misc-python/virtualenv.py')}, 05:18:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:18:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:18:45 INFO - 'installer_path': 'installer.exe', 05:18:45 INFO - 'log_level': 'info', 05:18:45 INFO - 'log_name': 'talos', 05:18:45 INFO - 'log_to_console': True, 05:18:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:18:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:18:45 INFO - 'opt_config_files': (), 05:18:45 INFO - 'pip_index': False, 05:18:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:45 INFO - 'cmd': ('bash', 05:18:45 INFO - '-c', 05:18:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:45 INFO - 'enabled': True, 05:18:45 INFO - 'halt_on_failure': False, 05:18:45 INFO - 'name': 'check_screen_resolution'},), 05:18:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:45 INFO - 'cmd': ('bash', 05:18:45 INFO - '-c', 05:18:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:45 INFO - 'enabled': True, 05:18:45 INFO - 'halt_on_failure': False, 05:18:45 INFO - 'name': 'check_screen_resolution'},), 05:18:45 INFO - 'run_cmd_checks_enabled': True, 05:18:45 INFO - 'sps_profile': False, 05:18:45 INFO - 'sps_profile_interval': 0, 05:18:45 INFO - 'suite': 'dromaeojs', 05:18:45 INFO - 'system_bits': '32', 05:18:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:18:45 INFO - 'title': 't-yosemite-r7-0164', 05:18:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:18:45 INFO - 'use_talos_json': True, 05:18:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:18:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:18:45 INFO - 'work_dir': 'build'} 05:18:45 INFO - ##### 05:18:45 INFO - ##### Running clobber step. 05:18:45 INFO - ##### 05:18:45 INFO - Running pre-action listener: _resource_record_pre_action 05:18:45 INFO - Running main action method: clobber 05:18:45 INFO - rmtree: /builds/slave/test/build 05:18:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:18:47 INFO - Running post-action listener: _resource_record_post_action 05:18:47 INFO - ##### 05:18:47 INFO - ##### Running read-buildbot-config step. 05:18:47 INFO - ##### 05:18:47 INFO - Running pre-action listener: _resource_record_pre_action 05:18:47 INFO - Running main action method: read_buildbot_config 05:18:47 INFO - Using buildbot properties: 05:18:47 INFO - { 05:18:47 INFO - "project": "", 05:18:47 INFO - "product": "firefox", 05:18:47 INFO - "script_repo_revision": "production", 05:18:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:18:47 INFO - "repository": "", 05:18:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:18:47 INFO - "buildid": "20160506013122", 05:18:47 INFO - "pgo_build": "False", 05:18:47 INFO - "basedir": "/builds/slave/test", 05:18:47 INFO - "buildnumber": 231, 05:18:47 INFO - "slavename": "t-yosemite-r7-0164", 05:18:47 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:18:47 INFO - "platform": "yosemite_r7", 05:18:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:18:47 INFO - "branch": "mozilla-aurora", 05:18:47 INFO - "repo_path": "releases/mozilla-aurora", 05:18:47 INFO - "revision": "b14d8e23323e95c5db3bc4cca69738edeee44302", 05:18:47 INFO - "stage_platform": "macosx64", 05:18:47 INFO - "builduid": "edd2de8de4ca48969474bf5b1f92b92a", 05:18:47 INFO - "slavebuilddir": "test" 05:18:47 INFO - } 05:18:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 05:18:47 INFO - Running post-action listener: _resource_record_post_action 05:18:47 INFO - ##### 05:18:47 INFO - ##### Running download-and-extract step. 05:18:47 INFO - ##### 05:18:47 INFO - Running pre-action listener: _resource_record_pre_action 05:18:47 INFO - Running main action method: download_and_extract 05:18:47 INFO - mkdir: /builds/slave/test/build/tests 05:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:47 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:18:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 05:18:47 INFO - Downloaded 1199 bytes. 05:18:47 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:47 INFO - Using the following test package requirements: 05:18:47 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:18:47 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:47 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 05:18:47 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:47 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 05:18:47 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:47 INFO - u'jsshell-mac.zip'], 05:18:47 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:47 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 05:18:47 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:18:47 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:47 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 05:18:47 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:47 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 05:18:47 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:47 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 05:18:47 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:47 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 05:18:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:47 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:18:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:18:49 INFO - Downloaded 18113035 bytes. 05:18:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:18:52 INFO - Return code: 0 05:18:52 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:52 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:18:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:18:53 INFO - Downloaded 18113035 bytes. 05:18:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:18:56 INFO - Return code: 0 05:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:56 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:18:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:18:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:18:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:18:57 INFO - Downloaded 10840145 bytes. 05:18:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:18:58 INFO - Return code: 0 05:18: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')]} 05:18:58 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:18:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 05:18:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 05:19:01 INFO - Downloaded 114079746 bytes. 05:19:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:19:01 INFO - mkdir: /builds/slave/test/properties 05:19:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:19:01 INFO - Writing to file /builds/slave/test/properties/build_url 05:19:01 INFO - Contents: 05:19:01 INFO - build_url:https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:19:02 INFO - Running post-action listener: _resource_record_post_action 05:19:02 INFO - Running post-action listener: set_extra_try_arguments 05:19:02 INFO - ##### 05:19:02 INFO - ##### Running populate-webroot step. 05:19:02 INFO - ##### 05:19:02 INFO - Running pre-action listener: _resource_record_pre_action 05:19:02 INFO - Running main action method: populate_webroot 05:19:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:19:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:19:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:19:02 INFO - '--tppagecycles', 05:19:02 INFO - '7'], 05:19:02 INFO - 'tests': ['tsvgm']}}, 05:19:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:19:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:19:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:19:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:19:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:02 INFO - 'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:02 INFO - 'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['damp', 'tps']}, 05:19:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:02 INFO - 'tests': ['damp', 'tps']}, 05:19:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['dromaeo_dom']}, 05:19:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:19:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other-e10s': {'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['tsvgx', 05:19:02 INFO - 'tsvgr_opacity', 05:19:02 INFO - 'tart', 05:19:02 INFO - 'tscrollx', 05:19:02 INFO - 'cart']}, 05:19:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:19:02 INFO - 'tsvgr_opacity', 05:19:02 INFO - 'tart', 05:19:02 INFO - 'tscrollx', 05:19:02 INFO - 'cart']}, 05:19:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:02 INFO - 'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['tp5o']}, 05:19:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:02 INFO - 'tests': ['tp5o']}, 05:19:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:02 INFO - 'talos_options': ['--disable-e10s', 05:19:02 INFO - '--xperf_path', 05:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:02 INFO - 'tests': ['tp5n']}, 05:19:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:19:02 INFO - 'talos_options': ['--xperf_path', 05:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:02 INFO - 'tests': ['tp5n']}}, 05:19:02 INFO - 'talos.zip': {'path': '', 05:19:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:19:02 INFO - Running post-action listener: _resource_record_post_action 05:19:02 INFO - ##### 05:19:02 INFO - ##### Running create-virtualenv step. 05:19:02 INFO - ##### 05:19:02 INFO - Running pre-action listener: _resource_record_pre_action 05:19:02 INFO - Running main action method: create_virtualenv 05:19:02 INFO - Creating virtualenv /builds/slave/test/build/venv 05:19:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:19:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:19:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:19:02 INFO - Using real prefix '/tools/python27' 05:19:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:19:03 INFO - Installing distribute.............................................................................................................................................................................................done. 05:19:06 INFO - Installing pip.................done. 05:19:06 INFO - Return code: 0 05:19:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 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 0x10cec5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f8cf5860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, '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.F9n4YlIoPR/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.3qAikAxoOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:06 INFO - 'HOME': '/Users/cltbld', 05:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:06 INFO - 'LOGNAME': 'cltbld', 05:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:06 INFO - 'MOZ_NO_REMOTE': '1', 05:19:06 INFO - 'NO_EM_RESTART': '1', 05:19:06 INFO - 'PAGER': '/bin/cat', 05:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:06 INFO - 'PWD': '/builds/slave/test', 05:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:06 INFO - 'SHELL': '/bin/bash', 05:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:19:06 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:06 INFO - 'USER': 'cltbld', 05:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:06 INFO - 'XPC_FLAGS': '0x0', 05:19:06 INFO - 'XPC_SERVICE_NAME': '0', 05:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:19:06 INFO - Cleaning up... 05:19:06 INFO - Return code: 0 05:19:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 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 0x10cec5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f8cf5860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, '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.F9n4YlIoPR/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.3qAikAxoOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:06 INFO - 'HOME': '/Users/cltbld', 05:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:06 INFO - 'LOGNAME': 'cltbld', 05:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:06 INFO - 'MOZ_NO_REMOTE': '1', 05:19:06 INFO - 'NO_EM_RESTART': '1', 05:19:06 INFO - 'PAGER': '/bin/cat', 05:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:06 INFO - 'PWD': '/builds/slave/test', 05:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:06 INFO - 'SHELL': '/bin/bash', 05:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:19:06 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:06 INFO - 'USER': 'cltbld', 05:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:06 INFO - 'XPC_FLAGS': '0x0', 05:19:06 INFO - 'XPC_SERVICE_NAME': '0', 05:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:06 INFO - Downloading/unpacking psutil>=0.7.1 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:19:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:09 INFO - Installing collected packages: psutil 05:19:09 INFO - Running setup.py install for psutil 05:19:09 INFO - building 'psutil._psutil_osx' extension 05:19:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:19:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:19:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:19:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:19:10 INFO - building 'psutil._psutil_posix' extension 05:19:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:19:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:19:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:19:10 INFO - ^ 05:19:10 INFO - 1 warning generated. 05:19:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:19:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:11 INFO - Successfully installed psutil 05:19:11 INFO - Cleaning up... 05:19:11 INFO - Return code: 0 05:19:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:11 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 0x10cec5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f8cf5860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, '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.F9n4YlIoPR/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.3qAikAxoOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:11 INFO - 'HOME': '/Users/cltbld', 05:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:11 INFO - 'LOGNAME': 'cltbld', 05:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:11 INFO - 'MOZ_NO_REMOTE': '1', 05:19:11 INFO - 'NO_EM_RESTART': '1', 05:19:11 INFO - 'PAGER': '/bin/cat', 05:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:11 INFO - 'PWD': '/builds/slave/test', 05:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:11 INFO - 'SHELL': '/bin/bash', 05:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:19:11 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:11 INFO - 'USER': 'cltbld', 05:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:11 INFO - 'XPC_FLAGS': '0x0', 05:19:11 INFO - 'XPC_SERVICE_NAME': '0', 05:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:19:13 INFO - Installing collected packages: mozsystemmonitor 05:19:13 INFO - Running setup.py install for mozsystemmonitor 05:19:14 INFO - Successfully installed mozsystemmonitor 05:19:14 INFO - Cleaning up... 05:19:14 INFO - Return code: 0 05:19:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:14 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 0x10cec5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f8cf5860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, '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.F9n4YlIoPR/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.3qAikAxoOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:19:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:14 INFO - 'HOME': '/Users/cltbld', 05:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:14 INFO - 'LOGNAME': 'cltbld', 05:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:14 INFO - 'MOZ_NO_REMOTE': '1', 05:19:14 INFO - 'NO_EM_RESTART': '1', 05:19:14 INFO - 'PAGER': '/bin/cat', 05:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:14 INFO - 'PWD': '/builds/slave/test', 05:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:14 INFO - 'SHELL': '/bin/bash', 05:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:19:14 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:14 INFO - 'USER': 'cltbld', 05:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:14 INFO - 'XPC_FLAGS': '0x0', 05:19:14 INFO - 'XPC_SERVICE_NAME': '0', 05:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:14 INFO - Downloading/unpacking blobuploader==1.2.4 05:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:16 INFO - Downloading blobuploader-1.2.4.tar.gz 05:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:19:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:19:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:17 INFO - Downloading docopt-0.6.1.tar.gz 05:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:19:18 INFO - Installing collected packages: blobuploader, requests, docopt 05:19:18 INFO - Running setup.py install for blobuploader 05:19:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:19:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:19:18 INFO - Running setup.py install for requests 05:19:18 INFO - Running setup.py install for docopt 05:19:18 INFO - Successfully installed blobuploader requests docopt 05:19:18 INFO - Cleaning up... 05:19:18 INFO - Return code: 0 05:19:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:18 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 0x10cec5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f8cf5860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, '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.F9n4YlIoPR/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.3qAikAxoOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:18 INFO - 'HOME': '/Users/cltbld', 05:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:18 INFO - 'LOGNAME': 'cltbld', 05:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:18 INFO - 'MOZ_NO_REMOTE': '1', 05:19:18 INFO - 'NO_EM_RESTART': '1', 05:19:18 INFO - 'PAGER': '/bin/cat', 05:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:18 INFO - 'PWD': '/builds/slave/test', 05:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:18 INFO - 'SHELL': '/bin/bash', 05:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:19:18 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:18 INFO - 'USER': 'cltbld', 05:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:18 INFO - 'XPC_FLAGS': '0x0', 05:19:18 INFO - 'XPC_SERVICE_NAME': '0', 05:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-5qo2n9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-4DScyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-X18eIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-s9fIOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-QYw5uV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-aUNMrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-jJYC38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-mjX4WU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-fx1d9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-5i2yVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-qeBqHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Leey9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-wo71fT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-hAOXHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vK4qwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-dbP0VJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:20 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-VTXpo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:19:21 INFO - Running setup.py install for manifestparser 05:19:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:19:21 INFO - Running setup.py install for mozcrash 05:19:21 INFO - Running setup.py install for mozdebug 05:19:21 INFO - Running setup.py install for mozdevice 05:19:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:19:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:19:21 INFO - Running setup.py install for mozfile 05:19:21 INFO - Running setup.py install for mozhttpd 05:19:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:19:21 INFO - Running setup.py install for mozinfo 05:19:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:19:22 INFO - Running setup.py install for mozInstall 05:19:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:19:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:19:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:19:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:19:22 INFO - Running setup.py install for mozleak 05:19:22 INFO - Running setup.py install for mozlog 05:19:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:19:22 INFO - Running setup.py install for moznetwork 05:19:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:19:22 INFO - Running setup.py install for mozprocess 05:19:22 INFO - Running setup.py install for mozprofile 05:19:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:19:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:19:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:19:23 INFO - Running setup.py install for mozrunner 05:19:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:19:23 INFO - Running setup.py install for mozscreenshot 05:19:23 INFO - Running setup.py install for moztest 05:19:23 INFO - Running setup.py install for mozversion 05:19:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:19:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:19:23 INFO - Cleaning up... 05:19:23 INFO - Return code: 0 05:19:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:23 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 0x10cec5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f8cf5860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, '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.F9n4YlIoPR/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.3qAikAxoOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:23 INFO - 'HOME': '/Users/cltbld', 05:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:23 INFO - 'LOGNAME': 'cltbld', 05:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:23 INFO - 'MOZ_NO_REMOTE': '1', 05:19:23 INFO - 'NO_EM_RESTART': '1', 05:19:23 INFO - 'PAGER': '/bin/cat', 05:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:23 INFO - 'PWD': '/builds/slave/test', 05:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:23 INFO - 'SHELL': '/bin/bash', 05:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:19:23 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:23 INFO - 'USER': 'cltbld', 05:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:23 INFO - 'XPC_FLAGS': '0x0', 05:19:23 INFO - 'XPC_SERVICE_NAME': '0', 05:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:23 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ncd9Y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-qY1tqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-OZD6RC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-rKbJry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-gPAXRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-p4qeZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-jOH6nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-XQkh6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-YdR5m3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ju8HAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:24 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-QwZL_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vz04qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_qZL9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-8rHDZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-7TJZgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-zThcZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:25 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-fRlF4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:28 INFO - Downloading blessings-1.6.tar.gz 05:19:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:28 INFO - Installing collected packages: blessings 05:19:28 INFO - Running setup.py install for blessings 05:19:28 INFO - Successfully installed blessings 05:19:28 INFO - Cleaning up... 05:19:28 INFO - Return code: 0 05:19:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:28 INFO - Reading from file tmpfile_stdout 05:19:28 INFO - Current package versions: 05:19:28 INFO - blessings == 1.6 05:19:28 INFO - blobuploader == 1.2.4 05:19:28 INFO - docopt == 0.6.1 05:19:28 INFO - manifestparser == 1.1 05:19:28 INFO - mozInstall == 1.12 05:19:28 INFO - mozcrash == 0.17 05:19:28 INFO - mozdebug == 0.1 05:19:28 INFO - mozdevice == 0.48 05:19:28 INFO - mozfile == 1.2 05:19:28 INFO - mozhttpd == 0.7 05:19:28 INFO - mozinfo == 0.9 05:19:28 INFO - mozleak == 0.1 05:19:28 INFO - mozlog == 3.1 05:19:28 INFO - moznetwork == 0.27 05:19:28 INFO - mozprocess == 0.22 05:19:28 INFO - mozprofile == 0.28 05:19:28 INFO - mozrunner == 6.11 05:19:28 INFO - mozscreenshot == 0.1 05:19:28 INFO - mozsystemmonitor == 0.0 05:19:28 INFO - moztest == 0.7 05:19:28 INFO - mozversion == 1.4 05:19:28 INFO - psutil == 3.1.1 05:19:28 INFO - requests == 1.2.3 05:19:28 INFO - wsgiref == 0.1.2 05:19:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:28 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 0x10cec5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f8cf5860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, '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.F9n4YlIoPR/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.3qAikAxoOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:28 INFO - 'HOME': '/Users/cltbld', 05:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:28 INFO - 'LOGNAME': 'cltbld', 05:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:28 INFO - 'MOZ_NO_REMOTE': '1', 05:19:28 INFO - 'NO_EM_RESTART': '1', 05:19:28 INFO - 'PAGER': '/bin/cat', 05:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:28 INFO - 'PWD': '/builds/slave/test', 05:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:28 INFO - 'SHELL': '/bin/bash', 05:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:19:28 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:28 INFO - 'USER': 'cltbld', 05:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:28 INFO - 'XPC_FLAGS': '0x0', 05:19:28 INFO - 'XPC_SERVICE_NAME': '0', 05:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:29 INFO - Cleaning up... 05:19:29 INFO - Return code: 0 05:19:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:29 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 0x10cec5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f8cf5860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, '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.F9n4YlIoPR/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.3qAikAxoOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:19:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:29 INFO - 'HOME': '/Users/cltbld', 05:19:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:29 INFO - 'LOGNAME': 'cltbld', 05:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:29 INFO - 'MOZ_NO_REMOTE': '1', 05:19:29 INFO - 'NO_EM_RESTART': '1', 05:19:29 INFO - 'PAGER': '/bin/cat', 05:19:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:29 INFO - 'PWD': '/builds/slave/test', 05:19:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:29 INFO - 'SHELL': '/bin/bash', 05:19:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:19:29 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:19:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:29 INFO - 'USER': 'cltbld', 05:19:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:29 INFO - 'XPC_FLAGS': '0x0', 05:19:29 INFO - 'XPC_SERVICE_NAME': '0', 05:19:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:29 INFO - Downloading/unpacking jsonschema 05:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:19:31 INFO - Downloading/unpacking functools32 (from jsonschema) 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:32 INFO - Downloading functools32-3.2.3-2.tar.gz 05:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:19:32 INFO - warning: no files found matching '*.txt' 05:19:32 INFO - no previously-included directories found matching 'build' 05:19:32 INFO - no previously-included directories found matching 'dist' 05:19:32 INFO - no previously-included directories found matching '.git*' 05:19:32 INFO - Installing collected packages: jsonschema, functools32 05:19:32 INFO - Running setup.py install for functools32 05:19:32 INFO - warning: no files found matching '*.txt' 05:19:32 INFO - no previously-included directories found matching 'build' 05:19:32 INFO - no previously-included directories found matching 'dist' 05:19:32 INFO - no previously-included directories found matching '.git*' 05:19:32 INFO - Successfully installed jsonschema functools32 05:19:32 INFO - Cleaning up... 05:19:32 INFO - Return code: 0 05:19:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cec5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f8cf5860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, '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.F9n4YlIoPR/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.3qAikAxoOt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:32 INFO - 'HOME': '/Users/cltbld', 05:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:32 INFO - 'LOGNAME': 'cltbld', 05:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:32 INFO - 'MOZ_NO_REMOTE': '1', 05:19:32 INFO - 'NO_EM_RESTART': '1', 05:19:32 INFO - 'PAGER': '/bin/cat', 05:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:32 INFO - 'PWD': '/builds/slave/test', 05:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:32 INFO - 'SHELL': '/bin/bash', 05:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:19:32 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:32 INFO - 'USER': 'cltbld', 05:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:32 INFO - 'XPC_FLAGS': '0x0', 05:19:32 INFO - 'XPC_SERVICE_NAME': '0', 05:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:32 INFO - Downloading/unpacking flake8 05:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:19:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:19:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:19:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:36 INFO - no previously-included directories found matching 'docs/_build' 05:19:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:19:36 INFO - Running setup.py install for pep8 05:19:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:36 INFO - no previously-included directories found matching 'docs/_build' 05:19:36 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:19:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:19:36 INFO - Cleaning up... 05:19:36 INFO - Return code: 0 05:19:36 INFO - Running post-action listener: _resource_record_post_action 05:19:36 INFO - Running post-action listener: _start_resource_monitoring 05:19:37 INFO - Starting resource monitoring. 05:19:37 INFO - ##### 05:19:37 INFO - ##### Running install step. 05:19:37 INFO - ##### 05:19:37 INFO - Running pre-action listener: _resource_record_pre_action 05:19:37 INFO - Running main action method: install 05:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:37 INFO - Reading from file tmpfile_stdout 05:19:37 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:37 INFO - Reading from file tmpfile_stdout 05:19:37 INFO - Output received: 05:19:37 INFO - Usage: mozinstall [options] installer 05:19:37 INFO - Options: 05:19:37 INFO - -h, --help show this help message and exit 05:19:37 INFO - -d DEST, --destination=DEST 05:19:37 INFO - Directory to install application into. [default: 05:19:37 INFO - "/builds/slave/test"] 05:19:37 INFO - --app=APP Application being installed. [default: firefox] 05:19:37 INFO - mkdir: /builds/slave/test/build/application 05:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:20:09 INFO - Reading from file tmpfile_stdout 05:20:09 INFO - Output received: 05:20:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:20:09 INFO - Running post-action listener: _resource_record_post_action 05:20:09 INFO - ##### 05:20:09 INFO - ##### Running run-tests step. 05:20:09 INFO - ##### 05:20:09 INFO - Running pre-action listener: _resource_record_pre_action 05:20:09 INFO - Running main action method: run_tests 05:20:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:20:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:20:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:20:09 INFO - 2016-05-06 05:20:09.406 screenresolution[1638:7346] starting screenresolution argv=screenresolution get 05:20:09 INFO - 2016-05-06 05:20:09.409 screenresolution[1638:7346] Display 0: 1600x1200x32@60 05:20:09 INFO - 2016-05-06 05:20:09.415 screenresolution[1639:7348] starting screenresolution argv=screenresolution list 05:20:09 INFO - Available Modes on Display 0 05:20:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:20:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:20:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:20:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:20:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:20:09 INFO - Intel Iris: 05:20:09 INFO - Chipset Model: Intel Iris 05:20:09 INFO - Type: GPU 05:20:09 INFO - Bus: Built-In 05:20:09 INFO - VRAM (Dynamic, Max): 1536 MB 05:20:09 INFO - Vendor: Intel (0x8086) 05:20:09 INFO - Device ID: 0x0a2e 05:20:09 INFO - Revision ID: 0x0009 05:20:09 INFO - Displays: 05:20:09 INFO - Display: 05:20:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:20:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:20:09 INFO - Display Serial Number: 1600x1200 60 05:20:09 INFO - Main Display: Yes 05:20:09 INFO - Mirror: Off 05:20:09 INFO - Online: Yes 05:20:09 INFO - Rotation: Supported 05:20:09 INFO - Return code: 0 05:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:20:09 INFO - Python 2.7.3 05:20:09 INFO - Return code: 0 05:20:09 INFO - grabbing minidump binary from tooltool 05:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:09 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 0x10cd64e00>, '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 0x10cbcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:20:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:20:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:20:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:20:09 INFO - Return code: 0 05:20:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:20:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:20:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:20:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:20:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:20:10 INFO - Return code: 0 05:20:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:20:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F9n4YlIoPR/Render', 05:20:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:10 INFO - 'HOME': '/Users/cltbld', 05:20:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:10 INFO - 'LOGNAME': 'cltbld', 05:20:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:10 INFO - 'MOZ_NO_REMOTE': '1', 05:20:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:10 INFO - 'NO_EM_RESTART': '1', 05:20:10 INFO - 'PAGER': '/bin/cat', 05:20:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:10 INFO - 'PWD': '/builds/slave/test', 05:20:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:10 INFO - 'SHELL': '/bin/bash', 05:20:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3qAikAxoOt/Listeners', 05:20:10 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 05:20:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:10 INFO - 'USER': 'cltbld', 05:20:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:10 INFO - 'XPC_FLAGS': '0x0', 05:20:10 INFO - 'XPC_SERVICE_NAME': '0', 05:20:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:20:11 INFO - mozversion application_buildid: 20160506013122 05:20:11 INFO - mozversion application_changeset: b14d8e23323e95c5db3bc4cca69738edeee44302 05:20:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:20:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:20:11 INFO - mozversion application_name: Firefox 05:20:11 INFO - mozversion application_remotingname: firefox-dev 05:20:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:11 INFO - mozversion application_vendor: Mozilla 05:20:11 INFO - mozversion application_version: 48.0a2 05:20:11 INFO - mozversion platform_buildid: 20160506013122 05:20:11 INFO - mozversion platform_changeset: b14d8e23323e95c5db3bc4cca69738edeee44302 05:20:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:11 INFO - mozversion platform_version: 48.0a2 05:20:11 INFO - using testdate: 1462537211 05:20:11 INFO - actual date: 1462537211 05:20:11 INFO - starting webserver on 'localhost:49275' 05:20:11 INFO - SUITE-START | Running 2 tests 05:20:11 INFO - TEST-START | dromaeo_css 05:20:11 INFO - operating with platform_type : mac_ 05:20:11 INFO - Initialising browser for dromaeo_css test... 05:20:11 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpRycGtm/profile http://localhost:49275/getInfo.html) 05:20:13 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 05:20:13 INFO - PROCESS | 1657 | colorDepth:24 05:20:13 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 05:20:13 INFO - PROCESS | 1657 | __metrics 05:20:13 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:20:13 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:20:14 INFO - TEST-INFO | 1657: exit 0 05:20:14 INFO - Browser initialized. 05:20:14 INFO - Running cycle 1/1 for dromaeo_css test... 05:20:14 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpRycGtm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:25 INFO - PROCESS | 1659 | RSS: Main: 211136512 05:20:25 INFO - PROCESS | 1659 | 05:21:16 INFO - PROCESS | 1659 | 1462537276719 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:23:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 05:23:30 INFO - PROCESS | 1659 | RSS: Main: 344461312 05:23:30 INFO - PROCESS | 1659 | 05:26:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 05:26:33 INFO - PROCESS | 1659 | RSS: Main: 359251968 05:26:33 INFO - PROCESS | 1659 | 05:29:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 05:29:36 INFO - PROCESS | 1659 | RSS: Main: 364773376 05:29:36 INFO - PROCESS | 1659 | 05:32:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 05:32:39 INFO - PROCESS | 1659 | RSS: Main: 361377792 05:32:39 INFO - PROCESS | 1659 | 05:35:42 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 05:35:43 INFO - PROCESS | 1659 | RSS: Main: 328081408 05:35:43 INFO - PROCESS | 1659 | 05:38:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 05:38:46 INFO - PROCESS | 1659 | RSS: Main: 367542272 05:38:46 INFO - PROCESS | 1659 | 05:38:46 INFO - PROCESS | 1659 | __start_tp_report 05:38:46 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 05:38:46 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 05:38:46 INFO - PROCESS | 1659 | |i|pagename|runs| 05:38:46 INFO - PROCESS | 1659 | |0;dojo.html;3388.9830550847246;3436.501707252447;3442.948355774663;3479.2171761353684;3485.860565577377;5763.193152958584;5779.711014449276;5833.212516310296;5840.211571437854;5848.9999999999945;5857.41425857414;5859.619124756888;5863.706814659271;5863.76544938202;5869.677167755772;5182.274481572583;5195.844124676256;5217.791288348461;5229.790808367661;5287.968846075017;5311.760970756325;5315.867103322409;5317.8936421271555;5318.069337865858;5322.547583455392;5238.5023422774775;5273.947260527403;5300.151975683892;5306.336707911511;5308.761105750251;5291.629585928968;5349.625526213166;5353.678779273256;5369.194620806871;5377.274068000809;5038.093143234217;5147.6654017488745;5174.870628234325;5181.015607034651;5241.659292145999;5704.828855134353;5713.428657134295;5766.769329226826;5767.106098554731;5774.538036957033;6174.691265436711;6192.659403732793;6197.597156184834;6199.411055949678;6201.565890387675;5353.866153346197;6089.269287685462;6150.04674275488;6185.536084793658;6265.530085243579;5134.717590532482;5844.88310233793;5847.152162936351;5871.2073870027;5968.104784282349;6318.399752023551;6327.082573026885;6330.746770129143;6332.968335158387;6346.143270658495;6035.973884439656;6136.8772624547255;6143.32423433422;6188.628682279078;6245.094461303085;6292.874142517124;6319.8104056878365;6332.176817013758;6335.714892829834;6349.428551430301;6229.3770622937345;6291.465225455796;6291.993281075098;6299.748010079546;6309.274433440213;6049.274087109487;6108.342071131425;6118.558266176196;6289.591176573508;6313.273973493016;4273.658107351404;4481.1933851906315;4905.3623029006;4933.58064564516;4937.580305674058;4370.650347972218;4469;4475.172093162775;4515.932261016087;4516.345129956138;5728.455796699307;5738.282714660668;5758.193852860602;5778.046622307356;5815.76736930518;6193.4425901669065;6222.160008398808;6292.842678933063;6296.937030629727;6340.841478963062;5717.313922329347;5743.885122297531;5767.85580360486;5778.942210577925;5798;5421.512063914188;5440.238366628675;5446.291982042309;5447.809326673548;5454.481824226692;6134.467201660849;6375.489960803217;6393.264774550986;6422.775202867877;6446.194225721785;6291.716872740829;6311.116443697794;6313;6326.335734747785;6336.968315158395;6362.236531616236;6368.904466432915;6374.713137908712;6384.457321127757;6410.903836442364;5472.151816468454;6096.634201947897;6175;6271.843203919939;6276.717547710273;6188.566800323934;6283.622982621057;6296.968515157578;6297.244330680348;6316;6152.507799376129;6163.599366041372;6190.3190649029475;6200.56596038273;6201.503879689524;5993.3706960768495;6017.338092809699;6083;6109.495762817132;6177.6911154443;6083.330833608388;6090.714246642122;6102.847428814315;6104.114903338902;6109.175261339662;6174.166487524127;6192.318844927145;6192.969035154795;6197.81406557804;6198.039303907902;5906.793262235801;5956.106584012433;6004.699765011819;6013.368596297326;6106.847328816815 05:38:46 INFO - PROCESS | 1659 | |1;ext.html;481.7711586996177;507.1353342550951;518.8184135552556;530.3821048478522;588.8557303460652;24068;31218;37768.62231377682;38978.635747748835;39072.609273907256;45161.54838451614;46351.768241158745;46383;46396.53603463964;46432.767836160856;9507.904920950788;9722.756931076708;9730.36752611078;9738.707838764849;9742.659006934759;8426.452280601741;8644.827103457927;8647.567621618926;8647.95676021619;8673.956630216871;11333.943330283337;11614.245074070159;11663.883361166365;11673.941630291836;11698.298102113858;11347.602833900815;11529.827052594217;11535.423228838608;11545;11552.6534203974;8430.620622072021;11137.941895519913;11261.43692815356;11395.943020284929;11456.255343402652;39878.20243595141;40581.18837623231;40686;40756.777296681146;40892.79553602243;3229.2411283348392;3309.751768617339;3586.2110335726097;3597.748157628967;3835.4822099016556;27402.76687549045;28759.42481150386;28783.85608071967;29175.124746257647;29180.708192918013;2749.1065403743664;2767.377340098462;2785.331520435101;2803.733645303693;2884.5961565380862;499600;501482;502152;504137.47931260476;504962;65108.348916511175;66416.33583664199;66454.66772666202;66587.66706166536;66598.33401666018;69850.65074674595;70021.94967075498;70079.64960175166;70107.64946175237;70171.2982870175;17278.740818887727;17823.10884455772;17824.46526604204;17851.553711157325;17856.46430607084;13748.106373085717;13943.790843137362;13944;13960.650983725489;13973.161610303414;14952.551423457313;15287.541373758804;15316.23418829054;15359.769603455734;18202.90898545499;5591.4688104629995;6601.207855057424;6616.206055273398;6627.138471998609;6647.135872336566;934.7289286107089;1024.5594394410475;1040.484959944817;1054.8892366301427;1162.4652659776598;28671.283217919303;28899.133026009255;28933;29614.815407383896;29736.405271894873;1099.5272033025715;1101.1864685728042;1128.9266197697123;1151.7293436042523;1187.655579881842;14442.855571444152;15044.323005464781;15576.45482408133;15728.842711572966;16071.839281607268;642034;651261;651618;652469.7376513086;653205;48898.511014889395;57213.71393143091;58158;63753;64344;1369.5480491438;1511.5465360392059;1516.60568252253;1527.3661430506438;1627.4710719016366;28048.859755701906;29221;29556.852215738785;29811.956581519542;29930.85034574813;50798.7300317495;51207;51220;51276.74361628317;51279.48720512747;379372;415992;425175;425228;431718;37348;37533.06167345838;38108.42837357497;38464.80767596144;38671;43769.56230437655;43990;44669.88325291894;45455;45500;35832.02923839109;35960.46059309165;37097.258054839294;38147.80926095463;39066.41400379053;37815.806035903384;38151;38538.80730596329;38606.80696596611;38859.611403885596;37390.06524836901;37611.2477750438;37812.62187378091;37999.43000854934;38910.80544597259;32189.839050804596;32510;32673.52969116348;32739;32777.01668949935;24736.876315618305;25035.49929001446;25140.874295628404;25236.369090772878;25292.74707252904 05:38:46 INFO - PROCESS | 1659 | |2;jquery.html;352.6790620535313;400.46737838674557;406.10656555577737;408.15104582468484;413.7931034482759;19667.901660491712;20064.99999999998;20680.793192068075;20685.37943861685;20687.793122068775;19421.12604932778;19789.604207915832;21414.571708565818;21451.67822482664;21458.14167433305;5137.460566640496;5566.693831839248;5571.275734154554;5573.27547418835;5587.692676902769;5481.232627432163;5501.587380946425;5599.776008959637;5606.915896261539;5620.932022915633;5412.188171774245;5581.49766521013;5590;5602;5606.327240731118;5479.2602998595075;5552.361478429992;5553.7778488860395;5584.385717571064;5590.385057643676;5121.155009423441;5319.388270348921;5356.491133342326;5358.98179345923;5362.115250983583;13441.26073065981;13685;13798.310084495835;13847.930760346235;13872.4451021959;1920.3470819921154;2089.40451971189;2105.968410473844;2128.5530038691895;2218.7637176264;9821.116099551004;9841.803163936753;9898.455584942825;10284;10341.172706183488;1585.7066442708021;1594.9840501594952;1658.6931417687645;1704.8806583539158;1725.8619310455133;101295;129187.35406322908;129595.7040429593;130645;131989.34005330008;11051.944740276247;11716.589919352782;11763.823542646867;11773.411329433666;11808.996235320094;13025.348732563334;14992.325345359484;15331.540053798404;15409.152496612794;15433.38266469351;8919.018907920123;8936.910630893737;9000.36997410188;9007.864882026775;9084.682036128705;8947.060558641379;8960.641574336954;9072.591733372014;9109.22571581423;9117.042710515432;4637.373954516097;4639.512851150581;4675.111728771522;4686.5782079613;4728.432588089451;1782.6345599152144;1914.5787926656114;1994.5738796760095;2006.0671787618828;2021.2622392826806;826.4669288309007;851.586980314547;895.5656506594297;938.5682586010512;1010.7321559786662;20871.174814030357;21255;21373.46566335823;21383.572328553342;21408.57182856334;965.5317171171976;971.4948226921961;971.5385192033868;981.3768257156614;1035.5081336365167;10062.202890164854;11233.157513186543;11404.714882127848;11456;11493.655190344303;585669;596062;611460;615471.922640384;616376;130207;135216;138449;138635;140079.99999999796;1019.6431249062769;1019.9949000254951;1036.896310368957;1046.3564907581783;1046.99476502617;18535.81464185341;18833;19197.52470306429;19253.518662033563;19351.225950961805;31046.379072418877;52390;54013;54263.45736542742;55379.44620553743;59443.81112377815;60214;66788.66422671617;76041.61979190068;77327.61336193283;10995.735490418705;11427.257228280136;11835.704107397383;12221.327826969602;12459.940905022811;7487.775366738789;9973.850392243978;11835.526578936746;12827;13389.933050334686;11327.86411347798;11445.198836081394;11472.483738231633;11749.647510574696;11776.646700598996;11120.555177792798;11266.267692599955;11390.316581005167;11585.899339562728;11640.53437862476;11209.383483908463;11549;11825;11842.822357664814;11895.048396128115;12034;12126.687847498224;12137.878621214028;12293.385330733248;12298.385080746106;11404;11440.313581185155;11500.194986350874;11671.591494297998;11757.47091380907 05:38:46 INFO - PROCESS | 1659 | |3;mootools.html;1172.5075468303312;1608.8873778835482;1624.42332971795;1875.840553552948;1992.1234656751028;7848.8430231395405;8642.91357086429;8860.689875854347;8863.335249856254;8866.77833054174;8442.535660538668;8717.302615790737;8721.651133954634;8724.73825785226;8748.168923952215;2186.092771499834;2420.878956052199;2442.181869073858;2476.4799392127675;2486.664300319461;2469.8271121021535;2501.874906254689;2502.311864237331;2503.198976327567;2510.184190138212;2397.3886658901956;2410.650455683925;2410.915617953372;2411;2417.6010958191964;2155.4503601581655;2383.249276477904;2448.3144719478487;2451.2891261534132;2452.239805660251;2444.3766839456002;2459.053264493165;2475.777180053804;2479.8884050217785;2481.131603938607;8642.524661143634;8715.215630593277;8727.60725767342;8732.170443807829;8735.475871447734;5208.869778255536;5409.9459005409835;5441.2654291670515;5452.454754524516;5455.345358556959;3556.3598552260582;3558.4948825406955;3615.963840361589;3663.487111804323;3665.486831843544;1020.7907378987289;1023.96416125436;1040.0639424517994;1042.3902017319876;1047.3558761361708;11666.591669291534;11670.474828632732;11675.299482031105;11684.00685941696;11707.648770536813;4810.95189048112;4813.687110337817;4908.141075311806;4940.209566469348;4954.3063971044085;10037.096661300518;10056.949715251378;10072.546735396925;10084.899151008543;10099;5425.104857698436;5478.424765399655;5480.561555075585;5485.122380419114;5490.450954904558;4454.220511410555;4635.6523260755575;4834;4959.82640607577;4962.379702537183;4038.1519880824767;4046.8751540000567;4113.465249517543;4155.0027993282;4160.708750387444;1347.3600039981127;1358.5516779462753;1359.8912087033013;1363.3455941148304;1375.7042235919325;797.330242596222;849.5403680022988;902.273669695901;938.2587755672942;960.3817567398955;5566.805161819398;5821.854453638608;5843.415658434123;5846.532277417797;5935.0503919372695;840.4999025579771;895.230102112183;901.5492253873064;970.1850445625568;985.9112679858847;3443.2252743132594;3494.405950988338;3582.0865679252147;3824;3843.6348546888003;9172;10157.441340726184;10562.207834412297;10607.204459665401;10613.469326533643;10641.73395665099;10717.035466807869;10725.570977160825;10776.407297598707;10787;7694.769156925301;8051.033875934925;8110.3511719062335;8151.5109093454585;8209.958950205211;5593.412691667317;5825.097109947965;5892.086726557391;5942.58401911862;5986.700664966821;9673.613055477703;9830.606775728891;10057.748556286151;10066.094051535398;10077.14344280715;9532.285078618992;9989.850152247576;10207.693769186937;10334.018268264503;10372.170226382023;6950.096487456598;7181.06646135999;7400.074990626172;7571.659275332513;7711.498752581064;8332.791680208044;8337.541435221121;8420.15798420153;8420.200080992296;8422.663093476192;6287.937120628918;7294;7459.515131516434;7550.735724249625;7620.8475830484185;7228.63856807168;7304;7370.815729606588;7392.593407362647;7403.1116266046265;5956.225690660186;6805.65971701403;6889.207741109836;6912.343327383891;6921.1002569665625;4641.791119399702;4648.976755116203;4700.4829468757725;4806.086843499724;4863.124637565272;4610.124076425468;4680.8127674893985;4834.782434790513;4847.297141914473;4906.607471402351 05:38:46 INFO - PROCESS | 1659 | |4;prototype.html;294.22325061836756;306.2145596544862;311.8585975330292;312.4266970109849;315.7048159970428;5231.86920326992;5354.196870469426;5379.462053794628;5386.030514507387;5452.372977107634;5122.846314610568;5231.346081739783;5313.495217954298;5405.4324295948945;5470.425605311445;3644.7995360255177;3876.8836934891997;3976.920461590781;4149.7510149391055;4193.664506839461;3645.4117576520703;3818.701641441909;3885.2812229737447;4004.579519150491;4156.459660244164;4078.796060196993;4080.1431699343175;4109.363048727452;4117.5176936302905;4152.231837110129;3683.9263214735824;4014.8594799182033;4048.4534587830562;4096.873359826041;4128.979355103205;3009.864556094981;3569.3575156471825;3579.856805727794;3585.8565657373674;3587.712982961357;4370;4970.378702662168;5078.4413714491375;5183.637145399824;5211.5830733541225;4317.460317460317;4636.605888499482;4660.044690838404;4671.392718946549;4712.6465515086165;5620.85947851307;5638.379778224392;5656.773729050833;5663.584103974006;5680.687562184078;3557.51973483579;3679.6504332088675;3750.49368335274;3972.417928346569;3988.162485877969;5486.094794358926;5950.7917222896995;5997.880042399128;6008.519318454513;6024.186734790791;4046.3728122141115;4046.433499310099;4054.7567145970747;4060.715749897479;4072.3687828386646;3325.7339412846914;3486.4786565568957;3487.8430470628373;3952.940705889414;4083.1833633273463;4289.978550107229;4654.930176047362;4668.253079507263;4673.415823022122;4687.601553867891;5201.9596080783995;5327.3074500314715;5657.2928383952;5673.687947162863;5687.744051517609;1218.524775337619;1297.3253907967803;1425.8645428684258;1456.6795305032877;1466.252211372208;890.8241121719268;975.7316737897135;976.8925418203992;1008.626808081;1010.4493051287067;4900.485449027872;5730.71346432685;5753.194552762533;5771;5779.393163717833;5543.724943263072;5957.910631340534;5964;6041.8218447403215;6368.872622547523;5066.6453348266;5396.784128634889;5654.293213348332;5679.716014199274;5700.287464066992;3241.4489536778806;3328;3344.6153692325693;3353.6646335366268;3369.3490189807308;6653.168353955755;6654.6672666366485;6665.933340666531;6690.799276021728;6744.42672372844;6210.192674952317;6533.411992920564;6542.738290468423;6546.443552298108;6572.436749667633;5333.546648534918;5482.396936337;5552.888942221133;6370.84072898172;6619.205695316593;5431.945680543144;5576.693281869536;5844.853878652898;6344.777932772331;6352.841178970562;5339.839804805862;5583.609147359646;5692.402297758676;6082.330943596289;6311.463525600376;6101.481374083252;6503.869922601427;6552.868942621216;6628.812491877765;6648.9002664961035;3695.8336874841234;3984;4276.037891474393;4287.506936702319;4302.505211900671;5065.669791895769;5442.346918369821;5454.318210223722;5663.773449062156;6407.167068281094;5359.249705041375;5497.780088796403;5580.804671836466;5638.4925356718095;5705.971470142623;4688.835890743808;5433.701146436984;5685.459881311269;5721.342045664802;5741.598088133791;5404.378496472956;5444.733160103906;5512.594288456418;5617.124184312811;5649;5706.258186435737;5706.715988902463;5720.3707592165665;5742.712864356682;5750.654960702372;5048.697078175322;5588.888222235451;5637.46444087811;5638.436156384329;5680.375158732453 05:38:46 INFO - PROCESS | 1659 | |5;yui.html;677.7492327838701;733.4059411876384;746.2947514598703;750.7222327738735;775.9650815713293;11988.460519276632;14309.155565308913;14926.850731492681;15008.924955375222;15046;13851.376688049037;13910.721785564283;13955.302234888266;13962.720745585108;14016.43934242629;712.081414974682;728.2571776787673;739.4528049243561;918.545320066568;929.45162354211;905.5336501701629;941.9058094190594;942.6370847223834;943.9946457023283;944.2964991081653;971.621067783565;988.9604415823359;989.5349185882624;991.6578780320777;991.8313886639289;748.9213632568584;768.677155594646;774.5817258680306;972.3873959405564;986.1666891476691;559.2003435087822;670.7216505150357;685.4619123987695;726.3825748114114;727.9781606551759;13574.728505429935;13577.524786632519;13592.86407135926;13608.251546164956;13647.658808529766;11781.234219775688;12025.579104731292;12069.879301206964;12117.517128336385;12242.877571224262;8732;8834.469931804048;8917.152870477294;9100.135487128713;9238.6304547818;3386.695197432243;3462.2556150427527;3511.438169892805;3526.8765593204116;3640.2719456108885;76194.61902690507;77166;78577.60711196407;79527;80314.19685803125;9239;9441.52792360393;9494.382865113745;9505.5722492488;9708.271879609058;21132.47168820761;21248;21279.361619151452;21308.04113814882;21343.78656213418;7233.132024157135;7307.123145222501;7345.77962661121;7384.741534046282;7401.962990185015;4048.352263637804;4050.270951228807;4120.608542188487;4121.855735049259;4239.618434340924;3383.9154021149175;3469.5142680024815;3475.548178736748;3477.6522347765526;3499.720022398253;626.1890851347516;642.1459458919719;647.7117682631279;653.5261935097112;666.3802663522873;564.7882044233412;579.843442270593;595.7438301530384;596.255951342322;597.3071237364636;7162.4628152888745;7534;7579.734709285149;7635.69457221716;7730.149683534807;519.5453977769452;578.1674388879999;578.6701580099303;595.8808238352347;601.2964831147568;3605.837737301828;3686.299603075407;3704.8888533344043;3719.3820688000806;3751.4747935289083;90318;91445;96638.51680741692;97030;97479;76545;78449.7580870886;78953.42093158104;82340.58829705813;83628;72811;73226.53546929032;75788.62105689436;76516.99999999888;77538;7939.920600793918;8014.759557213293;8019.358451323997;8141.674333026613;8166.060902996232;41091;44707;45528;46465.30302045404;46712.7664361676;51807;65241.17105947317;67239;67585.66207169129;67725.66137169283;10754.787459222676;11045.502952367002;11178.27341222818;11221.775564488828;12045.939770301444;10234.795304094025;11823.70440738954;11944.522219111139;12082.45628946682;12147.331896745764;8723.65105395777;10477.842832357368;10728.51721672511;11252.493637786483;11834.881651183723;10824.512896919816;11114.16643751738;11466.910643488854;11752.882471175179;12343.135980481638;10500.107490863362;10738.302010369302;10862.619808306672;10888.891111089104;11158.553657853596;9332.25341972627;9449.905500944902;9564.18704410105;9763.658271960447;9887.209023278265;9099.272058235458;9164.495952722664;9171.816563668823;9246.9537652314;9303.72088837336 05:38:46 INFO - PROCESS | 1659 | __end_tp_report 05:38:46 INFO - PROCESS | 1659 | __start_cc_report 05:38:46 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1204 05:38:46 INFO - PROCESS | 1659 | __end_cc_report 05:38:46 INFO - PROCESS | 1659 | __startTimestamp1462538326052__endTimestamp 05:38:46 INFO - PROCESS | 1659 | 05:38:46 INFO - PROCESS | 1659 | ------- Summary: start ------- 05:38:46 INFO - PROCESS | 1659 | Number of tests: 6 05:38:46 INFO - PROCESS | 1659 | 05:38:46 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5780.09 Median:6017.34 stddev:646.15 (10.7%) stddev-sans-first:620.31 05:38:46 INFO - PROCESS | 1659 | Values: 3389.0 3436.5 3442.9 3479.2 3485.9 5763.2 5779.7 5833.2 5840.2 5849.0 5857.4 5859.6 5863.7 5863.8 5869.7 5182.3 5195.8 5217.8 5229.8 5288.0 5311.8 5315.9 5317.9 5318.1 5322.5 5238.5 5273.9 5300.2 5306.3 5308.8 5291.6 5349.6 5353.7 5369.2 5377.3 5038.1 5147.7 5174.9 5181.0 5241.7 5704.8 5713.4 5766.8 5767.1 5774.5 6174.7 6192.7 6197.6 6199.4 6201.6 5353.9 6089.3 6150.0 6185.5 6265.5 5134.7 5844.9 5847.2 5871.2 5968.1 6318.4 6327.1 6330.7 6333.0 6346.1 6036.0 6136.9 6143.3 6188.6 6245.1 6292.9 6319.8 6332.2 6335.7 6349.4 6229.4 6291.5 6292.0 6299.7 6309.3 6049.3 6108.3 6118.6 6289.6 6313.3 4273.7 4481.2 4905.4 4933.6 4937.6 4370.7 4469.0 4475.2 4515.9 4516.3 5728.5 5738.3 5758.2 5778.0 5815.8 6193.4 6222.2 6292.8 6296.9 6340.8 5717.3 5743.9 5767.9 5778.9 5798.0 5421.5 5440.2 5446.3 5447.8 5454.5 6134.5 6375.5 6393.3 6422.8 6446.2 6291.7 6311.1 6313.0 6326.3 6337.0 6362.2 6368.9 6374.7 6384.5 6410.9 5472.2 6096.6 6175.0 6271.8 6276.7 6188.6 6283.6 6297.0 6297.2 6316.0 6152.5 6163.6 6190.3 6200.6 6201.5 5993.4 6017.3 6083.0 6109.5 6177.7 6083.3 6090.7 6102.8 6104.1 6109.2 6174.2 6192.3 6193.0 6197.8 6198.0 5906.8 5956.1 6004.7 6013.4 6106.8 05:38:46 INFO - PROCESS | 1659 | 05:38:46 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:66886.06 Median:37689.94 stddev:141914.60 (376.5%) stddev-sans-first:142225.13 05:38:46 INFO - PROCESS | 1659 | Values: 481.8 507.1 518.8 530.4 588.9 24068.0 31218.0 37768.6 38978.6 39072.6 45161.5 46351.8 46383.0 46396.5 46432.8 9507.9 9722.8 9730.4 9738.7 9742.7 8426.5 8644.8 8647.6 8648.0 8674.0 11333.9 11614.2 11663.9 11673.9 11698.3 11347.6 11529.8 11535.4 11545.0 11552.7 8430.6 11137.9 11261.4 11395.9 11456.3 39878.2 40581.2 40686.0 40756.8 40892.8 3229.2 3309.8 3586.2 3597.7 3835.5 27402.8 28759.4 28783.9 29175.1 29180.7 2749.1 2767.4 2785.3 2803.7 2884.6 499600.0 501482.0 502152.0 504137.5 504962.0 65108.3 66416.3 66454.7 66587.7 66598.3 69850.7 70021.9 70079.6 70107.6 70171.3 17278.7 17823.1 17824.5 17851.6 17856.5 13748.1 13943.8 13944.0 13960.7 13973.2 14952.6 15287.5 15316.2 15359.8 18202.9 5591.5 6601.2 6616.2 6627.1 6647.1 934.7 1024.6 1040.5 1054.9 1162.5 28671.3 28899.1 28933.0 29614.8 29736.4 1099.5 1101.2 1128.9 1151.7 1187.7 14442.9 15044.3 15576.5 15728.8 16071.8 642034.0 651261.0 651618.0 652469.7 653205.0 48898.5 57213.7 58158.0 63753.0 64344.0 1369.5 1511.5 1516.6 1527.4 1627.5 28048.9 29221.0 29556.9 29812.0 29930.9 50798.7 51207.0 51220.0 51276.7 51279.5 379372.0 415992.0 425175.0 425228.0 431718.0 37348.0 37533.1 38108.4 38464.8 38671.0 43769.6 43990.0 44669.9 45455.0 45500.0 35832.0 35960.5 37097.3 38147.8 39066.4 37815.8 38151.0 38538.8 38606.8 38859.6 37390.1 37611.2 37812.6 37999.4 38910.8 32189.8 32510.0 32673.5 32739.0 32777.0 24736.9 25035.5 25140.9 25236.4 25292.7 05:38:46 INFO - PROCESS | 1659 | 05:38:46 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35182.92 Median:11043.13 stddev:101227.87 (916.7%) stddev-sans-first:101478.06 05:38:46 INFO - PROCESS | 1659 | Values: 352.7 400.5 406.1 408.2 413.8 19667.9 20065.0 20680.8 20685.4 20687.8 19421.1 19789.6 21414.6 21451.7 21458.1 5137.5 5566.7 5571.3 5573.3 5587.7 5481.2 5501.6 5599.8 5606.9 5620.9 5412.2 5581.5 5590.0 5602.0 5606.3 5479.3 5552.4 5553.8 5584.4 5590.4 5121.2 5319.4 5356.5 5359.0 5362.1 13441.3 13685.0 13798.3 13847.9 13872.4 1920.3 2089.4 2106.0 2128.6 2218.8 9821.1 9841.8 9898.5 10284.0 10341.2 1585.7 1595.0 1658.7 1704.9 1725.9 101295.0 129187.4 129595.7 130645.0 131989.3 11051.9 11716.6 11763.8 11773.4 11809.0 13025.3 14992.3 15331.5 15409.2 15433.4 8919.0 8936.9 9000.4 9007.9 9084.7 8947.1 8960.6 9072.6 9109.2 9117.0 4637.4 4639.5 4675.1 4686.6 4728.4 1782.6 1914.6 1994.6 2006.1 2021.3 826.5 851.6 895.6 938.6 1010.7 20871.2 21255.0 21373.5 21383.6 21408.6 965.5 971.5 971.5 981.4 1035.5 10062.2 11233.2 11404.7 11456.0 11493.7 585669.0 596062.0 611460.0 615471.9 616376.0 130207.0 135216.0 138449.0 138635.0 140080.0 1019.6 1020.0 1036.9 1046.4 1047.0 18535.8 18833.0 19197.5 19253.5 19351.2 31046.4 52390.0 54013.0 54263.5 55379.4 59443.8 60214.0 66788.7 76041.6 77327.6 10995.7 11427.3 11835.7 12221.3 12459.9 7487.8 9973.9 11835.5 12827.0 13389.9 11327.9 11445.2 11472.5 11749.6 11776.6 11120.6 11266.3 11390.3 11585.9 11640.5 11209.4 11549.0 11825.0 11842.8 11895.0 12034.0 12126.7 12137.9 12293.4 12298.4 11404.0 11440.3 11500.2 11671.6 11757.5 05:38:46 INFO - PROCESS | 1659 | 05:38:46 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5690.20 Median:4834.39 stddev:3163.07 (65.4%) stddev-sans-first:3153.72 05:38:46 INFO - PROCESS | 1659 | Values: 1172.5 1608.9 1624.4 1875.8 1992.1 7848.8 8642.9 8860.7 8863.3 8866.8 8442.5 8717.3 8721.7 8724.7 8748.2 2186.1 2420.9 2442.2 2476.5 2486.7 2469.8 2501.9 2502.3 2503.2 2510.2 2397.4 2410.7 2410.9 2411.0 2417.6 2155.5 2383.2 2448.3 2451.3 2452.2 2444.4 2459.1 2475.8 2479.9 2481.1 8642.5 8715.2 8727.6 8732.2 8735.5 5208.9 5409.9 5441.3 5452.5 5455.3 3556.4 3558.5 3616.0 3663.5 3665.5 1020.8 1024.0 1040.1 1042.4 1047.4 11666.6 11670.5 11675.3 11684.0 11707.6 4811.0 4813.7 4908.1 4940.2 4954.3 10037.1 10056.9 10072.5 10084.9 10099.0 5425.1 5478.4 5480.6 5485.1 5490.5 4454.2 4635.7 4834.0 4959.8 4962.4 4038.2 4046.9 4113.5 4155.0 4160.7 1347.4 1358.6 1359.9 1363.3 1375.7 797.3 849.5 902.3 938.3 960.4 5566.8 5821.9 5843.4 5846.5 5935.1 840.5 895.2 901.5 970.2 985.9 3443.2 3494.4 3582.1 3824.0 3843.6 9172.0 10157.4 10562.2 10607.2 10613.5 10641.7 10717.0 10725.6 10776.4 10787.0 7694.8 8051.0 8110.4 8151.5 8210.0 5593.4 5825.1 5892.1 5942.6 5986.7 9673.6 9830.6 10057.7 10066.1 10077.1 9532.3 9989.9 10207.7 10334.0 10372.2 6950.1 7181.1 7400.1 7571.7 7711.5 8332.8 8337.5 8420.2 8420.2 8422.7 6287.9 7294.0 7459.5 7550.7 7620.8 7228.6 7304.0 7370.8 7392.6 7403.1 5956.2 6805.7 6889.2 6912.3 6921.1 4641.8 4649.0 4700.5 4806.1 4863.1 4610.1 4680.8 4834.8 4847.3 4906.6 05:38:46 INFO - PROCESS | 1659 | 05:38:46 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4739.08 Median:5356.72 stddev:1494.39 (27.9%) stddev-sans-first:1460.87 05:38:46 INFO - PROCESS | 1659 | Values: 294.2 306.2 311.9 312.4 315.7 5231.9 5354.2 5379.5 5386.0 5452.4 5122.8 5231.3 5313.5 5405.4 5470.4 3644.8 3876.9 3976.9 4149.8 4193.7 3645.4 3818.7 3885.3 4004.6 4156.5 4078.8 4080.1 4109.4 4117.5 4152.2 3683.9 4014.9 4048.5 4096.9 4129.0 3009.9 3569.4 3579.9 3585.9 3587.7 4370.0 4970.4 5078.4 5183.6 5211.6 4317.5 4636.6 4660.0 4671.4 4712.6 5620.9 5638.4 5656.8 5663.6 5680.7 3557.5 3679.7 3750.5 3972.4 3988.2 5486.1 5950.8 5997.9 6008.5 6024.2 4046.4 4046.4 4054.8 4060.7 4072.4 3325.7 3486.5 3487.8 3952.9 4083.2 4290.0 4654.9 4668.3 4673.4 4687.6 5202.0 5327.3 5657.3 5673.7 5687.7 1218.5 1297.3 1425.9 1456.7 1466.3 890.8 975.7 976.9 1008.6 1010.4 4900.5 5730.7 5753.2 5771.0 5779.4 5543.7 5957.9 5964.0 6041.8 6368.9 5066.6 5396.8 5654.3 5679.7 5700.3 3241.4 3328.0 3344.6 3353.7 3369.3 6653.2 6654.7 6665.9 6690.8 6744.4 6210.2 6533.4 6542.7 6546.4 6572.4 5333.5 5482.4 5552.9 6370.8 6619.2 5431.9 5576.7 5844.9 6344.8 6352.8 5339.8 5583.6 5692.4 6082.3 6311.5 6101.5 6503.9 6552.9 6628.8 6648.9 3695.8 3984.0 4276.0 4287.5 4302.5 5065.7 5442.3 5454.3 5663.8 6407.2 5359.2 5497.8 5580.8 5638.5 5706.0 4688.8 5433.7 5685.5 5721.3 5741.6 5404.4 5444.7 5512.6 5617.1 5649.0 5706.3 5706.7 5720.4 5742.7 5750.7 5048.7 5588.9 5637.5 5638.4 5680.4 05:38:46 INFO - PROCESS | 1659 | 05:38:46 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:18044.02 Median:659.95 stddev:25874.87 (3920.7%) stddev-sans-first:25914.60 05:38:46 INFO - PROCESS | 1659 | Values: 677.7 733.4 746.3 750.7 776.0 11988.5 14309.2 14926.9 15008.9 15046.0 13851.4 13910.7 13955.3 13962.7 14016.4 712.1 728.3 739.5 918.5 929.5 905.5 941.9 942.6 944.0 944.3 971.6 989.0 989.5 991.7 991.8 748.9 768.7 774.6 972.4 986.2 559.2 670.7 685.5 726.4 728.0 13574.7 13577.5 13592.9 13608.3 13647.7 11781.2 12025.6 12069.9 12117.5 12242.9 8732.0 8834.5 8917.2 9100.1 9238.6 3386.7 3462.3 3511.4 3526.9 3640.3 76194.6 77166.0 78577.6 79527.0 80314.2 9239.0 9441.5 9494.4 9505.6 9708.3 21132.5 21248.0 21279.4 21308.0 21343.8 7233.1 7307.1 7345.8 7384.7 7402.0 4048.4 4050.3 4120.6 4121.9 4239.6 3383.9 3469.5 3475.5 3477.7 3499.7 626.2 642.1 647.7 653.5 666.4 564.8 579.8 595.7 596.3 597.3 7162.5 7534.0 7579.7 7635.7 7730.1 519.5 578.2 578.7 595.9 601.3 3605.8 3686.3 3704.9 3719.4 3751.5 90318.0 91445.0 96638.5 97030.0 97479.0 76545.0 78449.8 78953.4 82340.6 83628.0 72811.0 73226.5 75788.6 76517.0 77538.0 7939.9 8014.8 8019.4 8141.7 8166.1 41091.0 44707.0 45528.0 46465.3 46712.8 51807.0 65241.2 67239.0 67585.7 67725.7 10754.8 11045.5 11178.3 11221.8 12045.9 10234.8 11823.7 11944.5 12082.5 12147.3 8723.7 10477.8 10728.5 11252.5 11834.9 10824.5 11114.2 11466.9 11752.9 12343.1 10500.1 10738.3 10862.6 10888.9 11158.6 9332.3 9449.9 9564.2 9763.7 9887.2 9099.3 9164.5 9171.8 9247.0 9303.7 05:38:46 INFO - PROCESS | 1659 | -------- Summary: end -------- 05:38:46 INFO - PROCESS | 1659 | 05:38:46 INFO - TEST-INFO | 1659: exit 0 05:38:46 INFO - TEST-OK | dromaeo_css | took 1115545ms 05:38:46 INFO - TEST-START | kraken 05:38:46 INFO - operating with platform_type : mac_ 05:38:46 INFO - Initialising browser for kraken test... 05:38:46 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmptdkxyr/profile http://localhost:49275/getInfo.html) 05:38:48 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 05:38:48 INFO - PROCESS | 1670 | colorDepth:24 05:38:48 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 05:38:48 INFO - PROCESS | 1670 | __metrics 05:38:48 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:38:48 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:38:49 INFO - TEST-INFO | 1670: exit 0 05:38:49 INFO - Browser initialized. 05:38:49 INFO - Running cycle 1/1 for kraken test... 05:38:49 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmptdkxyr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:01 INFO - PROCESS | 1672 | RSS: Main: 211361792 05:39:01 INFO - PROCESS | 1672 | 05:39:04 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 05:39:04 INFO - PROCESS | 1672 | RSS: Main: 272003072 05:39:04 INFO - PROCESS | 1672 | 05:39:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 05:39:08 INFO - PROCESS | 1672 | RSS: Main: 286298112 05:39:08 INFO - PROCESS | 1672 | 05:39:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 05:39:12 INFO - PROCESS | 1672 | RSS: Main: 373219328 05:39:12 INFO - PROCESS | 1672 | 05:39:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 05:39:14 INFO - PROCESS | 1672 | RSS: Main: 279744512 05:39:14 INFO - PROCESS | 1672 | 05:39:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:39:17 INFO - PROCESS | 1672 | RSS: Main: 281178112 05:39:17 INFO - PROCESS | 1672 | 05:39:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 05:39:21 INFO - PROCESS | 1672 | RSS: Main: 318885888 05:39:21 INFO - PROCESS | 1672 | 05:39:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 05:39:24 INFO - PROCESS | 1672 | RSS: Main: 319156224 05:39:24 INFO - PROCESS | 1672 | 05:39:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 05:39:27 INFO - PROCESS | 1672 | RSS: Main: 317775872 05:39:27 INFO - PROCESS | 1672 | 05:39:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:30 INFO - PROCESS | 1672 | RSS: Main: 309583872 05:39:30 INFO - PROCESS | 1672 | 05:39:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:32 INFO - PROCESS | 1672 | RSS: Main: 316432384 05:39:32 INFO - PROCESS | 1672 | 05:39:35 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:35 INFO - PROCESS | 1672 | RSS: Main: 318824448 05:39:35 INFO - PROCESS | 1672 | 05:39:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:39:39 INFO - PROCESS | 1672 | RSS: Main: 321470464 05:39:39 INFO - PROCESS | 1672 | 05:39:42 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:39:42 INFO - PROCESS | 1672 | RSS: Main: 318058496 05:39:42 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 05:39:44 INFO - PROCESS | 1672 | RSS: Main: 382857216 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | __start_tp_report 05:39:44 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 05:39:44 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 05:39:44 INFO - PROCESS | 1672 | |i|pagename|runs| 05:39:44 INFO - PROCESS | 1672 | |0;ai-astar;115;114;124;113;113;113;115;113;114;132 05:39:44 INFO - PROCESS | 1672 | |1;audio-beat-detection;154;124;157;156;118;141;138;129;191;144 05:39:44 INFO - PROCESS | 1672 | |2;audio-dft;184;186;186;186;186;186;184;186;186;188 05:39:44 INFO - PROCESS | 1672 | |3;audio-fft;69;102;104;184;57;56;56;64;138;55 05:39:44 INFO - PROCESS | 1672 | |4;audio-oscillator;95;97;140;76;74;87;127;80;91;165 05:39:44 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;89;86;90;87;89;86;90;87;89;85 05:39:44 INFO - PROCESS | 1672 | |6;imaging-darkroom;102;147;120;95;94;95;94;95;94;95 05:39:44 INFO - PROCESS | 1672 | |7;imaging-desaturate;84;84;86;86;86;84;83;84;85;90 05:39:44 INFO - PROCESS | 1672 | |8;json-parse-financial;59;62;64;63;63;60;71;109;94;89 05:39:44 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;62;63;67;77;73;72;66;68;68;66 05:39:44 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;80;96;104;104;106;80;80;78;82;83 05:39:44 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;149;150;134;132;135;136;137;136;238;178 05:39:44 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;167;169;167;168;169;168;176;165;166;166 05:39:44 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;67;68;70;70;69;67;68;67;67;67 05:39:44 INFO - PROCESS | 1672 | __end_tp_report 05:39:44 INFO - PROCESS | 1672 | __start_cc_report 05:39:44 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2002 05:39:44 INFO - PROCESS | 1672 | __end_cc_report 05:39:44 INFO - PROCESS | 1672 | __startTimestamp1462538384794__endTimestamp 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | ------- Summary: start ------- 05:39:44 INFO - PROCESS | 1672 | Number of tests: 14 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:116.60 Median:114.50 stddev:6.35 (5.5%) stddev-sans-first:6.70 05:39:44 INFO - PROCESS | 1672 | Values: 115.0 114.0 124.0 113.0 113.0 113.0 115.0 113.0 114.0 132.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:149.00 stddev:20.96 (14.1%) stddev-sans-first:21.99 05:39:44 INFO - PROCESS | 1672 | Values: 154.0 124.0 157.0 156.0 118.0 141.0 138.0 129.0 191.0 144.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:185.80 Median:186.00 stddev:1.14 (0.6%) stddev-sans-first:1.00 05:39:44 INFO - PROCESS | 1672 | Values: 184.0 186.0 186.0 186.0 186.0 186.0 184.0 186.0 186.0 188.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:88.50 Median:56.00 stddev:43.72 (78.1%) stddev-sans-first:45.80 05:39:44 INFO - PROCESS | 1672 | Values: 69.0 102.0 104.0 184.0 57.0 56.0 56.0 64.0 138.0 55.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:83.50 stddev:30.53 (36.6%) stddev-sans-first:32.24 05:39:44 INFO - PROCESS | 1672 | Values: 95.0 97.0 140.0 76.0 74.0 87.0 127.0 80.0 91.0 165.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.81 (2.0%) stddev-sans-first:1.87 05:39:44 INFO - PROCESS | 1672 | Values: 89.0 86.0 90.0 87.0 89.0 86.0 90.0 87.0 89.0 85.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:103.10 Median:94.50 stddev:17.40 (18.4%) stddev-sans-first:18.45 05:39:44 INFO - PROCESS | 1672 | Values: 102.0 147.0 120.0 95.0 94.0 95.0 94.0 95.0 94.0 95.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.99 (2.3%) stddev-sans-first:2.06 05:39:44 INFO - PROCESS | 1672 | Values: 84.0 84.0 86.0 86.0 86.0 84.0 83.0 84.0 85.0 90.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:73.40 Median:63.50 stddev:17.52 (27.6%) stddev-sans-first:17.79 05:39:44 INFO - PROCESS | 1672 | Values: 59.0 62.0 64.0 63.0 63.0 60.0 71.0 109.0 94.0 89.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:4.61 (6.8%) stddev-sans-first:4.31 05:39:44 INFO - PROCESS | 1672 | Values: 62.0 63.0 67.0 77.0 73.0 72.0 66.0 68.0 68.0 66.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:80.00 stddev:11.72 (14.6%) stddev-sans-first:11.94 05:39:44 INFO - PROCESS | 1672 | Values: 80.0 96.0 104.0 104.0 106.0 80.0 80.0 78.0 82.0 83.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:143.00 stddev:33.05 (23.1%) stddev-sans-first:35.03 05:39:44 INFO - PROCESS | 1672 | Values: 149.0 150.0 134.0 132.0 135.0 136.0 137.0 136.0 238.0 178.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.10 Median:168.00 stddev:3.07 (1.8%) stddev-sans-first:3.23 05:39:44 INFO - PROCESS | 1672 | Values: 167.0 169.0 167.0 168.0 169.0 168.0 176.0 165.0 166.0 166.0 05:39:44 INFO - PROCESS | 1672 | 05:39:44 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.25 (1.8%) stddev-sans-first:1.27 05:39:44 INFO - PROCESS | 1672 | Values: 67.0 68.0 70.0 70.0 69.0 67.0 68.0 67.0 67.0 67.0 05:39:44 INFO - PROCESS | 1672 | -------- Summary: end -------- 05:39:44 INFO - PROCESS | 1672 | 05:39:45 INFO - TEST-INFO | 1672: exit 0 05:39:45 INFO - TEST-OK | kraken | took 59124ms 05:39:45 INFO - SUITE-END | took 1174s 05:39:45 INFO - Completed test suite (00:19:34) 05:39:45 INFO - javascript benchmark 05:39:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.9830550847246, 3436.501707252447, 3442.948355774663, 3479.2171761353684, 3485.860565577377, 5763.193152958584, 5779.711014449276, 5833.212516310296, 5840.211571437854, 5848.9999999999945, 5857.41425857414, 5859.619124756888, 5863.706814659271, 5863.76544938202, 5869.677167755772, 5182.274481572583, 5195.844124676256, 5217.791288348461, 5229.790808367661, 5287.968846075017, 5311.760970756325, 5315.867103322409, 5317.8936421271555, 5318.069337865858, 5322.547583455392, 5238.5023422774775, 5273.947260527403, 5300.151975683892, 5306.336707911511, 5308.761105750251, 5291.629585928968, 5349.625526213166, 5353.678779273256, 5369.194620806871, 5377.274068000809, 5038.093143234217, 5147.6654017488745, 5174.870628234325, 5181.015607034651, 5241.659292145999, 5704.828855134353, 5713.428657134295, 5766.769329226826, 5767.106098554731, 5774.538036957033, 6174.691265436711, 6192.659403732793, 6197.597156184834, 6199.411055949678, 6201.565890387675, 5353.866153346197, 6089.269287685462, 6150.04674275488, 6185.536084793658, 6265.530085243579, 5134.717590532482, 5844.88310233793, 5847.152162936351, 5871.2073870027, 5968.104784282349, 6318.399752023551, 6327.082573026885, 6330.746770129143, 6332.968335158387, 6346.143270658495, 6035.973884439656, 6136.8772624547255, 6143.32423433422, 6188.628682279078, 6245.094461303085, 6292.874142517124, 6319.8104056878365, 6332.176817013758, 6335.714892829834, 6349.428551430301, 6229.3770622937345, 6291.465225455796, 6291.993281075098, 6299.748010079546, 6309.274433440213, 6049.274087109487, 6108.342071131425, 6118.558266176196, 6289.591176573508, 6313.273973493016, 4273.658107351404, 4481.1933851906315, 4905.3623029006, 4933.58064564516, 4937.580305674058, 4370.650347972218, 4469.0, 4475.172093162775, 4515.932261016087, 4516.345129956138, 5728.455796699307, 5738.282714660668, 5758.193852860602, 5778.046622307356, 5815.76736930518, 6193.4425901669065, 6222.160008398808, 6292.842678933063, 6296.937030629727, 6340.841478963062, 5717.313922329347, 5743.885122297531, 5767.85580360486, 5778.942210577925, 5798.0, 5421.512063914188, 5440.238366628675, 5446.291982042309, 5447.809326673548, 5454.481824226692, 6134.467201660849, 6375.489960803217, 6393.264774550986, 6422.775202867877, 6446.194225721785, 6291.716872740829, 6311.116443697794, 6313.0, 6326.335734747785, 6336.968315158395, 6362.236531616236, 6368.904466432915, 6374.713137908712, 6384.457321127757, 6410.903836442364, 5472.151816468454, 6096.634201947897, 6175.0, 6271.843203919939, 6276.717547710273, 6188.566800323934, 6283.622982621057, 6296.968515157578, 6297.244330680348, 6316.0, 6152.507799376129, 6163.599366041372, 6190.3190649029475, 6200.56596038273, 6201.503879689524, 5993.3706960768495, 6017.338092809699, 6083.0, 6109.495762817132, 6177.6911154443, 6083.330833608388, 6090.714246642122, 6102.847428814315, 6104.114903338902, 6109.175261339662, 6174.166487524127, 6192.318844927145, 6192.969035154795, 6197.81406557804, 6198.039303907902, 5906.793262235801, 5956.106584012433, 6004.699765011819, 6013.368596297326, 6106.847328816815], "value": 5738.868009964515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.7711586996177, 507.1353342550951, 518.8184135552556, 530.3821048478522, 588.8557303460652, 24068.0, 31218.0, 37768.62231377682, 38978.635747748835, 39072.609273907256, 45161.54838451614, 46351.768241158745, 46383.0, 46396.53603463964, 46432.767836160856, 9507.904920950788, 9722.756931076708, 9730.36752611078, 9738.707838764849, 9742.659006934759, 8426.452280601741, 8644.827103457927, 8647.567621618926, 8647.95676021619, 8673.956630216871, 11333.943330283337, 11614.245074070159, 11663.883361166365, 11673.941630291836, 11698.298102113858, 11347.602833900815, 11529.827052594217, 11535.423228838608, 11545.0, 11552.6534203974, 8430.620622072021, 11137.941895519913, 11261.43692815356, 11395.943020284929, 11456.255343402652, 39878.20243595141, 40581.18837623231, 40686.0, 40756.777296681146, 40892.79553602243, 3229.2411283348392, 3309.751768617339, 3586.2110335726097, 3597.748157628967, 3835.4822099016556, 27402.76687549045, 28759.42481150386, 28783.85608071967, 29175.124746257647, 29180.708192918013, 2749.1065403743664, 2767.377340098462, 2785.331520435101, 2803.733645303693, 2884.5961565380862, 499600.0, 501482.0, 502152.0, 504137.47931260476, 504962.0, 65108.348916511175, 66416.33583664199, 66454.66772666202, 66587.66706166536, 66598.33401666018, 69850.65074674595, 70021.94967075498, 70079.64960175166, 70107.64946175237, 70171.2982870175, 17278.740818887727, 17823.10884455772, 17824.46526604204, 17851.553711157325, 17856.46430607084, 13748.106373085717, 13943.790843137362, 13944.0, 13960.650983725489, 13973.161610303414, 14952.551423457313, 15287.541373758804, 15316.23418829054, 15359.769603455734, 18202.90898545499, 5591.4688104629995, 6601.207855057424, 6616.206055273398, 6627.138471998609, 6647.135872336566, 934.7289286107089, 1024.5594394410475, 1040.484959944817, 1054.8892366301427, 1162.4652659776598, 28671.283217919303, 28899.133026009255, 28933.0, 29614.815407383896, 29736.405271894873, 1099.5272033025715, 1101.1864685728042, 1128.9266197697123, 1151.7293436042523, 1187.655579881842, 14442.855571444152, 15044.323005464781, 15576.45482408133, 15728.842711572966, 16071.839281607268, 642034.0, 651261.0, 651618.0, 652469.7376513086, 653205.0, 48898.511014889395, 57213.71393143091, 58158.0, 63753.0, 64344.0, 1369.5480491438, 1511.5465360392059, 1516.60568252253, 1527.3661430506438, 1627.4710719016366, 28048.859755701906, 29221.0, 29556.852215738785, 29811.956581519542, 29930.85034574813, 50798.7300317495, 51207.0, 51220.0, 51276.74361628317, 51279.48720512747, 379372.0, 415992.0, 425175.0, 425228.0, 431718.0, 37348.0, 37533.06167345838, 38108.42837357497, 38464.80767596144, 38671.0, 43769.56230437655, 43990.0, 44669.88325291894, 45455.0, 45500.0, 35832.02923839109, 35960.46059309165, 37097.258054839294, 38147.80926095463, 39066.41400379053, 37815.806035903384, 38151.0, 38538.80730596329, 38606.80696596611, 38859.611403885596, 37390.06524836901, 37611.2477750438, 37812.62187378091, 37999.43000854934, 38910.80544597259, 32189.839050804596, 32510.0, 32673.52969116348, 32739.0, 32777.01668949935, 24736.876315618305, 25035.49929001446, 25140.874295628404, 25236.369090772878, 25292.74707252904], "value": 20179.958253711877, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.6790620535313, 400.46737838674557, 406.10656555577737, 408.15104582468484, 413.7931034482759, 19667.901660491712, 20064.99999999998, 20680.793192068075, 20685.37943861685, 20687.793122068775, 19421.12604932778, 19789.604207915832, 21414.571708565818, 21451.67822482664, 21458.14167433305, 5137.460566640496, 5566.693831839248, 5571.275734154554, 5573.27547418835, 5587.692676902769, 5481.232627432163, 5501.587380946425, 5599.776008959637, 5606.915896261539, 5620.932022915633, 5412.188171774245, 5581.49766521013, 5590.0, 5602.0, 5606.327240731118, 5479.2602998595075, 5552.361478429992, 5553.7778488860395, 5584.385717571064, 5590.385057643676, 5121.155009423441, 5319.388270348921, 5356.491133342326, 5358.98179345923, 5362.115250983583, 13441.26073065981, 13685.0, 13798.310084495835, 13847.930760346235, 13872.4451021959, 1920.3470819921154, 2089.40451971189, 2105.968410473844, 2128.5530038691895, 2218.7637176264, 9821.116099551004, 9841.803163936753, 9898.455584942825, 10284.0, 10341.172706183488, 1585.7066442708021, 1594.9840501594952, 1658.6931417687645, 1704.8806583539158, 1725.8619310455133, 101295.0, 129187.35406322908, 129595.7040429593, 130645.0, 131989.34005330008, 11051.944740276247, 11716.589919352782, 11763.823542646867, 11773.411329433666, 11808.996235320094, 13025.348732563334, 14992.325345359484, 15331.540053798404, 15409.152496612794, 15433.38266469351, 8919.018907920123, 8936.910630893737, 9000.36997410188, 9007.864882026775, 9084.682036128705, 8947.060558641379, 8960.641574336954, 9072.591733372014, 9109.22571581423, 9117.042710515432, 4637.373954516097, 4639.512851150581, 4675.111728771522, 4686.5782079613, 4728.432588089451, 1782.6345599152144, 1914.5787926656114, 1994.5738796760095, 2006.0671787618828, 2021.2622392826806, 826.4669288309007, 851.586980314547, 895.5656506594297, 938.5682586010512, 1010.7321559786662, 20871.174814030357, 21255.0, 21373.46566335823, 21383.572328553342, 21408.57182856334, 965.5317171171976, 971.4948226921961, 971.5385192033868, 981.3768257156614, 1035.5081336365167, 10062.202890164854, 11233.157513186543, 11404.714882127848, 11456.0, 11493.655190344303, 585669.0, 596062.0, 611460.0, 615471.922640384, 616376.0, 130207.0, 135216.0, 138449.0, 138635.0, 140079.99999999796, 1019.6431249062769, 1019.9949000254951, 1036.896310368957, 1046.3564907581783, 1046.99476502617, 18535.81464185341, 18833.0, 19197.52470306429, 19253.518662033563, 19351.225950961805, 31046.379072418877, 52390.0, 54013.0, 54263.45736542742, 55379.44620553743, 59443.81112377815, 60214.0, 66788.66422671617, 76041.61979190068, 77327.61336193283, 10995.735490418705, 11427.257228280136, 11835.704107397383, 12221.327826969602, 12459.940905022811, 7487.775366738789, 9973.850392243978, 11835.526578936746, 12827.0, 13389.933050334686, 11327.86411347798, 11445.198836081394, 11472.483738231633, 11749.647510574696, 11776.646700598996, 11120.555177792798, 11266.267692599955, 11390.316581005167, 11585.899339562728, 11640.53437862476, 11209.383483908463, 11549.0, 11825.0, 11842.822357664814, 11895.048396128115, 12034.0, 12126.687847498224, 12137.878621214028, 12293.385330733248, 12298.385080746106, 11404.0, 11440.313581185155, 11500.194986350874, 11671.591494297998, 11757.47091380907], "value": 9571.710396838256, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1172.5075468303312, 1608.8873778835482, 1624.42332971795, 1875.840553552948, 1992.1234656751028, 7848.8430231395405, 8642.91357086429, 8860.689875854347, 8863.335249856254, 8866.77833054174, 8442.535660538668, 8717.302615790737, 8721.651133954634, 8724.73825785226, 8748.168923952215, 2186.092771499834, 2420.878956052199, 2442.181869073858, 2476.4799392127675, 2486.664300319461, 2469.8271121021535, 2501.874906254689, 2502.311864237331, 2503.198976327567, 2510.184190138212, 2397.3886658901956, 2410.650455683925, 2410.915617953372, 2411.0, 2417.6010958191964, 2155.4503601581655, 2383.249276477904, 2448.3144719478487, 2451.2891261534132, 2452.239805660251, 2444.3766839456002, 2459.053264493165, 2475.777180053804, 2479.8884050217785, 2481.131603938607, 8642.524661143634, 8715.215630593277, 8727.60725767342, 8732.170443807829, 8735.475871447734, 5208.869778255536, 5409.9459005409835, 5441.2654291670515, 5452.454754524516, 5455.345358556959, 3556.3598552260582, 3558.4948825406955, 3615.963840361589, 3663.487111804323, 3665.486831843544, 1020.7907378987289, 1023.96416125436, 1040.0639424517994, 1042.3902017319876, 1047.3558761361708, 11666.591669291534, 11670.474828632732, 11675.299482031105, 11684.00685941696, 11707.648770536813, 4810.95189048112, 4813.687110337817, 4908.141075311806, 4940.209566469348, 4954.3063971044085, 10037.096661300518, 10056.949715251378, 10072.546735396925, 10084.899151008543, 10099.0, 5425.104857698436, 5478.424765399655, 5480.561555075585, 5485.122380419114, 5490.450954904558, 4454.220511410555, 4635.6523260755575, 4834.0, 4959.82640607577, 4962.379702537183, 4038.1519880824767, 4046.8751540000567, 4113.465249517543, 4155.0027993282, 4160.708750387444, 1347.3600039981127, 1358.5516779462753, 1359.8912087033013, 1363.3455941148304, 1375.7042235919325, 797.330242596222, 849.5403680022988, 902.273669695901, 938.2587755672942, 960.3817567398955, 5566.805161819398, 5821.854453638608, 5843.415658434123, 5846.532277417797, 5935.0503919372695, 840.4999025579771, 895.230102112183, 901.5492253873064, 970.1850445625568, 985.9112679858847, 3443.2252743132594, 3494.405950988338, 3582.0865679252147, 3824.0, 3843.6348546888003, 9172.0, 10157.441340726184, 10562.207834412297, 10607.204459665401, 10613.469326533643, 10641.73395665099, 10717.035466807869, 10725.570977160825, 10776.407297598707, 10787.0, 7694.769156925301, 8051.033875934925, 8110.3511719062335, 8151.5109093454585, 8209.958950205211, 5593.412691667317, 5825.097109947965, 5892.086726557391, 5942.58401911862, 5986.700664966821, 9673.613055477703, 9830.606775728891, 10057.748556286151, 10066.094051535398, 10077.14344280715, 9532.285078618992, 9989.850152247576, 10207.693769186937, 10334.018268264503, 10372.170226382023, 6950.096487456598, 7181.06646135999, 7400.074990626172, 7571.659275332513, 7711.498752581064, 8332.791680208044, 8337.541435221121, 8420.15798420153, 8420.200080992296, 8422.663093476192, 6287.937120628918, 7294.0, 7459.515131516434, 7550.735724249625, 7620.8475830484185, 7228.63856807168, 7304.0, 7370.815729606588, 7392.593407362647, 7403.1116266046265, 5956.225690660186, 6805.65971701403, 6889.207741109836, 6912.343327383891, 6921.1002569665625, 4641.791119399702, 4648.976755116203, 4700.4829468757725, 4806.086843499724, 4863.124637565272, 4610.124076425468, 4680.8127674893985, 4834.782434790513, 4847.297141914473, 4906.607471402351], "value": 4593.238780368934, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.22325061836756, 306.2145596544862, 311.8585975330292, 312.4266970109849, 315.7048159970428, 5231.86920326992, 5354.196870469426, 5379.462053794628, 5386.030514507387, 5452.372977107634, 5122.846314610568, 5231.346081739783, 5313.495217954298, 5405.4324295948945, 5470.425605311445, 3644.7995360255177, 3876.8836934891997, 3976.920461590781, 4149.7510149391055, 4193.664506839461, 3645.4117576520703, 3818.701641441909, 3885.2812229737447, 4004.579519150491, 4156.459660244164, 4078.796060196993, 4080.1431699343175, 4109.363048727452, 4117.5176936302905, 4152.231837110129, 3683.9263214735824, 4014.8594799182033, 4048.4534587830562, 4096.873359826041, 4128.979355103205, 3009.864556094981, 3569.3575156471825, 3579.856805727794, 3585.8565657373674, 3587.712982961357, 4370.0, 4970.378702662168, 5078.4413714491375, 5183.637145399824, 5211.5830733541225, 4317.460317460317, 4636.605888499482, 4660.044690838404, 4671.392718946549, 4712.6465515086165, 5620.85947851307, 5638.379778224392, 5656.773729050833, 5663.584103974006, 5680.687562184078, 3557.51973483579, 3679.6504332088675, 3750.49368335274, 3972.417928346569, 3988.162485877969, 5486.094794358926, 5950.7917222896995, 5997.880042399128, 6008.519318454513, 6024.186734790791, 4046.3728122141115, 4046.433499310099, 4054.7567145970747, 4060.715749897479, 4072.3687828386646, 3325.7339412846914, 3486.4786565568957, 3487.8430470628373, 3952.940705889414, 4083.1833633273463, 4289.978550107229, 4654.930176047362, 4668.253079507263, 4673.415823022122, 4687.601553867891, 5201.9596080783995, 5327.3074500314715, 5657.2928383952, 5673.687947162863, 5687.744051517609, 1218.524775337619, 1297.3253907967803, 1425.8645428684258, 1456.6795305032877, 1466.252211372208, 890.8241121719268, 975.7316737897135, 976.8925418203992, 1008.626808081, 1010.4493051287067, 4900.485449027872, 5730.71346432685, 5753.194552762533, 5771.0, 5779.393163717833, 5543.724943263072, 5957.910631340534, 5964.0, 6041.8218447403215, 6368.872622547523, 5066.6453348266, 5396.784128634889, 5654.293213348332, 5679.716014199274, 5700.287464066992, 3241.4489536778806, 3328.0, 3344.6153692325693, 3353.6646335366268, 3369.3490189807308, 6653.168353955755, 6654.6672666366485, 6665.933340666531, 6690.799276021728, 6744.42672372844, 6210.192674952317, 6533.411992920564, 6542.738290468423, 6546.443552298108, 6572.436749667633, 5333.546648534918, 5482.396936337, 5552.888942221133, 6370.84072898172, 6619.205695316593, 5431.945680543144, 5576.693281869536, 5844.853878652898, 6344.777932772331, 6352.841178970562, 5339.839804805862, 5583.609147359646, 5692.402297758676, 6082.330943596289, 6311.463525600376, 6101.481374083252, 6503.869922601427, 6552.868942621216, 6628.812491877765, 6648.9002664961035, 3695.8336874841234, 3984.0, 4276.037891474393, 4287.506936702319, 4302.505211900671, 5065.669791895769, 5442.346918369821, 5454.318210223722, 5663.773449062156, 6407.167068281094, 5359.249705041375, 5497.780088796403, 5580.804671836466, 5638.4925356718095, 5705.971470142623, 4688.835890743808, 5433.701146436984, 5685.459881311269, 5721.342045664802, 5741.598088133791, 5404.378496472956, 5444.733160103906, 5512.594288456418, 5617.124184312811, 5649.0, 5706.258186435737, 5706.715988902463, 5720.3707592165665, 5742.712864356682, 5750.654960702372, 5048.697078175322, 5588.888222235451, 5637.46444087811, 5638.436156384329, 5680.375158732453], "value": 4264.352924157479, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.7492327838701, 733.4059411876384, 746.2947514598703, 750.7222327738735, 775.9650815713293, 11988.460519276632, 14309.155565308913, 14926.850731492681, 15008.924955375222, 15046.0, 13851.376688049037, 13910.721785564283, 13955.302234888266, 13962.720745585108, 14016.43934242629, 712.081414974682, 728.2571776787673, 739.4528049243561, 918.545320066568, 929.45162354211, 905.5336501701629, 941.9058094190594, 942.6370847223834, 943.9946457023283, 944.2964991081653, 971.621067783565, 988.9604415823359, 989.5349185882624, 991.6578780320777, 991.8313886639289, 748.9213632568584, 768.677155594646, 774.5817258680306, 972.3873959405564, 986.1666891476691, 559.2003435087822, 670.7216505150357, 685.4619123987695, 726.3825748114114, 727.9781606551759, 13574.728505429935, 13577.524786632519, 13592.86407135926, 13608.251546164956, 13647.658808529766, 11781.234219775688, 12025.579104731292, 12069.879301206964, 12117.517128336385, 12242.877571224262, 8732.0, 8834.469931804048, 8917.152870477294, 9100.135487128713, 9238.6304547818, 3386.695197432243, 3462.2556150427527, 3511.438169892805, 3526.8765593204116, 3640.2719456108885, 76194.61902690507, 77166.0, 78577.60711196407, 79527.0, 80314.19685803125, 9239.0, 9441.52792360393, 9494.382865113745, 9505.5722492488, 9708.271879609058, 21132.47168820761, 21248.0, 21279.361619151452, 21308.04113814882, 21343.78656213418, 7233.132024157135, 7307.123145222501, 7345.77962661121, 7384.741534046282, 7401.962990185015, 4048.352263637804, 4050.270951228807, 4120.608542188487, 4121.855735049259, 4239.618434340924, 3383.9154021149175, 3469.5142680024815, 3475.548178736748, 3477.6522347765526, 3499.720022398253, 626.1890851347516, 642.1459458919719, 647.7117682631279, 653.5261935097112, 666.3802663522873, 564.7882044233412, 579.843442270593, 595.7438301530384, 596.255951342322, 597.3071237364636, 7162.4628152888745, 7534.0, 7579.734709285149, 7635.69457221716, 7730.149683534807, 519.5453977769452, 578.1674388879999, 578.6701580099303, 595.8808238352347, 601.2964831147568, 3605.837737301828, 3686.299603075407, 3704.8888533344043, 3719.3820688000806, 3751.4747935289083, 90318.0, 91445.0, 96638.51680741692, 97030.0, 97479.0, 76545.0, 78449.7580870886, 78953.42093158104, 82340.58829705813, 83628.0, 72811.0, 73226.53546929032, 75788.62105689436, 76516.99999999888, 77538.0, 7939.920600793918, 8014.759557213293, 8019.358451323997, 8141.674333026613, 8166.060902996232, 41091.0, 44707.0, 45528.0, 46465.30302045404, 46712.7664361676, 51807.0, 65241.17105947317, 67239.0, 67585.66207169129, 67725.66137169283, 10754.787459222676, 11045.502952367002, 11178.27341222818, 11221.775564488828, 12045.939770301444, 10234.795304094025, 11823.70440738954, 11944.522219111139, 12082.45628946682, 12147.331896745764, 8723.65105395777, 10477.842832357368, 10728.51721672511, 11252.493637786483, 11834.881651183723, 10824.512896919816, 11114.16643751738, 11466.910643488854, 11752.882471175179, 12343.135980481638, 10500.107490863362, 10738.302010369302, 10862.619808306672, 10888.891111089104, 11158.553657853596, 9332.25341972627, 9449.905500944902, 9564.18704410105, 9763.658271960447, 9887.209023278265, 9099.272058235458, 9164.495952722664, 9171.816563668823, 9246.9537652314, 9303.72088837336], "value": 6732.613179656404, "unit": "score"}], "name": "dromaeo_css", "value": 7258.096194673234, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 124.0, 113.0, 113.0, 113.0, 115.0, 113.0, 114.0, 132.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 157.0, 156.0, 118.0, 141.0, 138.0, 129.0, 191.0, 144.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 186.0, 186.0, 186.0, 186.0, 184.0, 186.0, 186.0, 188.0], "value": 185.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 102.0, 104.0, 184.0, 57.0, 56.0, 56.0, 64.0, 138.0, 55.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 140.0, 76.0, 74.0, 87.0, 127.0, 80.0, 91.0, 165.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 89.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 147.0, 120.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 103.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 86.0, 86.0, 84.0, 83.0, 84.0, 85.0, 90.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 64.0, 63.0, 63.0, 60.0, 71.0, 109.0, 94.0, 89.0], "value": 73.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 67.0, 77.0, 73.0, 72.0, 66.0, 68.0, 68.0, 66.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 96.0, 104.0, 104.0, 106.0, 80.0, 80.0, 78.0, 82.0, 83.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 134.0, 132.0, 135.0, 136.0, 137.0, 136.0, 238.0, 178.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 167.0, 168.0, 169.0, 168.0, 176.0, 165.0, 166.0, 166.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 70.0, 69.0, 67.0, 68.0, 67.0, 67.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1534.8999999999999, "alertThreshold": 2.0}]} 05:39:46 INFO - Return code: 0 05:39:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:39:46 ERROR - # TBPL SUCCESS # 05:39:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:39:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:39:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:39:46 INFO - 2016-05-06 05:39:46.084 screenresolution[1676:9661] starting screenresolution argv=screenresolution get 05:39:46 INFO - 2016-05-06 05:39:46.087 screenresolution[1676:9661] Display 0: 1600x1200x32@60 05:39:46 INFO - 2016-05-06 05:39:46.093 screenresolution[1677:9664] starting screenresolution argv=screenresolution list 05:39:46 INFO - Available Modes on Display 0 05:39:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:39:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:39:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:39:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:39:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:39:46 INFO - Intel Iris: 05:39:46 INFO - Chipset Model: Intel Iris 05:39:46 INFO - Type: GPU 05:39:46 INFO - Bus: Built-In 05:39:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:39:46 INFO - Vendor: Intel (0x8086) 05:39:46 INFO - Device ID: 0x0a2e 05:39:46 INFO - Revision ID: 0x0009 05:39:46 INFO - Displays: 05:39:46 INFO - Display: 05:39:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:39:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:39:46 INFO - Display Serial Number: 1600x1200 60 05:39:46 INFO - Main Display: Yes 05:39:46 INFO - Mirror: Off 05:39:46 INFO - Online: Yes 05:39:46 INFO - Rotation: Supported 05:39:46 INFO - Return code: 0 05:39:46 INFO - Running post-action listener: _resource_record_post_action 05:39:46 INFO - Running post-run listener: _resource_record_post_run 05:39:47 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 37545472; Write bytes: 650435584; Read time: 400; Write time: 1764 05:39:47 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 351076864; Write bytes: 346224640; Read time: 27556; Write time: 660 05:39:47 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 35710464; Write bytes: 293422080; Read time: 374; Write time: 1073 05:39:47 INFO - Running post-run listener: _upload_blobber_files 05:39:47 INFO - Blob upload gear active. 05:39:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:39:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:39:47 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:39:47 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:39:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:39:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:39:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:39:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:48 INFO - (blobuploader) - INFO - Done attempting. 05:39:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:39:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:39:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:49 INFO - (blobuploader) - INFO - Done attempting. 05:39:49 INFO - (blobuploader) - INFO - Iteration through files over. 05:39:49 INFO - Return code: 0 05:39:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:39:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:39:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1d04515c6c929e07d37264f53bd930a40f1d7167d4d96d26a083078f7172532fb38c4bea2b526dee4ce0a59963c5b65f535b72515d74994333a5a268bbcc7a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6b769a5b30319a987c344b385ccd2e98cdb8d302dd88be3ca287a4a5da71ae7c9a15945c5d3093e1a687ade081c3abd568e289133a08bf1181f308f0e0f4b27"} 05:39:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:39:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:39:49 INFO - Contents: 05:39:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1d04515c6c929e07d37264f53bd930a40f1d7167d4d96d26a083078f7172532fb38c4bea2b526dee4ce0a59963c5b65f535b72515d74994333a5a268bbcc7a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6b769a5b30319a987c344b385ccd2e98cdb8d302dd88be3ca287a4a5da71ae7c9a15945c5d3093e1a687ade081c3abd568e289133a08bf1181f308f0e0f4b27"} 05:39:49 INFO - Running post-run listener: copy_logs_to_upload_dir 05:39:49 INFO - Copying logs to upload dir... 05:39:49 INFO - mkdir: /builds/slave/test/build/upload/logs 05:39:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.465938 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 05:39:49.618422) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:49.621844) ========= 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.F9n4YlIoPR/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.3qAikAxoOt/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-Aurora/sha512/f1d04515c6c929e07d37264f53bd930a40f1d7167d4d96d26a083078f7172532fb38c4bea2b526dee4ce0a59963c5b65f535b72515d74994333a5a268bbcc7a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6b769a5b30319a987c344b385ccd2e98cdb8d302dd88be3ca287a4a5da71ae7c9a15945c5d3093e1a687ade081c3abd568e289133a08bf1181f308f0e0f4b27"} build_url:https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010714 build_url: 'https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1d04515c6c929e07d37264f53bd930a40f1d7167d4d96d26a083078f7172532fb38c4bea2b526dee4ce0a59963c5b65f535b72515d74994333a5a268bbcc7a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a6b769a5b30319a987c344b385ccd2e98cdb8d302dd88be3ca287a4a5da71ae7c9a15945c5d3093e1a687ade081c3abd568e289133a08bf1181f308f0e0f4b27"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:49.658250) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:49.658598) ========= 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.F9n4YlIoPR/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.3qAikAxoOt/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.004993 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:49.729978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:39:49.730264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:39:49.730586) ========= ========= Total master_lag: 0.18 =========