builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0081 starttime: 1461580144.4 results: success (0) buildid: 20160424125552 builduid: 29fa51451e6247e79de74546a4441689 revision: 98da47b4d83c576ce17d189942d2655df5a36dc1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:04.402940) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:04.403411) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:04.403713) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:04.530993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:04.533341) ========= 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.seADXhUFmK/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.Fumg0JE0w5/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-25 03:29:04-- 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% 123M=0s 2016-04-25 03:29:05 (123 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.551222 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:05.119232) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:05.119622) ========= 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.seADXhUFmK/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.Fumg0JE0w5/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.026461 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:05.177628) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:05.178209) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 98da47b4d83c576ce17d189942d2655df5a36dc1 --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 98da47b4d83c576ce17d189942d2655df5a36dc1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.seADXhUFmK/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.Fumg0JE0w5/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-25 03:29:05,273 truncating revision to first 12 chars 2016-04-25 03:29:05,273 Setting DEBUG logging. 2016-04-25 03:29:05,274 attempt 1/10 2016-04-25 03:29:05,274 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/98da47b4d83c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 03:29:05,531 unpacking tar archive at: mozilla-aurora-98da47b4d83c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.512938 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:05.730985) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:05.731289) ========= script_repo_revision: 98da47b4d83c576ce17d189942d2655df5a36dc1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:05.734818) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:05.735089) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 03:29:05.753119) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-04-25 03:29:05.753519) ========= /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.seADXhUFmK/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.Fumg0JE0w5/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:29:05 INFO - MultiFileLogger online at 20160425 03:29:05 in /builds/slave/test 03:29:05 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 03:29:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:29:05 INFO - {'append_to_log': False, 03:29:05 INFO - 'base_work_dir': '/builds/slave/test', 03:29:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:29:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:29:05 INFO - 'branch': 'Mozilla-Aurora', 03:29:05 INFO - 'buildbot_json_path': 'buildprops.json', 03:29:05 INFO - 'config_files': ('talos/mac_config.py',), 03:29:05 INFO - 'default_actions': ('clobber', 03:29:05 INFO - 'read-buildbot-config', 03:29:05 INFO - 'download-and-extract', 03:29:05 INFO - 'populate-webroot', 03:29:05 INFO - 'create-virtualenv', 03:29:05 INFO - 'install', 03:29:05 INFO - 'run-tests'), 03:29:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:29:05 INFO - 'download_minidump_stackwalk': True, 03:29:05 INFO - 'download_symbols': 'ondemand', 03:29:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:29:05 INFO - 'tooltool.py': '/tools/tooltool.py', 03:29:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:29:05 INFO - '/tools/misc-python/virtualenv.py')}, 03:29:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:29:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:29:05 INFO - 'installer_path': 'installer.exe', 03:29:05 INFO - 'log_level': 'info', 03:29:05 INFO - 'log_name': 'talos', 03:29:05 INFO - 'log_to_console': True, 03:29:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:29:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:29:05 INFO - 'opt_config_files': (), 03:29:05 INFO - 'pip_index': False, 03:29:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:29:05 INFO - 'cmd': ('bash', 03:29:05 INFO - '-c', 03:29:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:29:05 INFO - 'enabled': True, 03:29:05 INFO - 'halt_on_failure': False, 03:29:05 INFO - 'name': 'check_screen_resolution'},), 03:29:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:29:05 INFO - 'cmd': ('bash', 03:29:05 INFO - '-c', 03:29:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:29:05 INFO - 'enabled': True, 03:29:05 INFO - 'halt_on_failure': False, 03:29:05 INFO - 'name': 'check_screen_resolution'},), 03:29:05 INFO - 'run_cmd_checks_enabled': True, 03:29:05 INFO - 'sps_profile': False, 03:29:05 INFO - 'sps_profile_interval': 0, 03:29:05 INFO - 'suite': 'dromaeojs', 03:29:05 INFO - 'system_bits': '32', 03:29:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:29:05 INFO - 'title': 't-yosemite-r7-0081', 03:29:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:29:05 INFO - 'use_talos_json': True, 03:29:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:29:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:29:05 INFO - 'work_dir': 'build'} 03:29:05 INFO - ##### 03:29:05 INFO - ##### Running clobber step. 03:29:05 INFO - ##### 03:29:05 INFO - Running pre-action listener: _resource_record_pre_action 03:29:05 INFO - Running main action method: clobber 03:29:05 INFO - rmtree: /builds/slave/test/build 03:29:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:29:07 INFO - Running post-action listener: _resource_record_post_action 03:29:07 INFO - ##### 03:29:07 INFO - ##### Running read-buildbot-config step. 03:29:07 INFO - ##### 03:29:07 INFO - Running pre-action listener: _resource_record_pre_action 03:29:07 INFO - Running main action method: read_buildbot_config 03:29:07 INFO - Using buildbot properties: 03:29:07 INFO - { 03:29:07 INFO - "project": "", 03:29:07 INFO - "product": "firefox", 03:29:07 INFO - "script_repo_revision": "production", 03:29:07 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:29:07 INFO - "repository": "", 03:29:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:29:07 INFO - "buildid": "20160424125552", 03:29:07 INFO - "pgo_build": "False", 03:29:07 INFO - "basedir": "/builds/slave/test", 03:29:07 INFO - "buildnumber": 287, 03:29:07 INFO - "slavename": "t-yosemite-r7-0081", 03:29:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:29:07 INFO - "platform": "yosemite_r7", 03:29:07 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:29:07 INFO - "branch": "mozilla-aurora", 03:29:07 INFO - "repo_path": "releases/mozilla-aurora", 03:29:07 INFO - "revision": "98da47b4d83c576ce17d189942d2655df5a36dc1", 03:29:07 INFO - "stage_platform": "macosx64", 03:29:07 INFO - "builduid": "29fa51451e6247e79de74546a4441689", 03:29:07 INFO - "slavebuilddir": "test" 03:29:07 INFO - } 03:29:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 03:29:07 INFO - Running post-action listener: _resource_record_post_action 03:29:07 INFO - ##### 03:29:07 INFO - ##### Running download-and-extract step. 03:29:07 INFO - ##### 03:29:07 INFO - Running pre-action listener: _resource_record_pre_action 03:29:07 INFO - Running main action method: download_and_extract 03:29:07 INFO - mkdir: /builds/slave/test/build/tests 03:29:07 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')]} 03:29:07 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:29:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:29:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:29:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:29:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 03:29:22 INFO - Downloaded 1277 bytes. 03:29:22 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:29:22 INFO - Using the following test package requirements: 03:29:22 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:29:22 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:22 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 03:29:22 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:22 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 03:29:22 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:22 INFO - u'jsshell-mac.zip'], 03:29:22 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:22 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 03:29:22 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:29:22 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:22 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 03:29:22 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:22 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 03:29:22 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:22 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 03:29:22 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:29:22 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:29:22 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 03:29:22 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:29: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')]} 03:29:22 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:29:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:29:23 INFO - Downloaded 17919003 bytes. 03:29:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:29:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:29:27 INFO - Return code: 0 03:29:27 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:29:27 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')]} 03:29:27 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:29:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:29:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:29:32 INFO - Downloaded 17919003 bytes. 03:29:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:29:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:29:35 INFO - Return code: 0 03:29: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')]} 03:29:35 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:29:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:29:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:29:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:29:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:29:37 INFO - Downloaded 10845194 bytes. 03:29:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:29:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:29:37 INFO - Return code: 0 03:29:37 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')]} 03:29:37 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:29:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:29:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:29:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 03:29:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 03:29:42 INFO - Downloaded 115764096 bytes. 03:29:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:29:42 INFO - mkdir: /builds/slave/test/properties 03:29:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:29:42 INFO - Writing to file /builds/slave/test/properties/build_url 03:29:42 INFO - Contents: 03:29:42 INFO - build_url:https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:29:44 INFO - Running post-action listener: _resource_record_post_action 03:29:44 INFO - Running post-action listener: set_extra_try_arguments 03:29:44 INFO - ##### 03:29:44 INFO - ##### Running populate-webroot step. 03:29:44 INFO - ##### 03:29:44 INFO - Running pre-action listener: _resource_record_pre_action 03:29:44 INFO - Running main action method: populate_webroot 03:29:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:29:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:29:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:29:44 INFO - '--tppagecycles', 03:29:44 INFO - '7'], 03:29:44 INFO - 'tests': ['tsvgm']}}, 03:29:44 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:29:44 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:29:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:29:44 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 03:29:44 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:29:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:29:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:44 INFO - 'talos_options': ['--e10s'], 03:29:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:44 INFO - 'tests': ['damp', 'tps']}, 03:29:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:44 INFO - 'talos_options': ['--e10s'], 03:29:44 INFO - 'tests': ['damp', 'tps']}, 03:29:44 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:29:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:29:44 INFO - 'other': {'tests': ['a11yr', 03:29:44 INFO - 'ts_paint', 03:29:44 INFO - 'tpaint', 03:29:44 INFO - 'sessionrestore', 03:29:44 INFO - 'sessionrestore_no_auto_restore', 03:29:44 INFO - 'tabpaint']}, 03:29:44 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:29:44 INFO - 'tests': ['a11yr', 03:29:44 INFO - 'ts_paint', 03:29:44 INFO - 'tpaint', 03:29:44 INFO - 'sessionrestore', 03:29:44 INFO - 'sessionrestore_no_auto_restore', 03:29:44 INFO - 'tabpaint']}, 03:29:44 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:29:44 INFO - 'tests': ['a11yr', 03:29:44 INFO - 'ts_paint', 03:29:44 INFO - 'tpaint', 03:29:44 INFO - 'sessionrestore', 03:29:44 INFO - 'sessionrestore_no_auto_restore', 03:29:44 INFO - 'tabpaint']}, 03:29:44 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:29:44 INFO - 'tests': ['a11yr', 03:29:44 INFO - 'ts_paint', 03:29:44 INFO - 'tpaint', 03:29:44 INFO - 'sessionrestore', 03:29:44 INFO - 'sessionrestore_no_auto_restore', 03:29:44 INFO - 'tabpaint']}, 03:29:44 INFO - 'other_l64': {'tests': ['a11yr', 03:29:44 INFO - 'ts_paint', 03:29:44 INFO - 'tpaint', 03:29:44 INFO - 'sessionrestore', 03:29:44 INFO - 'sessionrestore_no_auto_restore', 03:29:44 INFO - 'tabpaint']}, 03:29:44 INFO - 'other_nol64': {'tests': ['a11yr', 03:29:44 INFO - 'ts_paint', 03:29:44 INFO - 'tpaint', 03:29:44 INFO - 'sessionrestore', 03:29:44 INFO - 'sessionrestore_no_auto_restore', 03:29:44 INFO - 'tabpaint']}, 03:29:44 INFO - 'svgr': {'tests': ['tsvgx', 03:29:44 INFO - 'tsvgr_opacity', 03:29:44 INFO - 'tart', 03:29:44 INFO - 'tscrollx', 03:29:44 INFO - 'cart']}, 03:29:44 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:29:44 INFO - 'tests': ['tsvgx', 03:29:44 INFO - 'tsvgr_opacity', 03:29:44 INFO - 'tart', 03:29:44 INFO - 'tscrollx', 03:29:44 INFO - 'cart']}, 03:29:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:44 INFO - 'tests': ['tp5o']}, 03:29:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:44 INFO - 'talos_options': ['--e10s'], 03:29:44 INFO - 'tests': ['tp5o']}, 03:29:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:44 INFO - 'talos_options': ['--xperf_path', 03:29:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:44 INFO - 'tests': ['tp5n']}, 03:29:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:44 INFO - 'talos_options': ['--e10s', 03:29:44 INFO - '--xperf_path', 03:29:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:44 INFO - 'tests': ['tp5n']}}, 03:29:44 INFO - 'talos.zip': {'path': '', 03:29:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:29:44 INFO - Running post-action listener: _resource_record_post_action 03:29:44 INFO - ##### 03:29:44 INFO - ##### Running create-virtualenv step. 03:29:44 INFO - ##### 03:29:44 INFO - Running pre-action listener: _resource_record_pre_action 03:29:44 INFO - Running main action method: create_virtualenv 03:29:44 INFO - Creating virtualenv /builds/slave/test/build/venv 03:29:44 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 03:29:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:29:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:29:44 INFO - Using real prefix '/tools/python27' 03:29:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:29:44 INFO - Installing distribute.............................................................................................................................................................................................done. 03:29:47 INFO - Installing pip.................done. 03:29:47 INFO - Return code: 0 03:29:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100aae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90a4a3780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, '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.seADXhUFmK/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.Fumg0JE0w5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:29:47 INFO - 'HOME': '/Users/cltbld', 03:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:29:47 INFO - 'LOGNAME': 'cltbld', 03:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:47 INFO - 'MOZ_NO_REMOTE': '1', 03:29:47 INFO - 'NO_EM_RESTART': '1', 03:29:47 INFO - 'PAGER': '/bin/cat', 03:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:47 INFO - 'PWD': '/builds/slave/test', 03:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:29:47 INFO - 'SHELL': '/bin/bash', 03:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:29:47 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:29:47 INFO - 'USER': 'cltbld', 03:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:47 INFO - 'XPC_FLAGS': '0x0', 03:29:47 INFO - 'XPC_SERVICE_NAME': '0', 03:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:48 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 03:29:48 INFO - Cleaning up... 03:29:48 INFO - Return code: 0 03:29:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:29: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')]} 03:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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')]} 03:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:48 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 0x100aa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100aae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90a4a3780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, '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.seADXhUFmK/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.Fumg0JE0w5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:29:48 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 03:29:48 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 03:29:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:29:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:29:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:29:48 INFO - 'HOME': '/Users/cltbld', 03:29:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:29:48 INFO - 'LOGNAME': 'cltbld', 03:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:48 INFO - 'MOZ_NO_REMOTE': '1', 03:29:48 INFO - 'NO_EM_RESTART': '1', 03:29:48 INFO - 'PAGER': '/bin/cat', 03:29:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:48 INFO - 'PWD': '/builds/slave/test', 03:29:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:29:48 INFO - 'SHELL': '/bin/bash', 03:29:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:29:48 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:29:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:29:48 INFO - 'USER': 'cltbld', 03:29:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:29:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:48 INFO - 'XPC_FLAGS': '0x0', 03:29:48 INFO - 'XPC_SERVICE_NAME': '0', 03:29:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:48 INFO - Downloading/unpacking psutil>=0.7.1 03:29:48 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 03:29:48 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 03:29:48 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 03:29:48 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 03:29:48 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 03:29:48 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 03:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:29:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:51 INFO - Installing collected packages: psutil 03:29:51 INFO - Running setup.py install for psutil 03:29:51 INFO - building 'psutil._psutil_osx' extension 03:29:51 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 03:29:52 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 03:29:52 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 03:29:52 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 03:29:52 INFO - building 'psutil._psutil_posix' extension 03:29:52 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 03:29:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:29:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:29:52 INFO - ^ 03:29:52 INFO - 1 warning generated. 03:29:52 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 03:29:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:52 INFO - Successfully installed psutil 03:29:52 INFO - Cleaning up... 03:29:52 INFO - Return code: 0 03:29:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100aa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100aae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90a4a3780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, '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.seADXhUFmK/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.Fumg0JE0w5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:29:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:29:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:29:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:29:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:29:52 INFO - 'HOME': '/Users/cltbld', 03:29:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:29:52 INFO - 'LOGNAME': 'cltbld', 03:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:52 INFO - 'MOZ_NO_REMOTE': '1', 03:29:52 INFO - 'NO_EM_RESTART': '1', 03:29:52 INFO - 'PAGER': '/bin/cat', 03:29:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:52 INFO - 'PWD': '/builds/slave/test', 03:29:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:29:52 INFO - 'SHELL': '/bin/bash', 03:29:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:29:52 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:29:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:29:52 INFO - 'USER': 'cltbld', 03:29:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:29:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:52 INFO - 'XPC_FLAGS': '0x0', 03:29:52 INFO - 'XPC_SERVICE_NAME': '0', 03:29:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:29:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:29:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:29:55 INFO - Installing collected packages: mozsystemmonitor 03:29:55 INFO - Running setup.py install for mozsystemmonitor 03:29:56 INFO - Successfully installed mozsystemmonitor 03:29:56 INFO - Cleaning up... 03:29:56 INFO - Return code: 0 03:29:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:29: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')]} 03:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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')]} 03:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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', '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 0x100aa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100aae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90a4a3780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, '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.seADXhUFmK/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.Fumg0JE0w5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:29: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:29: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 blobuploader==1.2.4 03:29:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:29:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:29:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:29:56 INFO - 'HOME': '/Users/cltbld', 03:29:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:29:56 INFO - 'LOGNAME': 'cltbld', 03:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:56 INFO - 'MOZ_NO_REMOTE': '1', 03:29:56 INFO - 'NO_EM_RESTART': '1', 03:29:56 INFO - 'PAGER': '/bin/cat', 03:29:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:56 INFO - 'PWD': '/builds/slave/test', 03:29:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:29:56 INFO - 'SHELL': '/bin/bash', 03:29:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:29:56 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:29:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:29:56 INFO - 'USER': 'cltbld', 03:29:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:29:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:56 INFO - 'XPC_FLAGS': '0x0', 03:29:56 INFO - 'XPC_SERVICE_NAME': '0', 03:29:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:29:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:56 INFO - Downloading/unpacking blobuploader==1.2.4 03:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:58 INFO - Downloading blobuploader-1.2.4.tar.gz 03:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:29:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:29:58 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 03:29:58 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 03:29:58 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 03:29:58 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 03:29:58 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 03:29:58 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 03:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:29:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:29:59 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 03:29:59 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 03:29:59 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 03:29:59 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 03:29:59 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 03:29:59 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 03:29:59 INFO - Downloading docopt-0.6.1.tar.gz 03:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:30:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:30:00 INFO - Running setup.py install for blobuploader 03:30:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:30:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:30:00 INFO - Running setup.py install for requests 03:30:00 INFO - Running setup.py install for docopt 03:30:00 INFO - Successfully installed blobuploader requests docopt 03:30:00 INFO - Cleaning up... 03:30:00 INFO - Return code: 0 03:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30: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')]} 03:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30: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')]} 03:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:00 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 0x100aa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100aae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90a4a3780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, '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.seADXhUFmK/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.Fumg0JE0w5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:30:00 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 03:30:00 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 03:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:00 INFO - 'HOME': '/Users/cltbld', 03:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:00 INFO - 'LOGNAME': 'cltbld', 03:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:00 INFO - 'MOZ_NO_REMOTE': '1', 03:30:00 INFO - 'NO_EM_RESTART': '1', 03:30:00 INFO - 'PAGER': '/bin/cat', 03:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:00 INFO - 'PWD': '/builds/slave/test', 03:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:00 INFO - 'SHELL': '/bin/bash', 03:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:30:00 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:00 INFO - 'USER': 'cltbld', 03:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:00 INFO - 'XPC_FLAGS': '0x0', 03:30:00 INFO - 'XPC_SERVICE_NAME': '0', 03:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:30:01 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-6CuZPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:30:01 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-FQAxCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:30:01 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-TD9kNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:30:01 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-UJpZfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:30:01 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-00dwpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:30:01 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-BYRblT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:30:01 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-dXDWgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:30:01 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-uphMUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:30:01 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-jky543-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:30:02 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-g4ABrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:30:02 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Kkg0nB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:30:02 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-doWSEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:30:02 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-m_REWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:30:02 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-38Hg4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:02 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-jDgTFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:30:02 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-0N2iph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:30:02 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-9lH5qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:30:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:30:02 INFO - Running setup.py install for manifestparser 03:30:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:30:03 INFO - Running setup.py install for mozcrash 03:30:03 INFO - Running setup.py install for mozdebug 03:30:03 INFO - Running setup.py install for mozdevice 03:30:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:30:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:30:03 INFO - Running setup.py install for mozfile 03:30:03 INFO - Running setup.py install for mozhttpd 03:30:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:30:03 INFO - Running setup.py install for mozinfo 03:30:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:30:03 INFO - Running setup.py install for mozInstall 03:30:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:30:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:30:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:30:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:30:04 INFO - Running setup.py install for mozleak 03:30:04 INFO - Running setup.py install for mozlog 03:30:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:30:04 INFO - Running setup.py install for moznetwork 03:30:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:30:04 INFO - Running setup.py install for mozprocess 03:30:04 INFO - Running setup.py install for mozprofile 03:30:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:30:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:30:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:30:04 INFO - Running setup.py install for mozrunner 03:30:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:30:05 INFO - Running setup.py install for mozscreenshot 03:30:05 INFO - Running setup.py install for moztest 03:30:05 INFO - Running setup.py install for mozversion 03:30:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:30:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:30:05 INFO - Cleaning up... 03:30:05 INFO - Return code: 0 03:30:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30:05 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')]} 03:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:05 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')]} 03:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:05 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 0x100aa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100aae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90a4a3780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, '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.seADXhUFmK/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.Fumg0JE0w5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:30:05 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 03:30:05 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 03:30:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:30:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:05 INFO - 'HOME': '/Users/cltbld', 03:30:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:05 INFO - 'LOGNAME': 'cltbld', 03:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:05 INFO - 'MOZ_NO_REMOTE': '1', 03:30:05 INFO - 'NO_EM_RESTART': '1', 03:30:05 INFO - 'PAGER': '/bin/cat', 03:30:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:05 INFO - 'PWD': '/builds/slave/test', 03:30:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:05 INFO - 'SHELL': '/bin/bash', 03:30:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:30:05 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:30:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:05 INFO - 'USER': 'cltbld', 03:30:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:05 INFO - 'XPC_FLAGS': '0x0', 03:30:05 INFO - 'XPC_SERVICE_NAME': '0', 03:30:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:30:05 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-7XN9Mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:30:05 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)) 03:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:30:05 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-NasR1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:30:05 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)) 03:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:30:05 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-EhslO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:30:05 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)) 03:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:30:05 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ViwpN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:30:06 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)) 03:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:30:06 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ffQgha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:30:06 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)) 03:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:30:06 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-DSuszN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:30:06 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)) 03:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:30:06 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-bJZSRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:30:06 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)) 03:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:30:06 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-F1p3Ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:30:06 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)) 03:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:30:06 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-jm6h1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:30:06 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)) 03:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:30:06 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-t7QPZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:30:06 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)) 03:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:30:06 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-tAWx_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:30:06 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)) 03:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:30:06 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-kvduPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:30:06 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)) 03:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:30:06 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-vAgbtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:30:07 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)) 03:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:30:07 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-12vbBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:30:07 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)) 03:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:07 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-L_USVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:07 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)) 03:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:30:07 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-bOi6S1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:30:07 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)) 03:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:30:07 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-9liRji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:30:07 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)) 03:30:07 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)) 03:30:07 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)) 03:30:07 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)) 03:30:07 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)) 03:30:07 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)) 03:30:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30: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 03:30:10 INFO - Downloading blessings-1.6.tar.gz 03:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:30:10 INFO - Installing collected packages: blessings 03:30:10 INFO - Running setup.py install for blessings 03:30:10 INFO - Successfully installed blessings 03:30:10 INFO - Cleaning up... 03:30:10 INFO - Return code: 0 03:30:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:30:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:10 INFO - Reading from file tmpfile_stdout 03:30:10 INFO - Current package versions: 03:30:10 INFO - blessings == 1.6 03:30:10 INFO - blobuploader == 1.2.4 03:30:10 INFO - docopt == 0.6.1 03:30:10 INFO - manifestparser == 1.1 03:30:10 INFO - mozInstall == 1.12 03:30:10 INFO - mozcrash == 0.17 03:30:10 INFO - mozdebug == 0.1 03:30:10 INFO - mozdevice == 0.48 03:30:10 INFO - mozfile == 1.2 03:30:10 INFO - mozhttpd == 0.7 03:30:10 INFO - mozinfo == 0.9 03:30:10 INFO - mozleak == 0.1 03:30:10 INFO - mozlog == 3.1 03:30:10 INFO - moznetwork == 0.27 03:30:10 INFO - mozprocess == 0.22 03:30:10 INFO - mozprofile == 0.28 03:30:10 INFO - mozrunner == 6.11 03:30:10 INFO - mozscreenshot == 0.1 03:30:10 INFO - mozsystemmonitor == 0.0 03:30:10 INFO - moztest == 0.7 03:30:10 INFO - mozversion == 1.4 03:30:10 INFO - psutil == 3.1.1 03:30:10 INFO - requests == 1.2.3 03:30:10 INFO - wsgiref == 0.1.2 03:30:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30:10 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')]} 03:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:10 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')]} 03:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:10 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 0x100aa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100aae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90a4a3780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, '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.seADXhUFmK/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.Fumg0JE0w5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:30:10 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 03:30:10 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 03:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:10 INFO - 'HOME': '/Users/cltbld', 03:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:10 INFO - 'LOGNAME': 'cltbld', 03:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:10 INFO - 'MOZ_NO_REMOTE': '1', 03:30:10 INFO - 'NO_EM_RESTART': '1', 03:30:10 INFO - 'PAGER': '/bin/cat', 03:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:10 INFO - 'PWD': '/builds/slave/test', 03:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:10 INFO - 'SHELL': '/bin/bash', 03:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:30:10 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:10 INFO - 'USER': 'cltbld', 03:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:10 INFO - 'XPC_FLAGS': '0x0', 03:30:10 INFO - 'XPC_SERVICE_NAME': '0', 03:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:11 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)) 03:30:11 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)) 03:30:11 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)) 03:30:11 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)) 03:30:11 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)) 03:30:11 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)) 03:30:11 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)) 03:30:11 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)) 03:30:11 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)) 03:30:11 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)) 03:30:11 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)) 03:30:11 INFO - Cleaning up... 03:30:11 INFO - Return code: 0 03:30:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100aae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90a4a3780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, '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.seADXhUFmK/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.Fumg0JE0w5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:30:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:30:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:30:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:11 INFO - 'HOME': '/Users/cltbld', 03:30:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:11 INFO - 'LOGNAME': 'cltbld', 03:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:11 INFO - 'MOZ_NO_REMOTE': '1', 03:30:11 INFO - 'NO_EM_RESTART': '1', 03:30:11 INFO - 'PAGER': '/bin/cat', 03:30:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:11 INFO - 'PWD': '/builds/slave/test', 03:30:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:11 INFO - 'SHELL': '/bin/bash', 03:30:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:30:11 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:30:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:11 INFO - 'USER': 'cltbld', 03:30:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:11 INFO - 'XPC_FLAGS': '0x0', 03:30:11 INFO - 'XPC_SERVICE_NAME': '0', 03:30:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:11 INFO - Downloading/unpacking jsonschema 03:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:30:13 INFO - Downloading/unpacking functools32 (from jsonschema) 03:30:13 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 03:30:13 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 03:30:13 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 03:30:13 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 03:30:13 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 03:30:13 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 03:30:14 INFO - Downloading functools32-3.2.3-2.tar.gz 03:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:30:14 INFO - warning: no files found matching '*.txt' 03:30:14 INFO - no previously-included directories found matching 'build' 03:30:14 INFO - no previously-included directories found matching 'dist' 03:30:14 INFO - no previously-included directories found matching '.git*' 03:30:14 INFO - Installing collected packages: jsonschema, functools32 03:30:14 INFO - Running setup.py install for functools32 03:30:14 INFO - warning: no files found matching '*.txt' 03:30:14 INFO - no previously-included directories found matching 'build' 03:30:14 INFO - no previously-included directories found matching 'dist' 03:30:14 INFO - no previously-included directories found matching '.git*' 03:30:14 INFO - Successfully installed jsonschema functools32 03:30:14 INFO - Cleaning up... 03:30:14 INFO - Return code: 0 03:30:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100aae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb90a4a3780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, '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.seADXhUFmK/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.Fumg0JE0w5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:30:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:30:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:14 INFO - 'HOME': '/Users/cltbld', 03:30:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:14 INFO - 'LOGNAME': 'cltbld', 03:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:14 INFO - 'MOZ_NO_REMOTE': '1', 03:30:14 INFO - 'NO_EM_RESTART': '1', 03:30:14 INFO - 'PAGER': '/bin/cat', 03:30:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:14 INFO - 'PWD': '/builds/slave/test', 03:30:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:14 INFO - 'SHELL': '/bin/bash', 03:30:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:30:14 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:30:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:14 INFO - 'USER': 'cltbld', 03:30:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:14 INFO - 'XPC_FLAGS': '0x0', 03:30:14 INFO - 'XPC_SERVICE_NAME': '0', 03:30:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:14 INFO - Downloading/unpacking flake8 03:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:30:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:30:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:30:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:30:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:30:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:30:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:30:18 INFO - no previously-included directories found matching 'docs/_build' 03:30:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:30:18 INFO - Running setup.py install for pep8 03:30:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:30:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:30:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:30:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:30:18 INFO - no previously-included directories found matching 'docs/_build' 03:30:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:30:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:30:18 INFO - Cleaning up... 03:30:18 INFO - Return code: 0 03:30:18 INFO - Running post-action listener: _resource_record_post_action 03:30:18 INFO - Running post-action listener: _start_resource_monitoring 03:30:18 INFO - Starting resource monitoring. 03:30:18 INFO - ##### 03:30:18 INFO - ##### Running install step. 03:30:18 INFO - ##### 03:30:18 INFO - Running pre-action listener: _resource_record_pre_action 03:30:18 INFO - Running main action method: install 03:30:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:19 INFO - Reading from file tmpfile_stdout 03:30:19 INFO - Detecting whether we're running mozinstall >=1.0... 03:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:30:19 INFO - Reading from file tmpfile_stdout 03:30:19 INFO - Output received: 03:30:19 INFO - Usage: mozinstall [options] installer 03:30:19 INFO - Options: 03:30:19 INFO - -h, --help show this help message and exit 03:30:19 INFO - -d DEST, --destination=DEST 03:30:19 INFO - Directory to install application into. [default: 03:30:19 INFO - "/builds/slave/test"] 03:30:19 INFO - --app=APP Application being installed. [default: firefox] 03:30:19 INFO - mkdir: /builds/slave/test/build/application 03:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:30:51 INFO - Reading from file tmpfile_stdout 03:30:51 INFO - Output received: 03:30:51 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:30:51 INFO - Running post-action listener: _resource_record_post_action 03:30:51 INFO - ##### 03:30:51 INFO - ##### Running run-tests step. 03:30:51 INFO - ##### 03:30:51 INFO - Running pre-action listener: _resource_record_pre_action 03:30:51 INFO - Running main action method: run_tests 03:30:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:30:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:30:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:30:51 INFO - 2016-04-25 03:30:51.434 screenresolution[1646:7839] starting screenresolution argv=screenresolution get 03:30:51 INFO - 2016-04-25 03:30:51.437 screenresolution[1646:7839] Display 0: 1600x1200x32@60 03:30:51 INFO - 2016-04-25 03:30:51.443 screenresolution[1647:7842] starting screenresolution argv=screenresolution list 03:30:51 INFO - Available Modes on Display 0 03:30:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:30:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:30:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:30:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:30:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:30:51 INFO - Intel Iris: 03:30:51 INFO - Chipset Model: Intel Iris 03:30:51 INFO - Type: GPU 03:30:51 INFO - Bus: Built-In 03:30:51 INFO - VRAM (Dynamic, Max): 1536 MB 03:30:51 INFO - Vendor: Intel (0x8086) 03:30:51 INFO - Device ID: 0x0a2e 03:30:51 INFO - Revision ID: 0x0009 03:30:51 INFO - Displays: 03:30:51 INFO - Display: 03:30:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:30:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:30:51 INFO - Display Serial Number: 1600x1200 60 03:30:51 INFO - Main Display: Yes 03:30:51 INFO - Mirror: Off 03:30:51 INFO - Online: Yes 03:30:51 INFO - Rotation: Supported 03:30:51 INFO - Return code: 0 03:30:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:30:51 INFO - Python 2.7.3 03:30:51 INFO - Return code: 0 03:30:51 INFO - grabbing minidump binary from tooltool 03:30:51 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')]} 03:30:51 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 0x10094bd30>, '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 0x1007afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100942b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:30:51 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 03:30:51 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 03:30:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:30:51 INFO - Return code: 0 03:30:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:30:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:30:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:30:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:30:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:30:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:30:52 INFO - Return code: 0 03:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 03:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0081 --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 03:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.seADXhUFmK/Render', 03:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:52 INFO - 'HOME': '/Users/cltbld', 03:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:52 INFO - 'LOGNAME': 'cltbld', 03:30:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:52 INFO - 'MOZ_NO_REMOTE': '1', 03:30:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:52 INFO - 'NO_EM_RESTART': '1', 03:30:52 INFO - 'PAGER': '/bin/cat', 03:30:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:52 INFO - 'PWD': '/builds/slave/test', 03:30:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:52 INFO - 'SHELL': '/bin/bash', 03:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Fumg0JE0w5/Listeners', 03:30:52 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:52 INFO - 'USER': 'cltbld', 03:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:52 INFO - 'XPC_FLAGS': '0x0', 03:30:52 INFO - 'XPC_SERVICE_NAME': '0', 03:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 03:30:53 INFO - mozversion application_buildid: 20160424125552 03:30:53 INFO - mozversion application_changeset: 98da47b4d83c576ce17d189942d2655df5a36dc1 03:30:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:30:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:30:53 INFO - mozversion application_name: Firefox 03:30:53 INFO - mozversion application_remotingname: firefox-dev 03:30:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:30:53 INFO - mozversion application_vendor: Mozilla 03:30:53 INFO - mozversion application_version: 47.0a2 03:30:53 INFO - mozversion platform_buildid: 20160424125552 03:30:53 INFO - mozversion platform_changeset: 98da47b4d83c576ce17d189942d2655df5a36dc1 03:30:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:30:53 INFO - mozversion platform_version: 47.0a2 03:30:53 INFO - using testdate: 1461580253 03:30:53 INFO - actual date: 1461580253 03:30:53 INFO - starting webserver on 'localhost:49275' 03:30:53 INFO - SUITE-START | Running 2 tests 03:30:53 INFO - TEST-START | dromaeo_css 03:30:53 INFO - operating with platform_type : mac_ 03:30:53 INFO - Initialising browser for dromaeo_css test... 03:30:53 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmp_66Ep2/profile http://localhost:49275/getInfo.html) 03:30:55 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 03:30:55 INFO - PROCESS | 1665 | colorDepth:24 03:30:55 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 03:30:55 INFO - PROCESS | 1665 | __metrics 03:30:55 INFO - PROCESS | 1665 | 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" 03:30:55 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:30:56 INFO - TEST-INFO | 1665: exit 0 03:30:56 INFO - Browser initialized. 03:30:56 INFO - Running cycle 1/1 for dromaeo_css test... 03:30:56 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmp_66Ep2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:31:08 INFO - PROCESS | 1668 | RSS: Main: 207802368 03:31:08 INFO - PROCESS | 1668 | 03:31:58 INFO - PROCESS | 1668 | 1461580318985 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] 03:34:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 03:34:10 INFO - PROCESS | 1668 | RSS: Main: 321400832 03:34:10 INFO - PROCESS | 1668 | 03:37:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 03:37:13 INFO - PROCESS | 1668 | RSS: Main: 343105536 03:37:13 INFO - PROCESS | 1668 | 03:40:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 03:40:15 INFO - PROCESS | 1668 | RSS: Main: 340836352 03:40:15 INFO - PROCESS | 1668 | 03:43:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 03:43:18 INFO - PROCESS | 1668 | RSS: Main: 305229824 03:43:18 INFO - PROCESS | 1668 | 03:46:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 03:46:21 INFO - PROCESS | 1668 | RSS: Main: 307568640 03:46:21 INFO - PROCESS | 1668 | 03:49:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 03:49:24 INFO - PROCESS | 1668 | RSS: Main: 355270656 03:49:24 INFO - PROCESS | 1668 | 03:49:24 INFO - PROCESS | 1668 | __start_tp_report 03:49:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 03:49:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 03:49:24 INFO - PROCESS | 1668 | |i|pagename|runs| 03:49:24 INFO - PROCESS | 1668 | |0;dojo.html;3398.779079359842;3434.6908778209954;3451.516787649728;3455.2743923776006;3471.44456886898;5595.468430499096;5597.440255974406;5600.663960162393;5603.635763675358;5622.072358060926;5595.916061259084;5615.073512870382;5623.26897503324;5632.831015069544;5634.267545219126;5068.46781087986;5073.416557095926;5079.517445842639;5107.897842043176;5120.15517439622;5201.063808514485;5215.504527069941;5216.895662086756;5222.00781851447;5223.164293713006;5158.561522270593;5207.062728708831;5207.765650545715;5211.322528071345;5222.608304377168;4142.544320124784;5211.42674305826;5216.556592689626;5237;5277.076511610453;4989.950100499022;5148.536631703165;5156.123459011939;5158.303629010074;5180.197069454241;5477.370102438231;5510.972445137789;5523.889522209573;5533.806316778893;5551.8889622207735;6047.516198704093;6187;6188.721507532172;6205.596636218631;6219.2847822500535;6130.264368275791;6176.258848938156;6178.505719542471;6245.812625621239;6252.31224565302;5766.740496677618;5796.710164491758;5809.419058094157;5827.24245848037;5850.882982340329;6182.381761823782;6193.287771906288;6252.124702541648;6252.3122456529745;6268.592541484789;6064.302605200459;6075.605085669506;6088.331526657591;6096.817095487142;6103.206583144251;6227.657478838707;6231.875362492816;6242;6244.188255526752;6249.218847644044;5060.49395060491;5925.792597259161;6077.088436734525;6108.11482540077;6131.754729810757;6165.352637973038;6173.32093469718;6208.844778880564;6215.875682486325;6222.408871157233;4832.275158726149;4838.322634831126;4838.7338696372035;4864.635152363516;4880.145974454456;4329.631981281564;4362.432883725159;4384.69307148503;4394.077243778778;4442.955570444319;5556.861078473071;5561.221428999943;5565.443455654402;5573.693446860461;5603.579731520153;6230.376962303733;6234.906476402857;6238.064290356392;6242.344553821874;6242.500599951993;5449.4005659377435;5470.835874923759;5527;5557.499825015768;5560.304961879765;4440.955590444054;5261.815836445706;5291.973540132275;5317.096093664087;5368.597355198376;6212.099245609452;6247.718852651642;6258.499320054385;6258.780942667076;6272.968635156886;6129.725578588854;6146.354632763674;6222.533310001859;6228.22147231596;6305.148804911279;6262.624242545279;6279.277883043508;6280.685965701788;6281.748730050746;6296;6074.635521868702;6092.116643086639;6092.390760923872;6104.20645316106;6111.327753947151;6000.457898247067;6069.878602428015;6162.168107305456;6252.656103914146;6263.342349053466;4983.451820299837;5992.101184822312;6027.156198132177;6032.276126864805;6049.516038716804;5842.707864606838;5990.8502287441415;5990.880182396415;6010.87978240424;6021.789237376671;5770.653760774367;5851.795187168429;5862.853428664317;5870.677112758839;5897.764089436546;5979.162917191509;5989.850253743692;5991.970040149771;6048.153258543719;6065.363136870742;5793.304803423616;5803.187553742564;5846.590738648254;5858.355580886185;5890.3226128994 03:49:24 INFO - PROCESS | 1668 | |1;ext.html;476.019400035926;531.8085489223877;579.5566391710342;657.3722095398895;667.176037593572;32279.19302017451;32783.836080819594;32889.17777055575;39949.400758988566;40620.187596248055;43502.782486087606;43663.563364366346;43991.12017759643;43997.900052498706;44029.77985110078;9665.5167241638;9898.851517227247;9901.950490247538;9910.603575856958;9912.504374781269;9673.613055477772;9821.6071357146;9828.312018158731;9834.803303933953;9837.360571562862;9747.902520974805;9834.55744491496;9842.06500382466;9994.650187243447;10018.799624007515;9916.752081197992;9990.600375984952;9992.400455972665;9995.80008399828;10008.799824003552;9792.363496372713;9946.801063978752;9958;9961.850572241423;9965.75085622858;40992.59007409948;41229.79385103085;41383.58616413827;41400;41512.96217594554;3240.7245384142375;3423.640517745651;3522.3483655523555;3734.7572407793677;3780.697544196485;29395.55906661402;29827.85086074556;29934.101976940725;30208.69791302081;30335.089947301618;2330.3242059802637;2569.730178331267;2573.935651608706;2597.5324441600505;2632.9994602051097;471447;471599.6420017912;472716;473290;476007;69120;69587.95618065733;69597;69726.9540956886;69860;73958.63020684863;74178.62910685412;78631.60684196542;79725.20274797294;79839.20160798318;18169.545761356072;18259.087045647666;18391.540211494554;18434.631307373777;18468.81531184671;13950.302484875716;14038.227897465737;14039.719205615831;14045.01684882069;14055.718885622231;18267.543311417056;22632.547349052926;22984;23030.769692303198;23189.53620927572;7624.80937976555;8910.821783564292;9193.310501712398;9206.907930920604;9279.376109180866;910.9772255693529;998.2114129837347;1055.6991257491577;1121.770037142384;1126.7633796902578;30468.54297185544;30585;30638.234044149074;30746.538801917988;31000.53499197514;1153.8846115388394;1155.664857191405;1156.9363684997406;1164.7612239490884;1265.3926115464628;11531.88468115325;14848.2575871206;16152.030878147349;16198.190090495427;16645.916770416312;513505;520671;520768;521952;524318;62651.68674156691;63139;63152.684236579444;63190.68404658039;63220.36779632237;1487.5016869348674;1506.3070987345943;1542.0747551469028;1547.110411513407;1608.630015096511;28938.565921511614;30790.38419231558;30892;30897.53653695241;31123.576406594802;41205.58794412138;50575.988480230924;50650.240246395595;50995.2350714747;51118.48881511137;359692;361858;361925;362630;363050.1847490746;30309.848450757607;49148;49276.26085608647;49614.50385496099;49870.75064624654;47891;47972.7601361991;48128;48380.7580962107;49950.750246248535;37994.24011519699;38955.2208955825;39069.609303906596;39472;40402.989925252106;37757.4336384949;37822.243555128196;38678.033049173995;38702;39189.608103918596;40238.597614023485;40575.1884962305;40970.59029409668;41931.7903410481;41956;30977.69022309748;33252;34621.307573848884;35310;35884.28231435305;26665.46669066569;26889.462210756068;26937.326566835985;26944.730552695008;27275.31811704723 03:49:24 INFO - PROCESS | 1668 | |2;jquery.html;387.93405121129405;388.9222155568887;391.0224438902743;399.04627939225236;399.51059951559364;17726.911365443186;20304.39086827394;21343;21591.784082159174;21892.671609925823;12108;12474.064445166603;20879;21700;21733.23933662322;5231.11071117909;5365.570754339663;5409.486098820606;5416.404195538487;5424;4202.117555313373;4384.8026838792175;5940.435658612425;6189.938100619003;6192.380761923817;6105.114758360035;6185.010398336267;6189.566730328879;6195.1946246988055;6203.410675985774;5979.491743201833;6106;6145.907811382833;6152.969235153818;6169.81490555284;4035.656969157625;5662.688552129631;5894.616849904743;5900.55745819061;5908.261467316585;13694.246816425093;13866.168029918237;13940.58178254654;14090.647733806634;14101.083429577046;2227.0759778987976;2303.1363238785457;2418.3349578865777;2721.6053672217618;2810.9718902810914;9426.811463770686;10218;10388.896111038868;10442.79114417715;10565.68302950905;1691.9577010574835;1748.4230204032638;1754.3859649122833;1816.6639171753136;1819.9454016379398;106426.93573064325;108720.4563977183;110007.44996275127;111506;113104;11541.826872596917;11576.24754390962;11615.6515304541;11631.127665425127;11702.063834893188;14612.926935365254;14667.486637967833;14676.853231467761;14695.706085878222;14736.705265894621;8743.781405464913;10807.567697292177;11162.776744465065;11649.708757280967;11701.882981170249;8059.556724380215;9460.52697365129;9513.238940884712;9550.761230969281;9884.752881177885;7283.5994020328335;8879.911200888038;8982.146696063863;9047.457152570867;9048.592813323416;3050.100220434977;3472.652734726558;3473.3747925373677;3525.990185594238;3590.3716849551224;747.6523416611302;931.9347645664874;963.5663951221978;975.1175186456269;1078.1698092468755;15228.923855380652;19875.204991800165;20561.38315850527;20610.175592976113;20678.966051697356;947.7488465556632;955.1833182628752;982.9017098290113;983.0759086458706;1037.190991027;11089.722756931142;11423.314601123959;11466.2546934449;11470.942645286721;11471.311721296748;615055;629230;638990;648623;649378;115630.32602122115;117895.41052294856;117953.41023294828;118898;119492;979.583676937313;988.367444835306;997.2122023601273;998.2513115163628;1019.6635110413546;18581.535461613566;18623.88256704602;18910.621787563898;18988.810111898703;19220.327288544835;52670.47329526656;54957.450425496834;55041.62395939973;55596.722016389656;56974;78246;78504;79323;79848.60075699585;80055.59972200102;8245.216704413071;8326.875096873673;8359.205875441823;8541.14588541141;8685.565721714016;9620.903790962;12724.872751272369;13321.933390332986;13637.318134093453;13726.725465490434;11360.829587556029;11608.535658573563;11799.52801887915;11837;11955.342456164994;11350.54597816078;11636.185467017227;11708.121890858049;11878.406079695807;11912.940435297767;11757.588484403346;11829.17195796286;11890.881091189323;11996.400179990791;12149;8319;8402.537860417735;8458.957705211435;8503.744887653133;8549.401541892257;8842.690505832265;9859.852102218616;11412.657620271406;11808.11439142085;11815.881841181477 03:49:24 INFO - PROCESS | 1668 | |3;mootools.html;1463.2976171437704;1669.3990163541125;1680.6470641165358;2003.9098240579171;2068.6069646767123;7505.911642811796;8714.782130446742;8860.734177974664;8871.112888711126;8878.023417424094;7269.382102521292;8996.505192214425;9002.099790021011;9007.369484136114;9020.278377729783;1930.1507336771776;1949.0547084663926;2488.042103790036;2534.1637259704253;2567.627693984371;2528.683914510686;2540.9110681126162;2541.885615147323;2543.49130173965;2546.414324705319;2461.2862269941697;2563.4873025394904;2565.8845351959208;2567.4351642638685;2573.163721790426;2512.133314006665;2543.5930251159816;2566;2568.4477837265076;2571.4085760275243;2084.5726626041624;2128;2454.5581795276844;2570.6015567587056;2574.086199399205;8908.554572271361;8917.286617070684;8984.910150898539;8996;9024.05247449015;5469.124940009619;5691.089425691911;5696.031674615326;5740.827775166752;5786.189933409326;3562.6437356264423;3620.1130722972775;3671;3702.5927148013698;3711.3690672585726;1050.14937900301;1053.1416895230363;1059.6609085092773;1065.664315740547;1109.4619109731773;12195.93902030493;12214.633560993185;12219.26684398939;12227.266364018187;12231.816522752166;4931.778069986859;4964.081644895706;5046.7476626168545;5048.990201959549;5075.035743208852;10253.94873025645;10263.435511046964;10265;10267.640632577824;10280.383177009404;5679.858003549862;5685.886282274331;5693.68684722336;5709.058005429058;5713.857153571194;4543.136804007228;4717.622590192776;4725.503822098699;4732.574068333866;4917.459079501252;4045.150518391171;4094.406311084876;4133.090720041586;4149.751014939114;4151.315033019579;1356.4438580182077;1357.6198664373794;1359.211657238799;1363.7545241856362;1372.5333386648388;1367.8495365509787;1385.7990591364196;1424.69369085645;1426.315368623046;1434.583970648521;8313.002439707152;8550.486970781772;8571.914280857112;8575.528345940907;8586.699465518677;1202.9233835716984;1245.64499117751;1255.8241846141548;1276.7063575377529;1285.517930775959;5856.882862342814;6289.245290565162;6399.9040014399825;6442.549021568539;6443.67781610927;10706.250562460553;11156.665300041012;11256.268342557707;11258.549658013751;11270.887291127148;10709.196810239264;11015.724606884893;11086.057685096857;11112;11118.833217501744;8528.061913189544;8534.445261058012;8552.657893684307;8566.27186689126;8659.263962563253;7938.404619653434;8054.919450805417;8064.112947575644;8067.757967260992;8089.919100809152;9574.808503830023;9801.77477815273;9873.210143188671;9979;10003.799924001334;10040;10234.283600147919;10311.381317120997;10395.480225988822;10452.790944181226;7161.9641901790155;7194.640267986684;7200;7237.167725711611;7257.818554535967;8400.705975290835;8427.705030323908;8471.364647651328;8485.78785530342;8490.660373584988;7493.887591686238;7590.316871481593;7673.117591477052;7750.612469376621;7778.6888524460655;7459.850802984019;7494.587797671181;7553.169151393233;7703.537787732754;7728.806779830549;7957.244061814118;8020;8033.758987230392;8037.67849286022;8050.476719013245;7111.43108551325;7216.603086830065;7286.234945330664;7308.5614863108385;7331.486795924234;7370.041894553674;7384.889226661497;7438.665260063419;7440.3675687567165;7443.995060666741 03:49:24 INFO - PROCESS | 1668 | |4;prototype.html;334.909574414908;337.5898283585445;342.72239486016343;345.02702379290406;348.87614896711665;6324.810255692325;6343.270523889754;6354.7775827846035;6383.872322553546;6450.709718062687;5012.949870501294;6334.3348948360335;6348.428641422271;6362.7454901803985;6364.9363506364925;3616.566012078554;4317.503487098977;4644.373009643706;4722.480527142012;4778.116048531016;4751;4778.307145463906;4797.544233297831;4800.759962001904;4832.226843705008;4776.379070720819;4777.928331075037;4778.402699662543;4792.808287668507;4793.616510679138;3626.474161246631;4566.1552612766745;4660.207764679979;4663.554298167545;4788.592969597571;4051.0682543014977;4063.8984025399304;4067.3288907330257;4083.673306135502;4085.1216988347646;6026.819195424145;6054;6056.878862422771;6110.480609148228;6124;5495.2856128703415;5507.972460137674;5527.198556209327;5540.390557038722;5565.026120428949;6663.533552651316;6666.600003999727;6701.832454188636;6710.127683401175;6722.966385168092;4433.357163211316;4589.724616523019;4596.54034596541;4637.721736695809;4645.419322584677;5715.914261286084;6730.394264516168;6971.6165610891385;7022.929770702279;7035.613041282727;4663.580277775017;4700.318453824177;4704.788284527204;4706.129366067288;4715.269133284346;4781.378420805273;4798;4801.975990120027;4807.95192048082;4809.9038019240115;5544.7782088716;5544.86137846549;5563.749631266603;5569.888602227933;5571.303587051619;6322.683865806691;6349.904751428732;6703.22912865017;6733.057371967929;6761.966190169018;1647.6539926615305;1648.291234769037;1661.7507373894014;1670.5656529302469;1672.0469332480614;1027.917766578672;1086.603389762731;1091.4324551233474;1105.5412004018403;1107.839363292318;5244.31823862903;6092.238470191226;6277.591956522811;6455.4835613150835;6474.320196379313;7136.821579460555;7137;7142.642867856586;7152.678129484186;7166.964165179141;6413.390727880844;6437.517186211053;6447.3552644735155;6454.9677251614385;6456.580322279036;3761.191343861083;3766.548014238309;3766.8116594170183;3771.453139294787;3793.013816407754;7205.495615306983;7442.441816863757;7481.85036299271;7485.288897554724;7525.58609276495;6197.442230199304;7314.96342518284;7339.816504587428;7357.337839594462;7361.815954601071;6881.449484041264;7431.4054875608745;7434.070741157356;7456.51532650376;7466.178720340758;5797.217375654402;7091.11361079865;7118.110236220473;7141.071660684078;7143.749968751069;6738.35985581369;7008.299170083154;7019.859602808017;7020.6489675514995;7032.824179395556;5141.61437892167;7359.632018399166;7403.555786652818;7407.592582407804;7416.8887466686965;4752.7623618818225;4766.571008609103;4778.808847646055;4785.234362501983;4793.95206047949;7125.67934442941;7138.428925686037;7139.607321597362;7140.107486564179;7142.750003749844;6272.71409361089;6497.772577959749;6615;6814.420774234246;6843.315668433117;6776.559523630948;6803.829904252433;6828.692708828015;6838.179418469816;6839.1451068616425;6683.498737594603;6686.0973768540625;6688;6695.397414232742;6783.898241526283;6840.589564626139;6840.8289792753585;6841.17905851301;6841.3842754152365;6847.863042739018;4678.532146785295;6165.84585385369;6478.4169424752;6497.805065848032;6696.698648560729 03:49:24 INFO - PROCESS | 1668 | |5;yui.html;728.1262485017978;761.8819083042127;780.1535334162434;782.4561929459028;814.3566582399905;14881.18153501557;15085.094894306321;15218.467353642625;15233.92383038086;15238.314275857585;14657;14866.702665946674;14884.627884302898;14896.851031489708;14903;805.794522396788;823.0617096509983;931.3945935142178;961.0725649747992;972.4359871274644;982.7985263021063;982.8181786369508;984.6849008317341;985.0986347492036;986.4820968991265;1045.8588090607784;1046.2310202001522;1047.7956798424311;1048.5124417146026;1050.9316894401877;845.974620761375;1011.2971484818055;1037.3672060043405;1040.432964034599;1043.8173319669104;775.0931410250021;775.7595145504912;776.3827756933251;776.8368642585008;777.0713996773858;15370.846291537053;15439.073655580702;15450.459233926871;15450.922745386313;15473.767893481607;13096.34518274092;13172.407241674147;13215;13277.86722132776;13437.865621343759;9963.850542241873;10171.288009839314;10323.225758068174;10424.374537527772;10474.109700675375;3542.167590616229;3619.27614477103;3619.4208926571623;3636;3661.5240018797654;76478.6176069116;79227.20772792256;79687.40625187464;81471;81735;9783.657571984946;9871.309008369346;9907.058829411195;10079.143272821746;10126.240531960133;23169.420764481023;23171.957261923253;23172.3048308551;23173.07307707673;23187.420314491934;7500.662470188746;7524.774256772305;7602.619869006528;7620.885686714703;7796.8050798729355;4102.220578090213;4177.582241775859;4264.211120942636;4296.5273819879785;4461.531539188403;3747.100695833035;3785.072657198949;3788.905277368033;3791.658750712449;3807.200487897517;585.4379795396381;644.0789670770843;665.2482694555218;673.4814193071405;688.3873352716087;557.2727590494362;571.8049277010983;574.5920396518435;578.2569398323135;594.4709208804168;7989.201079892081;8154.510729356258;8290.253877151084;8291.860771513962;8380.545802744993;601.9969900150471;605.2978544887909;614.8862460444742;614.9508039356841;619.9721012554446;3859.8842034739005;3910.530736311661;3919.1573811630637;4020.8994775130273;4047.1298670785923;83659.74510382349;87851;89815.20369592702;89852;90054;75557.86663200056;75826;75859.24140758521;76566;77182;67714.66142669354;69245.80656450786;69640.60718785596;69729.2567685812;70764.64617676982;7821.178776228641;7883.408744344312;7924.920750792418;7958.045034595885;7969;45136.54863451455;49831.003379932925;50372.49627503825;51168;51588;56411.71794141003;66715;68514.97227541491;68843.65578172076;69535.30464695288;10254.948725256327;10531.684049478528;10547;10559.04968552834;10635.680929572125;10709.892901070889;10919.672409827717;10989.285696429706;11142.721431964266;11629.59296424621;10248.026437488426;10280.588776448858;10364.689059328231;10380.27338086357;10591.205659575717;10491.842622360824;10494.790104198026;10553.788924221319;10687.51906164209;10773.569057237622;9990.15083717892;10054.64808731691;10243.641472548425;10404.011618896193;10697.946510267398;8793.780155495908;8928.15182557656;8939.195472407515;9075.727728168165;9156.17594416479;7247.855042899218;8826.338024648305;8842;8867.246284065668;9071.319651026068 03:49:24 INFO - PROCESS | 1668 | __end_tp_report 03:49:24 INFO - PROCESS | 1668 | __start_cc_report 03:49:24 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,968 03:49:24 INFO - PROCESS | 1668 | __end_cc_report 03:49:24 INFO - PROCESS | 1668 | __startTimestamp1461581364591__endTimestamp 03:49:24 INFO - PROCESS | 1668 | 03:49:24 INFO - PROCESS | 1668 | ------- Summary: start ------- 03:49:24 INFO - PROCESS | 1668 | Number of tests: 6 03:49:24 INFO - PROCESS | 1668 | 03:49:24 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5660.34 Median:5851.80 stddev:641.78 (11.0%) stddev-sans-first:618.74 03:49:24 INFO - PROCESS | 1668 | Values: 3398.8 3434.7 3451.5 3455.3 3471.4 5595.5 5597.4 5600.7 5603.6 5622.1 5595.9 5615.1 5623.3 5632.8 5634.3 5068.5 5073.4 5079.5 5107.9 5120.2 5201.1 5215.5 5216.9 5222.0 5223.2 5158.6 5207.1 5207.8 5211.3 5222.6 4142.5 5211.4 5216.6 5237.0 5277.1 4990.0 5148.5 5156.1 5158.3 5180.2 5477.4 5511.0 5523.9 5533.8 5551.9 6047.5 6187.0 6188.7 6205.6 6219.3 6130.3 6176.3 6178.5 6245.8 6252.3 5766.7 5796.7 5809.4 5827.2 5850.9 6182.4 6193.3 6252.1 6252.3 6268.6 6064.3 6075.6 6088.3 6096.8 6103.2 6227.7 6231.9 6242.0 6244.2 6249.2 5060.5 5925.8 6077.1 6108.1 6131.8 6165.4 6173.3 6208.8 6215.9 6222.4 4832.3 4838.3 4838.7 4864.6 4880.1 4329.6 4362.4 4384.7 4394.1 4443.0 5556.9 5561.2 5565.4 5573.7 5603.6 6230.4 6234.9 6238.1 6242.3 6242.5 5449.4 5470.8 5527.0 5557.5 5560.3 4441.0 5261.8 5292.0 5317.1 5368.6 6212.1 6247.7 6258.5 6258.8 6273.0 6129.7 6146.4 6222.5 6228.2 6305.1 6262.6 6279.3 6280.7 6281.7 6296.0 6074.6 6092.1 6092.4 6104.2 6111.3 6000.5 6069.9 6162.2 6252.7 6263.3 4983.5 5992.1 6027.2 6032.3 6049.5 5842.7 5990.9 5990.9 6010.9 6021.8 5770.7 5851.8 5862.9 5870.7 5897.8 5979.2 5989.9 5992.0 6048.2 6065.4 5793.3 5803.2 5846.6 5858.4 5890.3 03:49:24 INFO - PROCESS | 1668 | 03:49:24 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:62163.88 Median:40489.09 stddev:120986.75 (298.8%) stddev-sans-first:121237.49 03:49:24 INFO - PROCESS | 1668 | Values: 476.0 531.8 579.6 657.4 667.2 32279.2 32783.8 32889.2 39949.4 40620.2 43502.8 43663.6 43991.1 43997.9 44029.8 9665.5 9898.9 9902.0 9910.6 9912.5 9673.6 9821.6 9828.3 9834.8 9837.4 9747.9 9834.6 9842.1 9994.7 10018.8 9916.8 9990.6 9992.4 9995.8 10008.8 9792.4 9946.8 9958.0 9961.9 9965.8 40992.6 41229.8 41383.6 41400.0 41513.0 3240.7 3423.6 3522.3 3734.8 3780.7 29395.6 29827.9 29934.1 30208.7 30335.1 2330.3 2569.7 2573.9 2597.5 2633.0 471447.0 471599.6 472716.0 473290.0 476007.0 69120.0 69588.0 69597.0 69727.0 69860.0 73958.6 74178.6 78631.6 79725.2 79839.2 18169.5 18259.1 18391.5 18434.6 18468.8 13950.3 14038.2 14039.7 14045.0 14055.7 18267.5 22632.5 22984.0 23030.8 23189.5 7624.8 8910.8 9193.3 9206.9 9279.4 911.0 998.2 1055.7 1121.8 1126.8 30468.5 30585.0 30638.2 30746.5 31000.5 1153.9 1155.7 1156.9 1164.8 1265.4 11531.9 14848.3 16152.0 16198.2 16645.9 513505.0 520671.0 520768.0 521952.0 524318.0 62651.7 63139.0 63152.7 63190.7 63220.4 1487.5 1506.3 1542.1 1547.1 1608.6 28938.6 30790.4 30892.0 30897.5 31123.6 41205.6 50576.0 50650.2 50995.2 51118.5 359692.0 361858.0 361925.0 362630.0 363050.2 30309.8 49148.0 49276.3 49614.5 49870.8 47891.0 47972.8 48128.0 48380.8 49950.8 37994.2 38955.2 39069.6 39472.0 40403.0 37757.4 37822.2 38678.0 38702.0 39189.6 40238.6 40575.2 40970.6 41931.8 41956.0 30977.7 33252.0 34621.3 35310.0 35884.3 26665.5 26889.5 26937.3 26944.7 27275.3 03:49:24 INFO - PROCESS | 1668 | 03:49:24 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35574.72 Median:2569.97 stddev:105482.21 (4104.4%) stddev-sans-first:105745.03 03:49:24 INFO - PROCESS | 1668 | Values: 387.9 388.9 391.0 399.0 399.5 17726.9 20304.4 21343.0 21591.8 21892.7 12108.0 12474.1 20879.0 21700.0 21733.2 5231.1 5365.6 5409.5 5416.4 5424.0 4202.1 4384.8 5940.4 6189.9 6192.4 6105.1 6185.0 6189.6 6195.2 6203.4 5979.5 6106.0 6145.9 6153.0 6169.8 4035.7 5662.7 5894.6 5900.6 5908.3 13694.2 13866.2 13940.6 14090.6 14101.1 2227.1 2303.1 2418.3 2721.6 2811.0 9426.8 10218.0 10388.9 10442.8 10565.7 1692.0 1748.4 1754.4 1816.7 1819.9 106426.9 108720.5 110007.4 111506.0 113104.0 11541.8 11576.2 11615.7 11631.1 11702.1 14612.9 14667.5 14676.9 14695.7 14736.7 8743.8 10807.6 11162.8 11649.7 11701.9 8059.6 9460.5 9513.2 9550.8 9884.8 7283.6 8879.9 8982.1 9047.5 9048.6 3050.1 3472.7 3473.4 3526.0 3590.4 747.7 931.9 963.6 975.1 1078.2 15228.9 19875.2 20561.4 20610.2 20679.0 947.7 955.2 982.9 983.1 1037.2 11089.7 11423.3 11466.3 11470.9 11471.3 615055.0 629230.0 638990.0 648623.0 649378.0 115630.3 117895.4 117953.4 118898.0 119492.0 979.6 988.4 997.2 998.3 1019.7 18581.5 18623.9 18910.6 18988.8 19220.3 52670.5 54957.5 55041.6 55596.7 56974.0 78246.0 78504.0 79323.0 79848.6 80055.6 8245.2 8326.9 8359.2 8541.1 8685.6 9620.9 12724.9 13321.9 13637.3 13726.7 11360.8 11608.5 11799.5 11837.0 11955.3 11350.5 11636.2 11708.1 11878.4 11912.9 11757.6 11829.2 11890.9 11996.4 12149.0 8319.0 8402.5 8459.0 8503.7 8549.4 8842.7 9859.9 11412.7 11808.1 11815.9 03:49:24 INFO - PROCESS | 1668 | 03:49:24 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:6222.38 Median:4924.62 stddev:3270.02 (66.4%) stddev-sans-first:3259.62 03:49:24 INFO - PROCESS | 1668 | Values: 1463.3 1669.4 1680.6 2003.9 2068.6 7505.9 8714.8 8860.7 8871.1 8878.0 7269.4 8996.5 9002.1 9007.4 9020.3 1930.2 1949.1 2488.0 2534.2 2567.6 2528.7 2540.9 2541.9 2543.5 2546.4 2461.3 2563.5 2565.9 2567.4 2573.2 2512.1 2543.6 2566.0 2568.4 2571.4 2084.6 2128.0 2454.6 2570.6 2574.1 8908.6 8917.3 8984.9 8996.0 9024.1 5469.1 5691.1 5696.0 5740.8 5786.2 3562.6 3620.1 3671.0 3702.6 3711.4 1050.1 1053.1 1059.7 1065.7 1109.5 12195.9 12214.6 12219.3 12227.3 12231.8 4931.8 4964.1 5046.7 5049.0 5075.0 10253.9 10263.4 10265.0 10267.6 10280.4 5679.9 5685.9 5693.7 5709.1 5713.9 4543.1 4717.6 4725.5 4732.6 4917.5 4045.2 4094.4 4133.1 4149.8 4151.3 1356.4 1357.6 1359.2 1363.8 1372.5 1367.8 1385.8 1424.7 1426.3 1434.6 8313.0 8550.5 8571.9 8575.5 8586.7 1202.9 1245.6 1255.8 1276.7 1285.5 5856.9 6289.2 6399.9 6442.5 6443.7 10706.3 11156.7 11256.3 11258.5 11270.9 10709.2 11015.7 11086.1 11112.0 11118.8 8528.1 8534.4 8552.7 8566.3 8659.3 7938.4 8054.9 8064.1 8067.8 8089.9 9574.8 9801.8 9873.2 9979.0 10003.8 10040.0 10234.3 10311.4 10395.5 10452.8 7162.0 7194.6 7200.0 7237.2 7257.8 8400.7 8427.7 8471.4 8485.8 8490.7 7493.9 7590.3 7673.1 7750.6 7778.7 7459.9 7494.6 7553.2 7703.5 7728.8 7957.2 8020.0 8033.8 8037.7 8050.5 7111.4 7216.6 7286.2 7308.6 7331.5 7370.0 7384.9 7438.7 7440.4 7444.0 03:49:24 INFO - PROCESS | 1668 | 03:49:24 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5565.90 Median:6300.14 stddev:1745.52 (27.7%) stddev-sans-first:1705.69 03:49:24 INFO - PROCESS | 1668 | Values: 334.9 337.6 342.7 345.0 348.9 6324.8 6343.3 6354.8 6383.9 6450.7 5012.9 6334.3 6348.4 6362.7 6364.9 3616.6 4317.5 4644.4 4722.5 4778.1 4751.0 4778.3 4797.5 4800.8 4832.2 4776.4 4777.9 4778.4 4792.8 4793.6 3626.5 4566.2 4660.2 4663.6 4788.6 4051.1 4063.9 4067.3 4083.7 4085.1 6026.8 6054.0 6056.9 6110.5 6124.0 5495.3 5508.0 5527.2 5540.4 5565.0 6663.5 6666.6 6701.8 6710.1 6723.0 4433.4 4589.7 4596.5 4637.7 4645.4 5715.9 6730.4 6971.6 7022.9 7035.6 4663.6 4700.3 4704.8 4706.1 4715.3 4781.4 4798.0 4802.0 4808.0 4809.9 5544.8 5544.9 5563.7 5569.9 5571.3 6322.7 6349.9 6703.2 6733.1 6762.0 1647.7 1648.3 1661.8 1670.6 1672.0 1027.9 1086.6 1091.4 1105.5 1107.8 5244.3 6092.2 6277.6 6455.5 6474.3 7136.8 7137.0 7142.6 7152.7 7167.0 6413.4 6437.5 6447.4 6455.0 6456.6 3761.2 3766.5 3766.8 3771.5 3793.0 7205.5 7442.4 7481.9 7485.3 7525.6 6197.4 7315.0 7339.8 7357.3 7361.8 6881.4 7431.4 7434.1 7456.5 7466.2 5797.2 7091.1 7118.1 7141.1 7143.7 6738.4 7008.3 7019.9 7020.6 7032.8 5141.6 7359.6 7403.6 7407.6 7416.9 4752.8 4766.6 4778.8 4785.2 4794.0 7125.7 7138.4 7139.6 7140.1 7142.8 6272.7 6497.8 6615.0 6814.4 6843.3 6776.6 6803.8 6828.7 6838.2 6839.1 6683.5 6686.1 6688.0 6695.4 6783.9 6840.6 6840.8 6841.2 6841.4 6847.9 4678.5 6165.8 6478.4 6497.8 6696.7 03:49:24 INFO - PROCESS | 1668 | 03:49:24 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:18008.58 Median:28491.76 stddev:25083.61 (88.0%) stddev-sans-first:25120.42 03:49:24 INFO - PROCESS | 1668 | Values: 728.1 761.9 780.2 782.5 814.4 14881.2 15085.1 15218.5 15233.9 15238.3 14657.0 14866.7 14884.6 14896.9 14903.0 805.8 823.1 931.4 961.1 972.4 982.8 982.8 984.7 985.1 986.5 1045.9 1046.2 1047.8 1048.5 1050.9 846.0 1011.3 1037.4 1040.4 1043.8 775.1 775.8 776.4 776.8 777.1 15370.8 15439.1 15450.5 15450.9 15473.8 13096.3 13172.4 13215.0 13277.9 13437.9 9963.9 10171.3 10323.2 10424.4 10474.1 3542.2 3619.3 3619.4 3636.0 3661.5 76478.6 79227.2 79687.4 81471.0 81735.0 9783.7 9871.3 9907.1 10079.1 10126.2 23169.4 23172.0 23172.3 23173.1 23187.4 7500.7 7524.8 7602.6 7620.9 7796.8 4102.2 4177.6 4264.2 4296.5 4461.5 3747.1 3785.1 3788.9 3791.7 3807.2 585.4 644.1 665.2 673.5 688.4 557.3 571.8 574.6 578.3 594.5 7989.2 8154.5 8290.3 8291.9 8380.5 602.0 605.3 614.9 615.0 620.0 3859.9 3910.5 3919.2 4020.9 4047.1 83659.7 87851.0 89815.2 89852.0 90054.0 75557.9 75826.0 75859.2 76566.0 77182.0 67714.7 69245.8 69640.6 69729.3 70764.6 7821.2 7883.4 7924.9 7958.0 7969.0 45136.5 49831.0 50372.5 51168.0 51588.0 56411.7 66715.0 68515.0 68843.7 69535.3 10254.9 10531.7 10547.0 10559.0 10635.7 10709.9 10919.7 10989.3 11142.7 11629.6 10248.0 10280.6 10364.7 10380.3 10591.2 10491.8 10494.8 10553.8 10687.5 10773.6 9990.2 10054.6 10243.6 10404.0 10697.9 8793.8 8928.2 8939.2 9075.7 9156.2 7247.9 8826.3 8842.0 8867.2 9071.3 03:49:24 INFO - PROCESS | 1668 | -------- Summary: end -------- 03:49:24 INFO - PROCESS | 1668 | 03:49:25 INFO - TEST-INFO | 1668: exit 0 03:49:25 INFO - TEST-OK | dromaeo_css | took 1111968ms 03:49:25 INFO - TEST-START | kraken 03:49:25 INFO - operating with platform_type : mac_ 03:49:25 INFO - Initialising browser for kraken test... 03:49:25 INFO - TEST-INFO | started process 1697 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpgah7JA/profile http://localhost:49275/getInfo.html) 03:49:27 INFO - PROCESS | 1697 | __metrics Screen width/height:1600/1200 03:49:27 INFO - PROCESS | 1697 | colorDepth:24 03:49:27 INFO - PROCESS | 1697 | Browser inner width/height: 1024/689 03:49:27 INFO - PROCESS | 1697 | __metrics 03:49:27 INFO - PROCESS | 1697 | 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" 03:49:27 INFO - PROCESS | 1697 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:49:28 INFO - TEST-INFO | 1697: exit 0 03:49:28 INFO - Browser initialized. 03:49:28 INFO - Running cycle 1/1 for kraken test... 03:49:28 INFO - TEST-INFO | started process 1699 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpgah7JA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:49:40 INFO - PROCESS | 1699 | RSS: Main: 207998976 03:49:40 INFO - PROCESS | 1699 | 03:49:42 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 03:49:43 INFO - PROCESS | 1699 | RSS: Main: 263098368 03:49:43 INFO - PROCESS | 1699 | 03:49:45 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 03:49:46 INFO - PROCESS | 1699 | RSS: Main: 273367040 03:49:46 INFO - PROCESS | 1699 | 03:49:49 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 03:49:50 INFO - PROCESS | 1699 | RSS: Main: 263991296 03:49:50 INFO - PROCESS | 1699 | 03:49:52 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 03:49:52 INFO - PROCESS | 1699 | RSS: Main: 266047488 03:49:52 INFO - PROCESS | 1699 | 03:49:54 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:49:55 INFO - PROCESS | 1699 | RSS: Main: 265932800 03:49:55 INFO - PROCESS | 1699 | 03:49:58 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 03:49:58 INFO - PROCESS | 1699 | RSS: Main: 266362880 03:49:58 INFO - PROCESS | 1699 | 03:50:01 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 03:50:02 INFO - PROCESS | 1699 | RSS: Main: 265891840 03:50:02 INFO - PROCESS | 1699 | 03:50:05 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 03:50:05 INFO - PROCESS | 1699 | RSS: Main: 266682368 03:50:05 INFO - PROCESS | 1699 | 03:50:07 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:50:07 INFO - PROCESS | 1699 | RSS: Main: 299941888 03:50:07 INFO - PROCESS | 1699 | 03:50:09 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:50:10 INFO - PROCESS | 1699 | RSS: Main: 300294144 03:50:10 INFO - PROCESS | 1699 | 03:50:12 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:50:12 INFO - PROCESS | 1699 | RSS: Main: 305553408 03:50:12 INFO - PROCESS | 1699 | 03:50:15 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:50:16 INFO - PROCESS | 1699 | RSS: Main: 307650560 03:50:16 INFO - PROCESS | 1699 | 03:50:18 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:50:19 INFO - PROCESS | 1699 | RSS: Main: 307634176 03:50:19 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 03:50:20 INFO - PROCESS | 1699 | RSS: Main: 370868224 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | __start_tp_report 03:50:20 INFO - PROCESS | 1699 | _x_x_mozilla_page_load 03:50:20 INFO - PROCESS | 1699 | _x_x_mozilla_page_load_details 03:50:20 INFO - PROCESS | 1699 | |i|pagename|runs| 03:50:20 INFO - PROCESS | 1699 | |0;ai-astar;112;110;111;111;113;112;119;113;111;111 03:50:20 INFO - PROCESS | 1699 | |1;audio-beat-detection;149;119;152;147;118;132;115;147;148;116 03:50:20 INFO - PROCESS | 1699 | |2;audio-dft;188;188;185;185;189;187;183;185;186;184 03:50:20 INFO - PROCESS | 1699 | |3;audio-fft;73;81;78;117;55;66;66;84;155;55 03:50:20 INFO - PROCESS | 1699 | |4;audio-oscillator;90;93;131;80;78;85;118;77;80;115 03:50:20 INFO - PROCESS | 1699 | |5;imaging-gaussian-blur;89;85;101;129;123;84;88;85;90;84 03:50:20 INFO - PROCESS | 1699 | |6;imaging-darkroom;130;97;139;148;112;94;93;94;92;93 03:50:20 INFO - PROCESS | 1699 | |7;imaging-desaturate;87;85;87;85;85;84;86;86;86;87 03:50:20 INFO - PROCESS | 1699 | |8;json-parse-financial;54;58;57;56;57;56;57;68;51;52 03:50:20 INFO - PROCESS | 1699 | |9;json-stringify-tinderbox;62;62;71;78;70;69;66;68;65;76 03:50:20 INFO - PROCESS | 1699 | |10;stanford-crypto-aes;80;81;81;80;81;79;79;80;81;85 03:50:20 INFO - PROCESS | 1699 | |11;stanford-crypto-ccm;117;129;130;131;131;127;116;117;117;119 03:50:20 INFO - PROCESS | 1699 | |12;stanford-crypto-pbkdf2;134;124;135;133;128;123;133;133;134;133 03:50:20 INFO - PROCESS | 1699 | |13;stanford-crypto-sha256-iterative;66;66;65;66;66;66;64;67;66;66 03:50:20 INFO - PROCESS | 1699 | __end_tp_report 03:50:20 INFO - PROCESS | 1699 | __start_cc_report 03:50:20 INFO - PROCESS | 1699 | _x_x_mozilla_cycle_collect,1217 03:50:20 INFO - PROCESS | 1699 | __end_cc_report 03:50:20 INFO - PROCESS | 1699 | __startTimestamp1461581420936__endTimestamp 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | ------- Summary: start ------- 03:50:20 INFO - PROCESS | 1699 | Number of tests: 14 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.00 stddev:2.54 (2.3%) stddev-sans-first:2.69 03:50:20 INFO - PROCESS | 1699 | Values: 112.0 110.0 111.0 111.0 113.0 112.0 119.0 113.0 111.0 111.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:147.00 stddev:15.82 (10.8%) stddev-sans-first:15.86 03:50:20 INFO - PROCESS | 1699 | Values: 149.0 119.0 152.0 147.0 118.0 132.0 115.0 147.0 148.0 116.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#2] audio-dft Cycles:10 Average:186.00 Median:186.50 stddev:1.94 (1.0%) stddev-sans-first:1.92 03:50:20 INFO - PROCESS | 1699 | Values: 188.0 188.0 185.0 185.0 189.0 187.0 183.0 185.0 186.0 184.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#3] audio-fft Cycles:10 Average:83.00 Median:69.50 stddev:30.94 (44.5%) stddev-sans-first:32.61 03:50:20 INFO - PROCESS | 1699 | Values: 73.0 81.0 78.0 117.0 55.0 66.0 66.0 84.0 155.0 55.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:80.00 stddev:19.48 (24.4%) stddev-sans-first:20.59 03:50:20 INFO - PROCESS | 1699 | Values: 90.0 93.0 131.0 80.0 78.0 85.0 118.0 77.0 80.0 115.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:85.00 stddev:16.74 (19.7%) stddev-sans-first:17.57 03:50:20 INFO - PROCESS | 1699 | Values: 89.0 85.0 101.0 129.0 123.0 84.0 88.0 85.0 90.0 84.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#6] imaging-darkroom Cycles:10 Average:109.20 Median:93.00 stddev:21.77 (23.4%) stddev-sans-first:21.75 03:50:20 INFO - PROCESS | 1699 | Values: 130.0 97.0 139.0 148.0 112.0 94.0 93.0 94.0 92.0 93.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.00 stddev:1.03 (1.2%) stddev-sans-first:1.00 03:50:20 INFO - PROCESS | 1699 | Values: 87.0 85.0 87.0 85.0 85.0 84.0 86.0 86.0 86.0 87.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:4.62 (8.1%) stddev-sans-first:4.81 03:50:20 INFO - PROCESS | 1699 | Values: 54.0 58.0 57.0 56.0 57.0 56.0 57.0 68.0 51.0 52.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.50 stddev:5.36 (7.7%) stddev-sans-first:5.10 03:50:20 INFO - PROCESS | 1699 | Values: 62.0 62.0 71.0 78.0 70.0 69.0 66.0 68.0 65.0 76.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:81.00 stddev:1.70 (2.1%) stddev-sans-first:1.79 03:50:20 INFO - PROCESS | 1699 | Values: 80.0 81.0 81.0 80.0 81.0 79.0 79.0 80.0 81.0 85.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#11] stanford-crypto-ccm Cycles:10 Average:123.40 Median:128.00 stddev:6.67 (5.2%) stddev-sans-first:6.66 03:50:20 INFO - PROCESS | 1699 | Values: 117.0 129.0 130.0 131.0 131.0 127.0 116.0 117.0 117.0 119.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.00 Median:133.00 stddev:4.37 (3.3%) stddev-sans-first:4.50 03:50:20 INFO - PROCESS | 1699 | Values: 134.0 124.0 135.0 133.0 128.0 123.0 133.0 133.0 134.0 133.0 03:50:20 INFO - PROCESS | 1699 | 03:50:20 INFO - PROCESS | 1699 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 03:50:20 INFO - PROCESS | 1699 | Values: 66.0 66.0 65.0 66.0 66.0 66.0 64.0 67.0 66.0 66.0 03:50:20 INFO - PROCESS | 1699 | -------- Summary: end -------- 03:50:20 INFO - PROCESS | 1699 | 03:50:21 INFO - TEST-INFO | 1699: exit 0 03:50:21 INFO - TEST-OK | kraken | took 56518ms 03:50:21 INFO - SUITE-END | took 1168s 03:50:22 INFO - Completed test suite (00:19:28) 03:50:22 INFO - javascript benchmark 03:50:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3398.779079359842, 3434.6908778209954, 3451.516787649728, 3455.2743923776006, 3471.44456886898, 5595.468430499096, 5597.440255974406, 5600.663960162393, 5603.635763675358, 5622.072358060926, 5595.916061259084, 5615.073512870382, 5623.26897503324, 5632.831015069544, 5634.267545219126, 5068.46781087986, 5073.416557095926, 5079.517445842639, 5107.897842043176, 5120.15517439622, 5201.063808514485, 5215.504527069941, 5216.895662086756, 5222.00781851447, 5223.164293713006, 5158.561522270593, 5207.062728708831, 5207.765650545715, 5211.322528071345, 5222.608304377168, 4142.544320124784, 5211.42674305826, 5216.556592689626, 5237.0, 5277.076511610453, 4989.950100499022, 5148.536631703165, 5156.123459011939, 5158.303629010074, 5180.197069454241, 5477.370102438231, 5510.972445137789, 5523.889522209573, 5533.806316778893, 5551.8889622207735, 6047.516198704093, 6187.0, 6188.721507532172, 6205.596636218631, 6219.2847822500535, 6130.264368275791, 6176.258848938156, 6178.505719542471, 6245.812625621239, 6252.31224565302, 5766.740496677618, 5796.710164491758, 5809.419058094157, 5827.24245848037, 5850.882982340329, 6182.381761823782, 6193.287771906288, 6252.124702541648, 6252.3122456529745, 6268.592541484789, 6064.302605200459, 6075.605085669506, 6088.331526657591, 6096.817095487142, 6103.206583144251, 6227.657478838707, 6231.875362492816, 6242.0, 6244.188255526752, 6249.218847644044, 5060.49395060491, 5925.792597259161, 6077.088436734525, 6108.11482540077, 6131.754729810757, 6165.352637973038, 6173.32093469718, 6208.844778880564, 6215.875682486325, 6222.408871157233, 4832.275158726149, 4838.322634831126, 4838.7338696372035, 4864.635152363516, 4880.145974454456, 4329.631981281564, 4362.432883725159, 4384.69307148503, 4394.077243778778, 4442.955570444319, 5556.861078473071, 5561.221428999943, 5565.443455654402, 5573.693446860461, 5603.579731520153, 6230.376962303733, 6234.906476402857, 6238.064290356392, 6242.344553821874, 6242.500599951993, 5449.4005659377435, 5470.835874923759, 5527.0, 5557.499825015768, 5560.304961879765, 4440.955590444054, 5261.815836445706, 5291.973540132275, 5317.096093664087, 5368.597355198376, 6212.099245609452, 6247.718852651642, 6258.499320054385, 6258.780942667076, 6272.968635156886, 6129.725578588854, 6146.354632763674, 6222.533310001859, 6228.22147231596, 6305.148804911279, 6262.624242545279, 6279.277883043508, 6280.685965701788, 6281.748730050746, 6296.0, 6074.635521868702, 6092.116643086639, 6092.390760923872, 6104.20645316106, 6111.327753947151, 6000.457898247067, 6069.878602428015, 6162.168107305456, 6252.656103914146, 6263.342349053466, 4983.451820299837, 5992.101184822312, 6027.156198132177, 6032.276126864805, 6049.516038716804, 5842.707864606838, 5990.8502287441415, 5990.880182396415, 6010.87978240424, 6021.789237376671, 5770.653760774367, 5851.795187168429, 5862.853428664317, 5870.677112758839, 5897.764089436546, 5979.162917191509, 5989.850253743692, 5991.970040149771, 6048.153258543719, 6065.363136870742, 5793.304803423616, 5803.187553742564, 5846.590738648254, 5858.355580886185, 5890.3226128994], "unit": "score", "name": "dojo.html", "value": 5620.554992880465}, {"lowerIsBetter": false, "replicates": [476.019400035926, 531.8085489223877, 579.5566391710342, 657.3722095398895, 667.176037593572, 32279.19302017451, 32783.836080819594, 32889.17777055575, 39949.400758988566, 40620.187596248055, 43502.782486087606, 43663.563364366346, 43991.12017759643, 43997.900052498706, 44029.77985110078, 9665.5167241638, 9898.851517227247, 9901.950490247538, 9910.603575856958, 9912.504374781269, 9673.613055477772, 9821.6071357146, 9828.312018158731, 9834.803303933953, 9837.360571562862, 9747.902520974805, 9834.55744491496, 9842.06500382466, 9994.650187243447, 10018.799624007515, 9916.752081197992, 9990.600375984952, 9992.400455972665, 9995.80008399828, 10008.799824003552, 9792.363496372713, 9946.801063978752, 9958.0, 9961.850572241423, 9965.75085622858, 40992.59007409948, 41229.79385103085, 41383.58616413827, 41400.0, 41512.96217594554, 3240.7245384142375, 3423.640517745651, 3522.3483655523555, 3734.7572407793677, 3780.697544196485, 29395.55906661402, 29827.85086074556, 29934.101976940725, 30208.69791302081, 30335.089947301618, 2330.3242059802637, 2569.730178331267, 2573.935651608706, 2597.5324441600505, 2632.9994602051097, 471447.0, 471599.6420017912, 472716.0, 473290.0, 476007.0, 69120.0, 69587.95618065733, 69597.0, 69726.9540956886, 69860.0, 73958.63020684863, 74178.62910685412, 78631.60684196542, 79725.20274797294, 79839.20160798318, 18169.545761356072, 18259.087045647666, 18391.540211494554, 18434.631307373777, 18468.81531184671, 13950.302484875716, 14038.227897465737, 14039.719205615831, 14045.01684882069, 14055.718885622231, 18267.543311417056, 22632.547349052926, 22984.0, 23030.769692303198, 23189.53620927572, 7624.80937976555, 8910.821783564292, 9193.310501712398, 9206.907930920604, 9279.376109180866, 910.9772255693529, 998.2114129837347, 1055.6991257491577, 1121.770037142384, 1126.7633796902578, 30468.54297185544, 30585.0, 30638.234044149074, 30746.538801917988, 31000.53499197514, 1153.8846115388394, 1155.664857191405, 1156.9363684997406, 1164.7612239490884, 1265.3926115464628, 11531.88468115325, 14848.2575871206, 16152.030878147349, 16198.190090495427, 16645.916770416312, 513505.0, 520671.0, 520768.0, 521952.0, 524318.0, 62651.68674156691, 63139.0, 63152.684236579444, 63190.68404658039, 63220.36779632237, 1487.5016869348674, 1506.3070987345943, 1542.0747551469028, 1547.110411513407, 1608.630015096511, 28938.565921511614, 30790.38419231558, 30892.0, 30897.53653695241, 31123.576406594802, 41205.58794412138, 50575.988480230924, 50650.240246395595, 50995.2350714747, 51118.48881511137, 359692.0, 361858.0, 361925.0, 362630.0, 363050.1847490746, 30309.848450757607, 49148.0, 49276.26085608647, 49614.50385496099, 49870.75064624654, 47891.0, 47972.7601361991, 48128.0, 48380.7580962107, 49950.750246248535, 37994.24011519699, 38955.2208955825, 39069.609303906596, 39472.0, 40402.989925252106, 37757.4336384949, 37822.243555128196, 38678.033049173995, 38702.0, 39189.608103918596, 40238.597614023485, 40575.1884962305, 40970.59029409668, 41931.7903410481, 41956.0, 30977.69022309748, 33252.0, 34621.307573848884, 35310.0, 35884.28231435305, 26665.46669066569, 26889.462210756068, 26937.326566835985, 26944.730552695008, 27275.31811704723], "unit": "score", "name": "ext.html", "value": 20704.870379199358}, {"lowerIsBetter": false, "replicates": [387.93405121129405, 388.9222155568887, 391.0224438902743, 399.04627939225236, 399.51059951559364, 17726.911365443186, 20304.39086827394, 21343.0, 21591.784082159174, 21892.671609925823, 12108.0, 12474.064445166603, 20879.0, 21700.0, 21733.23933662322, 5231.11071117909, 5365.570754339663, 5409.486098820606, 5416.404195538487, 5424.0, 4202.117555313373, 4384.8026838792175, 5940.435658612425, 6189.938100619003, 6192.380761923817, 6105.114758360035, 6185.010398336267, 6189.566730328879, 6195.1946246988055, 6203.410675985774, 5979.491743201833, 6106.0, 6145.907811382833, 6152.969235153818, 6169.81490555284, 4035.656969157625, 5662.688552129631, 5894.616849904743, 5900.55745819061, 5908.261467316585, 13694.246816425093, 13866.168029918237, 13940.58178254654, 14090.647733806634, 14101.083429577046, 2227.0759778987976, 2303.1363238785457, 2418.3349578865777, 2721.6053672217618, 2810.9718902810914, 9426.811463770686, 10218.0, 10388.896111038868, 10442.79114417715, 10565.68302950905, 1691.9577010574835, 1748.4230204032638, 1754.3859649122833, 1816.6639171753136, 1819.9454016379398, 106426.93573064325, 108720.4563977183, 110007.44996275127, 111506.0, 113104.0, 11541.826872596917, 11576.24754390962, 11615.6515304541, 11631.127665425127, 11702.063834893188, 14612.926935365254, 14667.486637967833, 14676.853231467761, 14695.706085878222, 14736.705265894621, 8743.781405464913, 10807.567697292177, 11162.776744465065, 11649.708757280967, 11701.882981170249, 8059.556724380215, 9460.52697365129, 9513.238940884712, 9550.761230969281, 9884.752881177885, 7283.5994020328335, 8879.911200888038, 8982.146696063863, 9047.457152570867, 9048.592813323416, 3050.100220434977, 3472.652734726558, 3473.3747925373677, 3525.990185594238, 3590.3716849551224, 747.6523416611302, 931.9347645664874, 963.5663951221978, 975.1175186456269, 1078.1698092468755, 15228.923855380652, 19875.204991800165, 20561.38315850527, 20610.175592976113, 20678.966051697356, 947.7488465556632, 955.1833182628752, 982.9017098290113, 983.0759086458706, 1037.190991027, 11089.722756931142, 11423.314601123959, 11466.2546934449, 11470.942645286721, 11471.311721296748, 615055.0, 629230.0, 638990.0, 648623.0, 649378.0, 115630.32602122115, 117895.41052294856, 117953.41023294828, 118898.0, 119492.0, 979.583676937313, 988.367444835306, 997.2122023601273, 998.2513115163628, 1019.6635110413546, 18581.535461613566, 18623.88256704602, 18910.621787563898, 18988.810111898703, 19220.327288544835, 52670.47329526656, 54957.450425496834, 55041.62395939973, 55596.722016389656, 56974.0, 78246.0, 78504.0, 79323.0, 79848.60075699585, 80055.59972200102, 8245.216704413071, 8326.875096873673, 8359.205875441823, 8541.14588541141, 8685.565721714016, 9620.903790962, 12724.872751272369, 13321.933390332986, 13637.318134093453, 13726.725465490434, 11360.829587556029, 11608.535658573563, 11799.52801887915, 11837.0, 11955.342456164994, 11350.54597816078, 11636.185467017227, 11708.121890858049, 11878.406079695807, 11912.940435297767, 11757.588484403346, 11829.17195796286, 11890.881091189323, 11996.400179990791, 12149.0, 8319.0, 8402.537860417735, 8458.957705211435, 8503.744887653133, 8549.401541892257, 8842.690505832265, 9859.852102218616, 11412.657620271406, 11808.11439142085, 11815.881841181477], "unit": "score", "name": "jquery.html", "value": 9857.622807081176}, {"lowerIsBetter": false, "replicates": [1463.2976171437704, 1669.3990163541125, 1680.6470641165358, 2003.9098240579171, 2068.6069646767123, 7505.911642811796, 8714.782130446742, 8860.734177974664, 8871.112888711126, 8878.023417424094, 7269.382102521292, 8996.505192214425, 9002.099790021011, 9007.369484136114, 9020.278377729783, 1930.1507336771776, 1949.0547084663926, 2488.042103790036, 2534.1637259704253, 2567.627693984371, 2528.683914510686, 2540.9110681126162, 2541.885615147323, 2543.49130173965, 2546.414324705319, 2461.2862269941697, 2563.4873025394904, 2565.8845351959208, 2567.4351642638685, 2573.163721790426, 2512.133314006665, 2543.5930251159816, 2566.0, 2568.4477837265076, 2571.4085760275243, 2084.5726626041624, 2128.0, 2454.5581795276844, 2570.6015567587056, 2574.086199399205, 8908.554572271361, 8917.286617070684, 8984.910150898539, 8996.0, 9024.05247449015, 5469.124940009619, 5691.089425691911, 5696.031674615326, 5740.827775166752, 5786.189933409326, 3562.6437356264423, 3620.1130722972775, 3671.0, 3702.5927148013698, 3711.3690672585726, 1050.14937900301, 1053.1416895230363, 1059.6609085092773, 1065.664315740547, 1109.4619109731773, 12195.93902030493, 12214.633560993185, 12219.26684398939, 12227.266364018187, 12231.816522752166, 4931.778069986859, 4964.081644895706, 5046.7476626168545, 5048.990201959549, 5075.035743208852, 10253.94873025645, 10263.435511046964, 10265.0, 10267.640632577824, 10280.383177009404, 5679.858003549862, 5685.886282274331, 5693.68684722336, 5709.058005429058, 5713.857153571194, 4543.136804007228, 4717.622590192776, 4725.503822098699, 4732.574068333866, 4917.459079501252, 4045.150518391171, 4094.406311084876, 4133.090720041586, 4149.751014939114, 4151.315033019579, 1356.4438580182077, 1357.6198664373794, 1359.211657238799, 1363.7545241856362, 1372.5333386648388, 1367.8495365509787, 1385.7990591364196, 1424.69369085645, 1426.315368623046, 1434.583970648521, 8313.002439707152, 8550.486970781772, 8571.914280857112, 8575.528345940907, 8586.699465518677, 1202.9233835716984, 1245.64499117751, 1255.8241846141548, 1276.7063575377529, 1285.517930775959, 5856.882862342814, 6289.245290565162, 6399.9040014399825, 6442.549021568539, 6443.67781610927, 10706.250562460553, 11156.665300041012, 11256.268342557707, 11258.549658013751, 11270.887291127148, 10709.196810239264, 11015.724606884893, 11086.057685096857, 11112.0, 11118.833217501744, 8528.061913189544, 8534.445261058012, 8552.657893684307, 8566.27186689126, 8659.263962563253, 7938.404619653434, 8054.919450805417, 8064.112947575644, 8067.757967260992, 8089.919100809152, 9574.808503830023, 9801.77477815273, 9873.210143188671, 9979.0, 10003.799924001334, 10040.0, 10234.283600147919, 10311.381317120997, 10395.480225988822, 10452.790944181226, 7161.9641901790155, 7194.640267986684, 7200.0, 7237.167725711611, 7257.818554535967, 8400.705975290835, 8427.705030323908, 8471.364647651328, 8485.78785530342, 8490.660373584988, 7493.887591686238, 7590.316871481593, 7673.117591477052, 7750.612469376621, 7778.6888524460655, 7459.850802984019, 7494.587797671181, 7553.169151393233, 7703.537787732754, 7728.806779830549, 7957.244061814118, 8020.0, 8033.758987230392, 8037.67849286022, 8050.476719013245, 7111.43108551325, 7216.603086830065, 7286.234945330664, 7308.5614863108385, 7331.486795924234, 7370.041894553674, 7384.889226661497, 7438.665260063419, 7440.3675687567165, 7443.995060666741], "unit": "score", "name": "mootools.html", "value": 5089.132355310041}, {"lowerIsBetter": false, "replicates": [334.909574414908, 337.5898283585445, 342.72239486016343, 345.02702379290406, 348.87614896711665, 6324.810255692325, 6343.270523889754, 6354.7775827846035, 6383.872322553546, 6450.709718062687, 5012.949870501294, 6334.3348948360335, 6348.428641422271, 6362.7454901803985, 6364.9363506364925, 3616.566012078554, 4317.503487098977, 4644.373009643706, 4722.480527142012, 4778.116048531016, 4751.0, 4778.307145463906, 4797.544233297831, 4800.759962001904, 4832.226843705008, 4776.379070720819, 4777.928331075037, 4778.402699662543, 4792.808287668507, 4793.616510679138, 3626.474161246631, 4566.1552612766745, 4660.207764679979, 4663.554298167545, 4788.592969597571, 4051.0682543014977, 4063.8984025399304, 4067.3288907330257, 4083.673306135502, 4085.1216988347646, 6026.819195424145, 6054.0, 6056.878862422771, 6110.480609148228, 6124.0, 5495.2856128703415, 5507.972460137674, 5527.198556209327, 5540.390557038722, 5565.026120428949, 6663.533552651316, 6666.600003999727, 6701.832454188636, 6710.127683401175, 6722.966385168092, 4433.357163211316, 4589.724616523019, 4596.54034596541, 4637.721736695809, 4645.419322584677, 5715.914261286084, 6730.394264516168, 6971.6165610891385, 7022.929770702279, 7035.613041282727, 4663.580277775017, 4700.318453824177, 4704.788284527204, 4706.129366067288, 4715.269133284346, 4781.378420805273, 4798.0, 4801.975990120027, 4807.95192048082, 4809.9038019240115, 5544.7782088716, 5544.86137846549, 5563.749631266603, 5569.888602227933, 5571.303587051619, 6322.683865806691, 6349.904751428732, 6703.22912865017, 6733.057371967929, 6761.966190169018, 1647.6539926615305, 1648.291234769037, 1661.7507373894014, 1670.5656529302469, 1672.0469332480614, 1027.917766578672, 1086.603389762731, 1091.4324551233474, 1105.5412004018403, 1107.839363292318, 5244.31823862903, 6092.238470191226, 6277.591956522811, 6455.4835613150835, 6474.320196379313, 7136.821579460555, 7137.0, 7142.642867856586, 7152.678129484186, 7166.964165179141, 6413.390727880844, 6437.517186211053, 6447.3552644735155, 6454.9677251614385, 6456.580322279036, 3761.191343861083, 3766.548014238309, 3766.8116594170183, 3771.453139294787, 3793.013816407754, 7205.495615306983, 7442.441816863757, 7481.85036299271, 7485.288897554724, 7525.58609276495, 6197.442230199304, 7314.96342518284, 7339.816504587428, 7357.337839594462, 7361.815954601071, 6881.449484041264, 7431.4054875608745, 7434.070741157356, 7456.51532650376, 7466.178720340758, 5797.217375654402, 7091.11361079865, 7118.110236220473, 7141.071660684078, 7143.749968751069, 6738.35985581369, 7008.299170083154, 7019.859602808017, 7020.6489675514995, 7032.824179395556, 5141.61437892167, 7359.632018399166, 7403.555786652818, 7407.592582407804, 7416.8887466686965, 4752.7623618818225, 4766.571008609103, 4778.808847646055, 4785.234362501983, 4793.95206047949, 7125.67934442941, 7138.428925686037, 7139.607321597362, 7140.107486564179, 7142.750003749844, 6272.71409361089, 6497.772577959749, 6615.0, 6814.420774234246, 6843.315668433117, 6776.559523630948, 6803.829904252433, 6828.692708828015, 6838.179418469816, 6839.1451068616425, 6683.498737594603, 6686.0973768540625, 6688.0, 6695.397414232742, 6783.898241526283, 6840.589564626139, 6840.8289792753585, 6841.17905851301, 6841.3842754152365, 6847.863042739018, 4678.532146785295, 6165.84585385369, 6478.4169424752, 6497.805065848032, 6696.698648560729], "unit": "score", "name": "prototype.html", "value": 5000.897966080817}, {"lowerIsBetter": false, "replicates": [728.1262485017978, 761.8819083042127, 780.1535334162434, 782.4561929459028, 814.3566582399905, 14881.18153501557, 15085.094894306321, 15218.467353642625, 15233.92383038086, 15238.314275857585, 14657.0, 14866.702665946674, 14884.627884302898, 14896.851031489708, 14903.0, 805.794522396788, 823.0617096509983, 931.3945935142178, 961.0725649747992, 972.4359871274644, 982.7985263021063, 982.8181786369508, 984.6849008317341, 985.0986347492036, 986.4820968991265, 1045.8588090607784, 1046.2310202001522, 1047.7956798424311, 1048.5124417146026, 1050.9316894401877, 845.974620761375, 1011.2971484818055, 1037.3672060043405, 1040.432964034599, 1043.8173319669104, 775.0931410250021, 775.7595145504912, 776.3827756933251, 776.8368642585008, 777.0713996773858, 15370.846291537053, 15439.073655580702, 15450.459233926871, 15450.922745386313, 15473.767893481607, 13096.34518274092, 13172.407241674147, 13215.0, 13277.86722132776, 13437.865621343759, 9963.850542241873, 10171.288009839314, 10323.225758068174, 10424.374537527772, 10474.109700675375, 3542.167590616229, 3619.27614477103, 3619.4208926571623, 3636.0, 3661.5240018797654, 76478.6176069116, 79227.20772792256, 79687.40625187464, 81471.0, 81735.0, 9783.657571984946, 9871.309008369346, 9907.058829411195, 10079.143272821746, 10126.240531960133, 23169.420764481023, 23171.957261923253, 23172.3048308551, 23173.07307707673, 23187.420314491934, 7500.662470188746, 7524.774256772305, 7602.619869006528, 7620.885686714703, 7796.8050798729355, 4102.220578090213, 4177.582241775859, 4264.211120942636, 4296.5273819879785, 4461.531539188403, 3747.100695833035, 3785.072657198949, 3788.905277368033, 3791.658750712449, 3807.200487897517, 585.4379795396381, 644.0789670770843, 665.2482694555218, 673.4814193071405, 688.3873352716087, 557.2727590494362, 571.8049277010983, 574.5920396518435, 578.2569398323135, 594.4709208804168, 7989.201079892081, 8154.510729356258, 8290.253877151084, 8291.860771513962, 8380.545802744993, 601.9969900150471, 605.2978544887909, 614.8862460444742, 614.9508039356841, 619.9721012554446, 3859.8842034739005, 3910.530736311661, 3919.1573811630637, 4020.8994775130273, 4047.1298670785923, 83659.74510382349, 87851.0, 89815.20369592702, 89852.0, 90054.0, 75557.86663200056, 75826.0, 75859.24140758521, 76566.0, 77182.0, 67714.66142669354, 69245.80656450786, 69640.60718785596, 69729.2567685812, 70764.64617676982, 7821.178776228641, 7883.408744344312, 7924.920750792418, 7958.045034595885, 7969.0, 45136.54863451455, 49831.003379932925, 50372.49627503825, 51168.0, 51588.0, 56411.71794141003, 66715.0, 68514.97227541491, 68843.65578172076, 69535.30464695288, 10254.948725256327, 10531.684049478528, 10547.0, 10559.04968552834, 10635.680929572125, 10709.892901070889, 10919.672409827717, 10989.285696429706, 11142.721431964266, 11629.59296424621, 10248.026437488426, 10280.588776448858, 10364.689059328231, 10380.27338086357, 10591.205659575717, 10491.842622360824, 10494.790104198026, 10553.788924221319, 10687.51906164209, 10773.569057237622, 9990.15083717892, 10054.64808731691, 10243.641472548425, 10404.011618896193, 10697.946510267398, 8793.780155495908, 8928.15182557656, 8939.195472407515, 9075.727728168165, 9156.17594416479, 7247.855042899218, 8826.338024648305, 8842.0, 8867.246284065668, 9071.319651026068], "unit": "score", "name": "yui.html", "value": 6941.406259266994}], "name": "dromaeo_css", "value": 7664.197609719655}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 110.0, 111.0, 111.0, 113.0, 112.0, 119.0, 113.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.3}, {"lowerIsBetter": true, "replicates": [149.0, 119.0, 152.0, 147.0, 118.0, 132.0, 115.0, 147.0, 148.0, 116.0], "unit": "score", "name": "audio-beat-detection", "value": 134.3}, {"lowerIsBetter": true, "replicates": [188.0, 188.0, 185.0, 185.0, 189.0, 187.0, 183.0, 185.0, 186.0, 184.0], "unit": "score", "name": "audio-dft", "value": 186.0}, {"lowerIsBetter": true, "replicates": [73.0, 81.0, 78.0, 117.0, 55.0, 66.0, 66.0, 84.0, 155.0, 55.0], "unit": "score", "name": "audio-fft", "value": 83.0}, {"lowerIsBetter": true, "replicates": [90.0, 93.0, 131.0, 80.0, 78.0, 85.0, 118.0, 77.0, 80.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 94.7}, {"lowerIsBetter": true, "replicates": [89.0, 85.0, 101.0, 129.0, 123.0, 84.0, 88.0, 85.0, 90.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 95.8}, {"lowerIsBetter": true, "replicates": [130.0, 97.0, 139.0, 148.0, 112.0, 94.0, 93.0, 94.0, 92.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 109.2}, {"lowerIsBetter": true, "replicates": [87.0, 85.0, 87.0, 85.0, 85.0, 84.0, 86.0, 86.0, 86.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 85.8}, {"lowerIsBetter": true, "replicates": [54.0, 58.0, 57.0, 56.0, 57.0, 56.0, 57.0, 68.0, 51.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 56.6}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 71.0, 78.0, 70.0, 69.0, 66.0, 68.0, 65.0, 76.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 81.0, 80.0, 81.0, 79.0, 79.0, 80.0, 81.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.7}, {"lowerIsBetter": true, "replicates": [117.0, 129.0, 130.0, 131.0, 131.0, 127.0, 116.0, 117.0, 117.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.4}, {"lowerIsBetter": true, "replicates": [134.0, 124.0, 135.0, 133.0, 128.0, 123.0, 133.0, 133.0, 134.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.0}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 66.0, 64.0, 67.0, 66.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.8}], "name": "kraken", "value": 1427.3000000000002}]} 03:50:22 INFO - Return code: 0 03:50:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:50:22 INFO - # TBPL SUCCESS # 03:50:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:50:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:50:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:50:22 INFO - 2016-04-25 03:50:22.266 screenresolution[1703:10160] starting screenresolution argv=screenresolution get 03:50:22 INFO - 2016-04-25 03:50:22.268 screenresolution[1703:10160] Display 0: 1600x1200x32@60 03:50:22 INFO - 2016-04-25 03:50:22.274 screenresolution[1704:10163] starting screenresolution argv=screenresolution list 03:50:22 INFO - Available Modes on Display 0 03:50:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:50:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:50:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:50:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:50:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:50:22 INFO - Intel Iris: 03:50:22 INFO - Chipset Model: Intel Iris 03:50:22 INFO - Type: GPU 03:50:22 INFO - Bus: Built-In 03:50:22 INFO - VRAM (Dynamic, Max): 1536 MB 03:50:22 INFO - Vendor: Intel (0x8086) 03:50:22 INFO - Device ID: 0x0a2e 03:50:22 INFO - Revision ID: 0x0009 03:50:22 INFO - Displays: 03:50:22 INFO - Display: 03:50:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:50:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:50:22 INFO - Display Serial Number: 1600x1200 60 03:50:22 INFO - Main Display: Yes 03:50:22 INFO - Mirror: Off 03:50:22 INFO - Online: Yes 03:50:22 INFO - Rotation: Supported 03:50:22 INFO - Return code: 0 03:50:22 INFO - Running post-action listener: _resource_record_post_action 03:50:22 INFO - Running post-run listener: _resource_record_post_run 03:50:23 INFO - Total resource usage - Wall time: 1203s; CPU: 52.0%; Read bytes: 32456192; Write bytes: 666066432; Read time: 327; Write time: 2195 03:50:23 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 357157376; Write bytes: 353875456; Read time: 27840; Write time: 639 03:50:23 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 30674432; Write bytes: 301012992; Read time: 308; Write time: 1527 03:50:23 INFO - Running post-run listener: _upload_blobber_files 03:50:23 INFO - Blob upload gear active. 03:50:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:50:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:50:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:50:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:50:23 INFO - (blobuploader) - INFO - Open directory for files ... 03:50:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:50:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:50:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:25 INFO - (blobuploader) - INFO - Done attempting. 03:50:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:50:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:50:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:26 INFO - (blobuploader) - INFO - Done attempting. 03:50:26 INFO - (blobuploader) - INFO - Iteration through files over. 03:50:26 INFO - Return code: 0 03:50:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:50:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:50:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e91ab966c95c1879009e32218589a073bfc6dee2a49f9039f33714b91c79539b4927b541822d7627a953d30bb0c73023e7c877bb600502e8d329bc0d62640184", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b22a98ac827a18821777434d141b2c95b2f8e09a7b61911203ed080888d1134b3000cbbca49029c578e9b7e06d74cb3cef58efa9f10b170207f7e4b6d84c3983"} 03:50:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:50:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:50:26 INFO - Contents: 03:50:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e91ab966c95c1879009e32218589a073bfc6dee2a49f9039f33714b91c79539b4927b541822d7627a953d30bb0c73023e7c877bb600502e8d329bc0d62640184", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b22a98ac827a18821777434d141b2c95b2f8e09a7b61911203ed080888d1134b3000cbbca49029c578e9b7e06d74cb3cef58efa9f10b170207f7e4b6d84c3983"} 03:50:26 INFO - Running post-run listener: copy_logs_to_upload_dir 03:50:26 INFO - Copying logs to upload dir... 03:50:26 INFO - mkdir: /builds/slave/test/build/upload/logs 03:50:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.836145 ========= master_lag: 1.67 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-04-25 03:50:28.255424) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 03:50:28.256550) ========= 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.seADXhUFmK/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.Fumg0JE0w5/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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/e91ab966c95c1879009e32218589a073bfc6dee2a49f9039f33714b91c79539b4927b541822d7627a953d30bb0c73023e7c877bb600502e8d329bc0d62640184", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b22a98ac827a18821777434d141b2c95b2f8e09a7b61911203ed080888d1134b3000cbbca49029c578e9b7e06d74cb3cef58efa9f10b170207f7e4b6d84c3983"} build_url:https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011034 build_url: 'https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e91ab966c95c1879009e32218589a073bfc6dee2a49f9039f33714b91c79539b4927b541822d7627a953d30bb0c73023e7c877bb600502e8d329bc0d62640184", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b22a98ac827a18821777434d141b2c95b2f8e09a7b61911203ed080888d1134b3000cbbca49029c578e9b7e06d74cb3cef58efa9f10b170207f7e4b6d84c3983"}' ========= master_lag: 0.73 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 03:50:28.993731) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-25 03:50:28.994064) ========= 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.seADXhUFmK/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.Fumg0JE0w5/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.005208 ========= master_lag: 4.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-25 03:50:33.099834) ========= ========= Started reboot skipped (results: 3, elapsed: 4 secs) (at 2016-04-25 03:50:33.100111) ========= ========= Finished reboot skipped (results: 3, elapsed: 4 secs) (at 2016-04-25 03:50:38.058336) ========= ========= Total master_lag: 6.60 =========