builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1461239035.76 results: success (0) buildid: 20160421025510 builduid: 56befa3ccb104e0bb0d0de85150549f3 revision: 9ad67a907cae9644493ecbabae4529602ca3fa72 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:55.756418) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:55.756853) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:55.757134) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:55.775729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:55.775999) ========= 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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-21 04:43:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.79M=0.001s 2016-04-21 04:43:55 (9.79 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089263 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:55.881989) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:55.882297) ========= 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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.028217 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:55.933078) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-21 04:43:55.933392) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9ad67a907cae9644493ecbabae4529602ca3fa72 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 9ad67a907cae9644493ecbabae4529602ca3fa72 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-21 04:43:56,010 truncating revision to first 12 chars 2016-04-21 04:43:56,010 Setting DEBUG logging. 2016-04-21 04:43:56,010 attempt 1/10 2016-04-21 04:43:56,010 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9ad67a907cae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 04:43:57,821 unpacking tar archive at: fx-team-9ad67a907cae/testing/mozharness/ program finished with exit code 0 elapsedTime=2.056013 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-21 04:43:58.011891) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:58.012231) ========= script_repo_revision: 9ad67a907cae9644493ecbabae4529602ca3fa72 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:58.012672) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:58.012947) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 04:43:58.026349) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 41 secs) (at 2016-04-21 04:43:58.026646) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:43:58 INFO - MultiFileLogger online at 20160421 04:43:58 in /builds/slave/test 04:43:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:43:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:43:58 INFO - {'append_to_log': False, 04:43:58 INFO - 'base_work_dir': '/builds/slave/test', 04:43:58 INFO - 'blob_upload_branch': 'Fx-Team', 04:43:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:43:58 INFO - 'branch': 'Fx-Team', 04:43:58 INFO - 'buildbot_json_path': 'buildprops.json', 04:43:58 INFO - 'config_files': ('talos/mac_config.py',), 04:43:58 INFO - 'default_actions': ('clobber', 04:43:58 INFO - 'read-buildbot-config', 04:43:58 INFO - 'download-and-extract', 04:43:58 INFO - 'populate-webroot', 04:43:58 INFO - 'create-virtualenv', 04:43:58 INFO - 'install', 04:43:58 INFO - 'run-tests'), 04:43:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:43:58 INFO - 'download_minidump_stackwalk': True, 04:43:58 INFO - 'download_symbols': 'ondemand', 04:43:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:43:58 INFO - 'tooltool.py': '/tools/tooltool.py', 04:43:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:43:58 INFO - '/tools/misc-python/virtualenv.py')}, 04:43:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:43:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:43:58 INFO - 'installer_path': 'installer.exe', 04:43:58 INFO - 'log_level': 'info', 04:43:58 INFO - 'log_name': 'talos', 04:43:58 INFO - 'log_to_console': True, 04:43:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:43:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:43:58 INFO - 'opt_config_files': (), 04:43:58 INFO - 'pip_index': False, 04:43:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:43:58 INFO - 'cmd': ('bash', 04:43:58 INFO - '-c', 04:43:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:43:58 INFO - 'enabled': True, 04:43:58 INFO - 'halt_on_failure': False, 04:43:58 INFO - 'name': 'check_screen_resolution'},), 04:43:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:43:58 INFO - 'cmd': ('bash', 04:43:58 INFO - '-c', 04:43:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:43:58 INFO - 'enabled': True, 04:43:58 INFO - 'halt_on_failure': False, 04:43:58 INFO - 'name': 'check_screen_resolution'},), 04:43:58 INFO - 'run_cmd_checks_enabled': True, 04:43:58 INFO - 'sps_profile': False, 04:43:58 INFO - 'sps_profile_interval': 0, 04:43:58 INFO - 'suite': 'dromaeojs', 04:43:58 INFO - 'system_bits': '32', 04:43:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:43:58 INFO - 'title': 't-yosemite-r7-0110', 04:43:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:43:58 INFO - 'use_talos_json': True, 04:43:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:43:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:43:58 INFO - 'work_dir': 'build'} 04:43:58 INFO - ##### 04:43:58 INFO - ##### Running clobber step. 04:43:58 INFO - ##### 04:43:58 INFO - Running pre-action listener: _resource_record_pre_action 04:43:58 INFO - Running main action method: clobber 04:43:58 INFO - rmtree: /builds/slave/test/build 04:43:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:00 INFO - Running post-action listener: _resource_record_post_action 04:44:00 INFO - ##### 04:44:00 INFO - ##### Running read-buildbot-config step. 04:44:00 INFO - ##### 04:44:00 INFO - Running pre-action listener: _resource_record_pre_action 04:44:00 INFO - Running main action method: read_buildbot_config 04:44:00 INFO - Using buildbot properties: 04:44:00 INFO - { 04:44:00 INFO - "project": "", 04:44:00 INFO - "product": "firefox", 04:44:00 INFO - "script_repo_revision": "production", 04:44:00 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:44:00 INFO - "repository": "", 04:44:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:44:00 INFO - "buildid": "20160421025510", 04:44:00 INFO - "pgo_build": "False", 04:44:00 INFO - "basedir": "/builds/slave/test", 04:44:00 INFO - "buildnumber": 746, 04:44:00 INFO - "slavename": "t-yosemite-r7-0110", 04:44:00 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:44:00 INFO - "platform": "yosemite_r7", 04:44:00 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:44:00 INFO - "branch": "fx-team", 04:44:00 INFO - "repo_path": "integration/fx-team", 04:44:00 INFO - "revision": "9ad67a907cae9644493ecbabae4529602ca3fa72", 04:44:00 INFO - "stage_platform": "macosx64", 04:44:00 INFO - "builduid": "56befa3ccb104e0bb0d0de85150549f3", 04:44:00 INFO - "slavebuilddir": "test" 04:44:00 INFO - } 04:44:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:44:00 INFO - Running post-action listener: _resource_record_post_action 04:44:00 INFO - ##### 04:44:00 INFO - ##### Running download-and-extract step. 04:44:00 INFO - ##### 04:44:00 INFO - Running pre-action listener: _resource_record_pre_action 04:44:00 INFO - Running main action method: download_and_extract 04:44:00 INFO - mkdir: /builds/slave/test/build/tests 04:44: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')]} 04:44:00 INFO - https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:44:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:44:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:44:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:44:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 04:44:01 INFO - Downloaded 1199 bytes. 04:44:01 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:44:01 INFO - Using the following test package requirements: 04:44:01 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:44:01 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:44:01 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:44:01 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:44:01 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:44:01 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:44:01 INFO - u'jsshell-mac.zip'], 04:44:01 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:44:01 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:44:01 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:44:01 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:44:01 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:44:01 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:44:01 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:44:01 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:44:01 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:44:01 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:44:01 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:44:01 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:44:01 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')]} 04:44:01 INFO - https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:44:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:44:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:44:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:44:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:03 INFO - Downloaded 18099993 bytes. 04:44:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:44:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:44:06 INFO - Return code: 0 04:44:06 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:06 INFO - https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:44:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:44:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:44:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:44:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:07 INFO - Downloaded 18099993 bytes. 04:44:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:44:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:44:11 INFO - Return code: 0 04:44: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')]} 04:44:11 INFO - https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:44:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:44:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:44:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:44:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:44:13 INFO - Downloaded 10845952 bytes. 04:44:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:44:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:44:13 INFO - Return code: 0 04:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:13 INFO - https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:44:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:44:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:44:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:44:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:44:16 INFO - Downloaded 110037234 bytes. 04:44:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:44:16 INFO - mkdir: /builds/slave/test/properties 04:44:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:44:16 INFO - Writing to file /builds/slave/test/properties/build_url 04:44:16 INFO - Contents: 04:44:16 INFO - build_url:https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:44:17 INFO - Running post-action listener: _resource_record_post_action 04:44:17 INFO - Running post-action listener: set_extra_try_arguments 04:44:17 INFO - ##### 04:44:17 INFO - ##### Running populate-webroot step. 04:44:17 INFO - ##### 04:44:17 INFO - Running pre-action listener: _resource_record_pre_action 04:44:17 INFO - Running main action method: populate_webroot 04:44:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:44:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:44:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:44:17 INFO - '--tppagecycles', 04:44:17 INFO - '7'], 04:44:17 INFO - 'tests': ['tsvgm']}}, 04:44:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:44:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:44:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:44:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:44:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:17 INFO - 'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:17 INFO - 'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['damp', 'tps']}, 04:44:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:17 INFO - 'tests': ['damp', 'tps']}, 04:44:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['dromaeo_dom']}, 04:44:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:44:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['a11yr', 04:44:17 INFO - 'ts_paint', 04:44:17 INFO - 'tpaint', 04:44:17 INFO - 'sessionrestore', 04:44:17 INFO - 'sessionrestore_no_auto_restore', 04:44:17 INFO - 'tabpaint']}, 04:44:17 INFO - 'other-e10s': {'tests': ['a11yr', 04:44:17 INFO - 'ts_paint', 04:44:17 INFO - 'tpaint', 04:44:17 INFO - 'sessionrestore', 04:44:17 INFO - 'sessionrestore_no_auto_restore', 04:44:17 INFO - 'tabpaint']}, 04:44:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:44:17 INFO - 'ts_paint', 04:44:17 INFO - 'tpaint', 04:44:17 INFO - 'sessionrestore', 04:44:17 INFO - 'sessionrestore_no_auto_restore', 04:44:17 INFO - 'tabpaint']}, 04:44:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:44:17 INFO - 'ts_paint', 04:44:17 INFO - 'tpaint', 04:44:17 INFO - 'sessionrestore', 04:44:17 INFO - 'sessionrestore_no_auto_restore', 04:44:17 INFO - 'tabpaint']}, 04:44:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['a11yr', 04:44:17 INFO - 'ts_paint', 04:44:17 INFO - 'tpaint', 04:44:17 INFO - 'sessionrestore', 04:44:17 INFO - 'sessionrestore_no_auto_restore', 04:44:17 INFO - 'tabpaint']}, 04:44:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['a11yr', 04:44:17 INFO - 'ts_paint', 04:44:17 INFO - 'tpaint', 04:44:17 INFO - 'sessionrestore', 04:44:17 INFO - 'sessionrestore_no_auto_restore', 04:44:17 INFO - 'tabpaint']}, 04:44:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['tsvgx', 04:44:17 INFO - 'tsvgr_opacity', 04:44:17 INFO - 'tart', 04:44:17 INFO - 'tscrollx', 04:44:17 INFO - 'cart']}, 04:44:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:44:17 INFO - 'tsvgr_opacity', 04:44:17 INFO - 'tart', 04:44:17 INFO - 'tscrollx', 04:44:17 INFO - 'cart']}, 04:44:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:17 INFO - 'talos_options': ['--disable-e10s'], 04:44:17 INFO - 'tests': ['tp5o']}, 04:44:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:17 INFO - 'tests': ['tp5o']}, 04:44:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:17 INFO - 'talos_options': ['--disable-e10s', 04:44:17 INFO - '--xperf_path', 04:44:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:17 INFO - 'tests': ['tp5n']}, 04:44:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:17 INFO - 'talos_options': ['--xperf_path', 04:44:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:17 INFO - 'tests': ['tp5n']}}, 04:44:17 INFO - 'talos.zip': {'path': '', 04:44:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:44:17 INFO - Running post-action listener: _resource_record_post_action 04:44:17 INFO - ##### 04:44:17 INFO - ##### Running create-virtualenv step. 04:44:17 INFO - ##### 04:44:17 INFO - Running pre-action listener: _resource_record_pre_action 04:44:17 INFO - Running main action method: create_virtualenv 04:44:17 INFO - Creating virtualenv /builds/slave/test/build/venv 04:44:17 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 04:44:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:44:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:44:18 INFO - Using real prefix '/tools/python27' 04:44:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:44:18 INFO - Installing distribute.............................................................................................................................................................................................done. 04:44:21 INFO - Installing pip.................done. 04:44:21 INFO - Return code: 0 04:44:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:44:21 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')]} 04:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:21 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')]} 04:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:21 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 0x107f65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f75420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7abcbafd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, '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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:44:21 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 04:44:21 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 04:44:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:44:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:21 INFO - 'HOME': '/Users/cltbld', 04:44:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:21 INFO - 'LOGNAME': 'cltbld', 04:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:21 INFO - 'MOZ_NO_REMOTE': '1', 04:44:21 INFO - 'NO_EM_RESTART': '1', 04:44:21 INFO - 'PAGER': '/bin/cat', 04:44:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:21 INFO - 'PWD': '/builds/slave/test', 04:44:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:21 INFO - 'SHELL': '/bin/bash', 04:44:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:44:21 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:44:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:21 INFO - 'USER': 'cltbld', 04:44:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:21 INFO - 'XPC_FLAGS': '0x0', 04:44:21 INFO - 'XPC_SERVICE_NAME': '0', 04:44:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:21 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 04:44:21 INFO - Cleaning up... 04:44:21 INFO - Return code: 0 04:44:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:44:21 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')]} 04:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:21 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')]} 04:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:21 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 0x107f65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f75420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7abcbafd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, '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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:44:21 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 04:44:21 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 04:44:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:44:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:21 INFO - 'HOME': '/Users/cltbld', 04:44:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:21 INFO - 'LOGNAME': 'cltbld', 04:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:21 INFO - 'MOZ_NO_REMOTE': '1', 04:44:21 INFO - 'NO_EM_RESTART': '1', 04:44:21 INFO - 'PAGER': '/bin/cat', 04:44:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:21 INFO - 'PWD': '/builds/slave/test', 04:44:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:21 INFO - 'SHELL': '/bin/bash', 04:44:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:44:21 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:44:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:21 INFO - 'USER': 'cltbld', 04:44:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:21 INFO - 'XPC_FLAGS': '0x0', 04:44:21 INFO - 'XPC_SERVICE_NAME': '0', 04:44:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:22 INFO - Downloading/unpacking psutil>=0.7.1 04:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:44:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:44:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:44:25 INFO - Installing collected packages: psutil 04:44:25 INFO - Running setup.py install for psutil 04:44:25 INFO - building 'psutil._psutil_osx' extension 04:44:25 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 04:44:25 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 04:44:25 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 04:44:25 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 04:44:25 INFO - building 'psutil._psutil_posix' extension 04:44:25 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 04:44:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:44:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:44:26 INFO - ^ 04:44:26 INFO - 1 warning generated. 04:44:26 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 04:44:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:44:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:44:26 INFO - Successfully installed psutil 04:44:26 INFO - Cleaning up... 04:44:26 INFO - Return code: 0 04:44:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:26 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 0x107f65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f75420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7abcbafd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, '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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:44:26 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 04:44:26 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 04:44:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:44:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:26 INFO - 'HOME': '/Users/cltbld', 04:44:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:26 INFO - 'LOGNAME': 'cltbld', 04:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:26 INFO - 'MOZ_NO_REMOTE': '1', 04:44:26 INFO - 'NO_EM_RESTART': '1', 04:44:26 INFO - 'PAGER': '/bin/cat', 04:44:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:26 INFO - 'PWD': '/builds/slave/test', 04:44:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:26 INFO - 'SHELL': '/bin/bash', 04:44:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:44:26 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:44:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:26 INFO - 'USER': 'cltbld', 04:44:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:26 INFO - 'XPC_FLAGS': '0x0', 04:44:26 INFO - 'XPC_SERVICE_NAME': '0', 04:44:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:44:26 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 04:44:26 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 04:44:26 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 04:44:26 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 04:44:26 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 04:44:26 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 04:44:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:44:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:44:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:44:29 INFO - Installing collected packages: mozsystemmonitor 04:44:29 INFO - Running setup.py install for mozsystemmonitor 04:44:29 INFO - Successfully installed mozsystemmonitor 04:44:29 INFO - Cleaning up... 04:44:29 INFO - Return code: 0 04:44:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107f65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f75420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7abcbafd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, '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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:44:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:44:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:29 INFO - 'HOME': '/Users/cltbld', 04:44:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:29 INFO - 'LOGNAME': 'cltbld', 04:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:29 INFO - 'MOZ_NO_REMOTE': '1', 04:44:29 INFO - 'NO_EM_RESTART': '1', 04:44:29 INFO - 'PAGER': '/bin/cat', 04:44:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:29 INFO - 'PWD': '/builds/slave/test', 04:44:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:29 INFO - 'SHELL': '/bin/bash', 04:44:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:44:29 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:44:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:29 INFO - 'USER': 'cltbld', 04:44:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:29 INFO - 'XPC_FLAGS': '0x0', 04:44:29 INFO - 'XPC_SERVICE_NAME': '0', 04:44:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:29 INFO - Downloading/unpacking blobuploader==1.2.4 04:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:32 INFO - Downloading blobuploader-1.2.4.tar.gz 04:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:44:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:44:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:33 INFO - Downloading docopt-0.6.1.tar.gz 04:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:44:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:44:33 INFO - Running setup.py install for blobuploader 04:44:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:44:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:44:33 INFO - Running setup.py install for requests 04:44:33 INFO - Running setup.py install for docopt 04:44:34 INFO - Successfully installed blobuploader requests docopt 04:44:34 INFO - Cleaning up... 04:44:34 INFO - Return code: 0 04:44:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44:34 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')]} 04:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:34 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')]} 04:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:34 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 0x107f65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f75420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7abcbafd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, '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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:44:34 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 04:44:34 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 04:44:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:44:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:34 INFO - 'HOME': '/Users/cltbld', 04:44:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:34 INFO - 'LOGNAME': 'cltbld', 04:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:34 INFO - 'MOZ_NO_REMOTE': '1', 04:44:34 INFO - 'NO_EM_RESTART': '1', 04:44:34 INFO - 'PAGER': '/bin/cat', 04:44:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:34 INFO - 'PWD': '/builds/slave/test', 04:44:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:34 INFO - 'SHELL': '/bin/bash', 04:44:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:44:34 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:44:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:34 INFO - 'USER': 'cltbld', 04:44:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:34 INFO - 'XPC_FLAGS': '0x0', 04:44:34 INFO - 'XPC_SERVICE_NAME': '0', 04:44:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:44:34 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-GRxiLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:44:34 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-9GWXwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:44:34 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-68jVXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:44:34 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-XrXyQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:44:34 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-RN0WeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:44:34 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-W57uF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:44:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QQfK_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:44:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-uV4las-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:44:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-VcZtp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:44:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-BtnR6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:44:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-9SaNLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:44:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-kMvoPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:44:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-iJvajZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:44:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-WR6c5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-aAROYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:44:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-dOoMpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:44:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Dgdx8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:44:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:44:36 INFO - Running setup.py install for manifestparser 04:44:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:44:36 INFO - Running setup.py install for mozcrash 04:44:36 INFO - Running setup.py install for mozdebug 04:44:36 INFO - Running setup.py install for mozdevice 04:44:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:44:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:44:36 INFO - Running setup.py install for mozfile 04:44:36 INFO - Running setup.py install for mozhttpd 04:44:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:44:37 INFO - Running setup.py install for mozinfo 04:44:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:44:37 INFO - Running setup.py install for mozInstall 04:44:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:44:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:44:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:44:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:44:37 INFO - Running setup.py install for mozleak 04:44:37 INFO - Running setup.py install for mozlog 04:44:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:44:37 INFO - Running setup.py install for moznetwork 04:44:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:44:37 INFO - Running setup.py install for mozprocess 04:44:37 INFO - Running setup.py install for mozprofile 04:44:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:44:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:44:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:44:38 INFO - Running setup.py install for mozrunner 04:44:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:44:38 INFO - Running setup.py install for mozscreenshot 04:44:38 INFO - Running setup.py install for moztest 04:44:38 INFO - Running setup.py install for mozversion 04:44:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:44:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:44:38 INFO - Cleaning up... 04:44:38 INFO - Return code: 0 04:44:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:38 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 0x107f65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f75420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7abcbafd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, '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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:44:38 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 04:44:38 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 04:44:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:44:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:38 INFO - 'HOME': '/Users/cltbld', 04:44:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:38 INFO - 'LOGNAME': 'cltbld', 04:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:38 INFO - 'MOZ_NO_REMOTE': '1', 04:44:38 INFO - 'NO_EM_RESTART': '1', 04:44:38 INFO - 'PAGER': '/bin/cat', 04:44:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:38 INFO - 'PWD': '/builds/slave/test', 04:44:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:38 INFO - 'SHELL': '/bin/bash', 04:44:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:44:38 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:44:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:38 INFO - 'USER': 'cltbld', 04:44:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:38 INFO - 'XPC_FLAGS': '0x0', 04:44:38 INFO - 'XPC_SERVICE_NAME': '0', 04:44:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Ek8Woj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:44:39 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)) 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-StLrri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:44:39 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)) 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-XnAJp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:44:39 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)) 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-TBd8TL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:44:39 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)) 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-GjyMnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:44:39 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)) 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-bE_1Xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:44:39 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)) 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-nMKm5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:44:39 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)) 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Mgw2q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:44:39 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)) 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-PFfM0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:44:39 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)) 04:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:44:39 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-duYH3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:44:40 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)) 04:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:44:40 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-l1J2bV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:44:40 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)) 04:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:44:40 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-iYmMbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:44:40 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)) 04:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:44:40 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-JnMTAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:44:40 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)) 04:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:44:40 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-AbKcWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:44:40 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)) 04:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:40 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-u_rfXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:40 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)) 04:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:44:40 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-E5PUXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:44:40 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)) 04:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:44:40 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-09CplK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:44:40 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)) 04:44:40 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)) 04:44:40 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)) 04:44:40 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)) 04:44:40 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)) 04:44:40 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)) 04:44:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:44:40 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 04:44:40 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 04:44:40 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 04:44:40 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 04:44:40 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 04:44:40 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 04:44:43 INFO - Downloading blessings-1.6.tar.gz 04:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:44:43 INFO - Installing collected packages: blessings 04:44:43 INFO - Running setup.py install for blessings 04:44:43 INFO - Successfully installed blessings 04:44:43 INFO - Cleaning up... 04:44:43 INFO - Return code: 0 04:44:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:44:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:44:43 INFO - Reading from file tmpfile_stdout 04:44:43 INFO - Current package versions: 04:44:43 INFO - blessings == 1.6 04:44:43 INFO - blobuploader == 1.2.4 04:44:43 INFO - docopt == 0.6.1 04:44:43 INFO - manifestparser == 1.1 04:44:43 INFO - mozInstall == 1.12 04:44:43 INFO - mozcrash == 0.17 04:44:43 INFO - mozdebug == 0.1 04:44:43 INFO - mozdevice == 0.48 04:44:43 INFO - mozfile == 1.2 04:44:43 INFO - mozhttpd == 0.7 04:44:43 INFO - mozinfo == 0.9 04:44:43 INFO - mozleak == 0.1 04:44:43 INFO - mozlog == 3.1 04:44:43 INFO - moznetwork == 0.27 04:44:43 INFO - mozprocess == 0.22 04:44:43 INFO - mozprofile == 0.28 04:44:43 INFO - mozrunner == 6.11 04:44:43 INFO - mozscreenshot == 0.1 04:44:43 INFO - mozsystemmonitor == 0.0 04:44:43 INFO - moztest == 0.7 04:44:43 INFO - mozversion == 1.4 04:44:43 INFO - psutil == 3.1.1 04:44:43 INFO - requests == 1.2.3 04:44:43 INFO - wsgiref == 0.1.2 04:44:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:43 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 0x107f65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f75420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7abcbafd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, '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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:44:43 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 04:44:43 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 04:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:43 INFO - 'HOME': '/Users/cltbld', 04:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:43 INFO - 'LOGNAME': 'cltbld', 04:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:43 INFO - 'MOZ_NO_REMOTE': '1', 04:44:43 INFO - 'NO_EM_RESTART': '1', 04:44:43 INFO - 'PAGER': '/bin/cat', 04:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:43 INFO - 'PWD': '/builds/slave/test', 04:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:43 INFO - 'SHELL': '/bin/bash', 04:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:44:43 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:43 INFO - 'USER': 'cltbld', 04:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:43 INFO - 'XPC_FLAGS': '0x0', 04:44:43 INFO - 'XPC_SERVICE_NAME': '0', 04:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:44 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)) 04:44:44 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)) 04:44:44 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)) 04:44:44 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)) 04:44:44 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)) 04:44:44 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)) 04:44:44 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)) 04:44:44 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)) 04:44:44 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)) 04:44:44 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)) 04:44:44 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)) 04:44:44 INFO - Cleaning up... 04:44:44 INFO - Return code: 0 04:44:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:44:44 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')]} 04:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:44 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')]} 04:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:44 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 0x107f65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f75420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7abcbafd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, '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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:44:44 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 04:44:44 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 04:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:44 INFO - 'HOME': '/Users/cltbld', 04:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:44 INFO - 'LOGNAME': 'cltbld', 04:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:44 INFO - 'MOZ_NO_REMOTE': '1', 04:44:44 INFO - 'NO_EM_RESTART': '1', 04:44:44 INFO - 'PAGER': '/bin/cat', 04:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:44 INFO - 'PWD': '/builds/slave/test', 04:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:44 INFO - 'SHELL': '/bin/bash', 04:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:44:44 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:44 INFO - 'USER': 'cltbld', 04:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:44 INFO - 'XPC_FLAGS': '0x0', 04:44:44 INFO - 'XPC_SERVICE_NAME': '0', 04:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:44 INFO - Downloading/unpacking jsonschema 04:44:44 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 04:44:44 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 04:44:44 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 04:44:44 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 04:44:44 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 04:44:44 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 04:44:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:44:46 INFO - Downloading/unpacking functools32 (from jsonschema) 04:44:46 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 04:44:46 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 04:44:46 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 04:44:46 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 04:44:46 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 04:44:46 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 04:44:47 INFO - Downloading functools32-3.2.3-2.tar.gz 04:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:44:47 INFO - warning: no files found matching '*.txt' 04:44:47 INFO - no previously-included directories found matching 'build' 04:44:47 INFO - no previously-included directories found matching 'dist' 04:44:47 INFO - no previously-included directories found matching '.git*' 04:44:47 INFO - Installing collected packages: jsonschema, functools32 04:44:47 INFO - Running setup.py install for functools32 04:44:47 INFO - warning: no files found matching '*.txt' 04:44:47 INFO - no previously-included directories found matching 'build' 04:44:47 INFO - no previously-included directories found matching 'dist' 04:44:47 INFO - no previously-included directories found matching '.git*' 04:44:47 INFO - Successfully installed jsonschema functools32 04:44:47 INFO - Cleaning up... 04:44:47 INFO - Return code: 0 04:44:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:44: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')]} 04:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107f65a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f75420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7abcbafd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, '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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 04:44: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', 'flake8'] in /builds/slave/test/build 04:44: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 flake8 04:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:47 INFO - 'HOME': '/Users/cltbld', 04:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:47 INFO - 'LOGNAME': 'cltbld', 04:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:47 INFO - 'MOZ_NO_REMOTE': '1', 04:44:47 INFO - 'NO_EM_RESTART': '1', 04:44:47 INFO - 'PAGER': '/bin/cat', 04:44:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:47 INFO - 'PWD': '/builds/slave/test', 04:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:47 INFO - 'SHELL': '/bin/bash', 04:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:44:47 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:47 INFO - 'USER': 'cltbld', 04:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:47 INFO - 'XPC_FLAGS': '0x0', 04:44:47 INFO - 'XPC_SERVICE_NAME': '0', 04:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:47 INFO - Downloading/unpacking flake8 04:44:47 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 04:44:47 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 04:44:47 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 04:44:47 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 04:44:47 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 04:44:47 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 04:44:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:44:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:44:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:44:51 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 04:44:51 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 04:44:51 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 04:44:51 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 04:44:51 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 04:44:51 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 04:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:44:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:44:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:44:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:44:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:44:51 INFO - no previously-included directories found matching 'docs/_build' 04:44:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:44:51 INFO - Running setup.py install for pep8 04:44:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:44:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:44:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:44:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:44:51 INFO - no previously-included directories found matching 'docs/_build' 04:44:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:44:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:44:51 INFO - Cleaning up... 04:44:51 INFO - Return code: 0 04:44:51 INFO - Running post-action listener: _resource_record_post_action 04:44:51 INFO - Running post-action listener: _start_resource_monitoring 04:44:51 INFO - Starting resource monitoring. 04:44:51 INFO - ##### 04:44:51 INFO - ##### Running install step. 04:44:51 INFO - ##### 04:44:51 INFO - Running pre-action listener: _resource_record_pre_action 04:44:51 INFO - Running main action method: install 04:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:44:52 INFO - Reading from file tmpfile_stdout 04:44:52 INFO - Detecting whether we're running mozinstall >=1.0... 04:44:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:44:52 INFO - Reading from file tmpfile_stdout 04:44:52 INFO - Output received: 04:44:52 INFO - Usage: mozinstall [options] installer 04:44:52 INFO - Options: 04:44:52 INFO - -h, --help show this help message and exit 04:44:52 INFO - -d DEST, --destination=DEST 04:44:52 INFO - Directory to install application into. [default: 04:44:52 INFO - "/builds/slave/test"] 04:44:52 INFO - --app=APP Application being installed. [default: firefox] 04:44:52 INFO - mkdir: /builds/slave/test/build/application 04:44:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:45:26 INFO - Reading from file tmpfile_stdout 04:45:26 INFO - Output received: 04:45:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:45:26 INFO - Running post-action listener: _resource_record_post_action 04:45:26 INFO - ##### 04:45:26 INFO - ##### Running run-tests step. 04:45:26 INFO - ##### 04:45:26 INFO - Running pre-action listener: _resource_record_pre_action 04:45:26 INFO - Running main action method: run_tests 04:45:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:45:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:45:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:45:26 INFO - 2016-04-21 04:45:26.125 screenresolution[1648:7566] starting screenresolution argv=screenresolution get 04:45:26 INFO - 2016-04-21 04:45:26.127 screenresolution[1648:7566] Display 0: 1600x1200x32@60 04:45:26 INFO - 2016-04-21 04:45:26.133 screenresolution[1649:7568] starting screenresolution argv=screenresolution list 04:45:26 INFO - Available Modes on Display 0 04:45:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:45:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:45:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:45:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:45:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:45:26 INFO - Intel Iris: 04:45:26 INFO - Chipset Model: Intel Iris 04:45:26 INFO - Type: GPU 04:45:26 INFO - Bus: Built-In 04:45:26 INFO - VRAM (Dynamic, Max): 1536 MB 04:45:26 INFO - Vendor: Intel (0x8086) 04:45:26 INFO - Device ID: 0x0a2e 04:45:26 INFO - Revision ID: 0x0009 04:45:26 INFO - Displays: 04:45:26 INFO - Display: 04:45:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:45:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:45:26 INFO - Display Serial Number: 1600x1200 60 04:45:26 INFO - Main Display: Yes 04:45:26 INFO - Mirror: Off 04:45:26 INFO - Online: Yes 04:45:26 INFO - Rotation: Supported 04:45:26 INFO - Return code: 0 04:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:45:26 INFO - Python 2.7.3 04:45:26 INFO - Return code: 0 04:45:26 INFO - grabbing minidump binary from tooltool 04:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:26 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 0x107e04e00>, '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 0x107c2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107dffb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:45:26 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 04:45:26 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 04:45:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:45:26 INFO - Return code: 0 04:45:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:45:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:45:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:45:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:45:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:45:27 INFO - Return code: 0 04:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 04:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --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 04:45:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mw9MwrkLYq/Render', 04:45:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:27 INFO - 'HOME': '/Users/cltbld', 04:45:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:27 INFO - 'LOGNAME': 'cltbld', 04:45:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:45:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:27 INFO - 'MOZ_NO_REMOTE': '1', 04:45:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:45:27 INFO - 'NO_EM_RESTART': '1', 04:45:27 INFO - 'PAGER': '/bin/cat', 04:45:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:27 INFO - 'PWD': '/builds/slave/test', 04:45:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:45:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:27 INFO - 'SHELL': '/bin/bash', 04:45:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3hxYE9uWLx/Listeners', 04:45:27 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 04:45:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:27 INFO - 'USER': 'cltbld', 04:45:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:27 INFO - 'XPC_FLAGS': '0x0', 04:45:27 INFO - 'XPC_SERVICE_NAME': '0', 04:45:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 04:45:27 INFO - mozversion application_buildid: 20160421025510 04:45:27 INFO - mozversion application_changeset: 9ad67a907cae9644493ecbabae4529602ca3fa72 04:45:27 INFO - mozversion application_display_name: Nightly 04:45:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:45:27 INFO - mozversion application_name: Firefox 04:45:27 INFO - mozversion application_remotingname: firefox 04:45:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:45:27 INFO - mozversion application_vendor: Mozilla 04:45:27 INFO - mozversion application_version: 48.0a1 04:45:27 INFO - mozversion platform_buildid: 20160421025510 04:45:27 INFO - mozversion platform_changeset: 9ad67a907cae9644493ecbabae4529602ca3fa72 04:45:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:45:27 INFO - mozversion platform_version: 48.0a1 04:45:27 INFO - using testdate: 1461239127 04:45:27 INFO - actual date: 1461239127 04:45:27 INFO - starting webserver on 'localhost:49277' 04:45:27 INFO - SUITE-START | Running 2 tests 04:45:27 INFO - TEST-START | dromaeo_css 04:45:27 INFO - operating with platform_type : mac_ 04:45:27 INFO - Initialising browser for dromaeo_css test... 04:45:27 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpRzAOaT/profile http://localhost:49277/getInfo.html) 04:45:30 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 04:45:30 INFO - PROCESS | 1667 | colorDepth:24 04:45:30 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 04:45:30 INFO - PROCESS | 1667 | __metrics 04:45:30 INFO - PROCESS | 1667 | 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" 04:45:30 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:45:30 INFO - TEST-INFO | 1667: exit 0 04:45:30 INFO - Browser initialized. 04:45:30 INFO - Running cycle 1/1 for dromaeo_css test... 04:45:30 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpRzAOaT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:45:42 INFO - PROCESS | 1669 | RSS: Main: 238731264 04:45:42 INFO - PROCESS | 1669 | 04:46:33 INFO - PROCESS | 1669 | 1461239193669 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] 04:48:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 04:48:46 INFO - PROCESS | 1669 | RSS: Main: 369766400 04:48:46 INFO - PROCESS | 1669 | 04:51:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 04:51:50 INFO - PROCESS | 1669 | RSS: Main: 379654144 04:51:50 INFO - PROCESS | 1669 | 04:54:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 04:54:53 INFO - PROCESS | 1669 | RSS: Main: 376856576 04:54:53 INFO - PROCESS | 1669 | 04:57:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 04:57:56 INFO - PROCESS | 1669 | RSS: Main: 376160256 04:57:56 INFO - PROCESS | 1669 | 05:00:59 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 05:01:00 INFO - PROCESS | 1669 | RSS: Main: 347455488 05:01:00 INFO - PROCESS | 1669 | 05:04:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 05:04:03 INFO - PROCESS | 1669 | RSS: Main: 429461504 05:04:03 INFO - PROCESS | 1669 | 05:04:03 INFO - PROCESS | 1669 | __start_tp_report 05:04:03 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 05:04:03 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 05:04:03 INFO - PROCESS | 1669 | |i|pagename|runs| 05:04:03 INFO - PROCESS | 1669 | |0;dojo.html;3431.107911942895;3482.895513134607;3526.2947410517904;3530.3115892400983;3568.446890731936;5657.094864821629;5768.682722450264;5808.680522571262;5811.709414529272;5816.360200377961;5766.423357664232;5798.188253644483;5861.618994765348;5884.646921184722;5925.585209035369;4346.934795978047;5193.9740301298625;5229.189475631283;5233.764480598363;5268.0517506848755;5321.467853214686;5321.547668448186;5344.984452953946;5379.865503362408;5382.2195781611645;5195.766190521435;5284.048871203182;5293.86765330866;5297.231901374318;5314.76083576238;5309.575233981311;5312.76092575835;5313.734313284359;5320.6275560710765;5326.174442961308;4354.477462704495;5234.18870075139;5265.131253343194;5268.130758424893;5283.233931079972;5709.77160913563;5749.108888122348;5752.942470575283;5755.194272801811;5758.79844205455;6219.1915051043525;6276.497880169622;6325.051242313643;6343;6411.743530258784;6158.56890017699;6283.654399008098;6309.968450157719;6322.810315690536;6325.430711236011;5718.456746609066;5765.106408506688;5789.623674461231;5791.449812267828;5803.245578074865;5216.21756736485;6244.781432649836;6256.7810126645345;6316.652584107918;6356.078368636614;6173.228346456693;6195.411435913581;6207.441330280297;6219.222597175353;6231.22109736283;6196.81409557714;6342.429181373698;6345.302016778151;6355.3326900674765;6380.106785050004;6191.690415479208;6309.779157729457;6311.990081586924;6325.05124231369;6343.682815859281;6310.1796766420075;6325.968370158212;6333.240011198685;6335.334789847092;6351.110844481776;4904.509549045139;4992.525710057539;4996.4503904570465;5002.249662550574;5059.342285502861;4478.373027776114;4494.483134439582;4509.751963641966;4563.977180114144;4590.540945905383;5663.433656634386;5691.857703557444;5716.714164291769;5719.19931209631;5763.164341170591;6247.468965138014;6268.122462855204;6286.717097730613;6358.682065896686;6384.265809431975;5776.855578610568;5792.94207057924;5802.071668533099;5803;5850;5476;5477.616566840363;5488.4785945335125;5489.560835133179;5491.560675145979;6253.218347706536;6306.085617585332;6385.10608514799;6401.615903045832;6441.130447389636;6328.177336946167;6334.049892515975;6349.396807303298;6356.11014457967;6356.55504114726;6390.13733146038;6406.903896441464;6416.48668106541;6418.935810641834;6423.935760642334;5939.16851640762;6178.907316390348;6251.156093927261;6300.338464461165;6334.6832658365975;6350.7142178603;6355.824172528097;6358.1734374531015;6358.459530939922;6362.268339140873;5057.898842023213;6128.264608247039;6177.2587289525545;6198.597091189058;6198.876022479615;5938.524918006636;6099.329073801967;6144.986077297196;6147.631142131487;6186.1030150627;5839.941600583939;5992.850178745566;6010.218671572842;6016.5487588431915;6030.728617212324;6037.15479832815;6194.101855231056;6206.596571222856;6225.470834979077;6237.7193026314835;5499.257600223918;5605.990921633982;5983;5988.251468566429;6095.969520152371 05:04:03 INFO - PROCESS | 1669 | |1;ext.html;507.23914128806786;530.5384315645389;531.6570811826372;546.3225600255682;575.5855783835636;30216.546751798713;37867.43198852016;39330.60669393302;39413.802930985374;39519.80240098795;43762.34356484647;44388.77805610975;44442.33336499947;44481.77759111208;44756.3286550702;8542.273906717937;9683.176929960944;9906.009399060109;9918.801623967516;9930.354526955725;8725.563721813916;11534.077273818075;11827.112966527546;11849.881501184964;11852.288862668225;11563.248388854698;11683.299002059903;11687;11688.824667629993;11759.647210583697;8628.956855215747;11526.596569120082;11600.941995290053;11669.824952625717;11914.46384912681;11387.202895797298;11546.307221566733;11549.364784936824;11558.826617600742;11572.59495917639;38571.03572410684;38942.415863762064;38976.830695079196;39054.60945390538;39114.80442597797;2960.911172664802;3215.3569286142865;3221.291315910496;3300.3069355435387;3452.637473065317;29223.70776292231;29250.414991700047;29262.41475170506;29453.705462945312;29628.70371296281;2654.4160284737327;2680.5309070912513;2743.5884617307365;2791.2463634818487;2807.2841425436586;462497;462534;462589.68705156597;462617;464720.67639661586;35114;60622.69688651617;60899;61078.0838287426;61297;35392;35473.645263547034;35642.64357356445;35670.92987210388;35671.28657426837;14326.641833954027;14660.266986650624;14669.413223470941;14672.926635366755;14680.339384727713;11334.716632084099;11693.76612467763;12257.203281786655;12461.87538124607;12707.110502264937;8094.623913934645;8105.716299929474;8115.594220288962;8204.261616454547;8228.670853165806;3338.6160591532284;3388.0682812226837;3388.22070923684;3394.6605339465855;3427.2288735034417;969.486172328667;974.5565767575736;983.5868935047297;991.4745185051934;1073.6295977887617;25590.23229303124;29602.703972959996;29972.95094671676;30351.544726829114;30540.694593054228;1123.8145705958589;1131.2477202660139;1141.9143564232716;1179.2099293473393;1228.0237211416945;13389.129706569236;13817.792733109012;14595.92702036483;15539.689206216039;15884.76172857408;639574;648111;648223;649693;649771;61718.69140654358;62584.687076565235;62604;62636.74726505535;62732.686336568026;1332.2739107186412;1419.6717383491082;1495.5139579636443;1506.623344163959;1541;27317;28071.456069916352;29774.553381698857;29855.402891941605;30782.846085769426;50395.74802125966;50606.963582185184;51052.74473627608;51083.23375149301;51210.48789512057;433727.83136084117;438675;438870;438983;440082;23567.41081472977;23729.406764831016;24645.753542464347;27019.91920323165;27424.040158594355;42615.360769589104;42891.785541072095;42911;42928.35607465828;44165.558344416146;37007;37314.6268537322;37460.94589663236;37685.623143768215;38306.61693383031;36911;36912.323385415635;37394.62605373911;37739;37899.81050094732;30645.62898081254;37069.62930370662;37126;37461.063473413386;38244.61755382411;31969.200769980936;31984.52023219607;32455.837720811243;32551.674483254865;32835.34329313353;19038.809611903704;24202.87898560496;24247;24632.384190395383;25023 05:04:03 INFO - PROCESS | 1669 | |2;jquery.html;397.6560275361813;401.38187191724745;406.1693836105165;410.39876580809107;414.3639513347011;20038.499037524052;20431;20855.791442085592;20883.164673413063;21578.244761433354;11355.205135640508;12435.378231088454;17997.280108795632;21113.68329475059;21131.260405885798;5323.840284791453;5500.6424582402215;6127.448529632332;6134.785282515113;6136.815895523141;3877.8448862045484;4140.1305725797465;4156.771377574232;6071.544634152434;6084.330723620398;3951.4908033398856;4151.501819781615;6123.418275263843;6158.846028849292;6169.845753856144;5186.377634683824;5981.970090149565;6026.0960855871535;6142.201513803199;6181.443670069715;5935.52515798735;5944.910826337608;5951.166836642873;5956.880862382772;5960.4635582797755;10694.732631684192;13619.591412257649;13658.726825463535;13673.589792306248;13685.11046781956;2020.070767446976;2046.5088378789133;2247.1011595361824;2250.5948929192737;2474.9605165830394;9474.242060635133;9927.602895884156;10588.523516441779;11194;15044.774328385083;1693.3565245206867;1697.6010637500178;1753.9649207015916;1772.3885259585552;1817.8000419953794;105516;111485;112279;113338;115699.84300156975;9545.809083818423;9995.85047719507;10740.174170391052;11674.357910314844;11745.29528228309;15018.249087545579;15125.54623361301;15155.696886062438;15159.014664047023;15166.393344266326;7230.349268565749;8375.581220938928;8588.398812083095;8619.956900215584;8709.346798990102;8367.790805229917;8373.455725377831;8389.161083891684;8480.194381533744;8488.74533763988;3885.9028524286555;4619.976900115478;4638.489766125724;4646.186917289461;4663.393758811333;1749.6507351194048;1754.9473515794546;1885.1893685715274;1964.7445832041742;1981.7027445882945;925.4169872979979;933.9252859771202;960.3661583355092;1007.345225603349;1026.3174988632475;20027.699584506245;20919.37241882746;20977.265795697076;21419.678704819442;21634.56730865374;934.7336009237334;953.6757502449201;972.1056627902346;993.5926270229171;1055.831067029287;10932.836007459895;10969.860877129044;11006.174536909764;11265.26775759573;11780.528778848922;639328;640371.7981410156;643679;647748;652052;106720;123547;130861;132168;135856.9621455679;832.4131487301438;967.8257913575483;1065.8827528971963;1072.7479042425025;1078.0513148429488;14823.481178159143;18466.538336541693;18753.718694219326;18849.811501885353;19027.953462559693;32837;48949.5105048945;49518.50481495139;50130;50487.49512504828;69103.65448172727;69805;70757.64621176862;72687;77724.61137694502;7844.64699088531;7893;8038.638261278374;8280.958595206985;8563.743087707378;11215.775684486427;12229.14395992272;12536.247825130522;12906.032047596656;13054;11066.778664426827;11202.831957520806;11235.157363197957;11347;11537.423128843357;10695.144388449062;11010.229283950046;11061.225714199929;11185.832212516656;11680.88319116832;11084.667459976214;11256.774864502828;11612.709682257673;11664.416779161178;11735.941320293685;8273.33813294947;8286.881271028335;8328.458650187718;8411.897025743565;8658.48049117055;9880.297349901277;10808.675739727822;11177.776444470903;11419.37193454368;11557.826632600685 05:04:03 INFO - PROCESS | 1669 | |3;mootools.html;1265.4748279464022;1611.4118346803416;1619.9271032803526;1921.7789954155267;1989.393235063306;7864.252895974881;8589.269912057474;8812.38313318068;8842.69050583229;8880.111988801116;8451.87322190166;8689.131086891308;8692.13078692132;8694.521801300925;8696.869546956801;2137.059693734752;2371.833971621987;2433.3429973907037;2460.458699086198;2474.1216868011866;2474.084588702183;2500.2124330835727;2502.2118032819694;2503.0113105323385;2508.423062695581;2405.8556486610773;2457.336519139831;2458.1765108688523;2458.60662294033;2462.495188486365;2140.6039882621767;2356.9646455303014;2422.0917156066475;2429.9028038878423;2436.951260974771;2427.9878600606885;2451.5587194305017;2453.2394957563033;2462.953244870923;2463.74130716274;8516.361272904494;8562.571871406468;8629;8629.913700862973;8766.991795943484;5161.4322424533275;5371.086915224421;5383.300170977787;5417.674939503642;5417.783288668448;3353;3392.287619599887;3443.1564266754554;3460.048486666162;3471.461923401877;982.4350998176006;998.0468652436978;1006.1447769396028;1009.5709323537541;1011.1354791653177;11567.305961642329;11701.882981170165;11723.531058757555;11736.471858766376;11750.706232344175;4657.371254880615;4750.667453278237;4809.398825146856;4894.535019173244;4897.3878265216845;10049.045340692623;10050.899491004997;10067.395956242648;10070.04334588213;10071.748206294755;5411.918821217685;5420.83737487876;5428.104362780176;5443.237946687467;5451.945480545223;4399.714018588846;4572.108438854458;4781.808727650925;4948.727819969864;4976.129177393942;3913.395507841771;4012.2176175646055;4043.4137050127565;4063.1061166543313;4149.605787450188;1306.875846794553;1324.1062282958965;1325.827642406481;1335.8063080853221;1337.7859542473352;739.2201227704822;840.5923127283263;852.3138873207128;877.8507653698887;888.6356593796512;5362.463753624606;5562.527185189304;5616.747246373841;5617.101263797855;5755.740991655385;437.30250250849843;451.7989494674904;456.792159567396;457.40308896889877;469.6829639992991;3593.2274560968995;3753.3056384568395;3812.1041555234497;3815.2560250751394;3821.9044523887123;10276;10484.10885074762;10615.787684246272;10621.734456638645;10689.518971646294;10566.841497377545;10586.947065264625;10603.681889543326;10644.840327395095;10701.89298107009;7667.616619169131;8139.226773456512;8166.75499734997;8179.732141518074;8183.14077021916;4456.710313829591;5478.233047373451;5487.64330318528;5615.2700148982;5746.339170995389;9548.856767148638;9651.034896510573;9762.75593110178;9785.902140978498;9806.460644664327;9653.324267301503;9926.205903527845;10032.347897386646;10081.647142349982;10099.798004040025;6701.095352127595;6943.166819981635;6944.2708515607155;7222.277772222736;7311.524750891175;7445.851082978202;7464.328210461084;8137.430379873589;8169.428140030379;8172.223638754309;7114.750983715545;7212.531185472927;7213.350798428167;7348.228436014141;7371;7067.858642827217;7123.85752284941;7132.964335178499;7210.89183662235;7228.903627936535;6793.66031698423;6951.339622735832;6956.721731130698;6957.269486704025;6964.582125072512;4649.418822647169;4727.078219747254;4795.112904112751;4842.878928026827;4956.628252880976;4744.691595046311;4774.928376074431;4799.3760811094335;4822.855314340575;4879.365682461257 05:04:03 INFO - PROCESS | 1669 | |4;prototype.html;311.5980385302959;318.38233826376825;327.6625076171543;328.34659028533224;332.69225965981474;4995.4255260644995;5280.471952804718;5634.04221282382;5658.603897727161;5663.546916246701;5071.717727022836;5379.166229234465;5535.806246781363;5566.471185237396;5727.513161381277;4316.913661726763;4338.154059958294;4351.0862718829085;4352.434183556149;4356.150550642626;3629.94555081674;3846.884403522975;3994.5805690402526;4332.371806088116;4373.457499425475;3695.020819482839;4009;4281.7002809803325;4290.356446533014;4301.591348821873;4097.631213190827;4193.727407718488;4213.199492096492;4216.47294088239;4258.829646814123;3092.597962264914;3693.2059607184583;3728.254349130185;3736.532933383327;3740.2519496100617;5245.291885595434;5431.755570999275;5459.781608735686;5477.1510415885605;5484.862878428032;3802.6007269236607;4077;4913.606911447076;4952.777125029383;4974.701517908937;4626.866417727645;4649.418822647169;5881.441263080001;5913.645181289136;5940.762369505214;4087.4277601135864;4093.6111069448357;4108.281050816096;4116.320807066831;4152.211079894811;5902.911456328158;5945.256842894638;6028.336882942873;6310.495160387158;6406.935930640634;4069.959300407017;4110.362893751413;4112.239235741397;4118.434994702054;4126.607972242632;3851.863700208445;3987.960120398759;4062.7765472899273;4191.098913733562;4197.97901010493;4723.409573803275;4876.902461950812;4899.314096026559;4906.1414252505665;4934.8766280843265;5732.598718089694;5938.762449501972;6030.718190085001;6082.2701275847185;6112.144299798032;1371.067673981682;1400.551823416497;1436.547487541421;1436.74138655041;1451.3759083593945;797.529457620007;917.9306108383659;928.9396189247677;941.5339406993583;944.2587568758491;5489.008490372757;5507.586930980113;5511.751971161227;5560.332760068737;5598.944010559842;4679.29205839866;5955.40445955401;6140.263168419729;6143.56995010345;6149.354317796657;4928.802847886044;4950.158473059517;4954.9009019819405;5312.415634280226;5550.944490555124;2725.8773355199064;2738.931526711808;3249.5125731140047;3267.2158681916644;3267.4445344291526;5368.463153684678;5584.972075139598;6424.751337032095;6432.35676432353;6452.257990331078;6135;6278.843028924222;6293.748250070037;6317.557770955989;6335.968320158369;6064.241969753781;6357.650329231937;6382.585131966407;6402.9115050440605;6423.0686550450855;5370.597205209547;5838.386969368155;6113.606948143823;6142.846428839315;6205.913965056006;5450.937067272002;5976.551758618034;6060.515158787198;6088;6090.725917333819;5614.6631202128;6050.6369617823075;6354.52341074412;6401.7119229633845;6430.324815894263;4049.696272779612;4059.594040595917;4082.938755918723;4094.877153685394;4100.5899410058755;5834.445727655866;5986.760529578768;6042.425969532887;6160.383961603804;6164.506839452923;5243.924995376015;5294.8941021180135;5644.472204871955;5953.255843019622;6003.549733769897;6098.146259523759;6098.176746139215;6099.695015249131;6126.540509461897;6135.447809697149;5862.355140934579;5925.1704761332585;5942.821715348547;5956.672383018976;5963.52291816645;5703.258576385043;5834.737436815439;6133.693315334304;6142.447179753844;6162.815115546541;5381.1121165007335;6016.2479690038745;6031.215941927521;6040.456358927718;6045.758169673164 05:04:03 INFO - PROCESS | 1669 | |5;yui.html;648.5589798936722;680.1701923653142;700.597156634935;719.3633634233705;746.8207630168761;13378.063535552516;14470.348834302455;14673.926630366846;15159.545213643594;15171.772423413644;15532.534023979271;15583.298751556178;15605.453809116683;15620.453284135057;15641.061536307801;720.2581341218527;933.2114363362964;934.9902105725834;946.7065209784954;947.5972711597578;745.0388998192325;755.4711701808728;755.8601658693159;763.1376544504702;892.9464232146092;979.5200351827624;983.7196399026279;983.7491439682871;985.7387792235063;986.7286496213562;967.7580604848788;974.3617930255658;974.4786539201564;976.067855198284;976.2873102635081;551.58906614572;698.9405900498464;727.7525641281936;728.8032231297573;729.9927000730031;14828.70342593142;14872.405103795834;14895.255237238203;14906;14912.403503859832;12324.630261092181;12414.489234013472;12455.19041262324;12551.121421500498;12599.37003149848;9578.9521052395;9602.951985240099;9785;9952.701418957442;10055.095041446304;3426.5374174486374;3468.70891925267;3504.859805607798;3539.345221134098;3586.1751797086554;81261;81327.5933620334;83132.75300870431;87154.69267960986;87590;8539.274161696201;9427.481488518197;9435.716928492157;9589.712308630753;9626.229901607854;18424.723629145574;21625;21780.01989910458;21788.56422871534;21835.563288734455;6673.632950187689;7391.519551229153;7551.773446796605;7631.1605723370385;7641.312281894656;4049.149678567534;4081.1837632473625;4111.938320925188;4116.876493705194;4404.537523560044;3590.928181436409;3645.3620616392027;3673.081729567608;3674.7795132291612;3679.300932822755;578.1818726502037;612.8375980365207;626.3923993726079;631.3307893632764;641.4195153386192;535.6518263128997;566.1875209074991;587.1838144978483;589.078092784799;597.8475488750341;7443.9627801861725;7568.243175682498;7624.27569380908;7695.846083078307;8176.918230817616;548.8408361575099;558.7848678258899;568.7724910035936;572.1417873190214;586.823952814154;3249.707526322595;3549.574051113883;3717.739758216953;3727.962720372816;3762.6613604775703;86236.56881715677;92076.07923920809;93142.06857931335;93453.13093738088;93845;75469;76139;76177.03809699454;76374.23625763782;77033.61483192549;53151.468485315425;67238.66380668065;68385.3161468379;68439.65780171067;68799.3120068803;6479.254885688206;7187.712491500281;7276.381507571704;7343.77968660941;7454.962725186339;41374.172516550105;41557.961050973965;42013;42302.576974229865;44612.55387446084;60324.09513857384;62430.687846560475;62542.374576255475;62976;62979;9661;9758.316917815684;10081.092701656886;10517.526711297856;10936.179786516203;11702.941485292518;11761.411929403666;12111.273323600612;12402.689932751393;12992.480300787862;10763.784724305626;10936.453177340942;11169.72075698114;11342.262752921364;12056.698582535506;8624.913750862663;11210;11357.034652054337;11379.658610241706;11509.309441433541;10971.23201375896;11035.227534072537;11054.115670746482;11271.943640281746;11357.602483913024;9422.481763503074;9494.715158545254;9635.036496350309;9724.70825875225;9958.302918795614;8945.821083578423;9463.583953400073;9552;9588.808223835624;9654.324197306401 05:04:03 INFO - PROCESS | 1669 | __end_tp_report 05:04:03 INFO - PROCESS | 1669 | __start_cc_report 05:04:03 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1244 05:04:03 INFO - PROCESS | 1669 | __end_cc_report 05:04:03 INFO - PROCESS | 1669 | __startTimestamp1461240243200__endTimestamp 05:04:03 INFO - PROCESS | 1669 | 05:04:03 INFO - PROCESS | 1669 | ------- Summary: start ------- 05:04:03 INFO - PROCESS | 1669 | Number of tests: 6 05:04:03 INFO - PROCESS | 1669 | 05:04:03 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5791.01 Median:5983.00 stddev:647.42 (10.8%) stddev-sans-first:622.37 05:04:03 INFO - PROCESS | 1669 | Values: 3431.1 3482.9 3526.3 3530.3 3568.4 5657.1 5768.7 5808.7 5811.7 5816.4 5766.4 5798.2 5861.6 5884.6 5925.6 4346.9 5194.0 5229.2 5233.8 5268.1 5321.5 5321.5 5345.0 5379.9 5382.2 5195.8 5284.0 5293.9 5297.2 5314.8 5309.6 5312.8 5313.7 5320.6 5326.2 4354.5 5234.2 5265.1 5268.1 5283.2 5709.8 5749.1 5752.9 5755.2 5758.8 6219.2 6276.5 6325.1 6343.0 6411.7 6158.6 6283.7 6310.0 6322.8 6325.4 5718.5 5765.1 5789.6 5791.4 5803.2 5216.2 6244.8 6256.8 6316.7 6356.1 6173.2 6195.4 6207.4 6219.2 6231.2 6196.8 6342.4 6345.3 6355.3 6380.1 6191.7 6309.8 6312.0 6325.1 6343.7 6310.2 6326.0 6333.2 6335.3 6351.1 4904.5 4992.5 4996.5 5002.2 5059.3 4478.4 4494.5 4509.8 4564.0 4590.5 5663.4 5691.9 5716.7 5719.2 5763.2 6247.5 6268.1 6286.7 6358.7 6384.3 5776.9 5792.9 5802.1 5803.0 5850.0 5476.0 5477.6 5488.5 5489.6 5491.6 6253.2 6306.1 6385.1 6401.6 6441.1 6328.2 6334.0 6349.4 6356.1 6356.6 6390.1 6406.9 6416.5 6418.9 6423.9 5939.2 6178.9 6251.2 6300.3 6334.7 6350.7 6355.8 6358.2 6358.5 6362.3 5057.9 6128.3 6177.3 6198.6 6198.9 5938.5 6099.3 6145.0 6147.6 6186.1 5839.9 5992.9 6010.2 6016.5 6030.7 6037.2 6194.1 6206.6 6225.5 6237.7 5499.3 5606.0 5983.0 5988.3 6096.0 05:04:03 INFO - PROCESS | 1669 | 05:04:03 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:64287.70 Median:19283.32 stddev:140245.59 (727.3%) stddev-sans-first:140557.26 05:04:03 INFO - PROCESS | 1669 | Values: 507.2 530.5 531.7 546.3 575.6 30216.5 37867.4 39330.6 39413.8 39519.8 43762.3 44388.8 44442.3 44481.8 44756.3 8542.3 9683.2 9906.0 9918.8 9930.4 8725.6 11534.1 11827.1 11849.9 11852.3 11563.2 11683.3 11687.0 11688.8 11759.6 8629.0 11526.6 11600.9 11669.8 11914.5 11387.2 11546.3 11549.4 11558.8 11572.6 38571.0 38942.4 38976.8 39054.6 39114.8 2960.9 3215.4 3221.3 3300.3 3452.6 29223.7 29250.4 29262.4 29453.7 29628.7 2654.4 2680.5 2743.6 2791.2 2807.3 462497.0 462534.0 462589.7 462617.0 464720.7 35114.0 60622.7 60899.0 61078.1 61297.0 35392.0 35473.6 35642.6 35670.9 35671.3 14326.6 14660.3 14669.4 14672.9 14680.3 11334.7 11693.8 12257.2 12461.9 12707.1 8094.6 8105.7 8115.6 8204.3 8228.7 3338.6 3388.1 3388.2 3394.7 3427.2 969.5 974.6 983.6 991.5 1073.6 25590.2 29602.7 29973.0 30351.5 30540.7 1123.8 1131.2 1141.9 1179.2 1228.0 13389.1 13817.8 14595.9 15539.7 15884.8 639574.0 648111.0 648223.0 649693.0 649771.0 61718.7 62584.7 62604.0 62636.7 62732.7 1332.3 1419.7 1495.5 1506.6 1541.0 27317.0 28071.5 29774.6 29855.4 30782.8 50395.7 50607.0 51052.7 51083.2 51210.5 433727.8 438675.0 438870.0 438983.0 440082.0 23567.4 23729.4 24645.8 27019.9 27424.0 42615.4 42891.8 42911.0 42928.4 44165.6 37007.0 37314.6 37460.9 37685.6 38306.6 36911.0 36912.3 37394.6 37739.0 37899.8 30645.6 37069.6 37126.0 37461.1 38244.6 31969.2 31984.5 32455.8 32551.7 32835.3 19038.8 24202.9 24247.0 24632.4 25023.0 05:04:03 INFO - PROCESS | 1669 | 05:04:03 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:35290.71 Median:21498.96 stddev:107010.52 (497.7%) stddev-sans-first:107278.64 05:04:03 INFO - PROCESS | 1669 | Values: 397.7 401.4 406.2 410.4 414.4 20038.5 20431.0 20855.8 20883.2 21578.2 11355.2 12435.4 17997.3 21113.7 21131.3 5323.8 5500.6 6127.4 6134.8 6136.8 3877.8 4140.1 4156.8 6071.5 6084.3 3951.5 4151.5 6123.4 6158.8 6169.8 5186.4 5982.0 6026.1 6142.2 6181.4 5935.5 5944.9 5951.2 5956.9 5960.5 10694.7 13619.6 13658.7 13673.6 13685.1 2020.1 2046.5 2247.1 2250.6 2475.0 9474.2 9927.6 10588.5 11194.0 15044.8 1693.4 1697.6 1754.0 1772.4 1817.8 105516.0 111485.0 112279.0 113338.0 115699.8 9545.8 9995.9 10740.2 11674.4 11745.3 15018.2 15125.5 15155.7 15159.0 15166.4 7230.3 8375.6 8588.4 8620.0 8709.3 8367.8 8373.5 8389.2 8480.2 8488.7 3885.9 4620.0 4638.5 4646.2 4663.4 1749.7 1754.9 1885.2 1964.7 1981.7 925.4 933.9 960.4 1007.3 1026.3 20027.7 20919.4 20977.3 21419.7 21634.6 934.7 953.7 972.1 993.6 1055.8 10932.8 10969.9 11006.2 11265.3 11780.5 639328.0 640371.8 643679.0 647748.0 652052.0 106720.0 123547.0 130861.0 132168.0 135857.0 832.4 967.8 1065.9 1072.7 1078.1 14823.5 18466.5 18753.7 18849.8 19028.0 32837.0 48949.5 49518.5 50130.0 50487.5 69103.7 69805.0 70757.6 72687.0 77724.6 7844.6 7893.0 8038.6 8281.0 8563.7 11215.8 12229.1 12536.2 12906.0 13054.0 11066.8 11202.8 11235.2 11347.0 11537.4 10695.1 11010.2 11061.2 11185.8 11680.9 11084.7 11256.8 11612.7 11664.4 11735.9 8273.3 8286.9 8328.5 8411.9 8658.5 9880.3 10808.7 11177.8 11419.4 11557.8 05:04:03 INFO - PROCESS | 1669 | 05:04:03 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5618.32 Median:4788.46 stddev:3165.23 (66.1%) stddev-sans-first:3157.20 05:04:03 INFO - PROCESS | 1669 | Values: 1265.5 1611.4 1619.9 1921.8 1989.4 7864.3 8589.3 8812.4 8842.7 8880.1 8451.9 8689.1 8692.1 8694.5 8696.9 2137.1 2371.8 2433.3 2460.5 2474.1 2474.1 2500.2 2502.2 2503.0 2508.4 2405.9 2457.3 2458.2 2458.6 2462.5 2140.6 2357.0 2422.1 2429.9 2437.0 2428.0 2451.6 2453.2 2463.0 2463.7 8516.4 8562.6 8629.0 8629.9 8767.0 5161.4 5371.1 5383.3 5417.7 5417.8 3353.0 3392.3 3443.2 3460.0 3471.5 982.4 998.0 1006.1 1009.6 1011.1 11567.3 11701.9 11723.5 11736.5 11750.7 4657.4 4750.7 4809.4 4894.5 4897.4 10049.0 10050.9 10067.4 10070.0 10071.7 5411.9 5420.8 5428.1 5443.2 5451.9 4399.7 4572.1 4781.8 4948.7 4976.1 3913.4 4012.2 4043.4 4063.1 4149.6 1306.9 1324.1 1325.8 1335.8 1337.8 739.2 840.6 852.3 877.9 888.6 5362.5 5562.5 5616.7 5617.1 5755.7 437.3 451.8 456.8 457.4 469.7 3593.2 3753.3 3812.1 3815.3 3821.9 10276.0 10484.1 10615.8 10621.7 10689.5 10566.8 10586.9 10603.7 10644.8 10701.9 7667.6 8139.2 8166.8 8179.7 8183.1 4456.7 5478.2 5487.6 5615.3 5746.3 9548.9 9651.0 9762.8 9785.9 9806.5 9653.3 9926.2 10032.3 10081.6 10099.8 6701.1 6943.2 6944.3 7222.3 7311.5 7445.9 7464.3 8137.4 8169.4 8172.2 7114.8 7212.5 7213.4 7348.2 7371.0 7067.9 7123.9 7133.0 7210.9 7228.9 6793.7 6951.3 6956.7 6957.3 6964.6 4649.4 4727.1 4795.1 4842.9 4956.6 4744.7 4774.9 4799.4 4822.9 4879.4 05:04:03 INFO - PROCESS | 1669 | 05:04:03 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4843.28 Median:5481.01 stddev:1511.72 (27.6%) stddev-sans-first:1477.20 05:04:03 INFO - PROCESS | 1669 | Values: 311.6 318.4 327.7 328.3 332.7 4995.4 5280.5 5634.0 5658.6 5663.5 5071.7 5379.2 5535.8 5566.5 5727.5 4316.9 4338.2 4351.1 4352.4 4356.2 3629.9 3846.9 3994.6 4332.4 4373.5 3695.0 4009.0 4281.7 4290.4 4301.6 4097.6 4193.7 4213.2 4216.5 4258.8 3092.6 3693.2 3728.3 3736.5 3740.3 5245.3 5431.8 5459.8 5477.2 5484.9 3802.6 4077.0 4913.6 4952.8 4974.7 4626.9 4649.4 5881.4 5913.6 5940.8 4087.4 4093.6 4108.3 4116.3 4152.2 5902.9 5945.3 6028.3 6310.5 6406.9 4070.0 4110.4 4112.2 4118.4 4126.6 3851.9 3988.0 4062.8 4191.1 4198.0 4723.4 4876.9 4899.3 4906.1 4934.9 5732.6 5938.8 6030.7 6082.3 6112.1 1371.1 1400.6 1436.5 1436.7 1451.4 797.5 917.9 928.9 941.5 944.3 5489.0 5507.6 5511.8 5560.3 5598.9 4679.3 5955.4 6140.3 6143.6 6149.4 4928.8 4950.2 4954.9 5312.4 5550.9 2725.9 2738.9 3249.5 3267.2 3267.4 5368.5 5585.0 6424.8 6432.4 6452.3 6135.0 6278.8 6293.7 6317.6 6336.0 6064.2 6357.7 6382.6 6402.9 6423.1 5370.6 5838.4 6113.6 6142.8 6205.9 5450.9 5976.6 6060.5 6088.0 6090.7 5614.7 6050.6 6354.5 6401.7 6430.3 4049.7 4059.6 4082.9 4094.9 4100.6 5834.4 5986.8 6042.4 6160.4 6164.5 5243.9 5294.9 5644.5 5953.3 6003.5 6098.1 6098.2 6099.7 6126.5 6135.4 5862.4 5925.2 5942.8 5956.7 5963.5 5703.3 5834.7 6133.7 6142.4 6162.8 5381.1 6016.2 6031.2 6040.5 6045.8 05:04:03 INFO - PROCESS | 1669 | 05:04:03 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:17728.92 Median:31801.20 stddev:25045.77 (78.8%) stddev-sans-first:25083.19 05:04:03 INFO - PROCESS | 1669 | Values: 648.6 680.2 700.6 719.4 746.8 13378.1 14470.3 14673.9 15159.5 15171.8 15532.5 15583.3 15605.5 15620.5 15641.1 720.3 933.2 935.0 946.7 947.6 745.0 755.5 755.9 763.1 892.9 979.5 983.7 983.7 985.7 986.7 967.8 974.4 974.5 976.1 976.3 551.6 698.9 727.8 728.8 730.0 14828.7 14872.4 14895.3 14906.0 14912.4 12324.6 12414.5 12455.2 12551.1 12599.4 9579.0 9603.0 9785.0 9952.7 10055.1 3426.5 3468.7 3504.9 3539.3 3586.2 81261.0 81327.6 83132.8 87154.7 87590.0 8539.3 9427.5 9435.7 9589.7 9626.2 18424.7 21625.0 21780.0 21788.6 21835.6 6673.6 7391.5 7551.8 7631.2 7641.3 4049.1 4081.2 4111.9 4116.9 4404.5 3590.9 3645.4 3673.1 3674.8 3679.3 578.2 612.8 626.4 631.3 641.4 535.7 566.2 587.2 589.1 597.8 7444.0 7568.2 7624.3 7695.8 8176.9 548.8 558.8 568.8 572.1 586.8 3249.7 3549.6 3717.7 3728.0 3762.7 86236.6 92076.1 93142.1 93453.1 93845.0 75469.0 76139.0 76177.0 76374.2 77033.6 53151.5 67238.7 68385.3 68439.7 68799.3 6479.3 7187.7 7276.4 7343.8 7455.0 41374.2 41558.0 42013.0 42302.6 44612.6 60324.1 62430.7 62542.4 62976.0 62979.0 9661.0 9758.3 10081.1 10517.5 10936.2 11702.9 11761.4 12111.3 12402.7 12992.5 10763.8 10936.5 11169.7 11342.3 12056.7 8624.9 11210.0 11357.0 11379.7 11509.3 10971.2 11035.2 11054.1 11271.9 11357.6 9422.5 9494.7 9635.0 9724.7 9958.3 8945.8 9463.6 9552.0 9588.8 9654.3 05:04:03 INFO - PROCESS | 1669 | -------- Summary: end -------- 05:04:03 INFO - PROCESS | 1669 | 05:04:03 INFO - TEST-INFO | 1669: exit 0 05:04:03 INFO - TEST-OK | dromaeo_css | took 1115846ms 05:04:03 INFO - TEST-START | kraken 05:04:03 INFO - operating with platform_type : mac_ 05:04:03 INFO - Initialising browser for kraken test... 05:04:03 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpHfjm2W/profile http://localhost:49277/getInfo.html) 05:04:05 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 05:04:05 INFO - PROCESS | 1675 | colorDepth:24 05:04:05 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 05:04:05 INFO - PROCESS | 1675 | __metrics 05:04:06 INFO - PROCESS | 1675 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:04:06 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:04:06 INFO - TEST-INFO | 1675: exit 0 05:04:06 INFO - Browser initialized. 05:04:06 INFO - Running cycle 1/1 for kraken test... 05:04:06 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpHfjm2W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:18 INFO - PROCESS | 1677 | RSS: Main: 238641152 05:04:18 INFO - PROCESS | 1677 | 05:04:21 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 05:04:22 INFO - PROCESS | 1677 | RSS: Main: 286457856 05:04:22 INFO - PROCESS | 1677 | 05:04:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 05:04:25 INFO - PROCESS | 1677 | RSS: Main: 299552768 05:04:25 INFO - PROCESS | 1677 | 05:04:29 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 05:04:29 INFO - PROCESS | 1677 | RSS: Main: 386306048 05:04:29 INFO - PROCESS | 1677 | 05:04:32 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 05:04:32 INFO - PROCESS | 1677 | RSS: Main: 284569600 05:04:32 INFO - PROCESS | 1677 | 05:04:34 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:04:35 INFO - PROCESS | 1677 | RSS: Main: 288624640 05:04:35 INFO - PROCESS | 1677 | 05:04:38 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 05:04:38 INFO - PROCESS | 1677 | RSS: Main: 326754304 05:04:38 INFO - PROCESS | 1677 | 05:04:41 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 05:04:42 INFO - PROCESS | 1677 | RSS: Main: 329097216 05:04:42 INFO - PROCESS | 1677 | 05:04:45 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 05:04:45 INFO - PROCESS | 1677 | RSS: Main: 326172672 05:04:45 INFO - PROCESS | 1677 | 05:04:47 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:04:48 INFO - PROCESS | 1677 | RSS: Main: 320090112 05:04:48 INFO - PROCESS | 1677 | 05:04:49 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:04:50 INFO - PROCESS | 1677 | RSS: Main: 326778880 05:04:50 INFO - PROCESS | 1677 | 05:04:52 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:04:53 INFO - PROCESS | 1677 | RSS: Main: 330153984 05:04:53 INFO - PROCESS | 1677 | 05:04:56 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:04:56 INFO - PROCESS | 1677 | RSS: Main: 334532608 05:04:56 INFO - PROCESS | 1677 | 05:04:59 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:05:00 INFO - PROCESS | 1677 | RSS: Main: 331956224 05:05:00 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 05:05:02 INFO - PROCESS | 1677 | RSS: Main: 397705216 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | __start_tp_report 05:05:02 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 05:05:02 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 05:05:02 INFO - PROCESS | 1677 | |i|pagename|runs| 05:05:02 INFO - PROCESS | 1677 | |0;ai-astar;119;156;165;112;113;112;121;111;111;112 05:05:02 INFO - PROCESS | 1677 | |1;audio-beat-detection;167;149;150;171;119;140;139;121;173;118 05:05:02 INFO - PROCESS | 1677 | |2;audio-dft;188;184;188;186;185;184;226;193;181;183 05:05:02 INFO - PROCESS | 1677 | |3;audio-fft;78;75;90;99;83;83;83;80;84;55 05:05:02 INFO - PROCESS | 1677 | |4;audio-oscillator;90;101;135;78;78;79;128;102;114;169 05:05:02 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;88;84;86;83;86;84;87;84;90;128 05:05:02 INFO - PROCESS | 1677 | |6;imaging-darkroom;93;92;93;93;93;92;93;93;93;93 05:05:02 INFO - PROCESS | 1677 | |7;imaging-desaturate;84;86;84;84;86;86;84;84;84;84 05:05:02 INFO - PROCESS | 1677 | |8;json-parse-financial;61;59;58;58;58;57;69;95;90;67 05:05:02 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;64;73;64;74;72;70;70;68;68;67 05:05:02 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;82;81;82;95;105;106;104;82;83;83 05:05:02 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;139;140;140;138;137;136;136;141;194;130 05:05:02 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;171;171;169;171;169;172;168;174;225;194 05:05:02 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;69;68;69;68;69;69;68;69;69;84 05:05:02 INFO - PROCESS | 1677 | __end_tp_report 05:05:02 INFO - PROCESS | 1677 | __start_cc_report 05:05:02 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1984 05:05:02 INFO - PROCESS | 1677 | __end_cc_report 05:05:02 INFO - PROCESS | 1677 | __startTimestamp1461240302596__endTimestamp 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | ------- Summary: start ------- 05:05:02 INFO - PROCESS | 1677 | Number of tests: 14 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:123.20 Median:116.00 stddev:20.07 (17.3%) stddev-sans-first:21.22 05:05:02 INFO - PROCESS | 1677 | Values: 119.0 156.0 165.0 112.0 113.0 112.0 121.0 111.0 111.0 112.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:149.50 stddev:21.15 (14.1%) stddev-sans-first:20.84 05:05:02 INFO - PROCESS | 1677 | Values: 167.0 149.0 150.0 171.0 119.0 140.0 139.0 121.0 173.0 118.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:189.80 Median:187.00 stddev:13.15 (7.0%) stddev-sans-first:13.93 05:05:02 INFO - PROCESS | 1677 | Values: 188.0 184.0 188.0 186.0 185.0 184.0 226.0 193.0 181.0 183.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:81.00 Median:83.00 stddev:11.29 (13.6%) stddev-sans-first:11.93 05:05:02 INFO - PROCESS | 1677 | Values: 78.0 75.0 90.0 99.0 83.0 83.0 83.0 80.0 84.0 55.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:107.40 Median:123.50 stddev:29.69 (24.0%) stddev-sans-first:30.81 05:05:02 INFO - PROCESS | 1677 | Values: 90.0 101.0 135.0 78.0 78.0 79.0 128.0 102.0 114.0 169.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:86.00 stddev:13.52 (15.7%) stddev-sans-first:14.32 05:05:02 INFO - PROCESS | 1677 | Values: 88.0 84.0 86.0 83.0 86.0 84.0 87.0 84.0 90.0 128.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 05:05:02 INFO - PROCESS | 1677 | Values: 93.0 92.0 93.0 93.0 93.0 92.0 93.0 93.0 93.0 93.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.00 stddev:0.97 (1.2%) stddev-sans-first:1.00 05:05:02 INFO - PROCESS | 1677 | Values: 84.0 86.0 84.0 84.0 86.0 86.0 84.0 84.0 84.0 84.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:64.00 stddev:13.98 (21.8%) stddev-sans-first:14.65 05:05:02 INFO - PROCESS | 1677 | Values: 61.0 59.0 58.0 58.0 58.0 57.0 69.0 95.0 90.0 67.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:70.00 stddev:3.46 (4.9%) stddev-sans-first:3.17 05:05:02 INFO - PROCESS | 1677 | Values: 64.0 73.0 64.0 74.0 72.0 70.0 70.0 68.0 68.0 67.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:82.00 stddev:10.91 (13.3%) stddev-sans-first:11.16 05:05:02 INFO - PROCESS | 1677 | Values: 82.0 81.0 82.0 95.0 105.0 106.0 104.0 82.0 83.0 83.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:139.50 stddev:18.16 (13.0%) stddev-sans-first:19.20 05:05:02 INFO - PROCESS | 1677 | Values: 139.0 140.0 140.0 138.0 137.0 136.0 136.0 141.0 194.0 130.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:171.50 stddev:18.03 (10.5%) stddev-sans-first:18.92 05:05:02 INFO - PROCESS | 1677 | Values: 171.0 171.0 169.0 171.0 169.0 172.0 168.0 174.0 225.0 194.0 05:05:02 INFO - PROCESS | 1677 | 05:05:02 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:69.00 stddev:4.87 (7.1%) stddev-sans-first:5.15 05:05:02 INFO - PROCESS | 1677 | Values: 69.0 68.0 69.0 68.0 69.0 69.0 68.0 69.0 69.0 84.0 05:05:02 INFO - PROCESS | 1677 | -------- Summary: end -------- 05:05:02 INFO - PROCESS | 1677 | 05:05:03 INFO - TEST-INFO | 1677: exit 0 05:05:03 INFO - TEST-OK | kraken | took 59763ms 05:05:03 INFO - SUITE-END | took 1175s 05:05:03 INFO - Completed test suite (00:19:35) 05:05:03 INFO - javascript benchmark 05:05:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3431.107911942895, 3482.895513134607, 3526.2947410517904, 3530.3115892400983, 3568.446890731936, 5657.094864821629, 5768.682722450264, 5808.680522571262, 5811.709414529272, 5816.360200377961, 5766.423357664232, 5798.188253644483, 5861.618994765348, 5884.646921184722, 5925.585209035369, 4346.934795978047, 5193.9740301298625, 5229.189475631283, 5233.764480598363, 5268.0517506848755, 5321.467853214686, 5321.547668448186, 5344.984452953946, 5379.865503362408, 5382.2195781611645, 5195.766190521435, 5284.048871203182, 5293.86765330866, 5297.231901374318, 5314.76083576238, 5309.575233981311, 5312.76092575835, 5313.734313284359, 5320.6275560710765, 5326.174442961308, 4354.477462704495, 5234.18870075139, 5265.131253343194, 5268.130758424893, 5283.233931079972, 5709.77160913563, 5749.108888122348, 5752.942470575283, 5755.194272801811, 5758.79844205455, 6219.1915051043525, 6276.497880169622, 6325.051242313643, 6343.0, 6411.743530258784, 6158.56890017699, 6283.654399008098, 6309.968450157719, 6322.810315690536, 6325.430711236011, 5718.456746609066, 5765.106408506688, 5789.623674461231, 5791.449812267828, 5803.245578074865, 5216.21756736485, 6244.781432649836, 6256.7810126645345, 6316.652584107918, 6356.078368636614, 6173.228346456693, 6195.411435913581, 6207.441330280297, 6219.222597175353, 6231.22109736283, 6196.81409557714, 6342.429181373698, 6345.302016778151, 6355.3326900674765, 6380.106785050004, 6191.690415479208, 6309.779157729457, 6311.990081586924, 6325.05124231369, 6343.682815859281, 6310.1796766420075, 6325.968370158212, 6333.240011198685, 6335.334789847092, 6351.110844481776, 4904.509549045139, 4992.525710057539, 4996.4503904570465, 5002.249662550574, 5059.342285502861, 4478.373027776114, 4494.483134439582, 4509.751963641966, 4563.977180114144, 4590.540945905383, 5663.433656634386, 5691.857703557444, 5716.714164291769, 5719.19931209631, 5763.164341170591, 6247.468965138014, 6268.122462855204, 6286.717097730613, 6358.682065896686, 6384.265809431975, 5776.855578610568, 5792.94207057924, 5802.071668533099, 5803.0, 5850.0, 5476.0, 5477.616566840363, 5488.4785945335125, 5489.560835133179, 5491.560675145979, 6253.218347706536, 6306.085617585332, 6385.10608514799, 6401.615903045832, 6441.130447389636, 6328.177336946167, 6334.049892515975, 6349.396807303298, 6356.11014457967, 6356.55504114726, 6390.13733146038, 6406.903896441464, 6416.48668106541, 6418.935810641834, 6423.935760642334, 5939.16851640762, 6178.907316390348, 6251.156093927261, 6300.338464461165, 6334.6832658365975, 6350.7142178603, 6355.824172528097, 6358.1734374531015, 6358.459530939922, 6362.268339140873, 5057.898842023213, 6128.264608247039, 6177.2587289525545, 6198.597091189058, 6198.876022479615, 5938.524918006636, 6099.329073801967, 6144.986077297196, 6147.631142131487, 6186.1030150627, 5839.941600583939, 5992.850178745566, 6010.218671572842, 6016.5487588431915, 6030.728617212324, 6037.15479832815, 6194.101855231056, 6206.596571222856, 6225.470834979077, 6237.7193026314835, 5499.257600223918, 5605.990921633982, 5983.0, 5988.251468566429, 6095.969520152371], "value": 5751.347572634495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.23914128806786, 530.5384315645389, 531.6570811826372, 546.3225600255682, 575.5855783835636, 30216.546751798713, 37867.43198852016, 39330.60669393302, 39413.802930985374, 39519.80240098795, 43762.34356484647, 44388.77805610975, 44442.33336499947, 44481.77759111208, 44756.3286550702, 8542.273906717937, 9683.176929960944, 9906.009399060109, 9918.801623967516, 9930.354526955725, 8725.563721813916, 11534.077273818075, 11827.112966527546, 11849.881501184964, 11852.288862668225, 11563.248388854698, 11683.299002059903, 11687.0, 11688.824667629993, 11759.647210583697, 8628.956855215747, 11526.596569120082, 11600.941995290053, 11669.824952625717, 11914.46384912681, 11387.202895797298, 11546.307221566733, 11549.364784936824, 11558.826617600742, 11572.59495917639, 38571.03572410684, 38942.415863762064, 38976.830695079196, 39054.60945390538, 39114.80442597797, 2960.911172664802, 3215.3569286142865, 3221.291315910496, 3300.3069355435387, 3452.637473065317, 29223.70776292231, 29250.414991700047, 29262.41475170506, 29453.705462945312, 29628.70371296281, 2654.4160284737327, 2680.5309070912513, 2743.5884617307365, 2791.2463634818487, 2807.2841425436586, 462497.0, 462534.0, 462589.68705156597, 462617.0, 464720.67639661586, 35114.0, 60622.69688651617, 60899.0, 61078.0838287426, 61297.0, 35392.0, 35473.645263547034, 35642.64357356445, 35670.92987210388, 35671.28657426837, 14326.641833954027, 14660.266986650624, 14669.413223470941, 14672.926635366755, 14680.339384727713, 11334.716632084099, 11693.76612467763, 12257.203281786655, 12461.87538124607, 12707.110502264937, 8094.623913934645, 8105.716299929474, 8115.594220288962, 8204.261616454547, 8228.670853165806, 3338.6160591532284, 3388.0682812226837, 3388.22070923684, 3394.6605339465855, 3427.2288735034417, 969.486172328667, 974.5565767575736, 983.5868935047297, 991.4745185051934, 1073.6295977887617, 25590.23229303124, 29602.703972959996, 29972.95094671676, 30351.544726829114, 30540.694593054228, 1123.8145705958589, 1131.2477202660139, 1141.9143564232716, 1179.2099293473393, 1228.0237211416945, 13389.129706569236, 13817.792733109012, 14595.92702036483, 15539.689206216039, 15884.76172857408, 639574.0, 648111.0, 648223.0, 649693.0, 649771.0, 61718.69140654358, 62584.687076565235, 62604.0, 62636.74726505535, 62732.686336568026, 1332.2739107186412, 1419.6717383491082, 1495.5139579636443, 1506.623344163959, 1541.0, 27317.0, 28071.456069916352, 29774.553381698857, 29855.402891941605, 30782.846085769426, 50395.74802125966, 50606.963582185184, 51052.74473627608, 51083.23375149301, 51210.48789512057, 433727.83136084117, 438675.0, 438870.0, 438983.0, 440082.0, 23567.41081472977, 23729.406764831016, 24645.753542464347, 27019.91920323165, 27424.040158594355, 42615.360769589104, 42891.785541072095, 42911.0, 42928.35607465828, 44165.558344416146, 37007.0, 37314.6268537322, 37460.94589663236, 37685.623143768215, 38306.61693383031, 36911.0, 36912.323385415635, 37394.62605373911, 37739.0, 37899.81050094732, 30645.62898081254, 37069.62930370662, 37126.0, 37461.063473413386, 38244.61755382411, 31969.200769980936, 31984.52023219607, 32455.837720811243, 32551.674483254865, 32835.34329313353, 19038.809611903704, 24202.87898560496, 24247.0, 24632.384190395383, 25023.0], "value": 18609.208450991282, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.6560275361813, 401.38187191724745, 406.1693836105165, 410.39876580809107, 414.3639513347011, 20038.499037524052, 20431.0, 20855.791442085592, 20883.164673413063, 21578.244761433354, 11355.205135640508, 12435.378231088454, 17997.280108795632, 21113.68329475059, 21131.260405885798, 5323.840284791453, 5500.6424582402215, 6127.448529632332, 6134.785282515113, 6136.815895523141, 3877.8448862045484, 4140.1305725797465, 4156.771377574232, 6071.544634152434, 6084.330723620398, 3951.4908033398856, 4151.501819781615, 6123.418275263843, 6158.846028849292, 6169.845753856144, 5186.377634683824, 5981.970090149565, 6026.0960855871535, 6142.201513803199, 6181.443670069715, 5935.52515798735, 5944.910826337608, 5951.166836642873, 5956.880862382772, 5960.4635582797755, 10694.732631684192, 13619.591412257649, 13658.726825463535, 13673.589792306248, 13685.11046781956, 2020.070767446976, 2046.5088378789133, 2247.1011595361824, 2250.5948929192737, 2474.9605165830394, 9474.242060635133, 9927.602895884156, 10588.523516441779, 11194.0, 15044.774328385083, 1693.3565245206867, 1697.6010637500178, 1753.9649207015916, 1772.3885259585552, 1817.8000419953794, 105516.0, 111485.0, 112279.0, 113338.0, 115699.84300156975, 9545.809083818423, 9995.85047719507, 10740.174170391052, 11674.357910314844, 11745.29528228309, 15018.249087545579, 15125.54623361301, 15155.696886062438, 15159.014664047023, 15166.393344266326, 7230.349268565749, 8375.581220938928, 8588.398812083095, 8619.956900215584, 8709.346798990102, 8367.790805229917, 8373.455725377831, 8389.161083891684, 8480.194381533744, 8488.74533763988, 3885.9028524286555, 4619.976900115478, 4638.489766125724, 4646.186917289461, 4663.393758811333, 1749.6507351194048, 1754.9473515794546, 1885.1893685715274, 1964.7445832041742, 1981.7027445882945, 925.4169872979979, 933.9252859771202, 960.3661583355092, 1007.345225603349, 1026.3174988632475, 20027.699584506245, 20919.37241882746, 20977.265795697076, 21419.678704819442, 21634.56730865374, 934.7336009237334, 953.6757502449201, 972.1056627902346, 993.5926270229171, 1055.831067029287, 10932.836007459895, 10969.860877129044, 11006.174536909764, 11265.26775759573, 11780.528778848922, 639328.0, 640371.7981410156, 643679.0, 647748.0, 652052.0, 106720.0, 123547.0, 130861.0, 132168.0, 135856.9621455679, 832.4131487301438, 967.8257913575483, 1065.8827528971963, 1072.7479042425025, 1078.0513148429488, 14823.481178159143, 18466.538336541693, 18753.718694219326, 18849.811501885353, 19027.953462559693, 32837.0, 48949.5105048945, 49518.50481495139, 50130.0, 50487.49512504828, 69103.65448172727, 69805.0, 70757.64621176862, 72687.0, 77724.61137694502, 7844.64699088531, 7893.0, 8038.638261278374, 8280.958595206985, 8563.743087707378, 11215.775684486427, 12229.14395992272, 12536.247825130522, 12906.032047596656, 13054.0, 11066.778664426827, 11202.831957520806, 11235.157363197957, 11347.0, 11537.423128843357, 10695.144388449062, 11010.229283950046, 11061.225714199929, 11185.832212516656, 11680.88319116832, 11084.667459976214, 11256.774864502828, 11612.709682257673, 11664.416779161178, 11735.941320293685, 8273.33813294947, 8286.881271028335, 8328.458650187718, 8411.897025743565, 8658.48049117055, 9880.297349901277, 10808.675739727822, 11177.776444470903, 11419.37193454368, 11557.826632600685], "value": 9274.880717540289, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1265.4748279464022, 1611.4118346803416, 1619.9271032803526, 1921.7789954155267, 1989.393235063306, 7864.252895974881, 8589.269912057474, 8812.38313318068, 8842.69050583229, 8880.111988801116, 8451.87322190166, 8689.131086891308, 8692.13078692132, 8694.521801300925, 8696.869546956801, 2137.059693734752, 2371.833971621987, 2433.3429973907037, 2460.458699086198, 2474.1216868011866, 2474.084588702183, 2500.2124330835727, 2502.2118032819694, 2503.0113105323385, 2508.423062695581, 2405.8556486610773, 2457.336519139831, 2458.1765108688523, 2458.60662294033, 2462.495188486365, 2140.6039882621767, 2356.9646455303014, 2422.0917156066475, 2429.9028038878423, 2436.951260974771, 2427.9878600606885, 2451.5587194305017, 2453.2394957563033, 2462.953244870923, 2463.74130716274, 8516.361272904494, 8562.571871406468, 8629.0, 8629.913700862973, 8766.991795943484, 5161.4322424533275, 5371.086915224421, 5383.300170977787, 5417.674939503642, 5417.783288668448, 3353.0, 3392.287619599887, 3443.1564266754554, 3460.048486666162, 3471.461923401877, 982.4350998176006, 998.0468652436978, 1006.1447769396028, 1009.5709323537541, 1011.1354791653177, 11567.305961642329, 11701.882981170165, 11723.531058757555, 11736.471858766376, 11750.706232344175, 4657.371254880615, 4750.667453278237, 4809.398825146856, 4894.535019173244, 4897.3878265216845, 10049.045340692623, 10050.899491004997, 10067.395956242648, 10070.04334588213, 10071.748206294755, 5411.918821217685, 5420.83737487876, 5428.104362780176, 5443.237946687467, 5451.945480545223, 4399.714018588846, 4572.108438854458, 4781.808727650925, 4948.727819969864, 4976.129177393942, 3913.395507841771, 4012.2176175646055, 4043.4137050127565, 4063.1061166543313, 4149.605787450188, 1306.875846794553, 1324.1062282958965, 1325.827642406481, 1335.8063080853221, 1337.7859542473352, 739.2201227704822, 840.5923127283263, 852.3138873207128, 877.8507653698887, 888.6356593796512, 5362.463753624606, 5562.527185189304, 5616.747246373841, 5617.101263797855, 5755.740991655385, 437.30250250849843, 451.7989494674904, 456.792159567396, 457.40308896889877, 469.6829639992991, 3593.2274560968995, 3753.3056384568395, 3812.1041555234497, 3815.2560250751394, 3821.9044523887123, 10276.0, 10484.10885074762, 10615.787684246272, 10621.734456638645, 10689.518971646294, 10566.841497377545, 10586.947065264625, 10603.681889543326, 10644.840327395095, 10701.89298107009, 7667.616619169131, 8139.226773456512, 8166.75499734997, 8179.732141518074, 8183.14077021916, 4456.710313829591, 5478.233047373451, 5487.64330318528, 5615.2700148982, 5746.339170995389, 9548.856767148638, 9651.034896510573, 9762.75593110178, 9785.902140978498, 9806.460644664327, 9653.324267301503, 9926.205903527845, 10032.347897386646, 10081.647142349982, 10099.798004040025, 6701.095352127595, 6943.166819981635, 6944.2708515607155, 7222.277772222736, 7311.524750891175, 7445.851082978202, 7464.328210461084, 8137.430379873589, 8169.428140030379, 8172.223638754309, 7114.750983715545, 7212.531185472927, 7213.350798428167, 7348.228436014141, 7371.0, 7067.858642827217, 7123.85752284941, 7132.964335178499, 7210.89183662235, 7228.903627936535, 6793.66031698423, 6951.339622735832, 6956.721731130698, 6957.269486704025, 6964.582125072512, 4649.418822647169, 4727.078219747254, 4795.112904112751, 4842.878928026827, 4956.628252880976, 4744.691595046311, 4774.928376074431, 4799.3760811094335, 4822.855314340575, 4879.365682461257], "value": 4451.739913421346, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.5980385302959, 318.38233826376825, 327.6625076171543, 328.34659028533224, 332.69225965981474, 4995.4255260644995, 5280.471952804718, 5634.04221282382, 5658.603897727161, 5663.546916246701, 5071.717727022836, 5379.166229234465, 5535.806246781363, 5566.471185237396, 5727.513161381277, 4316.913661726763, 4338.154059958294, 4351.0862718829085, 4352.434183556149, 4356.150550642626, 3629.94555081674, 3846.884403522975, 3994.5805690402526, 4332.371806088116, 4373.457499425475, 3695.020819482839, 4009.0, 4281.7002809803325, 4290.356446533014, 4301.591348821873, 4097.631213190827, 4193.727407718488, 4213.199492096492, 4216.47294088239, 4258.829646814123, 3092.597962264914, 3693.2059607184583, 3728.254349130185, 3736.532933383327, 3740.2519496100617, 5245.291885595434, 5431.755570999275, 5459.781608735686, 5477.1510415885605, 5484.862878428032, 3802.6007269236607, 4077.0, 4913.606911447076, 4952.777125029383, 4974.701517908937, 4626.866417727645, 4649.418822647169, 5881.441263080001, 5913.645181289136, 5940.762369505214, 4087.4277601135864, 4093.6111069448357, 4108.281050816096, 4116.320807066831, 4152.211079894811, 5902.911456328158, 5945.256842894638, 6028.336882942873, 6310.495160387158, 6406.935930640634, 4069.959300407017, 4110.362893751413, 4112.239235741397, 4118.434994702054, 4126.607972242632, 3851.863700208445, 3987.960120398759, 4062.7765472899273, 4191.098913733562, 4197.97901010493, 4723.409573803275, 4876.902461950812, 4899.314096026559, 4906.1414252505665, 4934.8766280843265, 5732.598718089694, 5938.762449501972, 6030.718190085001, 6082.2701275847185, 6112.144299798032, 1371.067673981682, 1400.551823416497, 1436.547487541421, 1436.74138655041, 1451.3759083593945, 797.529457620007, 917.9306108383659, 928.9396189247677, 941.5339406993583, 944.2587568758491, 5489.008490372757, 5507.586930980113, 5511.751971161227, 5560.332760068737, 5598.944010559842, 4679.29205839866, 5955.40445955401, 6140.263168419729, 6143.56995010345, 6149.354317796657, 4928.802847886044, 4950.158473059517, 4954.9009019819405, 5312.415634280226, 5550.944490555124, 2725.8773355199064, 2738.931526711808, 3249.5125731140047, 3267.2158681916644, 3267.4445344291526, 5368.463153684678, 5584.972075139598, 6424.751337032095, 6432.35676432353, 6452.257990331078, 6135.0, 6278.843028924222, 6293.748250070037, 6317.557770955989, 6335.968320158369, 6064.241969753781, 6357.650329231937, 6382.585131966407, 6402.9115050440605, 6423.0686550450855, 5370.597205209547, 5838.386969368155, 6113.606948143823, 6142.846428839315, 6205.913965056006, 5450.937067272002, 5976.551758618034, 6060.515158787198, 6088.0, 6090.725917333819, 5614.6631202128, 6050.6369617823075, 6354.52341074412, 6401.7119229633845, 6430.324815894263, 4049.696272779612, 4059.594040595917, 4082.938755918723, 4094.877153685394, 4100.5899410058755, 5834.445727655866, 5986.760529578768, 6042.425969532887, 6160.383961603804, 6164.506839452923, 5243.924995376015, 5294.8941021180135, 5644.472204871955, 5953.255843019622, 6003.549733769897, 6098.146259523759, 6098.176746139215, 6099.695015249131, 6126.540509461897, 6135.447809697149, 5862.355140934579, 5925.1704761332585, 5942.821715348547, 5956.672383018976, 5963.52291816645, 5703.258576385043, 5834.737436815439, 6133.693315334304, 6142.447179753844, 6162.815115546541, 5381.1121165007335, 6016.2479690038745, 6031.215941927521, 6040.456358927718, 6045.758169673164], "value": 4357.145941216751, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.5589798936722, 680.1701923653142, 700.597156634935, 719.3633634233705, 746.8207630168761, 13378.063535552516, 14470.348834302455, 14673.926630366846, 15159.545213643594, 15171.772423413644, 15532.534023979271, 15583.298751556178, 15605.453809116683, 15620.453284135057, 15641.061536307801, 720.2581341218527, 933.2114363362964, 934.9902105725834, 946.7065209784954, 947.5972711597578, 745.0388998192325, 755.4711701808728, 755.8601658693159, 763.1376544504702, 892.9464232146092, 979.5200351827624, 983.7196399026279, 983.7491439682871, 985.7387792235063, 986.7286496213562, 967.7580604848788, 974.3617930255658, 974.4786539201564, 976.067855198284, 976.2873102635081, 551.58906614572, 698.9405900498464, 727.7525641281936, 728.8032231297573, 729.9927000730031, 14828.70342593142, 14872.405103795834, 14895.255237238203, 14906.0, 14912.403503859832, 12324.630261092181, 12414.489234013472, 12455.19041262324, 12551.121421500498, 12599.37003149848, 9578.9521052395, 9602.951985240099, 9785.0, 9952.701418957442, 10055.095041446304, 3426.5374174486374, 3468.70891925267, 3504.859805607798, 3539.345221134098, 3586.1751797086554, 81261.0, 81327.5933620334, 83132.75300870431, 87154.69267960986, 87590.0, 8539.274161696201, 9427.481488518197, 9435.716928492157, 9589.712308630753, 9626.229901607854, 18424.723629145574, 21625.0, 21780.01989910458, 21788.56422871534, 21835.563288734455, 6673.632950187689, 7391.519551229153, 7551.773446796605, 7631.1605723370385, 7641.312281894656, 4049.149678567534, 4081.1837632473625, 4111.938320925188, 4116.876493705194, 4404.537523560044, 3590.928181436409, 3645.3620616392027, 3673.081729567608, 3674.7795132291612, 3679.300932822755, 578.1818726502037, 612.8375980365207, 626.3923993726079, 631.3307893632764, 641.4195153386192, 535.6518263128997, 566.1875209074991, 587.1838144978483, 589.078092784799, 597.8475488750341, 7443.9627801861725, 7568.243175682498, 7624.27569380908, 7695.846083078307, 8176.918230817616, 548.8408361575099, 558.7848678258899, 568.7724910035936, 572.1417873190214, 586.823952814154, 3249.707526322595, 3549.574051113883, 3717.739758216953, 3727.962720372816, 3762.6613604775703, 86236.56881715677, 92076.07923920809, 93142.06857931335, 93453.13093738088, 93845.0, 75469.0, 76139.0, 76177.03809699454, 76374.23625763782, 77033.61483192549, 53151.468485315425, 67238.66380668065, 68385.3161468379, 68439.65780171067, 68799.3120068803, 6479.254885688206, 7187.712491500281, 7276.381507571704, 7343.77968660941, 7454.962725186339, 41374.172516550105, 41557.961050973965, 42013.0, 42302.576974229865, 44612.55387446084, 60324.09513857384, 62430.687846560475, 62542.374576255475, 62976.0, 62979.0, 9661.0, 9758.316917815684, 10081.092701656886, 10517.526711297856, 10936.179786516203, 11702.941485292518, 11761.411929403666, 12111.273323600612, 12402.689932751393, 12992.480300787862, 10763.784724305626, 10936.453177340942, 11169.72075698114, 11342.262752921364, 12056.698582535506, 8624.913750862663, 11210.0, 11357.034652054337, 11379.658610241706, 11509.309441433541, 10971.23201375896, 11035.227534072537, 11054.115670746482, 11271.943640281746, 11357.602483913024, 9422.481763503074, 9494.715158545254, 9635.036496350309, 9724.70825875225, 9958.302918795614, 8945.821083578423, 9463.583953400073, 9552.0, 9588.808223835624, 9654.324197306401], "value": 6730.4287286096505, "unit": "score"}], "name": "dromaeo_css", "value": 7113.8319014664885, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 156.0, 165.0, 112.0, 113.0, 112.0, 121.0, 111.0, 111.0, 112.0], "value": 123.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 149.0, 150.0, 171.0, 119.0, 140.0, 139.0, 121.0, 173.0, 118.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 188.0, 186.0, 185.0, 184.0, 226.0, 193.0, 181.0, 183.0], "value": 189.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 90.0, 99.0, 83.0, 83.0, 83.0, 80.0, 84.0, 55.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 101.0, 135.0, 78.0, 78.0, 79.0, 128.0, 102.0, 114.0, 169.0], "value": 107.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 86.0, 83.0, 86.0, 84.0, 87.0, 84.0, 90.0, 128.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 84.0, 86.0, 86.0, 84.0, 84.0, 84.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 58.0, 58.0, 58.0, 57.0, 69.0, 95.0, 90.0, 67.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 64.0, 74.0, 72.0, 70.0, 70.0, 68.0, 68.0, 67.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 95.0, 105.0, 106.0, 104.0, 82.0, 83.0, 83.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 140.0, 138.0, 137.0, 136.0, 136.0, 141.0, 194.0, 130.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 169.0, 171.0, 169.0, 172.0, 168.0, 174.0, 225.0, 194.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 68.0, 69.0, 69.0, 68.0, 69.0, 69.0, 84.0], "value": 70.2, "unit": "score"}], "name": "kraken", "value": 1531.7, "alertThreshold": 2.0}]} 05:05:03 INFO - Return code: 0 05:05:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:05:03 ERROR - # TBPL SUCCESS # 05:05:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:03 INFO - 2016-04-21 05:05:03.900 screenresolution[1681:9654] starting screenresolution argv=screenresolution get 05:05:03 INFO - 2016-04-21 05:05:03.902 screenresolution[1681:9654] Display 0: 1600x1200x32@60 05:05:03 INFO - 2016-04-21 05:05:03.908 screenresolution[1682:9657] starting screenresolution argv=screenresolution list 05:05:03 INFO - Available Modes on Display 0 05:05:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:04 INFO - Intel Iris: 05:05:04 INFO - Chipset Model: Intel Iris 05:05:04 INFO - Type: GPU 05:05:04 INFO - Bus: Built-In 05:05:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:04 INFO - Vendor: Intel (0x8086) 05:05:04 INFO - Device ID: 0x0a2e 05:05:04 INFO - Revision ID: 0x0009 05:05:04 INFO - Displays: 05:05:04 INFO - Display: 05:05:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:04 INFO - Display Serial Number: 1600x1200 60 05:05:04 INFO - Main Display: Yes 05:05:04 INFO - Mirror: Off 05:05:04 INFO - Online: Yes 05:05:04 INFO - Rotation: Supported 05:05:04 INFO - Return code: 0 05:05:04 INFO - Running post-action listener: _resource_record_post_action 05:05:04 INFO - Running post-run listener: _resource_record_post_run 05:05:05 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37676544; Write bytes: 692594176; Read time: 395; Write time: 2150 05:05:05 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 399095808; Write bytes: 388670976; Read time: 29937; Write time: 761 05:05:05 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 30543360; Write bytes: 294662144; Read time: 305; Write time: 1363 05:05:05 INFO - Running post-run listener: _upload_blobber_files 05:05:05 INFO - Blob upload gear active. 05:05:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:05:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:05:05 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:05:05 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:05:05 INFO - (blobuploader) - INFO - Open directory for files ... 05:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:06 INFO - (blobuploader) - INFO - Done attempting. 05:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:05:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:07 INFO - (blobuploader) - INFO - Done attempting. 05:05:07 INFO - (blobuploader) - INFO - Iteration through files over. 05:05:07 INFO - Return code: 0 05:05:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:05:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:05:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/442a1a5304b61357479c58c99ad7061d5432cfaea87d51c059f236799bd7b5948694796cb81e56016c69c07520ad195df8e95d8f1226f4835956f0788e095f10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/263cf35b788009b34fa308f5c65d22f9034f34237038c210f26bce4160c5a4d94c1650595528ecd84c5b07178c59f15f901a56e49a4b3bb847906ae94ab4391d"} 05:05:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:05:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:05:07 INFO - Contents: 05:05:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/442a1a5304b61357479c58c99ad7061d5432cfaea87d51c059f236799bd7b5948694796cb81e56016c69c07520ad195df8e95d8f1226f4835956f0788e095f10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/263cf35b788009b34fa308f5c65d22f9034f34237038c210f26bce4160c5a4d94c1650595528ecd84c5b07178c59f15f901a56e49a4b3bb847906ae94ab4391d"} 05:05:07 INFO - Running post-run listener: copy_logs_to_upload_dir 05:05:07 INFO - Copying logs to upload dir... 05:05:07 INFO - mkdir: /builds/slave/test/build/upload/logs 05:05:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.503237 ========= master_lag: 92.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 41 secs) (at 2016-04-21 05:06:39.865496) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 28 secs) (at 2016-04-21 05:06:39.872945) ========= 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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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/Fx-Team/sha512/442a1a5304b61357479c58c99ad7061d5432cfaea87d51c059f236799bd7b5948694796cb81e56016c69c07520ad195df8e95d8f1226f4835956f0788e095f10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/263cf35b788009b34fa308f5c65d22f9034f34237038c210f26bce4160c5a4d94c1650595528ecd84c5b07178c59f15f901a56e49a4b3bb847906ae94ab4391d"} build_url:https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010987 build_url: 'https://queue.taskcluster.net/v1/task/M1xzmBpeSKGkk6Qd8IrTtw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/442a1a5304b61357479c58c99ad7061d5432cfaea87d51c059f236799bd7b5948694796cb81e56016c69c07520ad195df8e95d8f1226f4835956f0788e095f10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/263cf35b788009b34fa308f5c65d22f9034f34237038c210f26bce4160c5a4d94c1650595528ecd84c5b07178c59f15f901a56e49a4b3bb847906ae94ab4391d"}' ========= master_lag: 28.57 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 28 secs) (at 2016-04-21 05:07:08.455551) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 20 secs) (at 2016-04-21 05:07:08.458860) ========= 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.Mw9MwrkLYq/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.3hxYE9uWLx/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005865 ========= master_lag: 20.46 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 20 secs) (at 2016-04-21 05:07:28.920655) ========= ========= Started reboot skipped (results: 3, elapsed: 12 secs) (at 2016-04-21 05:07:28.920935) ========= ========= Finished reboot skipped (results: 3, elapsed: 12 secs) (at 2016-04-21 05:07:41.362663) ========= ========= Total master_lag: 141.42 =========