builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0183 starttime: 1461969142.52 results: success (0) buildid: 20160429133634 builduid: 978fae549a734e23b520b62985c9b2c1 revision: 259d41769207bbf0a55afe7475fc3a71f8a5d724 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:22.517720) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:22.518200) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:22.518482) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:22.537205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:22.537529) ========= 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.dDByRj7Z1w/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.JRaneXF1zO/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-29 15:32:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.8M=0.001s 2016-04-29 15:32:22 (17.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.191748 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:22.893280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:22.893639) ========= 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.dDByRj7Z1w/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.JRaneXF1zO/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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.026937 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:22.937398) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:22.937742) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 259d41769207bbf0a55afe7475fc3a71f8a5d724 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 259d41769207bbf0a55afe7475fc3a71f8a5d724 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dDByRj7Z1w/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.JRaneXF1zO/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-29 15:32:23,004 truncating revision to first 12 chars 2016-04-29 15:32:23,004 Setting DEBUG logging. 2016-04-29 15:32:23,004 attempt 1/10 2016-04-29 15:32:23,004 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/259d41769207?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 15:32:23,297 unpacking tar archive at: mozilla-beta-259d41769207/testing/mozharness/ program finished with exit code 0 elapsedTime=0.540401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:23.499246) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:23.499560) ========= script_repo_revision: 259d41769207bbf0a55afe7475fc3a71f8a5d724 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:23.500014) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:23.500289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 15:32:23.524344) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-04-29 15:32:23.524736) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dDByRj7Z1w/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.JRaneXF1zO/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:32:23 INFO - MultiFileLogger online at 20160429 15:32:23 in /builds/slave/test 15:32:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:32:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:32:23 INFO - {'append_to_log': False, 15:32:23 INFO - 'base_work_dir': '/builds/slave/test', 15:32:23 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:32:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:32:23 INFO - 'branch': 'Mozilla-Beta', 15:32:23 INFO - 'buildbot_json_path': 'buildprops.json', 15:32:23 INFO - 'config_files': ('talos/mac_config.py',), 15:32:23 INFO - 'default_actions': ('clobber', 15:32:23 INFO - 'read-buildbot-config', 15:32:23 INFO - 'download-and-extract', 15:32:23 INFO - 'populate-webroot', 15:32:23 INFO - 'create-virtualenv', 15:32:23 INFO - 'install', 15:32:23 INFO - 'run-tests'), 15:32:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:32:23 INFO - 'download_minidump_stackwalk': True, 15:32:23 INFO - 'download_symbols': 'ondemand', 15:32:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:32:23 INFO - 'tooltool.py': '/tools/tooltool.py', 15:32:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:32:23 INFO - '/tools/misc-python/virtualenv.py')}, 15:32:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:32:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:32:23 INFO - 'installer_path': 'installer.exe', 15:32:23 INFO - 'log_level': 'info', 15:32:23 INFO - 'log_name': 'talos', 15:32:23 INFO - 'log_to_console': True, 15:32:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:32:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:32:23 INFO - 'opt_config_files': (), 15:32:23 INFO - 'pip_index': False, 15:32:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:32:23 INFO - 'cmd': ('bash', 15:32:23 INFO - '-c', 15:32:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:32:23 INFO - 'enabled': True, 15:32:23 INFO - 'halt_on_failure': False, 15:32:23 INFO - 'name': 'check_screen_resolution'},), 15:32:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:32:23 INFO - 'cmd': ('bash', 15:32:23 INFO - '-c', 15:32:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:32:23 INFO - 'enabled': True, 15:32:23 INFO - 'halt_on_failure': False, 15:32:23 INFO - 'name': 'check_screen_resolution'},), 15:32:23 INFO - 'run_cmd_checks_enabled': True, 15:32:23 INFO - 'sps_profile': False, 15:32:23 INFO - 'sps_profile_interval': 0, 15:32:23 INFO - 'suite': 'dromaeojs', 15:32:23 INFO - 'system_bits': '32', 15:32:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:32:23 INFO - 'title': 't-yosemite-r7-0183', 15:32:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:32:23 INFO - 'use_talos_json': True, 15:32:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:32:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:32:23 INFO - 'work_dir': 'build'} 15:32:23 INFO - ##### 15:32:23 INFO - ##### Running clobber step. 15:32:23 INFO - ##### 15:32:23 INFO - Running pre-action listener: _resource_record_pre_action 15:32:23 INFO - Running main action method: clobber 15:32:23 INFO - rmtree: /builds/slave/test/build 15:32:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:32:25 INFO - Running post-action listener: _resource_record_post_action 15:32:25 INFO - ##### 15:32:25 INFO - ##### Running read-buildbot-config step. 15:32:25 INFO - ##### 15:32:25 INFO - Running pre-action listener: _resource_record_pre_action 15:32:25 INFO - Running main action method: read_buildbot_config 15:32:25 INFO - Using buildbot properties: 15:32:25 INFO - { 15:32:25 INFO - "project": "", 15:32:25 INFO - "product": "firefox", 15:32:25 INFO - "script_repo_revision": "production", 15:32:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 15:32:25 INFO - "repository": "", 15:32:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 15:32:25 INFO - "buildid": "20160429133634", 15:32:25 INFO - "pgo_build": "False", 15:32:25 INFO - "basedir": "/builds/slave/test", 15:32:25 INFO - "buildnumber": 7, 15:32:25 INFO - "slavename": "t-yosemite-r7-0183", 15:32:25 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:32:25 INFO - "platform": "yosemite_r7", 15:32:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:32:25 INFO - "branch": "mozilla-beta", 15:32:25 INFO - "repo_path": "releases/mozilla-beta", 15:32:25 INFO - "revision": "259d41769207bbf0a55afe7475fc3a71f8a5d724", 15:32:25 INFO - "stage_platform": "macosx64", 15:32:25 INFO - "builduid": "978fae549a734e23b520b62985c9b2c1", 15:32:25 INFO - "slavebuilddir": "test" 15:32:25 INFO - } 15:32:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 15:32:25 INFO - Running post-action listener: _resource_record_post_action 15:32:25 INFO - ##### 15:32:25 INFO - ##### Running download-and-extract step. 15:32:25 INFO - ##### 15:32:25 INFO - Running pre-action listener: _resource_record_pre_action 15:32:25 INFO - Running main action method: download_and_extract 15:32:25 INFO - mkdir: /builds/slave/test/build/tests 15:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:25 INFO - https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:32:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 15:32:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 15:32:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 15:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 15:32:26 INFO - Downloaded 1241 bytes. 15:32:26 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 15:32:26 INFO - Using the following test package requirements: 15:32:26 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 15:32:26 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:32:26 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 15:32:26 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:32:26 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 15:32:26 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:32:26 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:32:26 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 15:32:26 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 15:32:26 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:32:26 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 15:32:26 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:32:26 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 15:32:26 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:32:26 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 15:32:26 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 15:32:26 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:32:26 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 15:32:26 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 15:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:26 INFO - https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:32:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:32:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:32:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 15:32:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 15:32:29 INFO - Downloaded 17745673 bytes. 15:32:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:32:32 INFO - Return code: 0 15:32:32 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:32: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')]} 15:32:32 INFO - https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:32:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:32:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:32:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 15:32:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 15:32:34 INFO - Downloaded 17745673 bytes. 15:32:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:32:38 INFO - Return code: 0 15:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:38 INFO - https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:32:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 15:32:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 15:32:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 15:32:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 15:32:41 INFO - Downloaded 10845194 bytes. 15:32:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:32:41 INFO - Return code: 0 15:32:41 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')]} 15:32:41 INFO - https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 15:32:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:32:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:32:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 15:32:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 15:32:47 INFO - Downloaded 88298719 bytes. 15:32:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:32:47 INFO - mkdir: /builds/slave/test/properties 15:32:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:32:47 INFO - Writing to file /builds/slave/test/properties/build_url 15:32:47 INFO - Contents: 15:32:47 INFO - build_url:https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:32:48 INFO - Running post-action listener: _resource_record_post_action 15:32:48 INFO - Running post-action listener: set_extra_try_arguments 15:32:48 INFO - ##### 15:32:48 INFO - ##### Running populate-webroot step. 15:32:48 INFO - ##### 15:32:48 INFO - Running pre-action listener: _resource_record_pre_action 15:32:48 INFO - Running main action method: populate_webroot 15:32:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:32:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:32:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:32:48 INFO - '--tppagecycles', 15:32:48 INFO - '7'], 15:32:48 INFO - 'tests': ['tsvgm']}}, 15:32:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:32:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:32:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:32:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 15:32:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:32:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:32:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:48 INFO - 'talos_options': ['--e10s'], 15:32:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:48 INFO - 'tests': ['damp', 'tps']}, 15:32:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:48 INFO - 'talos_options': ['--e10s'], 15:32:48 INFO - 'tests': ['damp', 'tps']}, 15:32:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:32:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:32:48 INFO - 'other': {'tests': ['a11yr', 15:32:48 INFO - 'ts_paint', 15:32:48 INFO - 'tpaint', 15:32:48 INFO - 'sessionrestore', 15:32:48 INFO - 'sessionrestore_no_auto_restore', 15:32:48 INFO - 'tabpaint']}, 15:32:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:32:48 INFO - 'tests': ['a11yr', 15:32:48 INFO - 'ts_paint', 15:32:48 INFO - 'tpaint', 15:32:48 INFO - 'sessionrestore', 15:32:48 INFO - 'sessionrestore_no_auto_restore', 15:32:48 INFO - 'tabpaint']}, 15:32:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:32:48 INFO - 'tests': ['a11yr', 15:32:48 INFO - 'ts_paint', 15:32:48 INFO - 'tpaint', 15:32:48 INFO - 'sessionrestore', 15:32:48 INFO - 'sessionrestore_no_auto_restore', 15:32:48 INFO - 'tabpaint']}, 15:32:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:32:48 INFO - 'tests': ['a11yr', 15:32:48 INFO - 'ts_paint', 15:32:48 INFO - 'tpaint', 15:32:48 INFO - 'sessionrestore', 15:32:48 INFO - 'sessionrestore_no_auto_restore', 15:32:48 INFO - 'tabpaint']}, 15:32:48 INFO - 'other_l64': {'tests': ['a11yr', 15:32:48 INFO - 'ts_paint', 15:32:48 INFO - 'tpaint', 15:32:48 INFO - 'sessionrestore', 15:32:48 INFO - 'sessionrestore_no_auto_restore', 15:32:48 INFO - 'tabpaint']}, 15:32:48 INFO - 'other_nol64': {'tests': ['a11yr', 15:32:48 INFO - 'ts_paint', 15:32:48 INFO - 'tpaint', 15:32:48 INFO - 'sessionrestore', 15:32:48 INFO - 'sessionrestore_no_auto_restore', 15:32:48 INFO - 'tabpaint']}, 15:32:48 INFO - 'svgr': {'tests': ['tsvgx', 15:32:48 INFO - 'tsvgr_opacity', 15:32:48 INFO - 'tart', 15:32:48 INFO - 'tscrollx', 15:32:48 INFO - 'cart']}, 15:32:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:32:48 INFO - 'tests': ['tsvgx', 15:32:48 INFO - 'tsvgr_opacity', 15:32:48 INFO - 'tart', 15:32:48 INFO - 'tscrollx', 15:32:48 INFO - 'cart']}, 15:32:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:48 INFO - 'tests': ['tp5o']}, 15:32:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:48 INFO - 'talos_options': ['--e10s'], 15:32:48 INFO - 'tests': ['tp5o']}, 15:32:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:48 INFO - 'talos_options': ['--xperf_path', 15:32:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:48 INFO - 'tests': ['tp5n']}, 15:32:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:48 INFO - 'talos_options': ['--e10s', 15:32:48 INFO - '--xperf_path', 15:32:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:48 INFO - 'tests': ['tp5n']}}, 15:32:48 INFO - 'talos.zip': {'path': '', 15:32:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:32:48 INFO - Running post-action listener: _resource_record_post_action 15:32:48 INFO - ##### 15:32:48 INFO - ##### Running create-virtualenv step. 15:32:48 INFO - ##### 15:32:48 INFO - Running pre-action listener: _resource_record_pre_action 15:32:48 INFO - Running main action method: create_virtualenv 15:32:48 INFO - Creating virtualenv /builds/slave/test/build/venv 15:32:48 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 15:32:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:32:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:32:48 INFO - Using real prefix '/tools/python27' 15:32:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:32:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:32:52 INFO - Installing pip.................done. 15:32:52 INFO - Return code: 0 15:32:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:32: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')]} 15:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32: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')]} 15:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:52 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 0x106597a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10659e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e2582a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, '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.dDByRj7Z1w/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.JRaneXF1zO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:32:52 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 15:32:52 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 15:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:52 INFO - 'HOME': '/Users/cltbld', 15:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:52 INFO - 'LOGNAME': 'cltbld', 15:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:52 INFO - 'MOZ_NO_REMOTE': '1', 15:32:52 INFO - 'NO_EM_RESTART': '1', 15:32:52 INFO - 'PAGER': '/bin/cat', 15:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:52 INFO - 'PWD': '/builds/slave/test', 15:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:52 INFO - 'SHELL': '/bin/bash', 15:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:32:52 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:52 INFO - 'USER': 'cltbld', 15:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:52 INFO - 'XPC_FLAGS': '0x0', 15:32:52 INFO - 'XPC_SERVICE_NAME': '0', 15:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:52 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 15:32:52 INFO - Cleaning up... 15:32:52 INFO - Return code: 0 15:32:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:32: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')]} 15:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32: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')]} 15:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:52 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 0x106597a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10659e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e2582a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, '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.dDByRj7Z1w/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.JRaneXF1zO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:32:52 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 15:32:52 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 15:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:52 INFO - 'HOME': '/Users/cltbld', 15:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:52 INFO - 'LOGNAME': 'cltbld', 15:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:52 INFO - 'MOZ_NO_REMOTE': '1', 15:32:52 INFO - 'NO_EM_RESTART': '1', 15:32:52 INFO - 'PAGER': '/bin/cat', 15:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:52 INFO - 'PWD': '/builds/slave/test', 15:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:52 INFO - 'SHELL': '/bin/bash', 15:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:32:52 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:52 INFO - 'USER': 'cltbld', 15:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:52 INFO - 'XPC_FLAGS': '0x0', 15:32:52 INFO - 'XPC_SERVICE_NAME': '0', 15:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:52 INFO - Downloading/unpacking psutil>=0.7.1 15:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:32:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:55 INFO - Installing collected packages: psutil 15:32:55 INFO - Running setup.py install for psutil 15:32:55 INFO - building 'psutil._psutil_osx' extension 15:32:55 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 15:32:56 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 15:32:56 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 15:32:56 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 15:32:56 INFO - building 'psutil._psutil_posix' extension 15:32:56 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 15:32:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:32:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:32:56 INFO - ^ 15:32:56 INFO - 1 warning generated. 15:32:56 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 15:32:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:56 INFO - Successfully installed psutil 15:32:56 INFO - Cleaning up... 15:32:56 INFO - Return code: 0 15:32:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:32: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')]} 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32: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')]} 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:56 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 0x106597a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10659e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e2582a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, '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.dDByRj7Z1w/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.JRaneXF1zO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:32:56 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 15:32:56 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 15:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:56 INFO - 'HOME': '/Users/cltbld', 15:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:56 INFO - 'LOGNAME': 'cltbld', 15:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:56 INFO - 'MOZ_NO_REMOTE': '1', 15:32:56 INFO - 'NO_EM_RESTART': '1', 15:32:56 INFO - 'PAGER': '/bin/cat', 15:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:56 INFO - 'PWD': '/builds/slave/test', 15:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:56 INFO - 'SHELL': '/bin/bash', 15:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:32:56 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:56 INFO - 'USER': 'cltbld', 15:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:56 INFO - 'XPC_FLAGS': '0x0', 15:32:56 INFO - 'XPC_SERVICE_NAME': '0', 15:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:32:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:32:59 INFO - Installing collected packages: mozsystemmonitor 15:32:59 INFO - Running setup.py install for mozsystemmonitor 15:32:59 INFO - Successfully installed mozsystemmonitor 15:32:59 INFO - Cleaning up... 15:32:59 INFO - Return code: 0 15:32:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:59 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 0x106597a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10659e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e2582a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, '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.dDByRj7Z1w/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.JRaneXF1zO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:32:59 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 15:32:59 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 15:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:59 INFO - 'HOME': '/Users/cltbld', 15:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:59 INFO - 'LOGNAME': 'cltbld', 15:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:59 INFO - 'MOZ_NO_REMOTE': '1', 15:32:59 INFO - 'NO_EM_RESTART': '1', 15:32:59 INFO - 'PAGER': '/bin/cat', 15:32:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:59 INFO - 'PWD': '/builds/slave/test', 15:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:59 INFO - 'SHELL': '/bin/bash', 15:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:32:59 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:59 INFO - 'USER': 'cltbld', 15:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:59 INFO - 'XPC_FLAGS': '0x0', 15:32:59 INFO - 'XPC_SERVICE_NAME': '0', 15:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:00 INFO - Downloading/unpacking blobuploader==1.2.4 15:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:02 INFO - Downloading blobuploader-1.2.4.tar.gz 15:33:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:33:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:33:02 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 15:33:02 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 15:33:02 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 15:33:02 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 15:33:02 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 15:33:02 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 15:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:33:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:33:03 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 15:33:03 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 15:33:03 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 15:33:03 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 15:33:03 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 15:33:03 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 15:33:03 INFO - Downloading docopt-0.6.1.tar.gz 15:33:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:33:03 INFO - Installing collected packages: blobuploader, requests, docopt 15:33:03 INFO - Running setup.py install for blobuploader 15:33:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:33:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:33:03 INFO - Running setup.py install for requests 15:33:04 INFO - Running setup.py install for docopt 15:33:04 INFO - Successfully installed blobuploader requests docopt 15:33:04 INFO - Cleaning up... 15:33:04 INFO - Return code: 0 15:33:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:04 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 0x106597a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10659e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e2582a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, '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.dDByRj7Z1w/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.JRaneXF1zO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:33:04 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 15:33:04 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 15:33:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:33:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:04 INFO - 'HOME': '/Users/cltbld', 15:33:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:04 INFO - 'LOGNAME': 'cltbld', 15:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:04 INFO - 'MOZ_NO_REMOTE': '1', 15:33:04 INFO - 'NO_EM_RESTART': '1', 15:33:04 INFO - 'PAGER': '/bin/cat', 15:33:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:04 INFO - 'PWD': '/builds/slave/test', 15:33:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:04 INFO - 'SHELL': '/bin/bash', 15:33:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:33:04 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:33:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:04 INFO - 'USER': 'cltbld', 15:33:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:04 INFO - 'XPC_FLAGS': '0x0', 15:33:04 INFO - 'XPC_SERVICE_NAME': '0', 15:33:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:33:04 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-Po3sGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-uE5qyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-FnIxP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-55CZbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-pJH909-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-EqDOJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-R0pxHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-cXKiu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-eYGY_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-WJN6ZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:33:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-7thS1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-R5Pw9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-8E676N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-lGkkFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-imHrkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-ZYJTUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-5aqaWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:33:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:33:06 INFO - Running setup.py install for manifestparser 15:33:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:33:06 INFO - Running setup.py install for mozcrash 15:33:06 INFO - Running setup.py install for mozdebug 15:33:07 INFO - Running setup.py install for mozdevice 15:33:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:33:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:33:07 INFO - Running setup.py install for mozfile 15:33:07 INFO - Running setup.py install for mozhttpd 15:33:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:33:07 INFO - Running setup.py install for mozinfo 15:33:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:33:07 INFO - Running setup.py install for mozInstall 15:33:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:33:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:33:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:33:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:33:07 INFO - Running setup.py install for mozleak 15:33:08 INFO - Running setup.py install for mozlog 15:33:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:33:08 INFO - Running setup.py install for moznetwork 15:33:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:33:08 INFO - Running setup.py install for mozprocess 15:33:08 INFO - Running setup.py install for mozprofile 15:33:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:33:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:33:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:33:08 INFO - Running setup.py install for mozrunner 15:33:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:33:08 INFO - Running setup.py install for mozscreenshot 15:33:08 INFO - Running setup.py install for moztest 15:33:09 INFO - Running setup.py install for mozversion 15:33:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:33:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:33:09 INFO - Cleaning up... 15:33:09 INFO - Return code: 0 15:33:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:33: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')]} 15:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33: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')]} 15:33:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:09 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 0x106597a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10659e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e2582a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, '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.dDByRj7Z1w/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.JRaneXF1zO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:33:09 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 15:33:09 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 15:33:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:33:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:09 INFO - 'HOME': '/Users/cltbld', 15:33:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:09 INFO - 'LOGNAME': 'cltbld', 15:33:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:09 INFO - 'MOZ_NO_REMOTE': '1', 15:33:09 INFO - 'NO_EM_RESTART': '1', 15:33:09 INFO - 'PAGER': '/bin/cat', 15:33:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:09 INFO - 'PWD': '/builds/slave/test', 15:33:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:09 INFO - 'SHELL': '/bin/bash', 15:33:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:33:09 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:33:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:09 INFO - 'USER': 'cltbld', 15:33:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:09 INFO - 'XPC_FLAGS': '0x0', 15:33:09 INFO - 'XPC_SERVICE_NAME': '0', 15:33:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:33:09 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-wdOtID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:33:09 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)) 15:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:33:09 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-0rnNoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:33:09 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)) 15:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:33:09 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-71NfM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:33:09 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)) 15:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:33:09 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-S2BFf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:33:09 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)) 15:33:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:33:09 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-vSI13u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:33:10 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)) 15:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:33:10 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-oEZq4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:33:10 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)) 15:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:33:10 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-4sGCZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:33:10 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)) 15:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:33:10 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-9l8mtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:33:10 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)) 15:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:33:10 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-B8ESVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:33:10 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)) 15:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:33:10 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-Aw0k8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:33:10 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)) 15:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:33:10 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-FRv6Fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:33:10 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)) 15:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:33:10 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-XY7a08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:33:10 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)) 15:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:33:10 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-7XjdVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:33:10 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)) 15:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:33:10 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-zDLTKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:33:11 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)) 15:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:11 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-JcRhQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:11 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)) 15:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:33:11 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-WRG1cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:33:11 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)) 15:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:33:11 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-jk1Pag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:33:11 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)) 15:33:11 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)) 15:33:11 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)) 15:33:11 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)) 15:33:11 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)) 15:33:11 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)) 15:33:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:33: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 15:33: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 15:33: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 15:33: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 15:33: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 15:33: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 15:33:13 INFO - Downloading blessings-1.6.tar.gz 15:33:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:33:13 INFO - Installing collected packages: blessings 15:33:13 INFO - Running setup.py install for blessings 15:33:14 INFO - Successfully installed blessings 15:33:14 INFO - Cleaning up... 15:33:14 INFO - Return code: 0 15:33:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:33:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:33:14 INFO - Reading from file tmpfile_stdout 15:33:14 INFO - Current package versions: 15:33:14 INFO - blessings == 1.6 15:33:14 INFO - blobuploader == 1.2.4 15:33:14 INFO - docopt == 0.6.1 15:33:14 INFO - manifestparser == 1.1 15:33:14 INFO - mozInstall == 1.12 15:33:14 INFO - mozcrash == 0.17 15:33:14 INFO - mozdebug == 0.1 15:33:14 INFO - mozdevice == 0.48 15:33:14 INFO - mozfile == 1.2 15:33:14 INFO - mozhttpd == 0.7 15:33:14 INFO - mozinfo == 0.9 15:33:14 INFO - mozleak == 0.1 15:33:14 INFO - mozlog == 3.1 15:33:14 INFO - moznetwork == 0.27 15:33:14 INFO - mozprocess == 0.22 15:33:14 INFO - mozprofile == 0.28 15:33:14 INFO - mozrunner == 6.11 15:33:14 INFO - mozscreenshot == 0.1 15:33:14 INFO - mozsystemmonitor == 0.0 15:33:14 INFO - moztest == 0.7 15:33:14 INFO - mozversion == 1.4 15:33:14 INFO - psutil == 3.1.1 15:33:14 INFO - requests == 1.2.3 15:33:14 INFO - wsgiref == 0.1.2 15:33:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:33: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')]} 15:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33: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')]} 15:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:14 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 0x106597a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10659e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e2582a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, '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.dDByRj7Z1w/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.JRaneXF1zO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:33:14 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 15:33:14 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 15:33:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:33:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:14 INFO - 'HOME': '/Users/cltbld', 15:33:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:14 INFO - 'LOGNAME': 'cltbld', 15:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:14 INFO - 'MOZ_NO_REMOTE': '1', 15:33:14 INFO - 'NO_EM_RESTART': '1', 15:33:14 INFO - 'PAGER': '/bin/cat', 15:33:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:14 INFO - 'PWD': '/builds/slave/test', 15:33:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:14 INFO - 'SHELL': '/bin/bash', 15:33:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:33:14 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:33:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:14 INFO - 'USER': 'cltbld', 15:33:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:14 INFO - 'XPC_FLAGS': '0x0', 15:33:14 INFO - 'XPC_SERVICE_NAME': '0', 15:33:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:15 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)) 15:33:15 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)) 15:33:15 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)) 15:33:15 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)) 15:33:15 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)) 15:33:15 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)) 15:33:15 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)) 15:33:15 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)) 15:33:15 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)) 15:33:15 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)) 15:33:15 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)) 15:33:15 INFO - Cleaning up... 15:33:15 INFO - Return code: 0 15:33:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:33:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:15 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 0x106597a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10659e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e2582a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, '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.dDByRj7Z1w/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.JRaneXF1zO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:33:15 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 15:33:15 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 15:33:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:33:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:15 INFO - 'HOME': '/Users/cltbld', 15:33:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:15 INFO - 'LOGNAME': 'cltbld', 15:33:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:15 INFO - 'MOZ_NO_REMOTE': '1', 15:33:15 INFO - 'NO_EM_RESTART': '1', 15:33:15 INFO - 'PAGER': '/bin/cat', 15:33:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:15 INFO - 'PWD': '/builds/slave/test', 15:33:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:15 INFO - 'SHELL': '/bin/bash', 15:33:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:33:15 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:33:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:15 INFO - 'USER': 'cltbld', 15:33:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:15 INFO - 'XPC_FLAGS': '0x0', 15:33:15 INFO - 'XPC_SERVICE_NAME': '0', 15:33:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:15 INFO - Downloading/unpacking jsonschema 15:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:33:18 INFO - Downloading/unpacking functools32 (from jsonschema) 15:33:18 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 15:33:18 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 15:33:18 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 15:33:18 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 15:33:18 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 15:33:18 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 15:33:18 INFO - Downloading functools32-3.2.3-2.tar.gz 15:33:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:33:18 INFO - warning: no files found matching '*.txt' 15:33:18 INFO - no previously-included directories found matching 'build' 15:33:18 INFO - no previously-included directories found matching 'dist' 15:33:18 INFO - no previously-included directories found matching '.git*' 15:33:18 INFO - Installing collected packages: jsonschema, functools32 15:33:18 INFO - Running setup.py install for functools32 15:33:18 INFO - warning: no files found matching '*.txt' 15:33:18 INFO - no previously-included directories found matching 'build' 15:33:18 INFO - no previously-included directories found matching 'dist' 15:33:18 INFO - no previously-included directories found matching '.git*' 15:33:18 INFO - Successfully installed jsonschema functools32 15:33:18 INFO - Cleaning up... 15:33:18 INFO - Return code: 0 15:33:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:33: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')]} 15:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33: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')]} 15:33:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:18 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 0x106597a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10659e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7e2582a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, '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.dDByRj7Z1w/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.JRaneXF1zO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 15:33:18 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 15:33:18 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 15:33:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:33:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:18 INFO - 'HOME': '/Users/cltbld', 15:33:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:18 INFO - 'LOGNAME': 'cltbld', 15:33:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:18 INFO - 'MOZ_NO_REMOTE': '1', 15:33:18 INFO - 'NO_EM_RESTART': '1', 15:33:18 INFO - 'PAGER': '/bin/cat', 15:33:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:18 INFO - 'PWD': '/builds/slave/test', 15:33:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:18 INFO - 'SHELL': '/bin/bash', 15:33:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:33:18 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:33:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:18 INFO - 'USER': 'cltbld', 15:33:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:18 INFO - 'XPC_FLAGS': '0x0', 15:33:18 INFO - 'XPC_SERVICE_NAME': '0', 15:33:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:19 INFO - Downloading/unpacking flake8 15:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:33:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:33:21 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 15:33:21 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 15:33:21 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 15:33:21 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 15:33:21 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 15:33:21 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 15:33:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:33:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:33:21 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 15:33:21 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 15:33:21 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 15:33:21 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 15:33:21 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 15:33:21 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 15:33:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:33:22 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 15:33:22 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 15:33:22 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 15:33:22 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 15:33:22 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 15:33:22 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 15:33:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:33:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:33:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:33:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:33:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:33:22 INFO - no previously-included directories found matching 'docs/_build' 15:33:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:33:22 INFO - Running setup.py install for pep8 15:33:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:33:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:33:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:33:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:33:23 INFO - no previously-included directories found matching 'docs/_build' 15:33:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:33:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:33:23 INFO - Cleaning up... 15:33:23 INFO - Return code: 0 15:33:23 INFO - Running post-action listener: _resource_record_post_action 15:33:23 INFO - Running post-action listener: _start_resource_monitoring 15:33:23 INFO - Starting resource monitoring. 15:33:23 INFO - ##### 15:33:23 INFO - ##### Running install step. 15:33:23 INFO - ##### 15:33:23 INFO - Running pre-action listener: _resource_record_pre_action 15:33:23 INFO - Running main action method: install 15:33:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:33:23 INFO - Reading from file tmpfile_stdout 15:33:23 INFO - Detecting whether we're running mozinstall >=1.0... 15:33:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:33:23 INFO - Reading from file tmpfile_stdout 15:33:23 INFO - Output received: 15:33:23 INFO - Usage: mozinstall [options] installer 15:33:23 INFO - Options: 15:33:23 INFO - -h, --help show this help message and exit 15:33:23 INFO - -d DEST, --destination=DEST 15:33:23 INFO - Directory to install application into. [default: 15:33:23 INFO - "/builds/slave/test"] 15:33:23 INFO - --app=APP Application being installed. [default: firefox] 15:33:23 INFO - mkdir: /builds/slave/test/build/application 15:33:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:33:46 INFO - Reading from file tmpfile_stdout 15:33:46 INFO - Output received: 15:33:46 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:33:46 INFO - Running post-action listener: _resource_record_post_action 15:33:46 INFO - ##### 15:33:46 INFO - ##### Running run-tests step. 15:33:46 INFO - ##### 15:33:46 INFO - Running pre-action listener: _resource_record_pre_action 15:33:46 INFO - Running main action method: run_tests 15:33:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:33:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:33:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:33:46 INFO - 2016-04-29 15:33:46.110 screenresolution[1640:7406] starting screenresolution argv=screenresolution get 15:33:46 INFO - 2016-04-29 15:33:46.113 screenresolution[1640:7406] Display 0: 1600x1200x32@60 15:33:46 INFO - 2016-04-29 15:33:46.119 screenresolution[1641:7409] starting screenresolution argv=screenresolution list 15:33:46 INFO - Available Modes on Display 0 15:33:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:33:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:33:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:33:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:33:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:33:46 INFO - Intel Iris: 15:33:46 INFO - Chipset Model: Intel Iris 15:33:46 INFO - Type: GPU 15:33:46 INFO - Bus: Built-In 15:33:46 INFO - VRAM (Dynamic, Max): 1536 MB 15:33:46 INFO - Vendor: Intel (0x8086) 15:33:46 INFO - Device ID: 0x0a2e 15:33:46 INFO - Revision ID: 0x0009 15:33:46 INFO - Displays: 15:33:46 INFO - Display: 15:33:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:33:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:33:46 INFO - Display Serial Number: 1600x1200 60 15:33:46 INFO - Main Display: Yes 15:33:46 INFO - Mirror: Off 15:33:46 INFO - Online: Yes 15:33:46 INFO - Rotation: Supported 15:33:46 INFO - Return code: 0 15:33:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:33:46 INFO - Python 2.7.3 15:33:46 INFO - Return code: 0 15:33:46 INFO - grabbing minidump binary from tooltool 15:33:46 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')]} 15:33:46 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 0x10643bd30>, '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 0x10629fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106432b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:33:46 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 15:33:46 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 15:33:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:33:46 INFO - Return code: 0 15:33:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:33:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:33:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:33:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:33:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:33:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:33:47 INFO - Return code: 0 15:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0183', '--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 15:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0183 --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 15:33:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dDByRj7Z1w/Render', 15:33:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:47 INFO - 'HOME': '/Users/cltbld', 15:33:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:47 INFO - 'LOGNAME': 'cltbld', 15:33:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:33:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:47 INFO - 'MOZ_NO_REMOTE': '1', 15:33:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:33:47 INFO - 'NO_EM_RESTART': '1', 15:33:47 INFO - 'PAGER': '/bin/cat', 15:33:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:47 INFO - 'PWD': '/builds/slave/test', 15:33:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:33:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:47 INFO - 'SHELL': '/bin/bash', 15:33:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JRaneXF1zO/Listeners', 15:33:47 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 15:33:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:47 INFO - 'USER': 'cltbld', 15:33:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:47 INFO - 'XPC_FLAGS': '0x0', 15:33:47 INFO - 'XPC_SERVICE_NAME': '0', 15:33:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0183', '--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 15:33:47 INFO - mozversion application_buildid: 20160429133634 15:33:47 INFO - mozversion application_changeset: 259d41769207bbf0a55afe7475fc3a71f8a5d724 15:33:47 INFO - mozversion application_display_name: Firefox 15:33:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:33:47 INFO - mozversion application_name: Firefox 15:33:47 INFO - mozversion application_remotingname: firefox 15:33:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:33:47 INFO - mozversion application_vendor: Mozilla 15:33:47 INFO - mozversion application_version: 47.0 15:33:47 INFO - mozversion platform_buildid: 20160429133634 15:33:47 INFO - mozversion platform_changeset: 259d41769207bbf0a55afe7475fc3a71f8a5d724 15:33:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:33:47 INFO - mozversion platform_version: 47.0 15:33:47 INFO - using testdate: 1461969227 15:33:47 INFO - actual date: 1461969227 15:33:47 INFO - starting webserver on 'localhost:49275' 15:33:47 INFO - SUITE-START | Running 2 tests 15:33:47 INFO - TEST-START | dromaeo_css 15:33:47 INFO - operating with platform_type : mac_ 15:33:47 INFO - Initialising browser for dromaeo_css test... 15:33:47 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpsSg4fK/profile http://localhost:49275/getInfo.html) 15:33:50 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 15:33:50 INFO - PROCESS | 1659 | colorDepth:24 15:33:50 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 15:33:50 INFO - PROCESS | 1659 | __metrics 15:33:50 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:33:50 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:33:50 INFO - TEST-INFO | 1659: exit 0 15:33:50 INFO - Browser initialized. 15:33:50 INFO - Running cycle 1/1 for dromaeo_css test... 15:33:50 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpsSg4fK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:34:02 INFO - PROCESS | 1662 | RSS: Main: 156442624 15:34:02 INFO - PROCESS | 1662 | 15:34:53 INFO - PROCESS | 1662 | 1461969293585 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] 15:37:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 15:37:04 INFO - PROCESS | 1662 | RSS: Main: 264568832 15:37:04 INFO - PROCESS | 1662 | 15:40:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 15:40:07 INFO - PROCESS | 1662 | RSS: Main: 295890944 15:40:07 INFO - PROCESS | 1662 | 15:43:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 15:43:10 INFO - PROCESS | 1662 | RSS: Main: 297390080 15:43:10 INFO - PROCESS | 1662 | 15:46:12 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 15:46:12 INFO - PROCESS | 1662 | RSS: Main: 253788160 15:46:12 INFO - PROCESS | 1662 | 15:49:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 15:49:16 INFO - PROCESS | 1662 | RSS: Main: 256970752 15:49:16 INFO - PROCESS | 1662 | 15:52:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 15:52:18 INFO - PROCESS | 1662 | RSS: Main: 328126464 15:52:18 INFO - PROCESS | 1662 | 15:52:18 INFO - PROCESS | 1662 | __start_tp_report 15:52:18 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 15:52:18 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 15:52:18 INFO - PROCESS | 1662 | |i|pagename|runs| 15:52:18 INFO - PROCESS | 1662 | |0;dojo.html;3561.269939662369;3657.1040095176686;3664.926701465972;3684.6131156228603;3722.1997270586817;5971.522278217743;5972.014617588098;5979.521638268939;5980.222571065756;5989.730462129204;5975.402459754023;5999.970000150004;6005.549583781212;6006.129111278862;6020.909686354697;5230.215467679859;5336.759845806938;5345.49217824308;5395.325584301962;5446.346438427394;5433.483819037205;5449.945500545004;5468.043092458824;5468.0977638689765;5473.2063850741815;5382.542483888874;5452.863678408032;5453.100238460649;5466.535344495723;5471.37079235889;5451.318585176853;5465.480779325958;5479.424660410639;5487.533559647435;5505.008409736356;5201.635885488017;5276.709780962045;5390.1914712793005;5401.70290634015;5429.131338985783;5916.083007133901;5933.021051526493;5978.282606087254;5994.7901823436405;6014.609050411709;6189.783357582508;6278.340774218733;6296.464800491964;6304;6315.968420157916;6295.118683384329;6319.210098737658;6323;6398.840028999312;6407.455366293871;6101.603395779304;6128.1114238435175;6138.1099740537375;6139.846503837351;6143.508519318489;6401.135846660642;6489.70796314167;6522.086907832907;6522.543421960418;6534.37923397286;6172.629642221481;6235.064740288903;6240.063990401476;6244;6257.593256438408;6342.904856427157;6344.936550634527;6398.744050238031;6411.198600174978;6435.324290949477;5663.830085097371;5960.8807823844145;6277.309495955441;6288.339724328971;6289.213848268966;6189.535784816067;6284.591501552384;6296.779612713624;6298.937010629927;6319.431251187415;5033.320501732219;5038.571721403649;5044.445111037783;5045.217991211368;5059.822906198266;4471.776411179428;4587.42657167854;4594.908101837945;4597.3333866589155;4601.884952876205;6020.789272375446;6021.066734656135;6100.054491553817;6115.386344001658;6123.693815309217;6301.842453938688;6364.840878978062;6368.64972426521;6376.075469056961;6382.32985536512;5952.315483719428;6023.608465449732;6117.938820611826;6134.693265336716;6139.2325959255095;5481;5507.366652834895;5551.333839939152;5566.081596536526;5567.24842146305;6516.446102081376;6516.902246466307;6530.902036469457;6543.378379053982;6550.7707230246715;6217.222847144107;6309.810705678837;6321.747130114928;6325.525585581007;6338.271098823694;6489.707963141575;6553.049807777941;6561.442277406474;6585.045168450643;6590.9670451647435;6250.593711408744;6303.716332765129;6320.6839658016;6323.652199129092;6352.205974253218;5917.615355001911;6229.006717850244;6297.433231009048;6317.6841157941;6320.778772742932;4698.271767875985;6292.559520833681;6303.621782693053;6313.747450102128;6325.304216536269;6272.749090036347;6283.340249273943;6299.370062993848;6312.337204593451;6326.493880489642;6108.236470441195;6111.205543279345;6166.969165154145;6243.250809902841;6261.467775239155;6260.68696565179;6282.277538083179;6299.685015749286;6301.873962520815;6302.873942521032;5082.796688132581;6078.6048906820915;6146.231721034871;6167.259928808392;6191 15:52:18 INFO - PROCESS | 1662 | |1;ext.html;506.54157987021756;573.4036601933989;627.5230824573325;658.7595527632415;668.1014036121416;31979;40750.59249407505;40975.59024409755;41031.384529232084;41207.38188927169;43861;44320.55679443204;44384.89037774049;44626.77686611562;44780.77609611947;9708.708738737849;10047.94976025119;10050.296479246455;10055.044770746767;10076.697699069004;9717.271204659679;9737.51312434379;9866.260030497742;9896.307258491908;10002.699919002405;9943.701688949306;9991.000899910023;10026.649067282646;10033.799324013515;10037.498125093753;9931.404115753041;10008.349457285252;10009.149222316111;10014.098731114162;10040.196784257241;9854.753631159207;10000.549975251131;10006.399616023062;10021.699349019542;10022.89977100227;42518.362224566656;42934;42962.78518607418;42973.35539966903;42993.785031074956;3388.423967925459;3498.440249560058;3589.120665436985;3609.350316942949;3617.8733744318824;29040.70959290401;29884.59001433335;29937;30707.69292307093;30764.846175768977;2807.157852644219;2871.9712802871913;2874.928126796826;2922.0649392194505;2943.5290353543332;405520;411492;412245.8775412238;412526;413048;47415.762921185174;68627.65686171537;70050;70063.64968175229;70148.64925675339;39839.60160398359;47795;47948.280775787694;48008.5199148011;48244.517554824706;17405.390811321544;17543.385981490588;17614.823851761575;17628.471145865646;17637.7354339685;14016.229107399191;14022.22877741734;14111.647208819862;14114.647133821736;14120.646983825487;15247.237638118271;15407.922960385127;15540.766888496682;15550.066995980278;15701.685966280611;6404.967975160188;6505.243584232231;6544.59680965895;6552.967235163889;6667.466602671775;960.8991055939166;996.277698668474;1063.680895731293;1072.9088027517748;1194.9006913639473;31017.91437299684;31061.68938310633;31381.15525611801;31441.84279078636;31736.206594835316;1142.5486932661552;1199.7060720123452;1232.211384713784;1249.4564864283884;1261.1802328486558;15777.763333550007;15823.446179383667;15906.9204653976;16154.353825847069;16265.024098554124;508072;515744;516633;517534;519093;61996.690016549626;62578.687106565085;62648.686756566836;62685;62722;1501.114342537908;1558.9532314030598;1574.7480403135673;1580.1467207707708;1582.3591445464606;31365.215869603442;32263;32726.018219453454;33037.74363769222;33067.66932330646;52818.735906320224;53012.73493632507;53746.193807092146;53759.46240537701;53797.731011344695;375654.1217293896;455482;470862;471952;472414;34640.30719385648;34737;35555.64444355523;35590.11024724299;35599.93200203998;43682.34476482792;44036;47309.7634511839;47353.05293894172;48554.27168592544;30512;37995.050123747125;39055;39105.35632595389;39570.812875612624;36969.44545831868;38414.039649009;38644;38722.80638596789;39063.80468097755;38122;38227;38580.80709596434;38609;39266.01834954149;33505.994820155436;33588.496172556945;34457.96626101221;34505.96482105541;34547.309053819285;25557.616635750106;26071.869640651676;26139;26272.605910911734;26972.190834275003 15:52:18 INFO - PROCESS | 1662 | |2;jquery.html;365.29315773977356;368.1403921842498;375.2026942746663;384.1893604494516;384.45407521319726;20869.478263043413;21393.930303484824;21748.673769893463;22369.888150559225;22447.21434749784;12617.810732839014;12969.157004794683;12982.480700771981;12987.155834870728;13523.661908452294;5423.755930983105;5688.60179787415;5717.656940583578;5725.227094342252;5726.3987281335485;4216.7680777557225;4456.041950980539;6022.789202377918;6268.874622507547;6280.1835761351185;6190.288116866551;6273.184486016811;6285.371462853724;6285.717142728588;6288.276848162452;6809.0467334573195;6825.55633883796;6832.077669514601;6852.383285504304;6856.828579285533;4113.609207125318;5846.210761547221;5988.7604495820115;6019.578629495937;6034.698265086772;14048.086874353134;14173.43306267748;15250.694986100216;25990.35024124393;26038.739612603822;1696.5249730075598;2116.502621883856;2197.0772275644267;2207.359865638979;2230.6654001899683;10050.145737612313;10231.897681023169;10480.58077676892;12532.937335313265;15615.843841561553;1779.1371184975276;1849.704047352417;1864.3847530314963;1883.6986082226779;1921.807819218081;109804;110388.08466577665;113048;113680;114584.28123578236;11464.598739044093;11566.710832229262;11586.594469193538;11601.709957250967;11780;14612.707745845024;14676.192809395372;14677.853221467649;14678.339474723663;14691.191984440744;8653.653853845775;8946.150115738994;9000.954995224982;9051.230645395084;9108.225800806873;9199.126083022102;9213.078692130734;9217.308701847389;9295.488748118918;9324.393914395543;4522.773861306921;4605.447346318396;4613.139853290981;4647.279671650899;4654.394928659252;1806.0217055782744;1838.8253115953962;1891.243502598944;1954.6774782160785;2004.2183548416128;906.8775715278485;928.4478816676313;957.6408846682494;1036.5024788101603;1047.36634336227;19543.706844397344;20156.899215504123;20228.69656955147;20416.489587760425;20593.485162870747;827.557256867585;999.8052327468605;1023.2888142740724;1034.912032477248;1060.3160961179997;11080.722981925355;11408.714782130348;11415.143864210217;11445.427728613702;11475;625570;630223.8488807527;641242;643361;645121;123778;129746.35126824306;132486;133490.33254833665;134680;1080.9621663241905;1089.880113187533;1118.2954738514845;1161.1001473857827;1162.3084264862393;18754.780939238906;19192.424227273204;19309.03454827281;19384.612307754047;19411;39478.01304967284;54831;55350;55539.72230138823;56026;71839;73961.6301918487;76855.61572192103;78791.60604196943;80367;8117.959410202911;8350.498970061815;8375.539345335821;8423.115572865006;8535.829283414421;9632.373895696757;10322.793544128925;12873.22760634365;13193;14014.649633759238;11628.069754419457;11838.043575988142;11943.402829858645;11950.940245298718;12123.211991220542;11774.470148843151;11776.764464710486;11792.764144716886;11886.89845820322;12009;11793.29240245588;12025.338606376734;12396.31820249895;12409.689757756129;12536.498540058295;7458.10502739653;8491.660333586835;8515.74452766418;8791.560421979004;8871.423357481743;9399.966003739446;10699.94650026745;11678.124140689652;12149.81775273354;12172.573959911715 15:52:18 INFO - PROCESS | 1662 | |3;mootools.html;1492.45525383235;1635.272303824798;1661.269041621686;2000.2898970865342;2080.9479763005925;8253.917460825398;8861.46831190129;9064.320175986802;9081.409708368952;9089.091090890925;8744.606492707828;9009.369344145913;9011;9027.638894444231;9048.63805447783;2560.385507478206;2571.331453822001;2582.69007719074;2587.7153513113544;2588.120039186676;2525.8863351149153;2584.586466165414;2592.2352905892712;2592.8980183422063;2600.4799040191942;2547.248561674301;2578.2394193712807;2606.960895586568;2608.647832542611;2609.0868196131296;2573.1637217904163;2589.6633437653077;2600.5189040027653;2609.8042646801377;2611.190530935397;2555.6805399325085;2574.8027167367263;2590.352411897026;2593.0535354595436;2596.909298094805;9043.276537876955;9122.08779122089;9141.725748227564;9147.993720690718;9193.034731353178;5527.170924361338;5701.800436984686;5746.856328591777;5763.452472015194;5774.364819869811;3705.7274360678384;3780.8865734028022;3800.7529510581726;3833.8658146965;3835.6931445484574;1070.4273213830563;1088.967330980072;1094.1520321750706;1129.0685184722554;1131.2307630811044;12297.815532767016;12388.31864247466;12404.379781010914;12409.131360804748;12425.502979880794;5011.674241174312;5030.170021946338;5060.924086138711;5103.897922041539;5119.6160287978555;10333.896661033445;10343.327683700536;10367.222458315657;10372.896271037343;10373.58505659765;5737.942620573825;5785.768569257182;5789.971050144806;5795.478406943395;5822.039363504974;4220.07158425146;4573.63410927125;4732.787024583902;4808.807647694123;5212.296339994128;3417.3507033664023;3453.3438646657055;3813.599572044951;4074.6740260779065;4089.26393249218;1272.0459655258555;1345.8048582955416;1346.3537501999094;1349.6221058103747;1357.911735737174;1294.1135322304065;1403.277312184226;1405.641561401836;1419.361287420665;1441.942322307096;8265.917340826514;8387.748367548984;8443.746687599381;8474.1949514796;8499;532.9546988506021;543.2584522127347;546.1916363781626;548.7996881138436;549.1707521642269;6289.150964619809;6322.810315690536;6329.588576742497;6346.238451385863;6353.237611486651;10560.630377936888;10889.618863339745;11055.336679799238;11063.225574209728;11080.833787493195;10543;10839.187060970302;10940.960608742156;11039.889601104047;11060.502277397536;8189.467684600482;8222.013358397031;8237.258646721824;8302.335813135056;8334.20825021622;8023.277904988579;8039.598020099089;8184.54984975832;8273.586320683822;8396.992360916729;9450.243980481408;9706.6117355305;9827.508624568885;9831.901680983385;9854.11312981835;9734.756631083996;10069.295149339476;10108.747281318027;10115.7471063224;10153.39079655223;6232.875342493216;7678;8110.432269741062;8361.790955226168;8402;8504.702335418231;8511.574421279036;8567.186117318844;8567.871481927652;8597.914020859711;7387.2243414442855;7525;7559.811004724926;7644;7654.425918056057;7331.303526165006;7353.963230183815;7377.4098072155175;7502.21226771203;7541.660625271986;7963.163867793798;8011.599420028859;8017.759467215993;8034.236747508978;8058.435909486279;6180.381961803783;6998.685059172248;7172.928270717435;7195.244499327705;7206.639668016683;7311.780646580611;7329.303716146957;7351.264873512606;7362.77911662651;7380.409567234716 15:52:18 INFO - PROCESS | 1662 | |4;prototype.html;334.48656312560223;340.21071115013166;343.5756840302227;344.2718650055131;351.395599568742;5659.547236221103;6717.9328206717855;6720.831979200516;6761.628110453923;6772.390484856362;6709.3290670933;6718;6736.764213252537;6749.325067493248;6757.459403247741;4250.744955302676;4862.829800956967;4920.557149856512;4962.305277261186;4992.875178120558;4995.425526064495;5016.924746128792;5023.673461225008;5030.1448753711775;5037.974810125963;4967.354243948282;4981.701097934136;4988.675736077162;4989.151844186497;5002.799888004475;4783.306420569015;4854.708717476963;4910.263460480921;4919.21292593187;4931.432885218192;4276.444062271916;4279.058607106432;4280.614744672963;4293;4294.141171765628;6464.709088091047;6473.093766872595;6475.320091390383;6477.83805404864;6507.44686701631;5901.3803550627;5911.4975227105315;5918.763249470016;5922.911156332659;5927.1405646181065;6885.173779146485;7137.357637812622;7138.714451421937;7154.35610795031;7160.17657969335;4682.648801339913;4705.623550115983;4716.113554578162;4723.338732577442;4724.692894961817;7522.398208143335;7585.241475852371;7611.505252158592;7612.619369031471;7619.314261716472;4762.333273341735;4781.617470602343;4784.497627749106;4786.210275304606;4793.712377257306;4850.53919877611;4860.198067318924;4866.026794641086;4867.367242258484;4870.634702397334;4691.437027556715;5550.973069982067;5557.972210138923;5790.507806836466;5832.62087964281;7209.315115064061;7221.638918054076;7226.855462890818;7237.638118094074;7241.949917261967;1651.3147043976614;1688.3246701319324;1692.4414943068757;1695.4150817967782;1697.3634886917405;941.0495399646406;1051.921105917044;1052.8837037554417;1065.738893970982;1089.4117176724637;6791.830204244835;6870.587764734132;6932.0988271524375;7002.544834585736;7066.364027237573;7502.962485187648;7504.437167212481;7511.812204694927;7522.511036782592;7538.09542854861;5603.187537806995;6936.028955946172;6947.861042779217;6950.096487456598;7002.264762199896;3819.197968426606;3912.9804350978634;3950.743201691881;3954.8813535593977;3956.742811717229;7726.111497177896;7823.295903368724;7832.804179895548;7841.843163136706;7847.529148251009;7677.961610191914;7723.420743444151;7728.536287822749;7728.806779830549;7753.418493613001;6231.470325022424;7827.060752709711;7829.256220658915;7839.568823714751;7880.960595196987;7204.71181152748;7454.440916931143;7477.663505142173;7477.850442991218;7483.251674832691;7299.197088320174;7352.0442342495135;7352.96323518379;7364.447666424932;7386.409087273113;7804.375649947877;7818.609069546386;7827.92172078272;7828.569428681477;7841.4118941078505;4767.594754445911;4773.069251495925;4775.2598347256235;4779.593734532604;4790.353302304145;7145.749898753519;7463.776086717407;7466.701331946661;7469.850602988018;7504.6247687614305;7133.643317834191;7181.640917954186;7215.278472152742;7218;7227.891581626385;7169.426445884421;7175.0313707650885;7179.748708795167;7181.820454488679;7190.532615379983;7024.578525288499;7029.859402811813;7033.859322813618;7034.824129396806;7048.823779405556;6951.44388448933;7202.89195662055;7204.53170543913;7206.351428371472;7219.819504512429;6828.907374820005;6985.126859142607;7026.437884969088;7058.047163632844;7131.572105673677 15:52:18 INFO - PROCESS | 1662 | |5;yui.html;705.9929400705992;710.669538664521;763.2977660552294;799.4803377804425;800.8318253166834;15357;15734.449294274675;15762.211889405527;15775.684486310267;15814.920925395358;14672.89953253505;14730.63173420665;14760.114393136393;14763.188024658666;14782.926085369558;804.7907544038563;813.0609146435876;972.5137431284357;973.7273563402241;994.3437331361303;986.9802603947917;997.3517213811044;1003.0871906565027;1003.3027046202892;1004.9749255759122;973.1630797514132;982.1651596143292;1057.4871187474068;1058.5818601652343;1060.194252368202;851.6423102297019;1001.5242759689162;1025.4718819807806;1028.239103063735;1052.5000624703282;764.132709374858;764.5183534373309;765.4832987733314;769.7152053740097;787.3897729259812;15404.53786386399;15525;15553.22233888301;15593.844061559352;15610.45363412275;12892.935535322356;12974.675633109153;13201.339933003408;13249.271290079041;13285.535006274735;9732.562034708455;10397.428141452217;10511.1591072714;10846.837297440545;11270.774584508346;3489.546358973317;3555.6799888009946;3569.303985722785;3595.586507551639;3748.081719978623;86217;86598.56700716518;86747.56626216891;86856.18294353539;86919;9877.60489580423;9917.900820991697;9931.453770042572;9944.154746846454;10186.388816671024;22888.542229155322;22901.08395664188;22948.770512294665;22956.54086918252;22971.425714357276;7630.7710768677025;7691.230876912264;7726.690932362755;7771.6891324347525;7989.760307190793;4208.305629571087;4290;4356.6950313477755;4363.214621368184;4561.863144105682;3699.093722038118;3741.6258374162912;3760.1163726524246;3764.134249122717;3768.1144930941205;573.406524247402;653.5817077070678;668.548729607513;669.9866002680017;692.7506097804786;585.157373382328;616.8612062285949;622;626.2047200055995;627.2754968011953;7956.2441568050945;8399.392814977167;8405.579721013924;8499.872501912476;8532.445391049687;618.2426527503771;619.1548536248079;621.4500167351927;625.8748250349857;628.0359647940389;3804.524434445694;3864.8260828262796;3935.8032098394965;4052.250333688277;4060.127072679329;90429.54785226032;91902;92405.53797230971;92707;99514.50728239083;80281;80937;82107;82471.5876420626;83284.58357708172;71149;72247.91628125582;72791.63604182051;72899.27100728925;73993;8765.254953328784;8863.742579573704;8972.19250267479;9013.098690131197;9099.272058235192;49920.12301056478;50062;50147.74926125346;51920.701982450744;54116.45883541272;64738.35261647323;64951.67524162349;65077.67461162664;66635.33411664554;66823;11297.491612877277;11553.480093395985;11615.477303521531;11793.705157371134;12063.336516491678;12106.211512187423;12182.329971851634;12475.500979961063;12497.87502124967;12918.61244162639;11870.821937671115;12059.276443413422;12119.272843629411;12171.634850954486;12285.508579656713;11794.351310677652;11869.525218991144;12402.13185077036;12420.06849486274;12568.183068100545;11443.25618834773;11849.881501185222;12009.939950300193;12113.454894529592;12449.5723333949;9109.362344635812;9432.292578056811;9532.475713835805;9580.28147888925;9664.420134791935;8794.296456283353;9226.169644732006;9296.488693121673;9315.580798864203;9324.440533568008 15:52:18 INFO - PROCESS | 1662 | __end_tp_report 15:52:18 INFO - PROCESS | 1662 | __start_cc_report 15:52:18 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,967 15:52:18 INFO - PROCESS | 1662 | __end_cc_report 15:52:18 INFO - PROCESS | 1662 | __startTimestamp1461970338973__endTimestamp 15:52:18 INFO - PROCESS | 1662 | 15:52:18 INFO - PROCESS | 1662 | ------- Summary: start ------- 15:52:18 INFO - PROCESS | 1662 | Number of tests: 6 15:52:18 INFO - PROCESS | 1662 | 15:52:18 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5914.53 Median:6143.51 stddev:624.87 (10.2%) stddev-sans-first:598.90 15:52:18 INFO - PROCESS | 1662 | Values: 3561.3 3657.1 3664.9 3684.6 3722.2 5971.5 5972.0 5979.5 5980.2 5989.7 5975.4 6000.0 6005.5 6006.1 6020.9 5230.2 5336.8 5345.5 5395.3 5446.3 5433.5 5449.9 5468.0 5468.1 5473.2 5382.5 5452.9 5453.1 5466.5 5471.4 5451.3 5465.5 5479.4 5487.5 5505.0 5201.6 5276.7 5390.2 5401.7 5429.1 5916.1 5933.0 5978.3 5994.8 6014.6 6189.8 6278.3 6296.5 6304.0 6316.0 6295.1 6319.2 6323.0 6398.8 6407.5 6101.6 6128.1 6138.1 6139.8 6143.5 6401.1 6489.7 6522.1 6522.5 6534.4 6172.6 6235.1 6240.1 6244.0 6257.6 6342.9 6344.9 6398.7 6411.2 6435.3 5663.8 5960.9 6277.3 6288.3 6289.2 6189.5 6284.6 6296.8 6298.9 6319.4 5033.3 5038.6 5044.4 5045.2 5059.8 4471.8 4587.4 4594.9 4597.3 4601.9 6020.8 6021.1 6100.1 6115.4 6123.7 6301.8 6364.8 6368.6 6376.1 6382.3 5952.3 6023.6 6117.9 6134.7 6139.2 5481.0 5507.4 5551.3 5566.1 5567.2 6516.4 6516.9 6530.9 6543.4 6550.8 6217.2 6309.8 6321.7 6325.5 6338.3 6489.7 6553.0 6561.4 6585.0 6591.0 6250.6 6303.7 6320.7 6323.7 6352.2 5917.6 6229.0 6297.4 6317.7 6320.8 4698.3 6292.6 6303.6 6313.7 6325.3 6272.7 6283.3 6299.4 6312.3 6326.5 6108.2 6111.2 6167.0 6243.3 6261.5 6260.7 6282.3 6299.7 6301.9 6302.9 5082.8 6078.6 6146.2 6167.3 6191.0 15:52:18 INFO - PROCESS | 1662 | 15:52:18 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:61384.12 Median:20293.66 stddev:122232.54 (602.3%) stddev-sans-first:122489.98 15:52:18 INFO - PROCESS | 1662 | Values: 506.5 573.4 627.5 658.8 668.1 31979.0 40750.6 40975.6 41031.4 41207.4 43861.0 44320.6 44384.9 44626.8 44780.8 9708.7 10047.9 10050.3 10055.0 10076.7 9717.3 9737.5 9866.3 9896.3 10002.7 9943.7 9991.0 10026.6 10033.8 10037.5 9931.4 10008.3 10009.1 10014.1 10040.2 9854.8 10000.5 10006.4 10021.7 10022.9 42518.4 42934.0 42962.8 42973.4 42993.8 3388.4 3498.4 3589.1 3609.4 3617.9 29040.7 29884.6 29937.0 30707.7 30764.8 2807.2 2872.0 2874.9 2922.1 2943.5 405520.0 411492.0 412245.9 412526.0 413048.0 47415.8 68627.7 70050.0 70063.6 70148.6 39839.6 47795.0 47948.3 48008.5 48244.5 17405.4 17543.4 17614.8 17628.5 17637.7 14016.2 14022.2 14111.6 14114.6 14120.6 15247.2 15407.9 15540.8 15550.1 15701.7 6405.0 6505.2 6544.6 6553.0 6667.5 960.9 996.3 1063.7 1072.9 1194.9 31017.9 31061.7 31381.2 31441.8 31736.2 1142.5 1199.7 1232.2 1249.5 1261.2 15777.8 15823.4 15906.9 16154.4 16265.0 508072.0 515744.0 516633.0 517534.0 519093.0 61996.7 62578.7 62648.7 62685.0 62722.0 1501.1 1559.0 1574.7 1580.1 1582.4 31365.2 32263.0 32726.0 33037.7 33067.7 52818.7 53012.7 53746.2 53759.5 53797.7 375654.1 455482.0 470862.0 471952.0 472414.0 34640.3 34737.0 35555.6 35590.1 35599.9 43682.3 44036.0 47309.8 47353.1 48554.3 30512.0 37995.1 39055.0 39105.4 39570.8 36969.4 38414.0 38644.0 38722.8 39063.8 38122.0 38227.0 38580.8 38609.0 39266.0 33506.0 33588.5 34458.0 34506.0 34547.3 25557.6 26071.9 26139.0 26272.6 26972.2 15:52:18 INFO - PROCESS | 1662 | 15:52:18 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:35818.36 Median:20504.99 stddev:105941.24 (516.7%) stddev-sans-first:106204.99 15:52:18 INFO - PROCESS | 1662 | Values: 365.3 368.1 375.2 384.2 384.5 20869.5 21393.9 21748.7 22369.9 22447.2 12617.8 12969.2 12982.5 12987.2 13523.7 5423.8 5688.6 5717.7 5725.2 5726.4 4216.8 4456.0 6022.8 6268.9 6280.2 6190.3 6273.2 6285.4 6285.7 6288.3 6809.0 6825.6 6832.1 6852.4 6856.8 4113.6 5846.2 5988.8 6019.6 6034.7 14048.1 14173.4 15250.7 25990.4 26038.7 1696.5 2116.5 2197.1 2207.4 2230.7 10050.1 10231.9 10480.6 12532.9 15615.8 1779.1 1849.7 1864.4 1883.7 1921.8 109804.0 110388.1 113048.0 113680.0 114584.3 11464.6 11566.7 11586.6 11601.7 11780.0 14612.7 14676.2 14677.9 14678.3 14691.2 8653.7 8946.2 9001.0 9051.2 9108.2 9199.1 9213.1 9217.3 9295.5 9324.4 4522.8 4605.4 4613.1 4647.3 4654.4 1806.0 1838.8 1891.2 1954.7 2004.2 906.9 928.4 957.6 1036.5 1047.4 19543.7 20156.9 20228.7 20416.5 20593.5 827.6 999.8 1023.3 1034.9 1060.3 11080.7 11408.7 11415.1 11445.4 11475.0 625570.0 630223.8 641242.0 643361.0 645121.0 123778.0 129746.4 132486.0 133490.3 134680.0 1081.0 1089.9 1118.3 1161.1 1162.3 18754.8 19192.4 19309.0 19384.6 19411.0 39478.0 54831.0 55350.0 55539.7 56026.0 71839.0 73961.6 76855.6 78791.6 80367.0 8118.0 8350.5 8375.5 8423.1 8535.8 9632.4 10322.8 12873.2 13193.0 14014.6 11628.1 11838.0 11943.4 11950.9 12123.2 11774.5 11776.8 11792.8 11886.9 12009.0 11793.3 12025.3 12396.3 12409.7 12536.5 7458.1 8491.7 8515.7 8791.6 8871.4 9400.0 10699.9 11678.1 12149.8 12172.6 15:52:18 INFO - PROCESS | 1662 | 15:52:18 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:6221.55 Median:544.73 stddev:3286.28 (603.3%) stddev-sans-first:3276.28 15:52:18 INFO - PROCESS | 1662 | Values: 1492.5 1635.3 1661.3 2000.3 2080.9 8253.9 8861.5 9064.3 9081.4 9089.1 8744.6 9009.4 9011.0 9027.6 9048.6 2560.4 2571.3 2582.7 2587.7 2588.1 2525.9 2584.6 2592.2 2592.9 2600.5 2547.2 2578.2 2607.0 2608.6 2609.1 2573.2 2589.7 2600.5 2609.8 2611.2 2555.7 2574.8 2590.4 2593.1 2596.9 9043.3 9122.1 9141.7 9148.0 9193.0 5527.2 5701.8 5746.9 5763.5 5774.4 3705.7 3780.9 3800.8 3833.9 3835.7 1070.4 1089.0 1094.2 1129.1 1131.2 12297.8 12388.3 12404.4 12409.1 12425.5 5011.7 5030.2 5060.9 5103.9 5119.6 10333.9 10343.3 10367.2 10372.9 10373.6 5737.9 5785.8 5790.0 5795.5 5822.0 4220.1 4573.6 4732.8 4808.8 5212.3 3417.4 3453.3 3813.6 4074.7 4089.3 1272.0 1345.8 1346.4 1349.6 1357.9 1294.1 1403.3 1405.6 1419.4 1441.9 8265.9 8387.7 8443.7 8474.2 8499.0 533.0 543.3 546.2 548.8 549.2 6289.2 6322.8 6329.6 6346.2 6353.2 10560.6 10889.6 11055.3 11063.2 11080.8 10543.0 10839.2 10941.0 11039.9 11060.5 8189.5 8222.0 8237.3 8302.3 8334.2 8023.3 8039.6 8184.5 8273.6 8397.0 9450.2 9706.6 9827.5 9831.9 9854.1 9734.8 10069.3 10108.7 10115.7 10153.4 6232.9 7678.0 8110.4 8361.8 8402.0 8504.7 8511.6 8567.2 8567.9 8597.9 7387.2 7525.0 7559.8 7644.0 7654.4 7331.3 7354.0 7377.4 7502.2 7541.7 7963.2 8011.6 8017.8 8034.2 8058.4 6180.4 6998.7 7172.9 7195.2 7206.6 7311.8 7329.3 7351.3 7362.8 7380.4 15:52:18 INFO - PROCESS | 1662 | 15:52:18 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5914.29 Median:6782.11 stddev:1892.75 (27.9%) stddev-sans-first:1851.15 15:52:18 INFO - PROCESS | 1662 | Values: 334.5 340.2 343.6 344.3 351.4 5659.5 6717.9 6720.8 6761.6 6772.4 6709.3 6718.0 6736.8 6749.3 6757.5 4250.7 4862.8 4920.6 4962.3 4992.9 4995.4 5016.9 5023.7 5030.1 5038.0 4967.4 4981.7 4988.7 4989.2 5002.8 4783.3 4854.7 4910.3 4919.2 4931.4 4276.4 4279.1 4280.6 4293.0 4294.1 6464.7 6473.1 6475.3 6477.8 6507.4 5901.4 5911.5 5918.8 5922.9 5927.1 6885.2 7137.4 7138.7 7154.4 7160.2 4682.6 4705.6 4716.1 4723.3 4724.7 7522.4 7585.2 7611.5 7612.6 7619.3 4762.3 4781.6 4784.5 4786.2 4793.7 4850.5 4860.2 4866.0 4867.4 4870.6 4691.4 5551.0 5558.0 5790.5 5832.6 7209.3 7221.6 7226.9 7237.6 7241.9 1651.3 1688.3 1692.4 1695.4 1697.4 941.0 1051.9 1052.9 1065.7 1089.4 6791.8 6870.6 6932.1 7002.5 7066.4 7503.0 7504.4 7511.8 7522.5 7538.1 5603.2 6936.0 6947.9 6950.1 7002.3 3819.2 3913.0 3950.7 3954.9 3956.7 7726.1 7823.3 7832.8 7841.8 7847.5 7678.0 7723.4 7728.5 7728.8 7753.4 6231.5 7827.1 7829.3 7839.6 7881.0 7204.7 7454.4 7477.7 7477.9 7483.3 7299.2 7352.0 7353.0 7364.4 7386.4 7804.4 7818.6 7827.9 7828.6 7841.4 4767.6 4773.1 4775.3 4779.6 4790.4 7145.7 7463.8 7466.7 7469.9 7504.6 7133.6 7181.6 7215.3 7218.0 7227.9 7169.4 7175.0 7179.7 7181.8 7190.5 7024.6 7029.9 7033.9 7034.8 7048.8 6951.4 7202.9 7204.5 7206.4 7219.8 6828.9 6985.1 7026.4 7058.0 7131.6 15:52:18 INFO - PROCESS | 1662 | 15:52:18 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:18916.76 Median:579.28 stddev:26459.43 (4567.6%) stddev-sans-first:26498.33 15:52:18 INFO - PROCESS | 1662 | Values: 706.0 710.7 763.3 799.5 800.8 15357.0 15734.4 15762.2 15775.7 15814.9 14672.9 14730.6 14760.1 14763.2 14782.9 804.8 813.1 972.5 973.7 994.3 987.0 997.4 1003.1 1003.3 1005.0 973.2 982.2 1057.5 1058.6 1060.2 851.6 1001.5 1025.5 1028.2 1052.5 764.1 764.5 765.5 769.7 787.4 15404.5 15525.0 15553.2 15593.8 15610.5 12892.9 12974.7 13201.3 13249.3 13285.5 9732.6 10397.4 10511.2 10846.8 11270.8 3489.5 3555.7 3569.3 3595.6 3748.1 86217.0 86598.6 86747.6 86856.2 86919.0 9877.6 9917.9 9931.5 9944.2 10186.4 22888.5 22901.1 22948.8 22956.5 22971.4 7630.8 7691.2 7726.7 7771.7 7989.8 4208.3 4290.0 4356.7 4363.2 4561.9 3699.1 3741.6 3760.1 3764.1 3768.1 573.4 653.6 668.5 670.0 692.8 585.2 616.9 622.0 626.2 627.3 7956.2 8399.4 8405.6 8499.9 8532.4 618.2 619.2 621.5 625.9 628.0 3804.5 3864.8 3935.8 4052.3 4060.1 90429.5 91902.0 92405.5 92707.0 99514.5 80281.0 80937.0 82107.0 82471.6 83284.6 71149.0 72247.9 72791.6 72899.3 73993.0 8765.3 8863.7 8972.2 9013.1 9099.3 49920.1 50062.0 50147.7 51920.7 54116.5 64738.4 64951.7 65077.7 66635.3 66823.0 11297.5 11553.5 11615.5 11793.7 12063.3 12106.2 12182.3 12475.5 12497.9 12918.6 11870.8 12059.3 12119.3 12171.6 12285.5 11794.4 11869.5 12402.1 12420.1 12568.2 11443.3 11849.9 12009.9 12113.5 12449.6 9109.4 9432.3 9532.5 9580.3 9664.4 8794.3 9226.2 9296.5 9315.6 9324.4 15:52:18 INFO - PROCESS | 1662 | -------- Summary: end -------- 15:52:18 INFO - PROCESS | 1662 | 15:52:19 INFO - TEST-INFO | 1662: exit 0 15:52:19 INFO - TEST-OK | dromaeo_css | took 1111617ms 15:52:19 INFO - TEST-START | kraken 15:52:19 INFO - operating with platform_type : mac_ 15:52:19 INFO - Initialising browser for kraken test... 15:52:19 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpiLuevT/profile http://localhost:49275/getInfo.html) 15:52:21 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 15:52:21 INFO - PROCESS | 1671 | colorDepth:24 15:52:21 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 15:52:21 INFO - PROCESS | 1671 | __metrics 15:52:21 INFO - PROCESS | 1671 | 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" 15:52:21 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:52:22 INFO - TEST-INFO | 1671: exit 0 15:52:22 INFO - Browser initialized. 15:52:22 INFO - Running cycle 1/1 for kraken test... 15:52:22 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpiLuevT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:34 INFO - PROCESS | 1674 | RSS: Main: 156663808 15:52:34 INFO - PROCESS | 1674 | 15:52:36 INFO - PROCESS | 1674 | 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) 15:52:37 INFO - PROCESS | 1674 | RSS: Main: 212889600 15:52:37 INFO - PROCESS | 1674 | 15:52:39 INFO - PROCESS | 1674 | 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) 15:52:40 INFO - PROCESS | 1674 | RSS: Main: 223248384 15:52:40 INFO - PROCESS | 1674 | 15:52:43 INFO - PROCESS | 1674 | 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) 15:52:44 INFO - PROCESS | 1674 | RSS: Main: 212578304 15:52:44 INFO - PROCESS | 1674 | 15:52:46 INFO - PROCESS | 1674 | 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) 15:52:46 INFO - PROCESS | 1674 | RSS: Main: 215011328 15:52:46 INFO - PROCESS | 1674 | 15:52:48 INFO - PROCESS | 1674 | 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) 15:52:49 INFO - PROCESS | 1674 | RSS: Main: 214843392 15:52:49 INFO - PROCESS | 1674 | 15:52:52 INFO - PROCESS | 1674 | 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) 15:52:52 INFO - PROCESS | 1674 | RSS: Main: 214700032 15:52:52 INFO - PROCESS | 1674 | 15:52:55 INFO - PROCESS | 1674 | 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) 15:52:56 INFO - PROCESS | 1674 | RSS: Main: 214925312 15:52:56 INFO - PROCESS | 1674 | 15:52:58 INFO - PROCESS | 1674 | 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) 15:52:59 INFO - PROCESS | 1674 | RSS: Main: 215248896 15:52:59 INFO - PROCESS | 1674 | 15:53:01 INFO - PROCESS | 1674 | 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) 15:53:01 INFO - PROCESS | 1674 | RSS: Main: 249860096 15:53:01 INFO - PROCESS | 1674 | 15:53:03 INFO - PROCESS | 1674 | 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) 15:53:04 INFO - PROCESS | 1674 | RSS: Main: 250429440 15:53:04 INFO - PROCESS | 1674 | 15:53:06 INFO - PROCESS | 1674 | 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) 15:53:06 INFO - PROCESS | 1674 | RSS: Main: 255963136 15:53:06 INFO - PROCESS | 1674 | 15:53:09 INFO - PROCESS | 1674 | 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) 15:53:09 INFO - PROCESS | 1674 | RSS: Main: 257232896 15:53:09 INFO - PROCESS | 1674 | 15:53:12 INFO - PROCESS | 1674 | 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) 15:53:13 INFO - PROCESS | 1674 | RSS: Main: 257462272 15:53:13 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | 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) 15:53:14 INFO - PROCESS | 1674 | RSS: Main: 319864832 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | __start_tp_report 15:53:14 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 15:53:14 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 15:53:14 INFO - PROCESS | 1674 | |i|pagename|runs| 15:53:14 INFO - PROCESS | 1674 | |0;ai-astar;112;108;110;111;111;112;121;111;110;110 15:53:14 INFO - PROCESS | 1674 | |1;audio-beat-detection;154;120;153;148;115;138;117;148;148;118 15:53:14 INFO - PROCESS | 1674 | |2;audio-dft;184;184;183;184;188;184;185;185;184;184 15:53:14 INFO - PROCESS | 1674 | |3;audio-fft;76;74;73;117;80;82;84;60;119;56 15:53:14 INFO - PROCESS | 1674 | |4;audio-oscillator;88;93;127;77;81;80;119;75;79;119 15:53:14 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;90;87;90;86;90;87;90;87;91;131 15:53:14 INFO - PROCESS | 1674 | |6;imaging-darkroom;94;94;94;95;94;95;95;94;95;94 15:53:14 INFO - PROCESS | 1674 | |7;imaging-desaturate;125;137;92;85;84;84;87;83;85;86 15:53:14 INFO - PROCESS | 1674 | |8;json-parse-financial;56;58;58;57;58;59;60;54;54;75 15:53:14 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;62;61;76;74;72;66;67;66;66;66 15:53:14 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;81;82;81;81;79;93;107;108;85;83 15:53:14 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;128;123;138;111;117;116;116;120;118;128 15:53:14 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;135;123;135;133;144;163;171;134;131;167 15:53:14 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;65;65;65;65;64;65;65;65;65;65 15:53:14 INFO - PROCESS | 1674 | __end_tp_report 15:53:14 INFO - PROCESS | 1674 | __start_cc_report 15:53:14 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1165 15:53:14 INFO - PROCESS | 1674 | __end_cc_report 15:53:14 INFO - PROCESS | 1674 | __startTimestamp1461970394949__endTimestamp 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | ------- Summary: start ------- 15:53:14 INFO - PROCESS | 1674 | Number of tests: 14 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:111.60 Median:111.00 stddev:3.50 (3.2%) stddev-sans-first:3.71 15:53:14 INFO - PROCESS | 1674 | Values: 112.0 108.0 110.0 111.0 111.0 112.0 121.0 111.0 110.0 110.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:148.00 stddev:16.43 (11.1%) stddev-sans-first:16.07 15:53:14 INFO - PROCESS | 1674 | Values: 154.0 120.0 153.0 148.0 115.0 138.0 117.0 148.0 148.0 118.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.00 stddev:1.35 (0.7%) stddev-sans-first:1.42 15:53:14 INFO - PROCESS | 1674 | Values: 184.0 184.0 183.0 184.0 188.0 184.0 185.0 185.0 184.0 184.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.00 stddev:20.93 (27.9%) stddev-sans-first:22.08 15:53:14 INFO - PROCESS | 1674 | Values: 76.0 74.0 73.0 117.0 80.0 82.0 84.0 60.0 119.0 56.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:79.50 stddev:20.04 (25.2%) stddev-sans-first:21.15 15:53:14 INFO - PROCESS | 1674 | Values: 88.0 93.0 127.0 77.0 81.0 80.0 119.0 75.0 79.0 119.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:90.00 stddev:13.50 (15.0%) stddev-sans-first:14.28 15:53:14 INFO - PROCESS | 1674 | Values: 90.0 87.0 90.0 86.0 90.0 87.0 90.0 87.0 91.0 131.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 15:53:14 INFO - PROCESS | 1674 | Values: 94.0 94.0 94.0 95.0 94.0 95.0 95.0 94.0 95.0 94.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:85.00 stddev:19.45 (22.9%) stddev-sans-first:17.29 15:53:14 INFO - PROCESS | 1674 | Values: 125.0 137.0 92.0 85.0 84.0 84.0 87.0 83.0 85.0 86.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:58.00 stddev:5.99 (10.3%) stddev-sans-first:6.26 15:53:14 INFO - PROCESS | 1674 | Values: 56.0 58.0 58.0 57.0 58.0 59.0 60.0 54.0 54.0 75.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:66.50 stddev:4.90 (7.4%) stddev-sans-first:4.76 15:53:14 INFO - PROCESS | 1674 | Values: 62.0 61.0 76.0 74.0 72.0 66.0 67.0 66.0 66.0 66.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:81.50 stddev:10.97 (13.5%) stddev-sans-first:11.34 15:53:14 INFO - PROCESS | 1674 | Values: 81.0 82.0 81.0 81.0 79.0 93.0 107.0 108.0 85.0 83.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:121.50 Median:121.50 stddev:7.92 (6.5%) stddev-sans-first:8.04 15:53:14 INFO - PROCESS | 1674 | Values: 128.0 123.0 138.0 111.0 117.0 116.0 116.0 120.0 118.0 128.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.60 Median:139.50 stddev:17.03 (12.2%) stddev-sans-first:17.78 15:53:14 INFO - PROCESS | 1674 | Values: 135.0 123.0 135.0 133.0 144.0 163.0 171.0 134.0 131.0 167.0 15:53:14 INFO - PROCESS | 1674 | 15:53:14 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 15:53:14 INFO - PROCESS | 1674 | Values: 65.0 65.0 65.0 65.0 64.0 65.0 65.0 65.0 65.0 65.0 15:53:14 INFO - PROCESS | 1674 | -------- Summary: end -------- 15:53:14 INFO - PROCESS | 1674 | 15:53:15 INFO - TEST-INFO | 1674: exit 0 15:53:15 INFO - TEST-OK | kraken | took 56195ms 15:53:15 INFO - SUITE-END | took 1167s 15:53:16 INFO - Completed test suite (00:19:28) 15:53:16 INFO - javascript benchmark 15:53:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3561.269939662369, 3657.1040095176686, 3664.926701465972, 3684.6131156228603, 3722.1997270586817, 5971.522278217743, 5972.014617588098, 5979.521638268939, 5980.222571065756, 5989.730462129204, 5975.402459754023, 5999.970000150004, 6005.549583781212, 6006.129111278862, 6020.909686354697, 5230.215467679859, 5336.759845806938, 5345.49217824308, 5395.325584301962, 5446.346438427394, 5433.483819037205, 5449.945500545004, 5468.043092458824, 5468.0977638689765, 5473.2063850741815, 5382.542483888874, 5452.863678408032, 5453.100238460649, 5466.535344495723, 5471.37079235889, 5451.318585176853, 5465.480779325958, 5479.424660410639, 5487.533559647435, 5505.008409736356, 5201.635885488017, 5276.709780962045, 5390.1914712793005, 5401.70290634015, 5429.131338985783, 5916.083007133901, 5933.021051526493, 5978.282606087254, 5994.7901823436405, 6014.609050411709, 6189.783357582508, 6278.340774218733, 6296.464800491964, 6304.0, 6315.968420157916, 6295.118683384329, 6319.210098737658, 6323.0, 6398.840028999312, 6407.455366293871, 6101.603395779304, 6128.1114238435175, 6138.1099740537375, 6139.846503837351, 6143.508519318489, 6401.135846660642, 6489.70796314167, 6522.086907832907, 6522.543421960418, 6534.37923397286, 6172.629642221481, 6235.064740288903, 6240.063990401476, 6244.0, 6257.593256438408, 6342.904856427157, 6344.936550634527, 6398.744050238031, 6411.198600174978, 6435.324290949477, 5663.830085097371, 5960.8807823844145, 6277.309495955441, 6288.339724328971, 6289.213848268966, 6189.535784816067, 6284.591501552384, 6296.779612713624, 6298.937010629927, 6319.431251187415, 5033.320501732219, 5038.571721403649, 5044.445111037783, 5045.217991211368, 5059.822906198266, 4471.776411179428, 4587.42657167854, 4594.908101837945, 4597.3333866589155, 4601.884952876205, 6020.789272375446, 6021.066734656135, 6100.054491553817, 6115.386344001658, 6123.693815309217, 6301.842453938688, 6364.840878978062, 6368.64972426521, 6376.075469056961, 6382.32985536512, 5952.315483719428, 6023.608465449732, 6117.938820611826, 6134.693265336716, 6139.2325959255095, 5481.0, 5507.366652834895, 5551.333839939152, 5566.081596536526, 5567.24842146305, 6516.446102081376, 6516.902246466307, 6530.902036469457, 6543.378379053982, 6550.7707230246715, 6217.222847144107, 6309.810705678837, 6321.747130114928, 6325.525585581007, 6338.271098823694, 6489.707963141575, 6553.049807777941, 6561.442277406474, 6585.045168450643, 6590.9670451647435, 6250.593711408744, 6303.716332765129, 6320.6839658016, 6323.652199129092, 6352.205974253218, 5917.615355001911, 6229.006717850244, 6297.433231009048, 6317.6841157941, 6320.778772742932, 4698.271767875985, 6292.559520833681, 6303.621782693053, 6313.747450102128, 6325.304216536269, 6272.749090036347, 6283.340249273943, 6299.370062993848, 6312.337204593451, 6326.493880489642, 6108.236470441195, 6111.205543279345, 6166.969165154145, 6243.250809902841, 6261.467775239155, 6260.68696565179, 6282.277538083179, 6299.685015749286, 6301.873962520815, 6302.873942521032, 5082.796688132581, 6078.6048906820915, 6146.231721034871, 6167.259928808392, 6191.0], "unit": "score", "name": "dojo.html", "value": 5878.124826773294}, {"lowerIsBetter": false, "replicates": [506.54157987021756, 573.4036601933989, 627.5230824573325, 658.7595527632415, 668.1014036121416, 31979.0, 40750.59249407505, 40975.59024409755, 41031.384529232084, 41207.38188927169, 43861.0, 44320.55679443204, 44384.89037774049, 44626.77686611562, 44780.77609611947, 9708.708738737849, 10047.94976025119, 10050.296479246455, 10055.044770746767, 10076.697699069004, 9717.271204659679, 9737.51312434379, 9866.260030497742, 9896.307258491908, 10002.699919002405, 9943.701688949306, 9991.000899910023, 10026.649067282646, 10033.799324013515, 10037.498125093753, 9931.404115753041, 10008.349457285252, 10009.149222316111, 10014.098731114162, 10040.196784257241, 9854.753631159207, 10000.549975251131, 10006.399616023062, 10021.699349019542, 10022.89977100227, 42518.362224566656, 42934.0, 42962.78518607418, 42973.35539966903, 42993.785031074956, 3388.423967925459, 3498.440249560058, 3589.120665436985, 3609.350316942949, 3617.8733744318824, 29040.70959290401, 29884.59001433335, 29937.0, 30707.69292307093, 30764.846175768977, 2807.157852644219, 2871.9712802871913, 2874.928126796826, 2922.0649392194505, 2943.5290353543332, 405520.0, 411492.0, 412245.8775412238, 412526.0, 413048.0, 47415.762921185174, 68627.65686171537, 70050.0, 70063.64968175229, 70148.64925675339, 39839.60160398359, 47795.0, 47948.280775787694, 48008.5199148011, 48244.517554824706, 17405.390811321544, 17543.385981490588, 17614.823851761575, 17628.471145865646, 17637.7354339685, 14016.229107399191, 14022.22877741734, 14111.647208819862, 14114.647133821736, 14120.646983825487, 15247.237638118271, 15407.922960385127, 15540.766888496682, 15550.066995980278, 15701.685966280611, 6404.967975160188, 6505.243584232231, 6544.59680965895, 6552.967235163889, 6667.466602671775, 960.8991055939166, 996.277698668474, 1063.680895731293, 1072.9088027517748, 1194.9006913639473, 31017.91437299684, 31061.68938310633, 31381.15525611801, 31441.84279078636, 31736.206594835316, 1142.5486932661552, 1199.7060720123452, 1232.211384713784, 1249.4564864283884, 1261.1802328486558, 15777.763333550007, 15823.446179383667, 15906.9204653976, 16154.353825847069, 16265.024098554124, 508072.0, 515744.0, 516633.0, 517534.0, 519093.0, 61996.690016549626, 62578.687106565085, 62648.686756566836, 62685.0, 62722.0, 1501.114342537908, 1558.9532314030598, 1574.7480403135673, 1580.1467207707708, 1582.3591445464606, 31365.215869603442, 32263.0, 32726.018219453454, 33037.74363769222, 33067.66932330646, 52818.735906320224, 53012.73493632507, 53746.193807092146, 53759.46240537701, 53797.731011344695, 375654.1217293896, 455482.0, 470862.0, 471952.0, 472414.0, 34640.30719385648, 34737.0, 35555.64444355523, 35590.11024724299, 35599.93200203998, 43682.34476482792, 44036.0, 47309.7634511839, 47353.05293894172, 48554.27168592544, 30512.0, 37995.050123747125, 39055.0, 39105.35632595389, 39570.812875612624, 36969.44545831868, 38414.039649009, 38644.0, 38722.80638596789, 39063.80468097755, 38122.0, 38227.0, 38580.80709596434, 38609.0, 39266.01834954149, 33505.994820155436, 33588.496172556945, 34457.96626101221, 34505.96482105541, 34547.309053819285, 25557.616635750106, 26071.869640651676, 26139.0, 26272.605910911734, 26972.190834275003], "unit": "score", "name": "ext.html", "value": 20127.64780201638}, {"lowerIsBetter": false, "replicates": [365.29315773977356, 368.1403921842498, 375.2026942746663, 384.1893604494516, 384.45407521319726, 20869.478263043413, 21393.930303484824, 21748.673769893463, 22369.888150559225, 22447.21434749784, 12617.810732839014, 12969.157004794683, 12982.480700771981, 12987.155834870728, 13523.661908452294, 5423.755930983105, 5688.60179787415, 5717.656940583578, 5725.227094342252, 5726.3987281335485, 4216.7680777557225, 4456.041950980539, 6022.789202377918, 6268.874622507547, 6280.1835761351185, 6190.288116866551, 6273.184486016811, 6285.371462853724, 6285.717142728588, 6288.276848162452, 6809.0467334573195, 6825.55633883796, 6832.077669514601, 6852.383285504304, 6856.828579285533, 4113.609207125318, 5846.210761547221, 5988.7604495820115, 6019.578629495937, 6034.698265086772, 14048.086874353134, 14173.43306267748, 15250.694986100216, 25990.35024124393, 26038.739612603822, 1696.5249730075598, 2116.502621883856, 2197.0772275644267, 2207.359865638979, 2230.6654001899683, 10050.145737612313, 10231.897681023169, 10480.58077676892, 12532.937335313265, 15615.843841561553, 1779.1371184975276, 1849.704047352417, 1864.3847530314963, 1883.6986082226779, 1921.807819218081, 109804.0, 110388.08466577665, 113048.0, 113680.0, 114584.28123578236, 11464.598739044093, 11566.710832229262, 11586.594469193538, 11601.709957250967, 11780.0, 14612.707745845024, 14676.192809395372, 14677.853221467649, 14678.339474723663, 14691.191984440744, 8653.653853845775, 8946.150115738994, 9000.954995224982, 9051.230645395084, 9108.225800806873, 9199.126083022102, 9213.078692130734, 9217.308701847389, 9295.488748118918, 9324.393914395543, 4522.773861306921, 4605.447346318396, 4613.139853290981, 4647.279671650899, 4654.394928659252, 1806.0217055782744, 1838.8253115953962, 1891.243502598944, 1954.6774782160785, 2004.2183548416128, 906.8775715278485, 928.4478816676313, 957.6408846682494, 1036.5024788101603, 1047.36634336227, 19543.706844397344, 20156.899215504123, 20228.69656955147, 20416.489587760425, 20593.485162870747, 827.557256867585, 999.8052327468605, 1023.2888142740724, 1034.912032477248, 1060.3160961179997, 11080.722981925355, 11408.714782130348, 11415.143864210217, 11445.427728613702, 11475.0, 625570.0, 630223.8488807527, 641242.0, 643361.0, 645121.0, 123778.0, 129746.35126824306, 132486.0, 133490.33254833665, 134680.0, 1080.9621663241905, 1089.880113187533, 1118.2954738514845, 1161.1001473857827, 1162.3084264862393, 18754.780939238906, 19192.424227273204, 19309.03454827281, 19384.612307754047, 19411.0, 39478.01304967284, 54831.0, 55350.0, 55539.72230138823, 56026.0, 71839.0, 73961.6301918487, 76855.61572192103, 78791.60604196943, 80367.0, 8117.959410202911, 8350.498970061815, 8375.539345335821, 8423.115572865006, 8535.829283414421, 9632.373895696757, 10322.793544128925, 12873.22760634365, 13193.0, 14014.649633759238, 11628.069754419457, 11838.043575988142, 11943.402829858645, 11950.940245298718, 12123.211991220542, 11774.470148843151, 11776.764464710486, 11792.764144716886, 11886.89845820322, 12009.0, 11793.29240245588, 12025.338606376734, 12396.31820249895, 12409.689757756129, 12536.498540058295, 7458.10502739653, 8491.660333586835, 8515.74452766418, 8791.560421979004, 8871.423357481743, 9399.966003739446, 10699.94650026745, 11678.124140689652, 12149.81775273354, 12172.573959911715], "unit": "score", "name": "jquery.html", "value": 9620.9288409524}, {"lowerIsBetter": false, "replicates": [1492.45525383235, 1635.272303824798, 1661.269041621686, 2000.2898970865342, 2080.9479763005925, 8253.917460825398, 8861.46831190129, 9064.320175986802, 9081.409708368952, 9089.091090890925, 8744.606492707828, 9009.369344145913, 9011.0, 9027.638894444231, 9048.63805447783, 2560.385507478206, 2571.331453822001, 2582.69007719074, 2587.7153513113544, 2588.120039186676, 2525.8863351149153, 2584.586466165414, 2592.2352905892712, 2592.8980183422063, 2600.4799040191942, 2547.248561674301, 2578.2394193712807, 2606.960895586568, 2608.647832542611, 2609.0868196131296, 2573.1637217904163, 2589.6633437653077, 2600.5189040027653, 2609.8042646801377, 2611.190530935397, 2555.6805399325085, 2574.8027167367263, 2590.352411897026, 2593.0535354595436, 2596.909298094805, 9043.276537876955, 9122.08779122089, 9141.725748227564, 9147.993720690718, 9193.034731353178, 5527.170924361338, 5701.800436984686, 5746.856328591777, 5763.452472015194, 5774.364819869811, 3705.7274360678384, 3780.8865734028022, 3800.7529510581726, 3833.8658146965, 3835.6931445484574, 1070.4273213830563, 1088.967330980072, 1094.1520321750706, 1129.0685184722554, 1131.2307630811044, 12297.815532767016, 12388.31864247466, 12404.379781010914, 12409.131360804748, 12425.502979880794, 5011.674241174312, 5030.170021946338, 5060.924086138711, 5103.897922041539, 5119.6160287978555, 10333.896661033445, 10343.327683700536, 10367.222458315657, 10372.896271037343, 10373.58505659765, 5737.942620573825, 5785.768569257182, 5789.971050144806, 5795.478406943395, 5822.039363504974, 4220.07158425146, 4573.63410927125, 4732.787024583902, 4808.807647694123, 5212.296339994128, 3417.3507033664023, 3453.3438646657055, 3813.599572044951, 4074.6740260779065, 4089.26393249218, 1272.0459655258555, 1345.8048582955416, 1346.3537501999094, 1349.6221058103747, 1357.911735737174, 1294.1135322304065, 1403.277312184226, 1405.641561401836, 1419.361287420665, 1441.942322307096, 8265.917340826514, 8387.748367548984, 8443.746687599381, 8474.1949514796, 8499.0, 532.9546988506021, 543.2584522127347, 546.1916363781626, 548.7996881138436, 549.1707521642269, 6289.150964619809, 6322.810315690536, 6329.588576742497, 6346.238451385863, 6353.237611486651, 10560.630377936888, 10889.618863339745, 11055.336679799238, 11063.225574209728, 11080.833787493195, 10543.0, 10839.187060970302, 10940.960608742156, 11039.889601104047, 11060.502277397536, 8189.467684600482, 8222.013358397031, 8237.258646721824, 8302.335813135056, 8334.20825021622, 8023.277904988579, 8039.598020099089, 8184.54984975832, 8273.586320683822, 8396.992360916729, 9450.243980481408, 9706.6117355305, 9827.508624568885, 9831.901680983385, 9854.11312981835, 9734.756631083996, 10069.295149339476, 10108.747281318027, 10115.7471063224, 10153.39079655223, 6232.875342493216, 7678.0, 8110.432269741062, 8361.790955226168, 8402.0, 8504.702335418231, 8511.574421279036, 8567.186117318844, 8567.871481927652, 8597.914020859711, 7387.2243414442855, 7525.0, 7559.811004724926, 7644.0, 7654.425918056057, 7331.303526165006, 7353.963230183815, 7377.4098072155175, 7502.21226771203, 7541.660625271986, 7963.163867793798, 8011.599420028859, 8017.759467215993, 8034.236747508978, 8058.435909486279, 6180.381961803783, 6998.685059172248, 7172.928270717435, 7195.244499327705, 7206.639668016683, 7311.780646580611, 7329.303716146957, 7351.264873512606, 7362.77911662651, 7380.409567234716], "unit": "score", "name": "mootools.html", "value": 5006.885651660837}, {"lowerIsBetter": false, "replicates": [334.48656312560223, 340.21071115013166, 343.5756840302227, 344.2718650055131, 351.395599568742, 5659.547236221103, 6717.9328206717855, 6720.831979200516, 6761.628110453923, 6772.390484856362, 6709.3290670933, 6718.0, 6736.764213252537, 6749.325067493248, 6757.459403247741, 4250.744955302676, 4862.829800956967, 4920.557149856512, 4962.305277261186, 4992.875178120558, 4995.425526064495, 5016.924746128792, 5023.673461225008, 5030.1448753711775, 5037.974810125963, 4967.354243948282, 4981.701097934136, 4988.675736077162, 4989.151844186497, 5002.799888004475, 4783.306420569015, 4854.708717476963, 4910.263460480921, 4919.21292593187, 4931.432885218192, 4276.444062271916, 4279.058607106432, 4280.614744672963, 4293.0, 4294.141171765628, 6464.709088091047, 6473.093766872595, 6475.320091390383, 6477.83805404864, 6507.44686701631, 5901.3803550627, 5911.4975227105315, 5918.763249470016, 5922.911156332659, 5927.1405646181065, 6885.173779146485, 7137.357637812622, 7138.714451421937, 7154.35610795031, 7160.17657969335, 4682.648801339913, 4705.623550115983, 4716.113554578162, 4723.338732577442, 4724.692894961817, 7522.398208143335, 7585.241475852371, 7611.505252158592, 7612.619369031471, 7619.314261716472, 4762.333273341735, 4781.617470602343, 4784.497627749106, 4786.210275304606, 4793.712377257306, 4850.53919877611, 4860.198067318924, 4866.026794641086, 4867.367242258484, 4870.634702397334, 4691.437027556715, 5550.973069982067, 5557.972210138923, 5790.507806836466, 5832.62087964281, 7209.315115064061, 7221.638918054076, 7226.855462890818, 7237.638118094074, 7241.949917261967, 1651.3147043976614, 1688.3246701319324, 1692.4414943068757, 1695.4150817967782, 1697.3634886917405, 941.0495399646406, 1051.921105917044, 1052.8837037554417, 1065.738893970982, 1089.4117176724637, 6791.830204244835, 6870.587764734132, 6932.0988271524375, 7002.544834585736, 7066.364027237573, 7502.962485187648, 7504.437167212481, 7511.812204694927, 7522.511036782592, 7538.09542854861, 5603.187537806995, 6936.028955946172, 6947.861042779217, 6950.096487456598, 7002.264762199896, 3819.197968426606, 3912.9804350978634, 3950.743201691881, 3954.8813535593977, 3956.742811717229, 7726.111497177896, 7823.295903368724, 7832.804179895548, 7841.843163136706, 7847.529148251009, 7677.961610191914, 7723.420743444151, 7728.536287822749, 7728.806779830549, 7753.418493613001, 6231.470325022424, 7827.060752709711, 7829.256220658915, 7839.568823714751, 7880.960595196987, 7204.71181152748, 7454.440916931143, 7477.663505142173, 7477.850442991218, 7483.251674832691, 7299.197088320174, 7352.0442342495135, 7352.96323518379, 7364.447666424932, 7386.409087273113, 7804.375649947877, 7818.609069546386, 7827.92172078272, 7828.569428681477, 7841.4118941078505, 4767.594754445911, 4773.069251495925, 4775.2598347256235, 4779.593734532604, 4790.353302304145, 7145.749898753519, 7463.776086717407, 7466.701331946661, 7469.850602988018, 7504.6247687614305, 7133.643317834191, 7181.640917954186, 7215.278472152742, 7218.0, 7227.891581626385, 7169.426445884421, 7175.0313707650885, 7179.748708795167, 7181.820454488679, 7190.532615379983, 7024.578525288499, 7029.859402811813, 7033.859322813618, 7034.824129396806, 7048.823779405556, 6951.44388448933, 7202.89195662055, 7204.53170543913, 7206.351428371472, 7219.819504512429, 6828.907374820005, 6985.126859142607, 7026.437884969088, 7058.047163632844, 7131.572105673677], "unit": "score", "name": "prototype.html", "value": 5277.508585921883}, {"lowerIsBetter": false, "replicates": [705.9929400705992, 710.669538664521, 763.2977660552294, 799.4803377804425, 800.8318253166834, 15357.0, 15734.449294274675, 15762.211889405527, 15775.684486310267, 15814.920925395358, 14672.89953253505, 14730.63173420665, 14760.114393136393, 14763.188024658666, 14782.926085369558, 804.7907544038563, 813.0609146435876, 972.5137431284357, 973.7273563402241, 994.3437331361303, 986.9802603947917, 997.3517213811044, 1003.0871906565027, 1003.3027046202892, 1004.9749255759122, 973.1630797514132, 982.1651596143292, 1057.4871187474068, 1058.5818601652343, 1060.194252368202, 851.6423102297019, 1001.5242759689162, 1025.4718819807806, 1028.239103063735, 1052.5000624703282, 764.132709374858, 764.5183534373309, 765.4832987733314, 769.7152053740097, 787.3897729259812, 15404.53786386399, 15525.0, 15553.22233888301, 15593.844061559352, 15610.45363412275, 12892.935535322356, 12974.675633109153, 13201.339933003408, 13249.271290079041, 13285.535006274735, 9732.562034708455, 10397.428141452217, 10511.1591072714, 10846.837297440545, 11270.774584508346, 3489.546358973317, 3555.6799888009946, 3569.303985722785, 3595.586507551639, 3748.081719978623, 86217.0, 86598.56700716518, 86747.56626216891, 86856.18294353539, 86919.0, 9877.60489580423, 9917.900820991697, 9931.453770042572, 9944.154746846454, 10186.388816671024, 22888.542229155322, 22901.08395664188, 22948.770512294665, 22956.54086918252, 22971.425714357276, 7630.7710768677025, 7691.230876912264, 7726.690932362755, 7771.6891324347525, 7989.760307190793, 4208.305629571087, 4290.0, 4356.6950313477755, 4363.214621368184, 4561.863144105682, 3699.093722038118, 3741.6258374162912, 3760.1163726524246, 3764.134249122717, 3768.1144930941205, 573.406524247402, 653.5817077070678, 668.548729607513, 669.9866002680017, 692.7506097804786, 585.157373382328, 616.8612062285949, 622.0, 626.2047200055995, 627.2754968011953, 7956.2441568050945, 8399.392814977167, 8405.579721013924, 8499.872501912476, 8532.445391049687, 618.2426527503771, 619.1548536248079, 621.4500167351927, 625.8748250349857, 628.0359647940389, 3804.524434445694, 3864.8260828262796, 3935.8032098394965, 4052.250333688277, 4060.127072679329, 90429.54785226032, 91902.0, 92405.53797230971, 92707.0, 99514.50728239083, 80281.0, 80937.0, 82107.0, 82471.5876420626, 83284.58357708172, 71149.0, 72247.91628125582, 72791.63604182051, 72899.27100728925, 73993.0, 8765.254953328784, 8863.742579573704, 8972.19250267479, 9013.098690131197, 9099.272058235192, 49920.12301056478, 50062.0, 50147.74926125346, 51920.701982450744, 54116.45883541272, 64738.35261647323, 64951.67524162349, 65077.67461162664, 66635.33411664554, 66823.0, 11297.491612877277, 11553.480093395985, 11615.477303521531, 11793.705157371134, 12063.336516491678, 12106.211512187423, 12182.329971851634, 12475.500979961063, 12497.87502124967, 12918.61244162639, 11870.821937671115, 12059.276443413422, 12119.272843629411, 12171.634850954486, 12285.508579656713, 11794.351310677652, 11869.525218991144, 12402.13185077036, 12420.06849486274, 12568.183068100545, 11443.25618834773, 11849.881501185222, 12009.939950300193, 12113.454894529592, 12449.5723333949, 9109.362344635812, 9432.292578056811, 9532.475713835805, 9580.28147888925, 9664.420134791935, 8794.296456283353, 9226.169644732006, 9296.488693121673, 9315.580798864203, 9324.440533568008], "unit": "score", "name": "yui.html", "value": 7205.019517230516}], "name": "dromaeo_css", "value": 7750.309573624256}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 108.0, 110.0, 111.0, 111.0, 112.0, 121.0, 111.0, 110.0, 110.0], "unit": "score", "name": "ai-astar", "value": 111.6}, {"lowerIsBetter": true, "replicates": [154.0, 120.0, 153.0, 148.0, 115.0, 138.0, 117.0, 148.0, 148.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 135.9}, {"lowerIsBetter": true, "replicates": [184.0, 184.0, 183.0, 184.0, 188.0, 184.0, 185.0, 185.0, 184.0, 184.0], "unit": "score", "name": "audio-dft", "value": 184.5}, {"lowerIsBetter": true, "replicates": [76.0, 74.0, 73.0, 117.0, 80.0, 82.0, 84.0, 60.0, 119.0, 56.0], "unit": "score", "name": "audio-fft", "value": 82.1}, {"lowerIsBetter": true, "replicates": [88.0, 93.0, 127.0, 77.0, 81.0, 80.0, 119.0, 75.0, 79.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 93.8}, {"lowerIsBetter": true, "replicates": [90.0, 87.0, 90.0, 86.0, 90.0, 87.0, 90.0, 87.0, 91.0, 131.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 92.9}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 94.4}, {"lowerIsBetter": true, "replicates": [125.0, 137.0, 92.0, 85.0, 84.0, 84.0, 87.0, 83.0, 85.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 94.8}, {"lowerIsBetter": true, "replicates": [56.0, 58.0, 58.0, 57.0, 58.0, 59.0, 60.0, 54.0, 54.0, 75.0], "unit": "score", "name": "json-parse-financial", "value": 58.9}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 76.0, 74.0, 72.0, 66.0, 67.0, 66.0, 66.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.6}, {"lowerIsBetter": true, "replicates": [81.0, 82.0, 81.0, 81.0, 79.0, 93.0, 107.0, 108.0, 85.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 88.0}, {"lowerIsBetter": true, "replicates": [128.0, 123.0, 138.0, 111.0, 117.0, 116.0, 116.0, 120.0, 118.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.5}, {"lowerIsBetter": true, "replicates": [135.0, 123.0, 135.0, 133.0, 144.0, 163.0, 171.0, 134.0, 131.0, 167.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.6}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 64.9}], "name": "kraken", "value": 1434.5}]} 15:53:16 INFO - Return code: 0 15:53:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:53:16 INFO - # TBPL SUCCESS # 15:53:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:53:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:53:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:53:16 INFO - 2016-04-29 15:53:16.251 screenresolution[1678:9873] starting screenresolution argv=screenresolution get 15:53:16 INFO - 2016-04-29 15:53:16.253 screenresolution[1678:9873] Display 0: 1600x1200x32@60 15:53:16 INFO - 2016-04-29 15:53:16.259 screenresolution[1679:9876] starting screenresolution argv=screenresolution list 15:53:16 INFO - Available Modes on Display 0 15:53:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:53:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:53:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:53:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:53:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:53:16 INFO - Intel Iris: 15:53:16 INFO - Chipset Model: Intel Iris 15:53:16 INFO - Type: GPU 15:53:16 INFO - Bus: Built-In 15:53:16 INFO - VRAM (Dynamic, Max): 1536 MB 15:53:16 INFO - Vendor: Intel (0x8086) 15:53:16 INFO - Device ID: 0x0a2e 15:53:16 INFO - Revision ID: 0x0009 15:53:16 INFO - Displays: 15:53:16 INFO - Display: 15:53:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:53:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:53:16 INFO - Display Serial Number: 1600x1200 60 15:53:16 INFO - Main Display: Yes 15:53:16 INFO - Mirror: Off 15:53:16 INFO - Online: Yes 15:53:16 INFO - Rotation: Supported 15:53:16 INFO - Return code: 0 15:53:16 INFO - Running post-action listener: _resource_record_post_action 15:53:16 INFO - Running post-run listener: _resource_record_post_run 15:53:17 INFO - Total resource usage - Wall time: 1192s; CPU: 52.0%; Read bytes: 37725696; Write bytes: 508788736; Read time: 392; Write time: 1700 15:53:17 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 196059648; Write bytes: 198240256; Read time: 17931; Write time: 461 15:53:17 INFO - run-tests - Wall time: 1170s; CPU: 52.0%; Read bytes: 36771328; Write bytes: 299632640; Read time: 377; Write time: 1204 15:53:17 INFO - Running post-run listener: _upload_blobber_files 15:53:17 INFO - Blob upload gear active. 15:53:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:53:17 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 15:53:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:53:18 INFO - (blobuploader) - INFO - Open directory for files ... 15:53:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:53:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:53:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:53:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:53:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:53:18 INFO - (blobuploader) - INFO - Done attempting. 15:53:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:53:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:53:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:53:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:53:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:53:19 INFO - (blobuploader) - INFO - Done attempting. 15:53:19 INFO - (blobuploader) - INFO - Iteration through files over. 15:53:19 INFO - Return code: 0 15:53:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:53:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:53:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23b4efcad635770abd797779c42c5a28793097944dfaf898a5a6533a17e74a98f2012edb0393b1b31ff13de0f899dbc77750bd83967ee4c372e678eb8f652674", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6da398e05472b4f370991d6d96f9a882ddd02daf1622b9e8f9c3488f6c4fa76510c28cade2fcfe84035f89dcf384519fdf73098832ef40ac63be41a3d25d3e31"} 15:53:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:53:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:53:19 INFO - Contents: 15:53:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23b4efcad635770abd797779c42c5a28793097944dfaf898a5a6533a17e74a98f2012edb0393b1b31ff13de0f899dbc77750bd83967ee4c372e678eb8f652674", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6da398e05472b4f370991d6d96f9a882ddd02daf1622b9e8f9c3488f6c4fa76510c28cade2fcfe84035f89dcf384519fdf73098832ef40ac63be41a3d25d3e31"} 15:53:19 INFO - Running post-run listener: copy_logs_to_upload_dir 15:53:19 INFO - Copying logs to upload dir... 15:53:19 INFO - mkdir: /builds/slave/test/build/upload/logs 15:53:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.227819 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-04-29 15:53:19.784444) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 15:53:19.788199) ========= 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.dDByRj7Z1w/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.JRaneXF1zO/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23b4efcad635770abd797779c42c5a28793097944dfaf898a5a6533a17e74a98f2012edb0393b1b31ff13de0f899dbc77750bd83967ee4c372e678eb8f652674", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6da398e05472b4f370991d6d96f9a882ddd02daf1622b9e8f9c3488f6c4fa76510c28cade2fcfe84035f89dcf384519fdf73098832ef40ac63be41a3d25d3e31"} build_url:https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/YPVG8Z4FQcSHTpSSjtRfhg/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23b4efcad635770abd797779c42c5a28793097944dfaf898a5a6533a17e74a98f2012edb0393b1b31ff13de0f899dbc77750bd83967ee4c372e678eb8f652674", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6da398e05472b4f370991d6d96f9a882ddd02daf1622b9e8f9c3488f6c4fa76510c28cade2fcfe84035f89dcf384519fdf73098832ef40ac63be41a3d25d3e31"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 15:53:19.815749) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:53:19.816050) ========= 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.dDByRj7Z1w/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.JRaneXF1zO/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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.005246 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:53:19.864238) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 15:53:19.864508) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 15:53:19.864835) ========= ========= Total master_lag: 0.29 =========