builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0340 starttime: 1463507489.09 results: success (0) buildid: 20160517004009 builduid: ef91a013044b49f4abd91345e579e6b6 revision: 17724486e1591858eea09dc327475a25883854a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.087274) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.087762) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.088159) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.225669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.225966) ========= 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.QfN4tUaRfU/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.jeALDi3uU0/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-17 10:51:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2016-05-17 10:51:29 (19.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.156626 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.404414) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.404832) ========= 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.QfN4tUaRfU/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.jeALDi3uU0/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.025904 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.452876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 10:51:29.453211) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17724486e1591858eea09dc327475a25883854a4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17724486e1591858eea09dc327475a25883854a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QfN4tUaRfU/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.jeALDi3uU0/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-17 10:51:29,532 truncating revision to first 12 chars 2016-05-17 10:51:29,532 Setting DEBUG logging. 2016-05-17 10:51:29,532 attempt 1/10 2016-05-17 10:51:29,532 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/17724486e159?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 10:51:30,867 unpacking tar archive at: mozilla-aurora-17724486e159/testing/mozharness/ program finished with exit code 0 elapsedTime=1.590508 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 10:51:31.062503) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:31.062803) ========= script_repo_revision: 17724486e1591858eea09dc327475a25883854a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:31.063575) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:31.063851) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:31.080320) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2016-05-17 10:51:31.080627) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QfN4tUaRfU/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.jeALDi3uU0/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:51:31 INFO - MultiFileLogger online at 20160517 10:51:31 in /builds/slave/test 10:51:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:51:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:51:31 INFO - {'append_to_log': False, 10:51:31 INFO - 'base_work_dir': '/builds/slave/test', 10:51:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:51:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:51:31 INFO - 'branch': 'Mozilla-Aurora', 10:51:31 INFO - 'buildbot_json_path': 'buildprops.json', 10:51:31 INFO - 'config_files': ('talos/mac_config.py',), 10:51:31 INFO - 'default_actions': ('clobber', 10:51:31 INFO - 'read-buildbot-config', 10:51:31 INFO - 'download-and-extract', 10:51:31 INFO - 'populate-webroot', 10:51:31 INFO - 'create-virtualenv', 10:51:31 INFO - 'install', 10:51:31 INFO - 'run-tests'), 10:51:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:51:31 INFO - 'download_minidump_stackwalk': True, 10:51:31 INFO - 'download_symbols': 'ondemand', 10:51:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:51:31 INFO - 'tooltool.py': '/tools/tooltool.py', 10:51:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:51:31 INFO - '/tools/misc-python/virtualenv.py')}, 10:51:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:51:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:51:31 INFO - 'installer_path': 'installer.exe', 10:51:31 INFO - 'log_level': 'info', 10:51:31 INFO - 'log_name': 'talos', 10:51:31 INFO - 'log_to_console': True, 10:51:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:51:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:51:31 INFO - 'opt_config_files': (), 10:51:31 INFO - 'pip_index': False, 10:51:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:51:31 INFO - 'cmd': ('bash', 10:51:31 INFO - '-c', 10:51:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:51:31 INFO - 'enabled': True, 10:51:31 INFO - 'halt_on_failure': False, 10:51:31 INFO - 'name': 'check_screen_resolution'},), 10:51:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:51:31 INFO - 'cmd': ('bash', 10:51:31 INFO - '-c', 10:51:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:51:31 INFO - 'enabled': True, 10:51:31 INFO - 'halt_on_failure': False, 10:51:31 INFO - 'name': 'check_screen_resolution'},), 10:51:31 INFO - 'run_cmd_checks_enabled': True, 10:51:31 INFO - 'sps_profile': False, 10:51:31 INFO - 'sps_profile_interval': 0, 10:51:31 INFO - 'suite': 'dromaeojs', 10:51:31 INFO - 'system_bits': '32', 10:51:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:51:31 INFO - 'title': 't-yosemite-r7-0340', 10:51:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:51:31 INFO - 'use_talos_json': True, 10:51:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:51:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:51:31 INFO - 'work_dir': 'build'} 10:51:31 INFO - ##### 10:51:31 INFO - ##### Running clobber step. 10:51:31 INFO - ##### 10:51:31 INFO - Running pre-action listener: _resource_record_pre_action 10:51:31 INFO - Running main action method: clobber 10:51:31 INFO - rmtree: /builds/slave/test/build 10:51:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:51:33 INFO - Running post-action listener: _resource_record_post_action 10:51:33 INFO - ##### 10:51:33 INFO - ##### Running read-buildbot-config step. 10:51:33 INFO - ##### 10:51:33 INFO - Running pre-action listener: _resource_record_pre_action 10:51:33 INFO - Running main action method: read_buildbot_config 10:51:33 INFO - Using buildbot properties: 10:51:33 INFO - { 10:51:33 INFO - "project": "", 10:51:33 INFO - "product": "firefox", 10:51:33 INFO - "script_repo_revision": "production", 10:51:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:51:33 INFO - "repository": "", 10:51:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:51:33 INFO - "buildid": "20160517004009", 10:51:33 INFO - "stage_platform": "macosx64", 10:51:33 INFO - "basedir": "/builds/slave/test", 10:51:33 INFO - "nightly_build": "True", 10:51:33 INFO - "buildnumber": 236, 10:51:33 INFO - "slavename": "t-yosemite-r7-0340", 10:51:33 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:51:33 INFO - "platform": "yosemite_r7", 10:51:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:51:33 INFO - "branch": "mozilla-aurora", 10:51:33 INFO - "repo_path": "releases/mozilla-aurora", 10:51:33 INFO - "revision": "17724486e1591858eea09dc327475a25883854a4", 10:51:33 INFO - "pgo_build": "False", 10:51:33 INFO - "builduid": "ef91a013044b49f4abd91345e579e6b6", 10:51:33 INFO - "slavebuilddir": "test" 10:51:33 INFO - } 10:51:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 10:51:33 INFO - Running post-action listener: _resource_record_post_action 10:51:33 INFO - ##### 10:51:33 INFO - ##### Running download-and-extract step. 10:51:33 INFO - ##### 10:51:33 INFO - Running pre-action listener: _resource_record_pre_action 10:51:33 INFO - Running main action method: download_and_extract 10:51:33 INFO - mkdir: /builds/slave/test/build/tests 10:51:33 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')]} 10:51:33 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:51:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:51:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:51:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:51:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 10:51:35 INFO - Downloaded 1199 bytes. 10:51:35 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:51:35 INFO - Using the following test package requirements: 10:51:35 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:51:35 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:35 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 10:51:35 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:35 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 10:51:35 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:35 INFO - u'jsshell-mac.zip'], 10:51:35 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:35 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 10:51:35 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:51:35 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:35 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 10:51:35 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:35 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 10:51:35 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:35 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 10:51:35 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:35 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 10:51:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:35 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:51:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:51:37 INFO - Downloaded 18102489 bytes. 10:51:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:51:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:51:41 INFO - Return code: 0 10:51:41 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:51: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')]} 10:51:41 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:51:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:52:11 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip: timed out 10:52:11 INFO - retry: attempt #1 caught exception: timed out 10:52:11 INFO - retry: Failed, sleeping 30 seconds before retrying 10:52:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #2 10:52:43 INFO - Downloaded 18102489 bytes. 10:52:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:52:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:52:46 INFO - Return code: 0 10:52: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')]} 10:52:46 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:52:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:52:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:52:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:52:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:52:48 INFO - Downloaded 10840145 bytes. 10:52:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:52:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:52:48 INFO - Return code: 0 10:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:48 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 10:52:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:52:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:52:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 10:52:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 10:52:51 INFO - Downloaded 114146064 bytes. 10:52:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:52:51 INFO - mkdir: /builds/slave/test/properties 10:52:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:52:51 INFO - Writing to file /builds/slave/test/properties/build_url 10:52:51 INFO - Contents: 10:52:51 INFO - build_url:https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:52:52 WARNING - Can't figure out symbols_url from installer_url: https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg! 10:52:52 WARNING - No symbols_url found. Let minidump_stackwalk query for symbols. 10:52:52 INFO - Running post-action listener: _resource_record_post_action 10:52:52 INFO - Running post-action listener: set_extra_try_arguments 10:52:52 INFO - ##### 10:52:52 INFO - ##### Running populate-webroot step. 10:52:52 INFO - ##### 10:52:52 INFO - Running pre-action listener: _resource_record_pre_action 10:52:52 INFO - Running main action method: populate_webroot 10:52:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:52:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:52:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:52:52 INFO - '--tppagecycles', 10:52:52 INFO - '7'], 10:52:52 INFO - 'tests': ['tsvgm']}}, 10:52:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:52:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:52:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:52:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:52:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:52 INFO - 'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:52 INFO - 'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['damp', 'tps']}, 10:52:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:52 INFO - 'tests': ['damp', 'tps']}, 10:52:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['dromaeo_dom']}, 10:52:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:52:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['a11yr', 10:52:52 INFO - 'ts_paint', 10:52:52 INFO - 'tpaint', 10:52:52 INFO - 'sessionrestore', 10:52:52 INFO - 'sessionrestore_no_auto_restore', 10:52:52 INFO - 'tabpaint']}, 10:52:52 INFO - 'other-e10s': {'tests': ['a11yr', 10:52:52 INFO - 'ts_paint', 10:52:52 INFO - 'tpaint', 10:52:52 INFO - 'sessionrestore', 10:52:52 INFO - 'sessionrestore_no_auto_restore', 10:52:52 INFO - 'tabpaint']}, 10:52:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:52:52 INFO - 'ts_paint', 10:52:52 INFO - 'tpaint', 10:52:52 INFO - 'sessionrestore', 10:52:52 INFO - 'sessionrestore_no_auto_restore', 10:52:52 INFO - 'tabpaint']}, 10:52:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:52:52 INFO - 'ts_paint', 10:52:52 INFO - 'tpaint', 10:52:52 INFO - 'sessionrestore', 10:52:52 INFO - 'sessionrestore_no_auto_restore', 10:52:52 INFO - 'tabpaint']}, 10:52:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['a11yr', 10:52:52 INFO - 'ts_paint', 10:52:52 INFO - 'tpaint', 10:52:52 INFO - 'sessionrestore', 10:52:52 INFO - 'sessionrestore_no_auto_restore', 10:52:52 INFO - 'tabpaint']}, 10:52:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['a11yr', 10:52:52 INFO - 'ts_paint', 10:52:52 INFO - 'tpaint', 10:52:52 INFO - 'sessionrestore', 10:52:52 INFO - 'sessionrestore_no_auto_restore', 10:52:52 INFO - 'tabpaint']}, 10:52:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['tsvgx', 10:52:52 INFO - 'tsvgr_opacity', 10:52:52 INFO - 'tart', 10:52:52 INFO - 'tscrollx', 10:52:52 INFO - 'cart']}, 10:52:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:52:52 INFO - 'tsvgr_opacity', 10:52:52 INFO - 'tart', 10:52:52 INFO - 'tscrollx', 10:52:52 INFO - 'cart']}, 10:52:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:52 INFO - 'talos_options': ['--disable-e10s'], 10:52:52 INFO - 'tests': ['tp5o']}, 10:52:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:52 INFO - 'tests': ['tp5o']}, 10:52:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:52 INFO - 'talos_options': ['--disable-e10s', 10:52:52 INFO - '--xperf_path', 10:52:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:52 INFO - 'tests': ['tp5n']}, 10:52:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:52 INFO - 'talos_options': ['--xperf_path', 10:52:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:52 INFO - 'tests': ['tp5n']}}, 10:52:52 INFO - 'talos.zip': {'path': '', 10:52:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:52:52 INFO - Running post-action listener: _resource_record_post_action 10:52:52 INFO - ##### 10:52:52 INFO - ##### Running create-virtualenv step. 10:52:52 INFO - ##### 10:52:52 INFO - Running pre-action listener: _resource_record_pre_action 10:52:52 INFO - Running main action method: create_virtualenv 10:52:52 INFO - Creating virtualenv /builds/slave/test/build/venv 10:52:52 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 10:52:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:52:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:52:52 INFO - Using real prefix '/tools/python27' 10:52:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:52:53 INFO - Installing distribute.............................................................................................................................................................................................done. 10:52:56 INFO - Installing pip.................done. 10:52:56 INFO - Return code: 0 10:52:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:52: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')]} 10:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52: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')]} 10:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10205da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb24847cea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.QfN4tUaRfU/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.jeALDi3uU0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:52: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', 'pip>=1.5'] in /builds/slave/test/build 10:52: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 pip>=1.5 10:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:56 INFO - 'HOME': '/Users/cltbld', 10:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:56 INFO - 'LOGNAME': 'cltbld', 10:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:56 INFO - 'MOZ_NO_REMOTE': '1', 10:52:56 INFO - 'NO_EM_RESTART': '1', 10:52:56 INFO - 'PAGER': '/bin/cat', 10:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:56 INFO - 'PWD': '/builds/slave/test', 10:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:56 INFO - 'SHELL': '/bin/bash', 10:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:52:56 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:56 INFO - 'USER': 'cltbld', 10:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:56 INFO - 'XPC_FLAGS': '0x0', 10:52:56 INFO - 'XPC_SERVICE_NAME': '0', 10:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:56 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 10:52:56 INFO - Cleaning up... 10:52:56 INFO - Return code: 0 10:52:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:52: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')]} 10:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52: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')]} 10:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52: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', '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 0x10205da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb24847cea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.QfN4tUaRfU/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.jeALDi3uU0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:52: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', 'psutil>=0.7.1'] in /builds/slave/test/build 10:52: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 psutil>=0.7.1 10:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:56 INFO - 'HOME': '/Users/cltbld', 10:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:56 INFO - 'LOGNAME': 'cltbld', 10:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:56 INFO - 'MOZ_NO_REMOTE': '1', 10:52:56 INFO - 'NO_EM_RESTART': '1', 10:52:56 INFO - 'PAGER': '/bin/cat', 10:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:56 INFO - 'PWD': '/builds/slave/test', 10:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:56 INFO - 'SHELL': '/bin/bash', 10:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:52:56 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:56 INFO - 'USER': 'cltbld', 10:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:56 INFO - 'XPC_FLAGS': '0x0', 10:52:56 INFO - 'XPC_SERVICE_NAME': '0', 10:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:57 INFO - Downloading/unpacking psutil>=0.7.1 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:52:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:52:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:52:59 INFO - Installing collected packages: psutil 10:52:59 INFO - Running setup.py install for psutil 10:52:59 INFO - building 'psutil._psutil_osx' extension 10:52:59 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 10:53:00 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 10:53:00 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 10:53:00 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 10:53:00 INFO - building 'psutil._psutil_posix' extension 10:53:00 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 10:53:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:53:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:53:00 INFO - ^ 10:53:00 INFO - 1 warning generated. 10:53:00 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 10:53:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:53:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:53:00 INFO - Successfully installed psutil 10:53:00 INFO - Cleaning up... 10:53:00 INFO - Return code: 0 10:53:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:53:00 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')]} 10:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:00 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')]} 10:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:00 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 0x10205da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb24847cea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.QfN4tUaRfU/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.jeALDi3uU0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:53:00 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 10:53:00 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 10:53:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:53:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:00 INFO - 'HOME': '/Users/cltbld', 10:53:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:00 INFO - 'LOGNAME': 'cltbld', 10:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:00 INFO - 'MOZ_NO_REMOTE': '1', 10:53:00 INFO - 'NO_EM_RESTART': '1', 10:53:00 INFO - 'PAGER': '/bin/cat', 10:53:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:00 INFO - 'PWD': '/builds/slave/test', 10:53:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:00 INFO - 'SHELL': '/bin/bash', 10:53:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:53:00 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:53:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:00 INFO - 'USER': 'cltbld', 10:53:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:00 INFO - 'XPC_FLAGS': '0x0', 10:53:00 INFO - 'XPC_SERVICE_NAME': '0', 10:53:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:53:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:53:03 INFO - Installing collected packages: mozsystemmonitor 10:53:03 INFO - Running setup.py install for mozsystemmonitor 10:53:03 INFO - Successfully installed mozsystemmonitor 10:53:03 INFO - Cleaning up... 10:53:03 INFO - Return code: 0 10:53:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:53:03 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')]} 10:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:03 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')]} 10:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:03 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 0x10205da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb24847cea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.QfN4tUaRfU/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.jeALDi3uU0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:53:03 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 10:53:03 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 10:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:03 INFO - 'HOME': '/Users/cltbld', 10:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:03 INFO - 'LOGNAME': 'cltbld', 10:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:03 INFO - 'MOZ_NO_REMOTE': '1', 10:53:03 INFO - 'NO_EM_RESTART': '1', 10:53:03 INFO - 'PAGER': '/bin/cat', 10:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:03 INFO - 'PWD': '/builds/slave/test', 10:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:03 INFO - 'SHELL': '/bin/bash', 10:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:53:03 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:03 INFO - 'USER': 'cltbld', 10:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:03 INFO - 'XPC_FLAGS': '0x0', 10:53:03 INFO - 'XPC_SERVICE_NAME': '0', 10:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:04 INFO - Downloading/unpacking blobuploader==1.2.4 10:53:04 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 10:53:04 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 10:53:04 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 10:53:04 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 10:53:04 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 10:53:04 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 10:53:06 INFO - Downloading blobuploader-1.2.4.tar.gz 10:53:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:53:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:53:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:07 INFO - Downloading docopt-0.6.1.tar.gz 10:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:53:07 INFO - Installing collected packages: blobuploader, requests, docopt 10:53:07 INFO - Running setup.py install for blobuploader 10:53:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:53:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:53:08 INFO - Running setup.py install for requests 10:53:08 INFO - Running setup.py install for docopt 10:53:08 INFO - Successfully installed blobuploader requests docopt 10:53:08 INFO - Cleaning up... 10:53:08 INFO - Return code: 0 10:53:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:08 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 0x10205da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb24847cea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.QfN4tUaRfU/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.jeALDi3uU0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:53:08 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 10:53:08 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 10:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:08 INFO - 'HOME': '/Users/cltbld', 10:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:08 INFO - 'LOGNAME': 'cltbld', 10:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:08 INFO - 'MOZ_NO_REMOTE': '1', 10:53:08 INFO - 'NO_EM_RESTART': '1', 10:53:08 INFO - 'PAGER': '/bin/cat', 10:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:08 INFO - 'PWD': '/builds/slave/test', 10:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:08 INFO - 'SHELL': '/bin/bash', 10:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:53:08 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:08 INFO - 'USER': 'cltbld', 10:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:08 INFO - 'XPC_FLAGS': '0x0', 10:53:08 INFO - 'XPC_SERVICE_NAME': '0', 10:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:09 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-9nw65w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:09 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-wD1c5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:09 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-co9shg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:09 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-izERSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:09 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-F8TC0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:09 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-vLoDES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:09 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-or9kcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:09 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-fT3Jx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:09 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-68kYd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-NYV25Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-xwQPpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-xdUjt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-EtDPdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-M25xzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-lr00ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-aVOymq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-HnnIFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:53:10 INFO - Running setup.py install for manifestparser 10:53:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:53:10 INFO - Running setup.py install for mozcrash 10:53:11 INFO - Running setup.py install for mozdebug 10:53:11 INFO - Running setup.py install for mozdevice 10:53:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:53:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:53:11 INFO - Running setup.py install for mozfile 10:53:11 INFO - Running setup.py install for mozhttpd 10:53:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:53:11 INFO - Running setup.py install for mozinfo 10:53:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:53:11 INFO - Running setup.py install for mozInstall 10:53:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:53:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:53:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:53:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:53:12 INFO - Running setup.py install for mozleak 10:53:12 INFO - Running setup.py install for mozlog 10:53:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:53:12 INFO - Running setup.py install for moznetwork 10:53:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:53:12 INFO - Running setup.py install for mozprocess 10:53:12 INFO - Running setup.py install for mozprofile 10:53:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:53:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:53:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:53:12 INFO - Running setup.py install for mozrunner 10:53:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:53:12 INFO - Running setup.py install for mozscreenshot 10:53:13 INFO - Running setup.py install for moztest 10:53:13 INFO - Running setup.py install for mozversion 10:53:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:53:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:53:13 INFO - Cleaning up... 10:53:13 INFO - Return code: 0 10:53:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:13 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')]} 10:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:13 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')]} 10:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:13 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 0x10205da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb24847cea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.QfN4tUaRfU/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.jeALDi3uU0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:53:13 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 10:53:13 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 10:53:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:53:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:13 INFO - 'HOME': '/Users/cltbld', 10:53:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:13 INFO - 'LOGNAME': 'cltbld', 10:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:13 INFO - 'MOZ_NO_REMOTE': '1', 10:53:13 INFO - 'NO_EM_RESTART': '1', 10:53:13 INFO - 'PAGER': '/bin/cat', 10:53:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:13 INFO - 'PWD': '/builds/slave/test', 10:53:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:13 INFO - 'SHELL': '/bin/bash', 10:53:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:53:13 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:53:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:13 INFO - 'USER': 'cltbld', 10:53:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:13 INFO - 'XPC_FLAGS': '0x0', 10:53:13 INFO - 'XPC_SERVICE_NAME': '0', 10:53:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:13 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-KDRbIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:13 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)) 10:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:13 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-XbOq0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:13 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)) 10:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:13 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-big_gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-bIsruu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-hLzpCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-O7Cqcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Jc7ZCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-DLp29b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-BivU3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Ow8D4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-cDBsVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-PLloI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:14 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)) 10:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:14 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-SaBtO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:15 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)) 10:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:15 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-iXKrRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:15 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)) 10:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:15 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-lFEupd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:15 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)) 10:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:15 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-1GAQit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:15 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)) 10:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:15 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-rMhaMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:15 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)) 10:53:15 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)) 10:53:15 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)) 10:53:15 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)) 10:53:15 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)) 10:53:15 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)) 10:53:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:17 INFO - Downloading blessings-1.6.tar.gz 10:53:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:53:18 INFO - Installing collected packages: blessings 10:53:18 INFO - Running setup.py install for blessings 10:53:18 INFO - Successfully installed blessings 10:53:18 INFO - Cleaning up... 10:53:18 INFO - Return code: 0 10:53:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:53:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:18 INFO - Reading from file tmpfile_stdout 10:53:18 INFO - Current package versions: 10:53:18 INFO - blessings == 1.6 10:53:18 INFO - blobuploader == 1.2.4 10:53:18 INFO - docopt == 0.6.1 10:53:18 INFO - manifestparser == 1.1 10:53:18 INFO - mozInstall == 1.12 10:53:18 INFO - mozcrash == 0.17 10:53:18 INFO - mozdebug == 0.1 10:53:18 INFO - mozdevice == 0.48 10:53:18 INFO - mozfile == 1.2 10:53:18 INFO - mozhttpd == 0.7 10:53:18 INFO - mozinfo == 0.9 10:53:18 INFO - mozleak == 0.1 10:53:18 INFO - mozlog == 3.1 10:53:18 INFO - moznetwork == 0.27 10:53:18 INFO - mozprocess == 0.22 10:53:18 INFO - mozprofile == 0.28 10:53:18 INFO - mozrunner == 6.11 10:53:18 INFO - mozscreenshot == 0.1 10:53:18 INFO - mozsystemmonitor == 0.0 10:53:18 INFO - moztest == 0.7 10:53:18 INFO - mozversion == 1.4 10:53:18 INFO - psutil == 3.1.1 10:53:18 INFO - requests == 1.2.3 10:53:18 INFO - wsgiref == 0.1.2 10:53:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53: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')]} 10:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53: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')]} 10:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:18 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 0x10205da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb24847cea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.QfN4tUaRfU/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.jeALDi3uU0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:53:18 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 10:53:18 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 10:53:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:53:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:18 INFO - 'HOME': '/Users/cltbld', 10:53:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:18 INFO - 'LOGNAME': 'cltbld', 10:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:18 INFO - 'MOZ_NO_REMOTE': '1', 10:53:18 INFO - 'NO_EM_RESTART': '1', 10:53:18 INFO - 'PAGER': '/bin/cat', 10:53:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:18 INFO - 'PWD': '/builds/slave/test', 10:53:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:18 INFO - 'SHELL': '/bin/bash', 10:53:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:53:18 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:53:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:18 INFO - 'USER': 'cltbld', 10:53:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:18 INFO - 'XPC_FLAGS': '0x0', 10:53:18 INFO - 'XPC_SERVICE_NAME': '0', 10:53:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:18 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)) 10:53:18 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)) 10:53:18 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)) 10:53:18 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)) 10:53:18 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)) 10:53:18 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)) 10:53:18 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)) 10:53:18 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)) 10:53:18 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)) 10:53:18 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)) 10:53:18 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)) 10:53:18 INFO - Cleaning up... 10:53:18 INFO - Return code: 0 10:53:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:53: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')]} 10:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53: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')]} 10:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10205da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb24847cea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.QfN4tUaRfU/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.jeALDi3uU0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:53: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', 'jsonschema'] in /builds/slave/test/build 10:53: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 jsonschema 10:53:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:53:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:18 INFO - 'HOME': '/Users/cltbld', 10:53:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:18 INFO - 'LOGNAME': 'cltbld', 10:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:18 INFO - 'MOZ_NO_REMOTE': '1', 10:53:18 INFO - 'NO_EM_RESTART': '1', 10:53:18 INFO - 'PAGER': '/bin/cat', 10:53:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:18 INFO - 'PWD': '/builds/slave/test', 10:53:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:18 INFO - 'SHELL': '/bin/bash', 10:53:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:53:18 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:53:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:18 INFO - 'USER': 'cltbld', 10:53:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:18 INFO - 'XPC_FLAGS': '0x0', 10:53:18 INFO - 'XPC_SERVICE_NAME': '0', 10:53:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:19 INFO - Downloading/unpacking jsonschema 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:53:21 INFO - Downloading/unpacking functools32 (from jsonschema) 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:21 INFO - Downloading functools32-3.2.3-2.tar.gz 10:53:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:53:22 INFO - warning: no files found matching '*.txt' 10:53:22 INFO - no previously-included directories found matching 'build' 10:53:22 INFO - no previously-included directories found matching 'dist' 10:53:22 INFO - no previously-included directories found matching '.git*' 10:53:22 INFO - Installing collected packages: jsonschema, functools32 10:53:22 INFO - Running setup.py install for functools32 10:53:22 INFO - warning: no files found matching '*.txt' 10:53:22 INFO - no previously-included directories found matching 'build' 10:53:22 INFO - no previously-included directories found matching 'dist' 10:53:22 INFO - no previously-included directories found matching '.git*' 10:53:22 INFO - Successfully installed jsonschema functools32 10:53:22 INFO - Cleaning up... 10:53:22 INFO - Return code: 0 10:53:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:22 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 0x10205da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb24847cea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.QfN4tUaRfU/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.jeALDi3uU0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:53:22 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 10:53:22 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 10:53:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:53:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:22 INFO - 'HOME': '/Users/cltbld', 10:53:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:22 INFO - 'LOGNAME': 'cltbld', 10:53:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:22 INFO - 'MOZ_NO_REMOTE': '1', 10:53:22 INFO - 'NO_EM_RESTART': '1', 10:53:22 INFO - 'PAGER': '/bin/cat', 10:53:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:22 INFO - 'PWD': '/builds/slave/test', 10:53:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:22 INFO - 'SHELL': '/bin/bash', 10:53:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:53:22 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:53:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:22 INFO - 'USER': 'cltbld', 10:53:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:22 INFO - 'XPC_FLAGS': '0x0', 10:53:22 INFO - 'XPC_SERVICE_NAME': '0', 10:53:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:22 INFO - Downloading/unpacking flake8 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:53:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:53:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:53:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:53:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:53:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:53:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:53:26 INFO - no previously-included directories found matching 'docs/_build' 10:53:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:53:26 INFO - Running setup.py install for pep8 10:53:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:53:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:53:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:53:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:53:26 INFO - no previously-included directories found matching 'docs/_build' 10:53:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:53:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:53:26 INFO - Cleaning up... 10:53:26 INFO - Return code: 0 10:53:26 INFO - Running post-action listener: _resource_record_post_action 10:53:26 INFO - Running post-action listener: _start_resource_monitoring 10:53:26 INFO - Starting resource monitoring. 10:53:26 INFO - ##### 10:53:26 INFO - ##### Running install step. 10:53:26 INFO - ##### 10:53:26 INFO - Running pre-action listener: _resource_record_pre_action 10:53:26 INFO - Running main action method: install 10:53:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:26 INFO - Reading from file tmpfile_stdout 10:53:26 INFO - Detecting whether we're running mozinstall >=1.0... 10:53:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:53:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:53:27 INFO - Reading from file tmpfile_stdout 10:53:27 INFO - Output received: 10:53:27 INFO - Usage: mozinstall [options] installer 10:53:27 INFO - Options: 10:53:27 INFO - -h, --help show this help message and exit 10:53:27 INFO - -d DEST, --destination=DEST 10:53:27 INFO - Directory to install application into. [default: 10:53:27 INFO - "/builds/slave/test"] 10:53:27 INFO - --app=APP Application being installed. [default: firefox] 10:53:27 INFO - mkdir: /builds/slave/test/build/application 10:53:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:53:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:53:58 INFO - Reading from file tmpfile_stdout 10:53:58 INFO - Output received: 10:53:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:53:58 INFO - Running post-action listener: _resource_record_post_action 10:53:58 INFO - ##### 10:53:58 INFO - ##### Running run-tests step. 10:53:58 INFO - ##### 10:53:58 INFO - Running pre-action listener: _resource_record_pre_action 10:53:58 INFO - Running main action method: run_tests 10:53:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:53:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:53:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:53:58 INFO - 2016-05-17 10:53:58.639 screenresolution[1651:8935] starting screenresolution argv=screenresolution get 10:53:58 INFO - 2016-05-17 10:53:58.641 screenresolution[1651:8935] Display 0: 1600x1200x32@60 10:53:58 INFO - 2016-05-17 10:53:58.647 screenresolution[1652:8937] starting screenresolution argv=screenresolution list 10:53:58 INFO - Available Modes on Display 0 10:53:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:53:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:53:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:53:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:53:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:53:58 INFO - Intel Iris: 10:53:58 INFO - Chipset Model: Intel Iris 10:53:58 INFO - Type: GPU 10:53:58 INFO - Bus: Built-In 10:53:58 INFO - VRAM (Dynamic, Max): 1536 MB 10:53:58 INFO - Vendor: Intel (0x8086) 10:53:58 INFO - Device ID: 0x0a2e 10:53:58 INFO - Revision ID: 0x0009 10:53:58 INFO - Displays: 10:53:58 INFO - Display: 10:53:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:53:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:53:58 INFO - Display Serial Number: 1600x1200 60 10:53:58 INFO - Main Display: Yes 10:53:58 INFO - Mirror: Off 10:53:58 INFO - Online: Yes 10:53:58 INFO - Rotation: Supported 10:53:58 INFO - Return code: 0 10:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:53:58 INFO - Python 2.7.3 10:53:58 INFO - Return code: 0 10:53:58 INFO - grabbing minidump binary from tooltool 10:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:58 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 0x101efce00>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:53:58 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 10:53:58 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 10:53:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:53:58 INFO - Return code: 0 10:53:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:53:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:53:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:53:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:53:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:54:00 INFO - Return code: 0 10:54:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--title', 't-yosemite-r7-0340', '--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 10:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --title t-yosemite-r7-0340 --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 10:54:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfN4tUaRfU/Render', 10:54:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:00 INFO - 'HOME': '/Users/cltbld', 10:54:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:00 INFO - 'LOGNAME': 'cltbld', 10:54:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:54:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:00 INFO - 'MOZ_NO_REMOTE': '1', 10:54:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:54:00 INFO - 'NO_EM_RESTART': '1', 10:54:00 INFO - 'PAGER': '/bin/cat', 10:54:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:00 INFO - 'PWD': '/builds/slave/test', 10:54:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:54:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:00 INFO - 'SHELL': '/bin/bash', 10:54:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jeALDi3uU0/Listeners', 10:54:00 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:54:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:00 INFO - 'USER': 'cltbld', 10:54:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:00 INFO - 'XPC_FLAGS': '0x0', 10:54:00 INFO - 'XPC_SERVICE_NAME': '0', 10:54:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--title', 't-yosemite-r7-0340', '--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 10:54:00 INFO - mozversion application_buildid: 20160517004009 10:54:00 INFO - mozversion application_changeset: 17724486e1591858eea09dc327475a25883854a4 10:54:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:54:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:54:00 INFO - mozversion application_name: Firefox 10:54:00 INFO - mozversion application_remotingname: firefox-dev 10:54:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:54:00 INFO - mozversion application_vendor: Mozilla 10:54:00 INFO - mozversion application_version: 48.0a2 10:54:00 INFO - mozversion platform_buildid: 20160517004009 10:54:00 INFO - mozversion platform_changeset: 17724486e1591858eea09dc327475a25883854a4 10:54:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:54:00 INFO - mozversion platform_version: 48.0a2 10:54:00 INFO - using testdate: 1463507640 10:54:00 INFO - actual date: 1463507640 10:54:00 INFO - starting webserver on 'localhost:49278' 10:54:00 INFO - SUITE-START | Running 2 tests 10:54:00 INFO - TEST-START | dromaeo_css 10:54:00 INFO - operating with platform_type : mac_ 10:54:00 INFO - Initialising browser for dromaeo_css test... 10:54:00 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpqX6o6z/profile http://localhost:49278/getInfo.html) 10:54:02 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 10:54:02 INFO - PROCESS | 1670 | colorDepth:24 10:54:02 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 10:54:02 INFO - PROCESS | 1670 | __metrics 10:54:02 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:54:02 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:54:03 INFO - TEST-INFO | 1670: exit 0 10:54:03 INFO - Browser initialized. 10:54:03 INFO - Running cycle 1/1 for dromaeo_css test... 10:54:03 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpqX6o6z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:54:15 INFO - PROCESS | 1674 | RSS: Main: 210849792 10:54:15 INFO - PROCESS | 1674 | 10:55:07 INFO - PROCESS | 1674 | 1463507707250 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] 10:57:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 10:57:19 INFO - PROCESS | 1674 | RSS: Main: 349450240 10:57:19 INFO - PROCESS | 1674 | 11:00:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 11:00:22 INFO - PROCESS | 1674 | RSS: Main: 360280064 11:00:22 INFO - PROCESS | 1674 | 11:03:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 11:03:25 INFO - PROCESS | 1674 | RSS: Main: 358215680 11:03:25 INFO - PROCESS | 1674 | 11:06:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 11:06:28 INFO - PROCESS | 1674 | RSS: Main: 352546816 11:06:28 INFO - PROCESS | 1674 | 11:09:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 11:09:32 INFO - PROCESS | 1674 | RSS: Main: 322166784 11:09:32 INFO - PROCESS | 1674 | 11:12:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 11:12:35 INFO - PROCESS | 1674 | RSS: Main: 366350336 11:12:35 INFO - PROCESS | 1674 | 11:12:35 INFO - PROCESS | 1674 | __start_tp_report 11:12:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 11:12:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 11:12:35 INFO - PROCESS | 1674 | |i|pagename|runs| 11:12:35 INFO - PROCESS | 1674 | |0;dojo.html;3408.182036311285;3464.411050121479;3481.2863363010583;3485.3552092862815;3544.273423948091;5781.7109144542765;5808.883822323556;5830.533557315415;5835.241418615585;5854.882902341945;5769.990251705955;5827.854303642411;5831.912521312183;5849.268841394825;5869.70651467426;5071.061853557104;5128.71792051437;5172.379314482268;5249.4488078751565;5308.654937429074;5238.973805130969;5241.86895327616;5247.160454327309;5252.107141785906;5256.974889896453;5175.844724658266;5232.215167724853;5246.7638956247065;5253.159494480884;5272.367315922094;5239.978204250173;5275.208718692227;5291.232771248147;5302.257683924254;5323.973380133075;5092.261622064817;5135.512126348029;5188.766505507261;5200.817971370984;5203.375594928595;5581.246531718207;5602.579806514527;5636.323641163046;5678.971605141989;5743.023685973395;6237.407446292595;6264.843378915518;6267.310595834455;6270.122182894398;6276.686165691697;6205.162303089115;6260.780872669435;6264.1543391642;6264.404881536247;6270.498360131179;4873.342098816685;5699.829005129853;5797.043487824546;5859.970700146472;5869.148973398833;6198.442140207403;6221.191245138103;6243.469305109116;6260.342664020303;6268.373162683695;5218.478152184751;6213.596116252429;6215.564910456315;6222.906656400158;6225.159603453476;6138.07928810682;6157.138000679908;6261.96869015652;6271.43557079865;6278.215223097113;6172.814815555541;6213.440790328892;6234.37656234382;6242.906356404657;6246.625202487865;6110.755569777248;6237.719302631393;6242.687865606701;6244.250689917239;6276.905846412307;4868.099401610642;4870.805167793319;4897.510248975074;4955.603551715854;4959.776810043484;4444.200043992112;4460.553944605514;4461.018575913294;4474.127545128668;4507.909841803145;5848.561357898175;5853.0635098384055;5854.238948936696;5861.912071318934;5868.823935281948;6138.786925362681;6144.15675297409;6209.6895155242055;6210.72051757672;6213.906791398133;5755.913661295084;5777.62445441045;5783.103618939072;5784.652920824764;5792.507636850914;5318.760655770499;5400.702961337086;5405.648632838853;5408.729563521808;5421.891562168735;6177.4749146322165;6311.968440157862;6329.050642403492;6354.650494222862;6360.045993101072;6211.782587609412;6232.594881332699;6237.25152981627;6239.407256310643;6255.78104766331;6179.196704428396;6310.242770867525;6313.43179113882;6320.494360451061;6323.841903952438;6141.854248382235;6245.6252624842655;6250.781222657185;6254.843628909313;6255.40573645503;6226.470749986302;6230.127782110599;6230.501559875109;6232.594881332699;6235.189425374672;5956.731947062306;6038.094285856981;6101.816945491465;6154.261488621394;6172.8765424690355;6034.366391529002;6054.606450580698;6090.056041313604;6112.8777424452155;6122.020476723703;5792.507636850914;5938.109283607493;5961.016432288798;5978.970105149447;5983.640981541122;5121.257417674342;5976.133460648095;6121.143039974496;6147.831911936718;6150.261968563801;5979.671118088547;5981.611195272293;5985.521158307412;5992.4307190818545;6003.8198854034445 11:12:35 INFO - PROCESS | 1674 | |1;ext.html;510.1302279613262;516.9896602067959;523.3980921939768;561.5984571031713;602.8010756450371;30584.54123188151;31278.374432511337;31338.52992205116;31389.529157062607;31524.68475315246;32934.34131317378;40387.39418908719;41079;41119.383209251886;41270.58729412705;9643.421394716323;9868.703938881808;9876.60493580256;9879.407235565888;9882.703518894445;8243.258106770389;8618.78453038676;8890.288776897865;9616;9737.659181928633;11601.187916845824;11850;11850.170488065838;11851.585194518193;11887.940560297186;11498.8275175872;11591.304521728638;11602.12984026193;11616.88383116175;11627.767444651103;8565.914340856574;11398.487068081873;11493.080553555696;11738.882611173864;11742.530298788039;39033.82898513021;39504.80247598772;39623.20753584912;39717.40423893644;39816;3176.3806057818733;3230.2408933900515;3306.2891478332035;3338.4491558892755;3388.525606415104;28264.434711305865;28722.569161462594;28894.566581501294;29165.854170729224;29619.70380296191;2708.3906121122786;2770.7367800058964;2801.523740964041;2838.7728981681416;2879.3233590106306;331048;335275.3236233828;336065;336329;337427;59516;59524;59611.105833412534;59654.70172649109;59875;67603.98594021094;67655;67694.98457523141;67786.66106669435;67886.66056669685;17459.21433535494;17672.204750786244;17682;17683.646327073388;17685.93884366942;13424.261665608485;13512.986526010589;13541.05212635106;13542.661433464045;13549.661258468617;17569.56076098082;17793.644127117386;18515.629800851722;20720.896395517924;21106.366808995754;6819.420349270367;7380.889286660705;7801.024871891013;7988.360931125496;8160.67357305713;898.6585097662846;914.3736540469799;1078.3422112511362;1122.1976286954866;1179.8584169899666;30348.937787177343;30439.239019024702;30599.541006884916;30644.54033189504;30998.070057898298;1069.3957913778845;1121.0807138146643;1138.908887289015;1168.8136541410474;1235.7343171218051;14501.564953051426;15231.695366092616;15388.923055384652;15784;15843.920780396023;585877;1002718;1022922;1023184;1027718;63228;63448.68275658592;63860.68069659622;63995.6800215996;64104.71790564163;1525.9313330900136;1527.0303357368373;1531.8851086168581;1546.0569052877736;1548.1639914446298;29193.562096568145;30683.386332273676;30799.956062830366;31162.688373116885;31541;55542.72228638831;56004.159937601784;56132.71933640469;56277.71861140668;56312.71843640756;586189;588154;588433;588437;589285.107148923;27549.31126721848;28056.43887122287;42685.146297074505;43556;44421;42782.57217427786;43281.78359108185;43601.78199108984;43746.562534375524;43871.56128438675;35943.82028089843;36172;36291.27417451689;36742.63257367499;36903.44644830276;36328.09179770527;38202.61797382102;39048.41427378535;39468.80265598654;39961.00097497586;35175.64824351724;35684;36200;36203.09492262715;36363.81818090893;29248.56127158137;31726;31922.36155276928;31972.200694981882;32210;20977.790222098196;24041;24368.75631243736;24468.51062978695;24536 11:12:35 INFO - PROCESS | 1674 | |2;jquery.html;376.3038378998854;396.67869026088863;400.40939614069254;401.3357892687603;414.61026634963133;15112.395504179833;19843.60312793745;20517.281895133674;20584.485387865272;21020;19360.257576818083;19868.60262794743;20541;20556.869372184523;20557.588848223026;5828.766849326022;6007.519398448114;6049.546284028694;6057.3336932937345;6061.969690151543;4218.957810421887;6056.091586262052;6423.8394040148905;6730.226024007253;6762.357576030269;5832.300123985106;6631.104800851872;6682.498812589051;6692.23039350476;6730.024146498755;6438;6645.102911106987;6658.201015878101;6664.100346453215;6681.866362672719;3833;5324.722066704002;5831.358550559435;5831.708414579254;5831.912521312183;24371.878140609184;24783.62824557615;25025.749742502525;25591.360215994562;25657.486850263078;2041.0407108658906;2175.5540114276537;2388.940276493084;2511.183865243785;2800.0899707595113;9627.903720962771;9783.266255030901;9990.550425230882;10012.949935250277;10051.994800519962;1738.0527612451244;1759.0852756566642;1805.1966874740745;1807.13257636334;1902.8667993240479;111168;116877.41561292224;117357;118949.40525297404;119440.40279798546;9778.511074446249;10801.273560621574;11222.49498772541;11782.941085294518;11846.822297665542;14450.710985780226;15053.849461505464;15055.924720376546;15066.246687665793;15103.697926041417;8928.464292142493;8996.280297576179;9005;9011.188992990532;9098.408603440756;9008.099190081071;9015.684451044182;9032.63869445228;9104.590293436811;9122.680706175252;4034.4755181826763;4659.627229821606;4715.316279139505;4748.738819364899;4769.165396055676;1747.344745720355;1908.446550500367;1929.3825975687926;1956.7945365736393;1964.1912065746121;886.8536691445839;929.6885543343055;969.4280374579032;1013.5287091502487;1026.743314171457;19292.807071929383;20248.595028099357;20297.877818599587;20323.288684895957;20363.796362036486;954.613381580461;954.8663187153804;981.4994352880083;983.0709979069853;1083.550326614462;10669.733256668645;10783.352998819939;10802.189835762349;10812.729681758019;10826;510332;604260;616307;639208;640047;112462.43768781103;135514.32242838724;137227;138408;141016;1011.3173607814696;1035.7307100154014;1045.968620941373;1047.1936608811172;1056.0284538225005;17722.5569360767;17743.290268389635;17885.10574471245;18153.818461815743;18460.723089153405;51581;51661.22508162305;52040.479595203564;52862.47137528576;53400.73299633632;75647;78627;79230;80061.39877202307;81470.18529814626;7705.1909549496495;8137.430379873352;8259.091499934882;8261.669533218605;8470.49177049379;10464.529096190841;11661.640097658947;12237.632871013884;12557.372131393577;12642.620721378373;8203.917960820554;10779;10953.726156846142;11063.778724425627;11178.161637877354;10194.694159175237;10665.146788257076;10860.294080884718;10951.945240273748;11075.947784960415;10833.67498975032;11067.833982490109;11320.943395282971;11335.263207891461;11402.372869492257;7996.56018918965;8058.476199047043;8166.836663266583;8183.3862460316905;8415.200555947175;9177.587008584764;10802.567897284334;10848.783024339627;11095.167862410513;11107.944460277648 11:12:35 INFO - PROCESS | 1674 | |3;mootools.html;1405.3394904394943;1533.7239296926555;1562.2110834028817;1788.2221233763314;1934.516370907273;7864.8427031459405;8057.677692892277;8061.516309021463;8468.703595374163;8693.47839129651;8545.4445461045;8574.971003479575;8682.565871706422;8708.91291087089;8722.040575536701;2401.0635852017726;2425.745296743843;2425.8059355251626;2431.963520547193;2432.990309021754;2399;2430.86630235337;2453.7423570525107;2455.533448644752;2455.594826853567;2394.1500767227644;2433.1119141513395;2434.294054724137;2439.9268021959283;2444.1934161726585;2386.546556154325;2427.526632306701;2429.708434987795;2434.342727463593;2436.719777225624;2123.8938053097436;2364.7635236476217;2410.421498840283;2413.3363325085566;2413.5655581995234;8694.347923905732;8759.868601970975;8763.342749293764;8768.561571921378;8775.956120219358;5287.444818294062;5288.444713305086;5377.247185394048;5423.2949716537;5453.890922181574;3463.6363181865795;3545.18460754028;3591.7844929304065;3601.477785721082;3601.693856022241;1034.1881622925966;1040.2042437535292;1054.8628678271775;1077.13828936851;1085.5386460754148;11458.541658333657;11549.191556591042;11550.884491155064;11556.653300401002;11571.479283432265;4575.9084818303445;4615.469221039623;4635.188841952645;4688.8124475021295;4722.598579120745;9263.583138758771;9266.721998339926;9267.490288034089;9275.58259878307;9294.117058879396;5264.499872512105;5291.523762861361;5329.440408757102;5339.919901201485;5344.171653393731;4379.540148284448;4482.731036137842;4546.909061818745;4729.290606409066;4962.503749625009;3791.658750712394;3811.8284677189595;3913.745606535566;3921.9803900980314;3925.685945124383;1199.8080307150988;1220.097128125181;1227.110344999886;1233.340163012784;1237.665830225834;802.5866678660494;849.869673334476;876.96956076611;906.7733066733317;915.7481692534607;5618.747156377891;5712.571557133231;5770.105633626795;5852.8536786579825;6005.459508644243;449.6065942300487;454.2232781942915;473.93601863747955;474.4875534422815;486.61557369678263;3651.123730304708;3675.9707281961096;3679.687226585717;3723.3484140275345;3723.5531736191288;10264.024917632813;10601.893981060244;10610.681679549625;10660.946695266473;10743.78512429747;10508.527116279785;10512.264141510173;10518.631847885288;10584.57661693539;10589.9470502647;8148.633311500997;8197.385196110316;8238.258556729923;8298.502089874506;8298.99999999988;5413.918791218056;5422.701751403711;5563.638363506359;5680.4319568042865;5787.131930210502;9028.954855225682;9244.445333280024;9374.250059995047;9396.859047114436;9432.434053956784;9076;9337.673181438617;9524.857127143237;9525.666601668909;9535.189508891823;6820.727170913108;7009.7897063088185;7504.287092726182;7645.006149200568;7646.770596882103;7551.962240188764;7613.695452181851;7629.3133617974645;7914.564698941614;8161.836763264582;7233.782986510413;7338.082739657543;7340.339369456775;7463.738769143054;7468.365188958999;7081.893771593327;7103.2186459488385;7198.640067996684;7215.242399548182;7256.455765817479;6728.629925354153;6863.794086177223;6893.827654308682;6897.862042759017;6914.170299564084;4726.220173671306;4795.880102997453;4840.104580652591;4883.80464781419;4920.237363208708;4819.879503012453;4839.806407743651;4874.415070191458;4878.512148785235;4941.802327906844 11:12:35 INFO - PROCESS | 1674 | |4;prototype.html;317.98879562989686;323.1936318884383;333.3849048505507;335.24736965512426;335.4314437029236;6580.045893345462;6589.505787065966;6594.505412094094;6601.2408573014;6627.469802415807;6325.051242313655;6447.097406363113;6466.935330646692;6468.8059358219325;6535.3137920518375;4066.573009833962;4911.803527858881;4931.211006239003;4936.654434189608;4939.777710003059;4875.488073752258;4918.532739389752;4949.183384741531;4952.653314268003;4968.7764050617625;4645.790939407735;4709.740964246965;4838.830640927568;4892.804287828482;4900.803967841282;4811.711297322172;4824.758762061901;4835.202191638376;4840.443349014873;4847.51524847516;4161.771102589357;4165.645920096796;4171.186618609372;4173.061061261223;4182.6406417914295;5693.060644993571;5980.162777211194;6235.812925612194;6303.212098487689;6372.076048972873;5823.854403639943;5844.766209351621;5849.678267695275;5857.85355366115;5864.179014937912;6843.657817109125;7069.8939515907305;7079.575225486487;7092.468064895102;7105.253948335402;4735.857924262277;4775.641826862964;4777.952220477786;4778.163821331288;4786.305985632099;7393.964844921716;7492.100947886289;7493.138289096769;7504.662290196899;7505.099388073411;4843.39457567804;4857;4866.6106711463;4875.512448755097;4881.95118048822;4934.037862616755;4943.728094954812;4946.752662366867;4948.975255123702;4950.925736113961;5579.7489112990015;5862.560307976919;5868.2664666916635;5870.559708021916;5894.263217097863;7111.324424179695;7138;7140.178879428932;7145.964270178616;7159.606221657858;1686.215909602041;1719.8133288031217;1721.3200785689583;1724.249951271201;1725.1891610942837;1005.8340373838234;1007.6019972110975;1029.2383636109225;1109.7114750164853;1124.2692250037542;6507.414332709984;6608.339166083353;6612.07430959666;6612.471002319803;6633.204015518072;7338.669759860819;7343.963280183565;7345.192028876926;7345.595992220479;7358.668859901317;6582.341765823379;6593.769218077345;6596.241432235259;6605.273419923805;6606.141201643755;3739.4390841373465;3855.1904100139286;3869.090763670535;3881.883543493643;3888.980555097263;7660.655270512841;7680.116786569504;7711.730089446843;7714.035745531809;7736.690532378641;7477.588732619649;7532.661030253542;7556.735514256975;7557.05536807899;7567;7681.423893208143;7688.231176882379;7698.538087714755;7698.7305444310305;7701.3068823806125;7035.929640703527;7294.963525182552;7301.634918254173;7321.6705248265025;7338.669759860926;7129.572225666477;7173.354398104196;7183.138023437221;7187.1734750504365;7200.063991681048;7660.310572048543;7675.692972281269;7702.537847729154;7703.383729301754;7719.459637825523;4876.658633895593;4877.975610121926;4882.7558622068045;4883.340748998839;4886.53577910098;5949.970250148722;7006.824829379102;7257.963710181626;7274.527155734861;7296.379807716404;6630.104935833793;6998.335158159966;6999.335063168991;7065.57606543609;7116.359527642537;6273.1844860167885;7065.364117229474;7078.823029424306;7096.432285417258;7109.28907109285;5736;6703.128593282842;6930.306969303029;6960.5127641066665;6971.442284617117;6875.931240687529;7040.401565866959;7109.680064397218;7132.108486439195;7134.143902731498;5713.600047996767;6631.148606099424;6773.796786096425;6842.247352791289;6869.416099631588 11:12:35 INFO - PROCESS | 1674 | |5;yui.html;676.8510927595929;745.8657441660499;749.8762704153808;750.9849803003942;772.0426670928051;12260.754784904297;14896.180710060942;15070;15094.698106037886;15106.773398399033;13709.314534273297;13930.442782288696;13946.790798138036;13963.301834908265;13988.230647314394;736.3814395907436;746.8954346391482;755.5957562703954;899.8425275576781;925.2875286029753;724.2431658916445;735.6358602491774;911.4485736129645;945;946.7680418297492;895.552223888056;962.4802606592467;984.2372161574801;995.5967833027636;999.2305924438178;975.3855071305068;987.027777639025;988.7280997725611;990.8166989107038;991.6727479931606;571.6370104983324;716.2193209401725;729.3253740290263;734.867723809714;737.2590546500786;14807.333669984877;14813.925930370388;14855.034422762486;14872;14894.627634309121;12262.26426414418;12305;12361.69095772604;12530.310832904186;12983.805242921364;9841.261905357127;10094.274545872646;10104.494775261208;10242.692719218436;10654.840177397346;3505.614382417932;3514.3849826280552;3514.8752399232258;3533;3647.4528820676846;62469.75060498765;75974;76115.23884761137;79528;82308.58845705733;9392.90607093934;9423.717288481357;9426.057394260519;9516.809663806685;9561.474118923526;21042;21205.045772940255;21230.575388492143;21248.043838027017;21280.36158915235;7128.322809333105;7174.892376614355;7203.639818009078;7273.3453989141235;7574.280443357872;3969.4839670843185;4052.0882801370044;4116.0327323079055;4125.958740412618;4192.622663960258;3593.2633810068874;3617.746757726934;3653.762505437138;3656.8720094797086;3664.706823454117;634.2737565487491;638.8498702804837;649.2890285137767;650.2619526836979;652.3346186889441;506.89608630230714;584.5440556366042;592.4579010205614;621.7015832400473;626.1359324132668;7739.845203095907;7930.889675445443;8021.8395632088195;8159.184081591793;8192.098869124511;573.7360814025511;595.4462350014442;597.3160730963023;602.0277252237605;602.3945934336052;3307.7353811695007;3612.837422316002;3819.1216020315483;3891.6108389161423;3902.3366027775346;89731.55134224288;92788.53605732063;93353.71427715108;94557;94701;79872;79966.80049799258;80536.59731701305;80833;80934.595327023;66862;66943.66528167328;68970;68975;69205.65397172981;7639.656215470428;7756.457048006585;7875;7909.683612655429;7953.2842044213985;50496.73758156075;51320.74339628278;51476.742616286676;51875;52919;67745.30636734121;70627;71225.93161102483;71461;71726;10896.61861834832;11128.944355278172;11252.549898003988;11314.377709226072;11559.711007224887;11840.585579504675;11867.940660296643;11904.404779760804;11935.164538482222;12748.553800616934;9126.722258883894;11222.66332010041;11380.260283081574;11539.19225654196;11751.294922304689;10808.729781755519;11174.66476005721;11623.709407264885;12098.850609192114;12193.939030304791;11297.661070167907;11394.145439092266;11596.130290228435;11894.643160705193;11934.641960741192;8836.602352894264;9133.680321188727;9189.86215206759;9213.539323033956;9377.484238366955;8417.873731893904;8444.831103378021;8564.100769419121;8571.014333351744;8626.39615226928 11:12:35 INFO - PROCESS | 1674 | __end_tp_report 11:12:35 INFO - PROCESS | 1674 | __start_cc_report 11:12:35 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1361 11:12:35 INFO - PROCESS | 1674 | __end_cc_report 11:12:35 INFO - PROCESS | 1674 | __startTimestamp1463508755290__endTimestamp 11:12:35 INFO - PROCESS | 1674 | 11:12:35 INFO - PROCESS | 1674 | ------- Summary: start ------- 11:12:35 INFO - PROCESS | 1674 | Number of tests: 6 11:12:35 INFO - PROCESS | 1674 | 11:12:35 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5752.42 Median:5978.97 stddev:627.54 (10.5%) stddev-sans-first:601.91 11:12:35 INFO - PROCESS | 1674 | Values: 3408.2 3464.4 3481.3 3485.4 3544.3 5781.7 5808.9 5830.5 5835.2 5854.9 5770.0 5827.9 5831.9 5849.3 5869.7 5071.1 5128.7 5172.4 5249.4 5308.7 5239.0 5241.9 5247.2 5252.1 5257.0 5175.8 5232.2 5246.8 5253.2 5272.4 5240.0 5275.2 5291.2 5302.3 5324.0 5092.3 5135.5 5188.8 5200.8 5203.4 5581.2 5602.6 5636.3 5679.0 5743.0 6237.4 6264.8 6267.3 6270.1 6276.7 6205.2 6260.8 6264.2 6264.4 6270.5 4873.3 5699.8 5797.0 5860.0 5869.1 6198.4 6221.2 6243.5 6260.3 6268.4 5218.5 6213.6 6215.6 6222.9 6225.2 6138.1 6157.1 6262.0 6271.4 6278.2 6172.8 6213.4 6234.4 6242.9 6246.6 6110.8 6237.7 6242.7 6244.3 6276.9 4868.1 4870.8 4897.5 4955.6 4959.8 4444.2 4460.6 4461.0 4474.1 4507.9 5848.6 5853.1 5854.2 5861.9 5868.8 6138.8 6144.2 6209.7 6210.7 6213.9 5755.9 5777.6 5783.1 5784.7 5792.5 5318.8 5400.7 5405.6 5408.7 5421.9 6177.5 6312.0 6329.1 6354.7 6360.0 6211.8 6232.6 6237.3 6239.4 6255.8 6179.2 6310.2 6313.4 6320.5 6323.8 6141.9 6245.6 6250.8 6254.8 6255.4 6226.5 6230.1 6230.5 6232.6 6235.2 5956.7 6038.1 6101.8 6154.3 6172.9 6034.4 6054.6 6090.1 6112.9 6122.0 5792.5 5938.1 5961.0 5979.0 5983.6 5121.3 5976.1 6121.1 6147.8 6150.3 5979.7 5981.6 5985.5 5992.4 6003.8 11:12:35 INFO - PROCESS | 1674 | 11:12:35 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:74635.90 Median:18120.32 stddev:182338.32 (1006.3%) stddev-sans-first:182764.89 11:12:35 INFO - PROCESS | 1674 | Values: 510.1 517.0 523.4 561.6 602.8 30584.5 31278.4 31338.5 31389.5 31524.7 32934.3 40387.4 41079.0 41119.4 41270.6 9643.4 9868.7 9876.6 9879.4 9882.7 8243.3 8618.8 8890.3 9616.0 9737.7 11601.2 11850.0 11850.2 11851.6 11887.9 11498.8 11591.3 11602.1 11616.9 11627.8 8565.9 11398.5 11493.1 11738.9 11742.5 39033.8 39504.8 39623.2 39717.4 39816.0 3176.4 3230.2 3306.3 3338.4 3388.5 28264.4 28722.6 28894.6 29165.9 29619.7 2708.4 2770.7 2801.5 2838.8 2879.3 331048.0 335275.3 336065.0 336329.0 337427.0 59516.0 59524.0 59611.1 59654.7 59875.0 67604.0 67655.0 67695.0 67786.7 67886.7 17459.2 17672.2 17682.0 17683.6 17685.9 13424.3 13513.0 13541.1 13542.7 13549.7 17569.6 17793.6 18515.6 20720.9 21106.4 6819.4 7380.9 7801.0 7988.4 8160.7 898.7 914.4 1078.3 1122.2 1179.9 30348.9 30439.2 30599.5 30644.5 30998.1 1069.4 1121.1 1138.9 1168.8 1235.7 14501.6 15231.7 15388.9 15784.0 15843.9 585877.0 1002718.0 1022922.0 1023184.0 1027718.0 63228.0 63448.7 63860.7 63995.7 64104.7 1525.9 1527.0 1531.9 1546.1 1548.2 29193.6 30683.4 30800.0 31162.7 31541.0 55542.7 56004.2 56132.7 56277.7 56312.7 586189.0 588154.0 588433.0 588437.0 589285.1 27549.3 28056.4 42685.1 43556.0 44421.0 42782.6 43281.8 43601.8 43746.6 43871.6 35943.8 36172.0 36291.3 36742.6 36903.4 36328.1 38202.6 39048.4 39468.8 39961.0 35175.6 35684.0 36200.0 36203.1 36363.8 29248.6 31726.0 31922.4 31972.2 32210.0 20977.8 24041.0 24368.8 24468.5 24536.0 11:12:35 INFO - PROCESS | 1674 | 11:12:35 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:35177.00 Median:24577.75 stddev:100892.75 (410.5%) stddev-sans-first:101141.94 11:12:35 INFO - PROCESS | 1674 | Values: 376.3 396.7 400.4 401.3 414.6 15112.4 19843.6 20517.3 20584.5 21020.0 19360.3 19868.6 20541.0 20556.9 20557.6 5828.8 6007.5 6049.5 6057.3 6062.0 4219.0 6056.1 6423.8 6730.2 6762.4 5832.3 6631.1 6682.5 6692.2 6730.0 6438.0 6645.1 6658.2 6664.1 6681.9 3833.0 5324.7 5831.4 5831.7 5831.9 24371.9 24783.6 25025.7 25591.4 25657.5 2041.0 2175.6 2388.9 2511.2 2800.1 9627.9 9783.3 9990.6 10012.9 10052.0 1738.1 1759.1 1805.2 1807.1 1902.9 111168.0 116877.4 117357.0 118949.4 119440.4 9778.5 10801.3 11222.5 11782.9 11846.8 14450.7 15053.8 15055.9 15066.2 15103.7 8928.5 8996.3 9005.0 9011.2 9098.4 9008.1 9015.7 9032.6 9104.6 9122.7 4034.5 4659.6 4715.3 4748.7 4769.2 1747.3 1908.4 1929.4 1956.8 1964.2 886.9 929.7 969.4 1013.5 1026.7 19292.8 20248.6 20297.9 20323.3 20363.8 954.6 954.9 981.5 983.1 1083.6 10669.7 10783.4 10802.2 10812.7 10826.0 510332.0 604260.0 616307.0 639208.0 640047.0 112462.4 135514.3 137227.0 138408.0 141016.0 1011.3 1035.7 1046.0 1047.2 1056.0 17722.6 17743.3 17885.1 18153.8 18460.7 51581.0 51661.2 52040.5 52862.5 53400.7 75647.0 78627.0 79230.0 80061.4 81470.2 7705.2 8137.4 8259.1 8261.7 8470.5 10464.5 11661.6 12237.6 12557.4 12642.6 8203.9 10779.0 10953.7 11063.8 11178.2 10194.7 10665.1 10860.3 10951.9 11075.9 10833.7 11067.8 11320.9 11335.3 11402.4 7996.6 8058.5 8166.8 8183.4 8415.2 9177.6 10802.6 10848.8 11095.2 11107.9 11:12:35 INFO - PROCESS | 1674 | 11:12:35 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5568.22 Median:4881.16 stddev:3097.14 (63.5%) stddev-sans-first:3090.03 11:12:35 INFO - PROCESS | 1674 | Values: 1405.3 1533.7 1562.2 1788.2 1934.5 7864.8 8057.7 8061.5 8468.7 8693.5 8545.4 8575.0 8682.6 8708.9 8722.0 2401.1 2425.7 2425.8 2432.0 2433.0 2399.0 2430.9 2453.7 2455.5 2455.6 2394.2 2433.1 2434.3 2439.9 2444.2 2386.5 2427.5 2429.7 2434.3 2436.7 2123.9 2364.8 2410.4 2413.3 2413.6 8694.3 8759.9 8763.3 8768.6 8776.0 5287.4 5288.4 5377.2 5423.3 5453.9 3463.6 3545.2 3591.8 3601.5 3601.7 1034.2 1040.2 1054.9 1077.1 1085.5 11458.5 11549.2 11550.9 11556.7 11571.5 4575.9 4615.5 4635.2 4688.8 4722.6 9263.6 9266.7 9267.5 9275.6 9294.1 5264.5 5291.5 5329.4 5339.9 5344.2 4379.5 4482.7 4546.9 4729.3 4962.5 3791.7 3811.8 3913.7 3922.0 3925.7 1199.8 1220.1 1227.1 1233.3 1237.7 802.6 849.9 877.0 906.8 915.7 5618.7 5712.6 5770.1 5852.9 6005.5 449.6 454.2 473.9 474.5 486.6 3651.1 3676.0 3679.7 3723.3 3723.6 10264.0 10601.9 10610.7 10660.9 10743.8 10508.5 10512.3 10518.6 10584.6 10589.9 8148.6 8197.4 8238.3 8298.5 8299.0 5413.9 5422.7 5563.6 5680.4 5787.1 9029.0 9244.4 9374.3 9396.9 9432.4 9076.0 9337.7 9524.9 9525.7 9535.2 6820.7 7009.8 7504.3 7645.0 7646.8 7552.0 7613.7 7629.3 7914.6 8161.8 7233.8 7338.1 7340.3 7463.7 7468.4 7081.9 7103.2 7198.6 7215.2 7256.5 6728.6 6863.8 6893.8 6897.9 6914.2 4726.2 4795.9 4840.1 4883.8 4920.2 4819.9 4839.8 4874.4 4878.5 4941.8 11:12:35 INFO - PROCESS | 1674 | 11:12:35 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:5808.49 Median:6581.19 stddev:1827.75 (27.8%) stddev-sans-first:1785.81 11:12:35 INFO - PROCESS | 1674 | Values: 318.0 323.2 333.4 335.2 335.4 6580.0 6589.5 6594.5 6601.2 6627.5 6325.1 6447.1 6466.9 6468.8 6535.3 4066.6 4911.8 4931.2 4936.7 4939.8 4875.5 4918.5 4949.2 4952.7 4968.8 4645.8 4709.7 4838.8 4892.8 4900.8 4811.7 4824.8 4835.2 4840.4 4847.5 4161.8 4165.6 4171.2 4173.1 4182.6 5693.1 5980.2 6235.8 6303.2 6372.1 5823.9 5844.8 5849.7 5857.9 5864.2 6843.7 7069.9 7079.6 7092.5 7105.3 4735.9 4775.6 4778.0 4778.2 4786.3 7394.0 7492.1 7493.1 7504.7 7505.1 4843.4 4857.0 4866.6 4875.5 4882.0 4934.0 4943.7 4946.8 4949.0 4950.9 5579.7 5862.6 5868.3 5870.6 5894.3 7111.3 7138.0 7140.2 7146.0 7159.6 1686.2 1719.8 1721.3 1724.2 1725.2 1005.8 1007.6 1029.2 1109.7 1124.3 6507.4 6608.3 6612.1 6612.5 6633.2 7338.7 7344.0 7345.2 7345.6 7358.7 6582.3 6593.8 6596.2 6605.3 6606.1 3739.4 3855.2 3869.1 3881.9 3889.0 7660.7 7680.1 7711.7 7714.0 7736.7 7477.6 7532.7 7556.7 7557.1 7567.0 7681.4 7688.2 7698.5 7698.7 7701.3 7035.9 7295.0 7301.6 7321.7 7338.7 7129.6 7173.4 7183.1 7187.2 7200.1 7660.3 7675.7 7702.5 7703.4 7719.5 4876.7 4878.0 4882.8 4883.3 4886.5 5950.0 7006.8 7258.0 7274.5 7296.4 6630.1 6998.3 6999.3 7065.6 7116.4 6273.2 7065.4 7078.8 7096.4 7109.3 5736.0 6703.1 6930.3 6960.5 6971.4 6875.9 7040.4 7109.7 7132.1 7134.1 5713.6 6631.1 6773.8 6842.2 6869.4 11:12:35 INFO - PROCESS | 1674 | 11:12:35 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:18217.29 Median:636.56 stddev:25714.71 (4039.6%) stddev-sans-first:25753.12 11:12:35 INFO - PROCESS | 1674 | Values: 676.9 745.9 749.9 751.0 772.0 12260.8 14896.2 15070.0 15094.7 15106.8 13709.3 13930.4 13946.8 13963.3 13988.2 736.4 746.9 755.6 899.8 925.3 724.2 735.6 911.4 945.0 946.8 895.6 962.5 984.2 995.6 999.2 975.4 987.0 988.7 990.8 991.7 571.6 716.2 729.3 734.9 737.3 14807.3 14813.9 14855.0 14872.0 14894.6 12262.3 12305.0 12361.7 12530.3 12983.8 9841.3 10094.3 10104.5 10242.7 10654.8 3505.6 3514.4 3514.9 3533.0 3647.5 62469.8 75974.0 76115.2 79528.0 82308.6 9392.9 9423.7 9426.1 9516.8 9561.5 21042.0 21205.0 21230.6 21248.0 21280.4 7128.3 7174.9 7203.6 7273.3 7574.3 3969.5 4052.1 4116.0 4126.0 4192.6 3593.3 3617.7 3653.8 3656.9 3664.7 634.3 638.8 649.3 650.3 652.3 506.9 584.5 592.5 621.7 626.1 7739.8 7930.9 8021.8 8159.2 8192.1 573.7 595.4 597.3 602.0 602.4 3307.7 3612.8 3819.1 3891.6 3902.3 89731.6 92788.5 93353.7 94557.0 94701.0 79872.0 79966.8 80536.6 80833.0 80934.6 66862.0 66943.7 68970.0 68975.0 69205.7 7639.7 7756.5 7875.0 7909.7 7953.3 50496.7 51320.7 51476.7 51875.0 52919.0 67745.3 70627.0 71225.9 71461.0 71726.0 10896.6 11128.9 11252.5 11314.4 11559.7 11840.6 11867.9 11904.4 11935.2 12748.6 9126.7 11222.7 11380.3 11539.2 11751.3 10808.7 11174.7 11623.7 12098.9 12193.9 11297.7 11394.1 11596.1 11894.6 11934.6 8836.6 9133.7 9189.9 9213.5 9377.5 8417.9 8444.8 8564.1 8571.0 8626.4 11:12:35 INFO - PROCESS | 1674 | -------- Summary: end -------- 11:12:35 INFO - PROCESS | 1674 | 11:12:35 INFO - TEST-INFO | 1674: exit 0 11:12:35 INFO - TEST-OK | dromaeo_css | took 1115550ms 11:12:35 INFO - TEST-START | kraken 11:12:35 INFO - operating with platform_type : mac_ 11:12:35 INFO - Initialising browser for kraken test... 11:12:35 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpERn8bK/profile http://localhost:49278/getInfo.html) 11:12:38 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 11:12:38 INFO - PROCESS | 1681 | colorDepth:24 11:12:38 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 11:12:38 INFO - PROCESS | 1681 | __metrics 11:12:38 INFO - PROCESS | 1681 | 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" 11:12:38 ERROR - PROCESS | 1681 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:12:38 INFO - TEST-INFO | 1681: exit 0 11:12:38 INFO - Browser initialized. 11:12:38 INFO - Running cycle 1/1 for kraken test... 11:12:38 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpERn8bK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:50 INFO - PROCESS | 1684 | RSS: Main: 211214336 11:12:50 INFO - PROCESS | 1684 | 11:12:53 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 11:12:54 INFO - PROCESS | 1684 | RSS: Main: 272011264 11:12:54 INFO - PROCESS | 1684 | 11:12:57 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 11:12:57 INFO - PROCESS | 1684 | RSS: Main: 286232576 11:12:57 INFO - PROCESS | 1684 | 11:13:00 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 11:13:01 INFO - PROCESS | 1684 | RSS: Main: 374796288 11:13:01 INFO - PROCESS | 1684 | 11:13:03 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 11:13:04 INFO - PROCESS | 1684 | RSS: Main: 278368256 11:13:04 INFO - PROCESS | 1684 | 11:13:06 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:13:06 INFO - PROCESS | 1684 | RSS: Main: 278958080 11:13:06 INFO - PROCESS | 1684 | 11:13:09 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 11:13:10 INFO - PROCESS | 1684 | RSS: Main: 317919232 11:13:10 INFO - PROCESS | 1684 | 11:13:13 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 11:13:13 INFO - PROCESS | 1684 | RSS: Main: 319070208 11:13:13 INFO - PROCESS | 1684 | 11:13:16 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 11:13:17 INFO - PROCESS | 1684 | RSS: Main: 317468672 11:13:17 INFO - PROCESS | 1684 | 11:13:18 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:13:19 INFO - PROCESS | 1684 | RSS: Main: 309391360 11:13:19 INFO - PROCESS | 1684 | 11:13:21 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:13:21 INFO - PROCESS | 1684 | RSS: Main: 316088320 11:13:21 INFO - PROCESS | 1684 | 11:13:24 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:13:24 INFO - PROCESS | 1684 | RSS: Main: 318795776 11:13:24 INFO - PROCESS | 1684 | 11:13:27 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:13:28 INFO - PROCESS | 1684 | RSS: Main: 323862528 11:13:28 INFO - PROCESS | 1684 | 11:13:30 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:13:31 INFO - PROCESS | 1684 | RSS: Main: 321875968 11:13:31 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 11:13:33 INFO - PROCESS | 1684 | RSS: Main: 385941504 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | __start_tp_report 11:13:33 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 11:13:33 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 11:13:33 INFO - PROCESS | 1684 | |i|pagename|runs| 11:13:33 INFO - PROCESS | 1684 | |0;ai-astar;113;113;121;113;111;113;114;112;112;132 11:13:33 INFO - PROCESS | 1684 | |1;audio-beat-detection;157;191;180;156;121;167;103;171;179;120 11:13:33 INFO - PROCESS | 1684 | |2;audio-dft;185;188;184;185;185;183;187;187;187;186 11:13:33 INFO - PROCESS | 1684 | |3;audio-fft;75;74;76;148;55;73;50;59;141;56 11:13:33 INFO - PROCESS | 1684 | |4;audio-oscillator;90;92;140;76;80;91;123;76;76;135 11:13:33 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;85;83;85;83;85;83;86;83;86;82 11:13:33 INFO - PROCESS | 1684 | |6;imaging-darkroom;92;92;93;92;92;93;92;92;92;92 11:13:33 INFO - PROCESS | 1684 | |7;imaging-desaturate;87;88;88;89;89;87;88;88;89;101 11:13:33 INFO - PROCESS | 1684 | |8;json-parse-financial;59;64;64;66;64;63;64;85;60;58 11:13:33 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;63;67;72;78;70;69;67;68;69;74 11:13:33 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;81;79;79;96;97;96;101;80;82;85 11:13:33 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;138;114;151;142;143;127;144;135;162;147 11:13:33 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;175;165;163;174;162;165;163;173;163;166 11:13:33 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;66;67;70;66;67 11:13:33 INFO - PROCESS | 1684 | __end_tp_report 11:13:33 INFO - PROCESS | 1684 | __start_cc_report 11:13:33 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,2001 11:13:33 INFO - PROCESS | 1684 | __end_cc_report 11:13:33 INFO - PROCESS | 1684 | __startTimestamp1463508813559__endTimestamp 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | ------- Summary: start ------- 11:13:33 INFO - PROCESS | 1684 | Number of tests: 14 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:115.40 Median:113.00 stddev:6.45 (5.7%) stddev-sans-first:6.78 11:13:33 INFO - PROCESS | 1684 | Values: 113.0 113.0 121.0 113.0 111.0 113.0 114.0 112.0 112.0 132.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:154.50 Median:169.00 stddev:29.79 (17.6%) stddev-sans-first:31.58 11:13:33 INFO - PROCESS | 1684 | Values: 157.0 191.0 180.0 156.0 121.0 167.0 103.0 171.0 179.0 120.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:185.70 Median:186.50 stddev:1.57 (0.8%) stddev-sans-first:1.64 11:13:33 INFO - PROCESS | 1684 | Values: 185.0 188.0 184.0 185.0 185.0 183.0 187.0 187.0 187.0 186.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:80.70 Median:66.00 stddev:34.97 (53.0%) stddev-sans-first:37.03 11:13:33 INFO - PROCESS | 1684 | Values: 75.0 74.0 76.0 148.0 55.0 73.0 50.0 59.0 141.0 56.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:78.00 stddev:25.13 (32.2%) stddev-sans-first:26.49 11:13:33 INFO - PROCESS | 1684 | Values: 90.0 92.0 140.0 76.0 80.0 91.0 123.0 76.0 76.0 135.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:84.10 Median:85.00 stddev:1.45 (1.7%) stddev-sans-first:1.50 11:13:33 INFO - PROCESS | 1684 | Values: 85.0 83.0 85.0 83.0 85.0 83.0 86.0 83.0 86.0 82.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:92.20 Median:92.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 11:13:33 INFO - PROCESS | 1684 | Values: 92.0 92.0 93.0 92.0 92.0 93.0 92.0 92.0 92.0 92.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:89.40 Median:88.00 stddev:4.14 (4.7%) stddev-sans-first:4.30 11:13:33 INFO - PROCESS | 1684 | Values: 87.0 88.0 88.0 89.0 89.0 87.0 88.0 88.0 89.0 101.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:64.00 stddev:7.59 (11.9%) stddev-sans-first:7.76 11:13:33 INFO - PROCESS | 1684 | Values: 59.0 64.0 64.0 66.0 64.0 63.0 64.0 85.0 60.0 58.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:69.50 stddev:4.16 (6.0%) stddev-sans-first:3.64 11:13:33 INFO - PROCESS | 1684 | Values: 63.0 67.0 72.0 78.0 70.0 69.0 67.0 68.0 69.0 74.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:83.50 stddev:8.80 (10.5%) stddev-sans-first:9.00 11:13:33 INFO - PROCESS | 1684 | Values: 81.0 79.0 79.0 96.0 97.0 96.0 101.0 80.0 82.0 85.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:143.50 stddev:13.15 (9.2%) stddev-sans-first:13.92 11:13:33 INFO - PROCESS | 1684 | Values: 138.0 114.0 151.0 142.0 143.0 127.0 144.0 135.0 162.0 147.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:165.50 stddev:5.07 (3.1%) stddev-sans-first:4.44 11:13:33 INFO - PROCESS | 1684 | Values: 175.0 165.0 163.0 174.0 162.0 165.0 163.0 173.0 163.0 166.0 11:13:33 INFO - PROCESS | 1684 | 11:13:33 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 11:13:33 INFO - PROCESS | 1684 | Values: 67.0 67.0 68.0 67.0 67.0 66.0 67.0 70.0 66.0 67.0 11:13:33 INFO - PROCESS | 1684 | -------- Summary: end -------- 11:13:33 INFO - PROCESS | 1684 | 11:13:34 INFO - TEST-INFO | 1684: exit 0 11:13:34 INFO - TEST-OK | kraken | took 58483ms 11:13:34 INFO - SUITE-END | took 1174s 11:13:34 INFO - Completed test suite (00:19:34) 11:13:34 INFO - javascript benchmark 11:13:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3408.182036311285, 3464.411050121479, 3481.2863363010583, 3485.3552092862815, 3544.273423948091, 5781.7109144542765, 5808.883822323556, 5830.533557315415, 5835.241418615585, 5854.882902341945, 5769.990251705955, 5827.854303642411, 5831.912521312183, 5849.268841394825, 5869.70651467426, 5071.061853557104, 5128.71792051437, 5172.379314482268, 5249.4488078751565, 5308.654937429074, 5238.973805130969, 5241.86895327616, 5247.160454327309, 5252.107141785906, 5256.974889896453, 5175.844724658266, 5232.215167724853, 5246.7638956247065, 5253.159494480884, 5272.367315922094, 5239.978204250173, 5275.208718692227, 5291.232771248147, 5302.257683924254, 5323.973380133075, 5092.261622064817, 5135.512126348029, 5188.766505507261, 5200.817971370984, 5203.375594928595, 5581.246531718207, 5602.579806514527, 5636.323641163046, 5678.971605141989, 5743.023685973395, 6237.407446292595, 6264.843378915518, 6267.310595834455, 6270.122182894398, 6276.686165691697, 6205.162303089115, 6260.780872669435, 6264.1543391642, 6264.404881536247, 6270.498360131179, 4873.342098816685, 5699.829005129853, 5797.043487824546, 5859.970700146472, 5869.148973398833, 6198.442140207403, 6221.191245138103, 6243.469305109116, 6260.342664020303, 6268.373162683695, 5218.478152184751, 6213.596116252429, 6215.564910456315, 6222.906656400158, 6225.159603453476, 6138.07928810682, 6157.138000679908, 6261.96869015652, 6271.43557079865, 6278.215223097113, 6172.814815555541, 6213.440790328892, 6234.37656234382, 6242.906356404657, 6246.625202487865, 6110.755569777248, 6237.719302631393, 6242.687865606701, 6244.250689917239, 6276.905846412307, 4868.099401610642, 4870.805167793319, 4897.510248975074, 4955.603551715854, 4959.776810043484, 4444.200043992112, 4460.553944605514, 4461.018575913294, 4474.127545128668, 4507.909841803145, 5848.561357898175, 5853.0635098384055, 5854.238948936696, 5861.912071318934, 5868.823935281948, 6138.786925362681, 6144.15675297409, 6209.6895155242055, 6210.72051757672, 6213.906791398133, 5755.913661295084, 5777.62445441045, 5783.103618939072, 5784.652920824764, 5792.507636850914, 5318.760655770499, 5400.702961337086, 5405.648632838853, 5408.729563521808, 5421.891562168735, 6177.4749146322165, 6311.968440157862, 6329.050642403492, 6354.650494222862, 6360.045993101072, 6211.782587609412, 6232.594881332699, 6237.25152981627, 6239.407256310643, 6255.78104766331, 6179.196704428396, 6310.242770867525, 6313.43179113882, 6320.494360451061, 6323.841903952438, 6141.854248382235, 6245.6252624842655, 6250.781222657185, 6254.843628909313, 6255.40573645503, 6226.470749986302, 6230.127782110599, 6230.501559875109, 6232.594881332699, 6235.189425374672, 5956.731947062306, 6038.094285856981, 6101.816945491465, 6154.261488621394, 6172.8765424690355, 6034.366391529002, 6054.606450580698, 6090.056041313604, 6112.8777424452155, 6122.020476723703, 5792.507636850914, 5938.109283607493, 5961.016432288798, 5978.970105149447, 5983.640981541122, 5121.257417674342, 5976.133460648095, 6121.143039974496, 6147.831911936718, 6150.261968563801, 5979.671118088547, 5981.611195272293, 5985.521158307412, 5992.4307190818545, 6003.8198854034445], "value": 5713.9169280169235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.1302279613262, 516.9896602067959, 523.3980921939768, 561.5984571031713, 602.8010756450371, 30584.54123188151, 31278.374432511337, 31338.52992205116, 31389.529157062607, 31524.68475315246, 32934.34131317378, 40387.39418908719, 41079.0, 41119.383209251886, 41270.58729412705, 9643.421394716323, 9868.703938881808, 9876.60493580256, 9879.407235565888, 9882.703518894445, 8243.258106770389, 8618.78453038676, 8890.288776897865, 9616.0, 9737.659181928633, 11601.187916845824, 11850.0, 11850.170488065838, 11851.585194518193, 11887.940560297186, 11498.8275175872, 11591.304521728638, 11602.12984026193, 11616.88383116175, 11627.767444651103, 8565.914340856574, 11398.487068081873, 11493.080553555696, 11738.882611173864, 11742.530298788039, 39033.82898513021, 39504.80247598772, 39623.20753584912, 39717.40423893644, 39816.0, 3176.3806057818733, 3230.2408933900515, 3306.2891478332035, 3338.4491558892755, 3388.525606415104, 28264.434711305865, 28722.569161462594, 28894.566581501294, 29165.854170729224, 29619.70380296191, 2708.3906121122786, 2770.7367800058964, 2801.523740964041, 2838.7728981681416, 2879.3233590106306, 331048.0, 335275.3236233828, 336065.0, 336329.0, 337427.0, 59516.0, 59524.0, 59611.105833412534, 59654.70172649109, 59875.0, 67603.98594021094, 67655.0, 67694.98457523141, 67786.66106669435, 67886.66056669685, 17459.21433535494, 17672.204750786244, 17682.0, 17683.646327073388, 17685.93884366942, 13424.261665608485, 13512.986526010589, 13541.05212635106, 13542.661433464045, 13549.661258468617, 17569.56076098082, 17793.644127117386, 18515.629800851722, 20720.896395517924, 21106.366808995754, 6819.420349270367, 7380.889286660705, 7801.024871891013, 7988.360931125496, 8160.67357305713, 898.6585097662846, 914.3736540469799, 1078.3422112511362, 1122.1976286954866, 1179.8584169899666, 30348.937787177343, 30439.239019024702, 30599.541006884916, 30644.54033189504, 30998.070057898298, 1069.3957913778845, 1121.0807138146643, 1138.908887289015, 1168.8136541410474, 1235.7343171218051, 14501.564953051426, 15231.695366092616, 15388.923055384652, 15784.0, 15843.920780396023, 585877.0, 1002718.0, 1022922.0, 1023184.0, 1027718.0, 63228.0, 63448.68275658592, 63860.68069659622, 63995.6800215996, 64104.71790564163, 1525.9313330900136, 1527.0303357368373, 1531.8851086168581, 1546.0569052877736, 1548.1639914446298, 29193.562096568145, 30683.386332273676, 30799.956062830366, 31162.688373116885, 31541.0, 55542.72228638831, 56004.159937601784, 56132.71933640469, 56277.71861140668, 56312.71843640756, 586189.0, 588154.0, 588433.0, 588437.0, 589285.107148923, 27549.31126721848, 28056.43887122287, 42685.146297074505, 43556.0, 44421.0, 42782.57217427786, 43281.78359108185, 43601.78199108984, 43746.562534375524, 43871.56128438675, 35943.82028089843, 36172.0, 36291.27417451689, 36742.63257367499, 36903.44644830276, 36328.09179770527, 38202.61797382102, 39048.41427378535, 39468.80265598654, 39961.00097497586, 35175.64824351724, 35684.0, 36200.0, 36203.09492262715, 36363.81818090893, 29248.56127158137, 31726.0, 31922.36155276928, 31972.200694981882, 32210.0, 20977.790222098196, 24041.0, 24368.75631243736, 24468.51062978695, 24536.0], "value": 20396.443196906603, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.3038378998854, 396.67869026088863, 400.40939614069254, 401.3357892687603, 414.61026634963133, 15112.395504179833, 19843.60312793745, 20517.281895133674, 20584.485387865272, 21020.0, 19360.257576818083, 19868.60262794743, 20541.0, 20556.869372184523, 20557.588848223026, 5828.766849326022, 6007.519398448114, 6049.546284028694, 6057.3336932937345, 6061.969690151543, 4218.957810421887, 6056.091586262052, 6423.8394040148905, 6730.226024007253, 6762.357576030269, 5832.300123985106, 6631.104800851872, 6682.498812589051, 6692.23039350476, 6730.024146498755, 6438.0, 6645.102911106987, 6658.201015878101, 6664.100346453215, 6681.866362672719, 3833.0, 5324.722066704002, 5831.358550559435, 5831.708414579254, 5831.912521312183, 24371.878140609184, 24783.62824557615, 25025.749742502525, 25591.360215994562, 25657.486850263078, 2041.0407108658906, 2175.5540114276537, 2388.940276493084, 2511.183865243785, 2800.0899707595113, 9627.903720962771, 9783.266255030901, 9990.550425230882, 10012.949935250277, 10051.994800519962, 1738.0527612451244, 1759.0852756566642, 1805.1966874740745, 1807.13257636334, 1902.8667993240479, 111168.0, 116877.41561292224, 117357.0, 118949.40525297404, 119440.40279798546, 9778.511074446249, 10801.273560621574, 11222.49498772541, 11782.941085294518, 11846.822297665542, 14450.710985780226, 15053.849461505464, 15055.924720376546, 15066.246687665793, 15103.697926041417, 8928.464292142493, 8996.280297576179, 9005.0, 9011.188992990532, 9098.408603440756, 9008.099190081071, 9015.684451044182, 9032.63869445228, 9104.590293436811, 9122.680706175252, 4034.4755181826763, 4659.627229821606, 4715.316279139505, 4748.738819364899, 4769.165396055676, 1747.344745720355, 1908.446550500367, 1929.3825975687926, 1956.7945365736393, 1964.1912065746121, 886.8536691445839, 929.6885543343055, 969.4280374579032, 1013.5287091502487, 1026.743314171457, 19292.807071929383, 20248.595028099357, 20297.877818599587, 20323.288684895957, 20363.796362036486, 954.613381580461, 954.8663187153804, 981.4994352880083, 983.0709979069853, 1083.550326614462, 10669.733256668645, 10783.352998819939, 10802.189835762349, 10812.729681758019, 10826.0, 510332.0, 604260.0, 616307.0, 639208.0, 640047.0, 112462.43768781103, 135514.32242838724, 137227.0, 138408.0, 141016.0, 1011.3173607814696, 1035.7307100154014, 1045.968620941373, 1047.1936608811172, 1056.0284538225005, 17722.5569360767, 17743.290268389635, 17885.10574471245, 18153.818461815743, 18460.723089153405, 51581.0, 51661.22508162305, 52040.479595203564, 52862.47137528576, 53400.73299633632, 75647.0, 78627.0, 79230.0, 80061.39877202307, 81470.18529814626, 7705.1909549496495, 8137.430379873352, 8259.091499934882, 8261.669533218605, 8470.49177049379, 10464.529096190841, 11661.640097658947, 12237.632871013884, 12557.372131393577, 12642.620721378373, 8203.917960820554, 10779.0, 10953.726156846142, 11063.778724425627, 11178.161637877354, 10194.694159175237, 10665.146788257076, 10860.294080884718, 10951.945240273748, 11075.947784960415, 10833.67498975032, 11067.833982490109, 11320.943395282971, 11335.263207891461, 11402.372869492257, 7996.56018918965, 8058.476199047043, 8166.836663266583, 8183.3862460316905, 8415.200555947175, 9177.587008584764, 10802.567897284334, 10848.783024339627, 11095.167862410513, 11107.944460277648], "value": 9632.531580356232, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.3394904394943, 1533.7239296926555, 1562.2110834028817, 1788.2221233763314, 1934.516370907273, 7864.8427031459405, 8057.677692892277, 8061.516309021463, 8468.703595374163, 8693.47839129651, 8545.4445461045, 8574.971003479575, 8682.565871706422, 8708.91291087089, 8722.040575536701, 2401.0635852017726, 2425.745296743843, 2425.8059355251626, 2431.963520547193, 2432.990309021754, 2399.0, 2430.86630235337, 2453.7423570525107, 2455.533448644752, 2455.594826853567, 2394.1500767227644, 2433.1119141513395, 2434.294054724137, 2439.9268021959283, 2444.1934161726585, 2386.546556154325, 2427.526632306701, 2429.708434987795, 2434.342727463593, 2436.719777225624, 2123.8938053097436, 2364.7635236476217, 2410.421498840283, 2413.3363325085566, 2413.5655581995234, 8694.347923905732, 8759.868601970975, 8763.342749293764, 8768.561571921378, 8775.956120219358, 5287.444818294062, 5288.444713305086, 5377.247185394048, 5423.2949716537, 5453.890922181574, 3463.6363181865795, 3545.18460754028, 3591.7844929304065, 3601.477785721082, 3601.693856022241, 1034.1881622925966, 1040.2042437535292, 1054.8628678271775, 1077.13828936851, 1085.5386460754148, 11458.541658333657, 11549.191556591042, 11550.884491155064, 11556.653300401002, 11571.479283432265, 4575.9084818303445, 4615.469221039623, 4635.188841952645, 4688.8124475021295, 4722.598579120745, 9263.583138758771, 9266.721998339926, 9267.490288034089, 9275.58259878307, 9294.117058879396, 5264.499872512105, 5291.523762861361, 5329.440408757102, 5339.919901201485, 5344.171653393731, 4379.540148284448, 4482.731036137842, 4546.909061818745, 4729.290606409066, 4962.503749625009, 3791.658750712394, 3811.8284677189595, 3913.745606535566, 3921.9803900980314, 3925.685945124383, 1199.8080307150988, 1220.097128125181, 1227.110344999886, 1233.340163012784, 1237.665830225834, 802.5866678660494, 849.869673334476, 876.96956076611, 906.7733066733317, 915.7481692534607, 5618.747156377891, 5712.571557133231, 5770.105633626795, 5852.8536786579825, 6005.459508644243, 449.6065942300487, 454.2232781942915, 473.93601863747955, 474.4875534422815, 486.61557369678263, 3651.123730304708, 3675.9707281961096, 3679.687226585717, 3723.3484140275345, 3723.5531736191288, 10264.024917632813, 10601.893981060244, 10610.681679549625, 10660.946695266473, 10743.78512429747, 10508.527116279785, 10512.264141510173, 10518.631847885288, 10584.57661693539, 10589.9470502647, 8148.633311500997, 8197.385196110316, 8238.258556729923, 8298.502089874506, 8298.99999999988, 5413.918791218056, 5422.701751403711, 5563.638363506359, 5680.4319568042865, 5787.131930210502, 9028.954855225682, 9244.445333280024, 9374.250059995047, 9396.859047114436, 9432.434053956784, 9076.0, 9337.673181438617, 9524.857127143237, 9525.666601668909, 9535.189508891823, 6820.727170913108, 7009.7897063088185, 7504.287092726182, 7645.006149200568, 7646.770596882103, 7551.962240188764, 7613.695452181851, 7629.3133617974645, 7914.564698941614, 8161.836763264582, 7233.782986510413, 7338.082739657543, 7340.339369456775, 7463.738769143054, 7468.365188958999, 7081.893771593327, 7103.2186459488385, 7198.640067996684, 7215.242399548182, 7256.455765817479, 6728.629925354153, 6863.794086177223, 6893.827654308682, 6897.862042759017, 6914.170299564084, 4726.220173671306, 4795.880102997453, 4840.104580652591, 4883.80464781419, 4920.237363208708, 4819.879503012453, 4839.806407743651, 4874.415070191458, 4878.512148785235, 4941.802327906844], "value": 4429.491921444203, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.98879562989686, 323.1936318884383, 333.3849048505507, 335.24736965512426, 335.4314437029236, 6580.045893345462, 6589.505787065966, 6594.505412094094, 6601.2408573014, 6627.469802415807, 6325.051242313655, 6447.097406363113, 6466.935330646692, 6468.8059358219325, 6535.3137920518375, 4066.573009833962, 4911.803527858881, 4931.211006239003, 4936.654434189608, 4939.777710003059, 4875.488073752258, 4918.532739389752, 4949.183384741531, 4952.653314268003, 4968.7764050617625, 4645.790939407735, 4709.740964246965, 4838.830640927568, 4892.804287828482, 4900.803967841282, 4811.711297322172, 4824.758762061901, 4835.202191638376, 4840.443349014873, 4847.51524847516, 4161.771102589357, 4165.645920096796, 4171.186618609372, 4173.061061261223, 4182.6406417914295, 5693.060644993571, 5980.162777211194, 6235.812925612194, 6303.212098487689, 6372.076048972873, 5823.854403639943, 5844.766209351621, 5849.678267695275, 5857.85355366115, 5864.179014937912, 6843.657817109125, 7069.8939515907305, 7079.575225486487, 7092.468064895102, 7105.253948335402, 4735.857924262277, 4775.641826862964, 4777.952220477786, 4778.163821331288, 4786.305985632099, 7393.964844921716, 7492.100947886289, 7493.138289096769, 7504.662290196899, 7505.099388073411, 4843.39457567804, 4857.0, 4866.6106711463, 4875.512448755097, 4881.95118048822, 4934.037862616755, 4943.728094954812, 4946.752662366867, 4948.975255123702, 4950.925736113961, 5579.7489112990015, 5862.560307976919, 5868.2664666916635, 5870.559708021916, 5894.263217097863, 7111.324424179695, 7138.0, 7140.178879428932, 7145.964270178616, 7159.606221657858, 1686.215909602041, 1719.8133288031217, 1721.3200785689583, 1724.249951271201, 1725.1891610942837, 1005.8340373838234, 1007.6019972110975, 1029.2383636109225, 1109.7114750164853, 1124.2692250037542, 6507.414332709984, 6608.339166083353, 6612.07430959666, 6612.471002319803, 6633.204015518072, 7338.669759860819, 7343.963280183565, 7345.192028876926, 7345.595992220479, 7358.668859901317, 6582.341765823379, 6593.769218077345, 6596.241432235259, 6605.273419923805, 6606.141201643755, 3739.4390841373465, 3855.1904100139286, 3869.090763670535, 3881.883543493643, 3888.980555097263, 7660.655270512841, 7680.116786569504, 7711.730089446843, 7714.035745531809, 7736.690532378641, 7477.588732619649, 7532.661030253542, 7556.735514256975, 7557.05536807899, 7567.0, 7681.423893208143, 7688.231176882379, 7698.538087714755, 7698.7305444310305, 7701.3068823806125, 7035.929640703527, 7294.963525182552, 7301.634918254173, 7321.6705248265025, 7338.669759860926, 7129.572225666477, 7173.354398104196, 7183.138023437221, 7187.1734750504365, 7200.063991681048, 7660.310572048543, 7675.692972281269, 7702.537847729154, 7703.383729301754, 7719.459637825523, 4876.658633895593, 4877.975610121926, 4882.7558622068045, 4883.340748998839, 4886.53577910098, 5949.970250148722, 7006.824829379102, 7257.963710181626, 7274.527155734861, 7296.379807716404, 6630.104935833793, 6998.335158159966, 6999.335063168991, 7065.57606543609, 7116.359527642537, 6273.1844860167885, 7065.364117229474, 7078.823029424306, 7096.432285417258, 7109.28907109285, 5736.0, 6703.128593282842, 6930.306969303029, 6960.5127641066665, 6971.442284617117, 6875.931240687529, 7040.401565866959, 7109.680064397218, 7132.108486439195, 7134.143902731498, 5713.600047996767, 6631.148606099424, 6773.796786096425, 6842.247352791289, 6869.416099631588], "value": 5194.592499793025, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.8510927595929, 745.8657441660499, 749.8762704153808, 750.9849803003942, 772.0426670928051, 12260.754784904297, 14896.180710060942, 15070.0, 15094.698106037886, 15106.773398399033, 13709.314534273297, 13930.442782288696, 13946.790798138036, 13963.301834908265, 13988.230647314394, 736.3814395907436, 746.8954346391482, 755.5957562703954, 899.8425275576781, 925.2875286029753, 724.2431658916445, 735.6358602491774, 911.4485736129645, 945.0, 946.7680418297492, 895.552223888056, 962.4802606592467, 984.2372161574801, 995.5967833027636, 999.2305924438178, 975.3855071305068, 987.027777639025, 988.7280997725611, 990.8166989107038, 991.6727479931606, 571.6370104983324, 716.2193209401725, 729.3253740290263, 734.867723809714, 737.2590546500786, 14807.333669984877, 14813.925930370388, 14855.034422762486, 14872.0, 14894.627634309121, 12262.26426414418, 12305.0, 12361.69095772604, 12530.310832904186, 12983.805242921364, 9841.261905357127, 10094.274545872646, 10104.494775261208, 10242.692719218436, 10654.840177397346, 3505.614382417932, 3514.3849826280552, 3514.8752399232258, 3533.0, 3647.4528820676846, 62469.75060498765, 75974.0, 76115.23884761137, 79528.0, 82308.58845705733, 9392.90607093934, 9423.717288481357, 9426.057394260519, 9516.809663806685, 9561.474118923526, 21042.0, 21205.045772940255, 21230.575388492143, 21248.043838027017, 21280.36158915235, 7128.322809333105, 7174.892376614355, 7203.639818009078, 7273.3453989141235, 7574.280443357872, 3969.4839670843185, 4052.0882801370044, 4116.0327323079055, 4125.958740412618, 4192.622663960258, 3593.2633810068874, 3617.746757726934, 3653.762505437138, 3656.8720094797086, 3664.706823454117, 634.2737565487491, 638.8498702804837, 649.2890285137767, 650.2619526836979, 652.3346186889441, 506.89608630230714, 584.5440556366042, 592.4579010205614, 621.7015832400473, 626.1359324132668, 7739.845203095907, 7930.889675445443, 8021.8395632088195, 8159.184081591793, 8192.098869124511, 573.7360814025511, 595.4462350014442, 597.3160730963023, 602.0277252237605, 602.3945934336052, 3307.7353811695007, 3612.837422316002, 3819.1216020315483, 3891.6108389161423, 3902.3366027775346, 89731.55134224288, 92788.53605732063, 93353.71427715108, 94557.0, 94701.0, 79872.0, 79966.80049799258, 80536.59731701305, 80833.0, 80934.595327023, 66862.0, 66943.66528167328, 68970.0, 68975.0, 69205.65397172981, 7639.656215470428, 7756.457048006585, 7875.0, 7909.683612655429, 7953.2842044213985, 50496.73758156075, 51320.74339628278, 51476.742616286676, 51875.0, 52919.0, 67745.30636734121, 70627.0, 71225.93161102483, 71461.0, 71726.0, 10896.61861834832, 11128.944355278172, 11252.549898003988, 11314.377709226072, 11559.711007224887, 11840.585579504675, 11867.940660296643, 11904.404779760804, 11935.164538482222, 12748.553800616934, 9126.722258883894, 11222.66332010041, 11380.260283081574, 11539.19225654196, 11751.294922304689, 10808.729781755519, 11174.66476005721, 11623.709407264885, 12098.850609192114, 12193.939030304791, 11297.661070167907, 11394.145439092266, 11596.130290228435, 11894.643160705193, 11934.641960741192, 8836.602352894264, 9133.680321188727, 9189.86215206759, 9213.539323033956, 9377.484238366955, 8417.873731893904, 8444.831103378021, 8564.100769419121, 8571.014333351744, 8626.39615226928], "value": 6839.859668813157, "unit": "score"}], "name": "dromaeo_css", "value": 7490.949049947235, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 121.0, 113.0, 111.0, 113.0, 114.0, 112.0, 112.0, 132.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 191.0, 180.0, 156.0, 121.0, 167.0, 103.0, 171.0, 179.0, 120.0], "value": 154.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 184.0, 185.0, 185.0, 183.0, 187.0, 187.0, 187.0, 186.0], "value": 185.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 148.0, 55.0, 73.0, 50.0, 59.0, 141.0, 56.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 140.0, 76.0, 80.0, 91.0, 123.0, 76.0, 76.0, 135.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 83.0, 85.0, 83.0, 86.0, 83.0, 86.0, 82.0], "value": 84.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 92.0, 92.0, 93.0, 92.0, 92.0, 92.0, 92.0], "value": 92.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 88.0, 89.0, 89.0, 87.0, 88.0, 88.0, 89.0, 101.0], "value": 89.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 64.0, 66.0, 64.0, 63.0, 64.0, 85.0, 60.0, 58.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 72.0, 78.0, 70.0, 69.0, 67.0, 68.0, 69.0, 74.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 79.0, 96.0, 97.0, 96.0, 101.0, 80.0, 82.0, 85.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 114.0, 151.0, 142.0, 143.0, 127.0, 144.0, 135.0, 162.0, 147.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 165.0, 163.0, 174.0, 162.0, 165.0, 163.0, 173.0, 163.0, 166.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 66.0, 67.0, 70.0, 66.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1496.3, "alertThreshold": 2.0}]} 11:13:34 INFO - Return code: 0 11:13:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:13:34 ERROR - # TBPL SUCCESS # 11:13:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:13:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:13:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:13:34 INFO - 2016-05-17 11:13:34.857 screenresolution[1688:10983] starting screenresolution argv=screenresolution get 11:13:34 INFO - 2016-05-17 11:13:34.860 screenresolution[1688:10983] Display 0: 1600x1200x32@60 11:13:34 INFO - 2016-05-17 11:13:34.866 screenresolution[1689:10986] starting screenresolution argv=screenresolution list 11:13:34 INFO - Available Modes on Display 0 11:13:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:13:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:13:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:13:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:13:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:13:35 INFO - Intel Iris: 11:13:35 INFO - Chipset Model: Intel Iris 11:13:35 INFO - Type: GPU 11:13:35 INFO - Bus: Built-In 11:13:35 INFO - VRAM (Dynamic, Max): 1536 MB 11:13:35 INFO - Vendor: Intel (0x8086) 11:13:35 INFO - Device ID: 0x0a2e 11:13:35 INFO - Revision ID: 0x0009 11:13:35 INFO - Displays: 11:13:35 INFO - Display: 11:13:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:13:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:13:35 INFO - Display Serial Number: 1600x1200 60 11:13:35 INFO - Main Display: Yes 11:13:35 INFO - Mirror: Off 11:13:35 INFO - Online: Yes 11:13:35 INFO - Rotation: Supported 11:13:35 INFO - Return code: 0 11:13:35 INFO - Running post-action listener: _resource_record_post_action 11:13:35 INFO - Running post-run listener: _resource_record_post_run 11:13:36 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 31464960; Write bytes: 651765248; Read time: 197; Write time: 1856 11:13:36 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 342503936; Write bytes: 336848384; Read time: 26484; Write time: 710 11:13:36 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30543360; Write bytes: 295530496; Read time: 186; Write time: 1087 11:13:36 INFO - Running post-run listener: _upload_blobber_files 11:13:36 INFO - Blob upload gear active. 11:13:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:13:36 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 11:13:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:13:36 INFO - (blobuploader) - INFO - Open directory for files ... 11:13:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:13:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:13:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:37 INFO - (blobuploader) - INFO - Done attempting. 11:13:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:13:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:13:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:38 INFO - (blobuploader) - INFO - Done attempting. 11:13:38 INFO - (blobuploader) - INFO - Iteration through files over. 11:13:38 INFO - Return code: 0 11:13:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:13:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:13:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1c363299749f887eea03e91c3faab945b6e5aa57c9a7b95c08b88f06fb22b3991641892010fcbbee60e02ad7d184be868ce147ec3cfe2f450f919a868a19918", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d94cf03b2c98c7cf7bc324b63e72e6b59e967b07fb85e28ff2c3d7a642af92c5447cd64c81681534cbbea6c201bd5079022acb50692a3cd9c514349110ee5d75"} 11:13:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:13:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:13:38 INFO - Contents: 11:13:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1c363299749f887eea03e91c3faab945b6e5aa57c9a7b95c08b88f06fb22b3991641892010fcbbee60e02ad7d184be868ce147ec3cfe2f450f919a868a19918", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d94cf03b2c98c7cf7bc324b63e72e6b59e967b07fb85e28ff2c3d7a642af92c5447cd64c81681534cbbea6c201bd5079022acb50692a3cd9c514349110ee5d75"} 11:13:38 INFO - Running post-run listener: copy_logs_to_upload_dir 11:13:38 INFO - Copying logs to upload dir... 11:13:38 INFO - mkdir: /builds/slave/test/build/upload/logs 11:13:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1327.789961 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2016-05-17 11:13:38.905042) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 11:13:38.909921) ========= 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.QfN4tUaRfU/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.jeALDi3uU0/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1c363299749f887eea03e91c3faab945b6e5aa57c9a7b95c08b88f06fb22b3991641892010fcbbee60e02ad7d184be868ce147ec3cfe2f450f919a868a19918", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d94cf03b2c98c7cf7bc324b63e72e6b59e967b07fb85e28ff2c3d7a642af92c5447cd64c81681534cbbea6c201bd5079022acb50692a3cd9c514349110ee5d75"} build_url:https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1c363299749f887eea03e91c3faab945b6e5aa57c9a7b95c08b88f06fb22b3991641892010fcbbee60e02ad7d184be868ce147ec3cfe2f450f919a868a19918", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d94cf03b2c98c7cf7bc324b63e72e6b59e967b07fb85e28ff2c3d7a642af92c5447cd64c81681534cbbea6c201bd5079022acb50692a3cd9c514349110ee5d75"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 11:13:38.937817) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 11:13:38.938250) ========= 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.QfN4tUaRfU/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.jeALDi3uU0/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.005371 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 11:13:38.987700) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 11:13:38.988029) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 11:13:38.988384) ========= ========= Total master_lag: 0.16 =========