builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0012 starttime: 1461584799.22 results: success (0) buildid: 20160422035709 builduid: 3af33a9a7de54ba3986a82f34ab86caa revision: 80598be5d80e695727ff74e712faa623b02f3c70 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:39.223875) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:39.224385) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:39.224707) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:39.657232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:46:39.657602) ========= 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.GMtbIkCCHO/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.ovVjQCos45/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-25 04:46:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.55M=0.002s 2016-04-25 04:46:40 (7.55 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.532572 ========= master_lag: 1.33 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:46:41.522122) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:41.522438) ========= 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.GMtbIkCCHO/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.ovVjQCos45/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.019822 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:41.581628) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:46:41.581939) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 80598be5d80e695727ff74e712faa623b02f3c70 --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 80598be5d80e695727ff74e712faa623b02f3c70 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GMtbIkCCHO/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.ovVjQCos45/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-25 04:46:41,664 truncating revision to first 12 chars 2016-04-25 04:46:41,664 Setting DEBUG logging. 2016-04-25 04:46:41,664 attempt 1/10 2016-04-25 04:46:41,664 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/80598be5d80e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 04:46:41,984 unpacking tar archive at: fx-team-80598be5d80e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.567390 ========= master_lag: 0.62 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:46:42.773674) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:42.773995) ========= script_repo_revision: 80598be5d80e695727ff74e712faa623b02f3c70 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:42.774386) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:42.774658) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:42.844961) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-04-25 04:46:42.845234) ========= /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.GMtbIkCCHO/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.ovVjQCos45/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:46:43 INFO - MultiFileLogger online at 20160425 04:46:43 in /builds/slave/test 04:46:43 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:46:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:43 INFO - {'append_to_log': False, 04:46:43 INFO - 'base_work_dir': '/builds/slave/test', 04:46:43 INFO - 'blob_upload_branch': 'Fx-Team', 04:46:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:43 INFO - 'branch': 'Fx-Team', 04:46:43 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:43 INFO - 'config_files': ('talos/mac_config.py',), 04:46:43 INFO - 'default_actions': ('clobber', 04:46:43 INFO - 'read-buildbot-config', 04:46:43 INFO - 'download-and-extract', 04:46:43 INFO - 'populate-webroot', 04:46:43 INFO - 'create-virtualenv', 04:46:43 INFO - 'install', 04:46:43 INFO - 'run-tests'), 04:46:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:43 INFO - 'download_minidump_stackwalk': True, 04:46:43 INFO - 'download_symbols': 'ondemand', 04:46:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:43 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:43 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:43 INFO - 'installer_path': 'installer.exe', 04:46:43 INFO - 'log_level': 'info', 04:46:43 INFO - 'log_name': 'talos', 04:46:43 INFO - 'log_to_console': True, 04:46:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:46:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:46:43 INFO - 'opt_config_files': (), 04:46:43 INFO - 'pip_index': False, 04:46:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:43 INFO - 'cmd': ('bash', 04:46:43 INFO - '-c', 04:46:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:43 INFO - 'enabled': True, 04:46:43 INFO - 'halt_on_failure': False, 04:46:43 INFO - 'name': 'check_screen_resolution'},), 04:46:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:43 INFO - 'cmd': ('bash', 04:46:43 INFO - '-c', 04:46:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:43 INFO - 'enabled': True, 04:46:43 INFO - 'halt_on_failure': False, 04:46:43 INFO - 'name': 'check_screen_resolution'},), 04:46:43 INFO - 'run_cmd_checks_enabled': True, 04:46:43 INFO - 'sps_profile': False, 04:46:43 INFO - 'sps_profile_interval': 0, 04:46:43 INFO - 'suite': 'dromaeojs', 04:46:43 INFO - 'system_bits': '32', 04:46:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:43 INFO - 'title': 't-yosemite-r7-0012', 04:46:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:43 INFO - 'use_talos_json': True, 04:46:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:43 INFO - 'work_dir': 'build'} 04:46:43 INFO - ##### 04:46:43 INFO - ##### Running clobber step. 04:46:43 INFO - ##### 04:46:43 INFO - Running pre-action listener: _resource_record_pre_action 04:46:43 INFO - Running main action method: clobber 04:46:43 INFO - rmtree: /builds/slave/test/build 04:46:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:44 INFO - Running post-action listener: _resource_record_post_action 04:46:44 INFO - ##### 04:46:44 INFO - ##### Running read-buildbot-config step. 04:46:44 INFO - ##### 04:46:44 INFO - Running pre-action listener: _resource_record_pre_action 04:46:44 INFO - Running main action method: read_buildbot_config 04:46:44 INFO - Using buildbot properties: 04:46:44 INFO - { 04:46:44 INFO - "project": "", 04:46:44 INFO - "product": "firefox", 04:46:44 INFO - "script_repo_revision": "production", 04:46:44 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:46:44 INFO - "repository": "", 04:46:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:46:44 INFO - "buildid": "20160422035709", 04:46:44 INFO - "pgo_build": "False", 04:46:44 INFO - "basedir": "/builds/slave/test", 04:46:44 INFO - "buildnumber": 664, 04:46:44 INFO - "slavename": "t-yosemite-r7-0012", 04:46:44 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:46:44 INFO - "platform": "yosemite_r7", 04:46:44 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:46:44 INFO - "branch": "fx-team", 04:46:44 INFO - "repo_path": "integration/fx-team", 04:46:44 INFO - "revision": "80598be5d80e695727ff74e712faa623b02f3c70", 04:46:44 INFO - "stage_platform": "macosx64", 04:46:44 INFO - "builduid": "3af33a9a7de54ba3986a82f34ab86caa", 04:46:44 INFO - "slavebuilddir": "test" 04:46:44 INFO - } 04:46:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:46:44 INFO - Running post-action listener: _resource_record_post_action 04:46:44 INFO - ##### 04:46:44 INFO - ##### Running download-and-extract step. 04:46:44 INFO - ##### 04:46:44 INFO - Running pre-action listener: _resource_record_pre_action 04:46:44 INFO - Running main action method: download_and_extract 04:46:44 INFO - mkdir: /builds/slave/test/build/tests 04:46: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:46:44 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:46:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:46:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:46:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/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:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/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:46:45 INFO - Downloaded 1199 bytes. 04:46:45 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:46:45 INFO - Using the following test package requirements: 04:46:45 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:46:45 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:45 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:46:45 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:45 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:46:45 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:45 INFO - u'jsshell-mac.zip'], 04:46:45 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:45 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:46:45 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:46:45 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:45 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:46:45 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:45 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:46:45 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:45 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:46:45 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:45 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:46:45 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:46:45 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:46:45 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:46:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/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:46:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/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:46:46 INFO - Downloaded 18105796 bytes. 04:46:46 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:46:46 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:46:49 INFO - Return code: 0 04:46:49 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:46:49 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:46:49 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:46:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/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:46:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/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:46:51 INFO - Downloaded 18105796 bytes. 04:46:51 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:46:51 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:46:54 INFO - Return code: 0 04:46:54 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:46:54 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:46:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:46:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:46:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/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:46:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/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:46:55 INFO - Downloaded 10845952 bytes. 04:46:55 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:46:55 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:46:55 INFO - Return code: 0 04:46:55 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:46:55 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:46:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:46:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:46:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:46:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/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:46:58 INFO - Downloaded 110034607 bytes. 04:46:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:46:58 INFO - mkdir: /builds/slave/test/properties 04:46:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:46:58 INFO - Writing to file /builds/slave/test/properties/build_url 04:46:58 INFO - Contents: 04:46:58 INFO - build_url:https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:47:00 INFO - Running post-action listener: _resource_record_post_action 04:47:00 INFO - Running post-action listener: set_extra_try_arguments 04:47:00 INFO - ##### 04:47:00 INFO - ##### Running populate-webroot step. 04:47:00 INFO - ##### 04:47:00 INFO - Running pre-action listener: _resource_record_pre_action 04:47:00 INFO - Running main action method: populate_webroot 04:47:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:00 INFO - '--tppagecycles', 04:47:00 INFO - '7'], 04:47:00 INFO - 'tests': ['tsvgm']}}, 04:47:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:00 INFO - 'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:00 INFO - 'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['damp', 'tps']}, 04:47:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:00 INFO - 'tests': ['damp', 'tps']}, 04:47:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['dromaeo_dom']}, 04:47:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['a11yr', 04:47:00 INFO - 'ts_paint', 04:47:00 INFO - 'tpaint', 04:47:00 INFO - 'sessionrestore', 04:47:00 INFO - 'sessionrestore_no_auto_restore', 04:47:00 INFO - 'tabpaint']}, 04:47:00 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:00 INFO - 'ts_paint', 04:47:00 INFO - 'tpaint', 04:47:00 INFO - 'sessionrestore', 04:47:00 INFO - 'sessionrestore_no_auto_restore', 04:47:00 INFO - 'tabpaint']}, 04:47:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:00 INFO - 'ts_paint', 04:47:00 INFO - 'tpaint', 04:47:00 INFO - 'sessionrestore', 04:47:00 INFO - 'sessionrestore_no_auto_restore', 04:47:00 INFO - 'tabpaint']}, 04:47:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:00 INFO - 'ts_paint', 04:47:00 INFO - 'tpaint', 04:47:00 INFO - 'sessionrestore', 04:47:00 INFO - 'sessionrestore_no_auto_restore', 04:47:00 INFO - 'tabpaint']}, 04:47:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['a11yr', 04:47:00 INFO - 'ts_paint', 04:47:00 INFO - 'tpaint', 04:47:00 INFO - 'sessionrestore', 04:47:00 INFO - 'sessionrestore_no_auto_restore', 04:47:00 INFO - 'tabpaint']}, 04:47:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['a11yr', 04:47:00 INFO - 'ts_paint', 04:47:00 INFO - 'tpaint', 04:47:00 INFO - 'sessionrestore', 04:47:00 INFO - 'sessionrestore_no_auto_restore', 04:47:00 INFO - 'tabpaint']}, 04:47:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['tsvgx', 04:47:00 INFO - 'tsvgr_opacity', 04:47:00 INFO - 'tart', 04:47:00 INFO - 'tscrollx', 04:47:00 INFO - 'cart']}, 04:47:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:00 INFO - 'tsvgr_opacity', 04:47:00 INFO - 'tart', 04:47:00 INFO - 'tscrollx', 04:47:00 INFO - 'cart']}, 04:47:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:00 INFO - 'talos_options': ['--disable-e10s'], 04:47:00 INFO - 'tests': ['tp5o']}, 04:47:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:00 INFO - 'tests': ['tp5o']}, 04:47:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:00 INFO - 'talos_options': ['--disable-e10s', 04:47:00 INFO - '--xperf_path', 04:47:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:00 INFO - 'tests': ['tp5n']}, 04:47:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:00 INFO - 'talos_options': ['--xperf_path', 04:47:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:00 INFO - 'tests': ['tp5n']}}, 04:47:00 INFO - 'talos.zip': {'path': '', 04:47:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:00 INFO - Running post-action listener: _resource_record_post_action 04:47:00 INFO - ##### 04:47:00 INFO - ##### Running create-virtualenv step. 04:47:00 INFO - ##### 04:47:00 INFO - Running pre-action listener: _resource_record_pre_action 04:47:00 INFO - Running main action method: create_virtualenv 04:47:00 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:00 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:47:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:00 INFO - Using real prefix '/tools/python27' 04:47:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:00 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:03 INFO - Installing pip.................done. 04:47:03 INFO - Return code: 0 04:47:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c25d38c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, '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.GMtbIkCCHO/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.ovVjQCos45/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:47:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:47:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:47:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:03 INFO - 'HOME': '/Users/cltbld', 04:47:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:03 INFO - 'LOGNAME': 'cltbld', 04:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:03 INFO - 'MOZ_NO_REMOTE': '1', 04:47:03 INFO - 'NO_EM_RESTART': '1', 04:47:03 INFO - 'PAGER': '/bin/cat', 04:47:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:03 INFO - 'PWD': '/builds/slave/test', 04:47:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:03 INFO - 'SHELL': '/bin/bash', 04:47:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:47:03 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:47:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:03 INFO - 'USER': 'cltbld', 04:47:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:03 INFO - 'XPC_FLAGS': '0x0', 04:47:03 INFO - 'XPC_SERVICE_NAME': '0', 04:47:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:04 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:47:04 INFO - Cleaning up... 04:47:04 INFO - Return code: 0 04:47:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:04 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 0x1024c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c25d38c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, '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.GMtbIkCCHO/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.ovVjQCos45/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:47:04 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:47:04 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:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:04 INFO - 'HOME': '/Users/cltbld', 04:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:04 INFO - 'LOGNAME': 'cltbld', 04:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:04 INFO - 'MOZ_NO_REMOTE': '1', 04:47:04 INFO - 'NO_EM_RESTART': '1', 04:47:04 INFO - 'PAGER': '/bin/cat', 04:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:04 INFO - 'PWD': '/builds/slave/test', 04:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:04 INFO - 'SHELL': '/bin/bash', 04:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:47:04 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:04 INFO - 'USER': 'cltbld', 04:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:04 INFO - 'XPC_FLAGS': '0x0', 04:47:04 INFO - 'XPC_SERVICE_NAME': '0', 04:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:04 INFO - Downloading/unpacking psutil>=0.7.1 04:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:07 INFO - Installing collected packages: psutil 04:47:07 INFO - Running setup.py install for psutil 04:47:07 INFO - building 'psutil._psutil_osx' extension 04:47:07 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:47:08 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:47:08 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:47:08 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:47:08 INFO - building 'psutil._psutil_posix' extension 04:47:08 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:47:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:47:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:47:08 INFO - ^ 04:47:08 INFO - 1 warning generated. 04:47:08 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:47:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:08 INFO - Successfully installed psutil 04:47:08 INFO - Cleaning up... 04:47:08 INFO - Return code: 0 04:47:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:08 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 0x1024c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c25d38c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, '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.GMtbIkCCHO/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.ovVjQCos45/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:47:08 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:47:08 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:47:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:47:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:08 INFO - 'HOME': '/Users/cltbld', 04:47:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:08 INFO - 'LOGNAME': 'cltbld', 04:47:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:08 INFO - 'MOZ_NO_REMOTE': '1', 04:47:08 INFO - 'NO_EM_RESTART': '1', 04:47:08 INFO - 'PAGER': '/bin/cat', 04:47:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:08 INFO - 'PWD': '/builds/slave/test', 04:47:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:08 INFO - 'SHELL': '/bin/bash', 04:47:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:47:08 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:47:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:08 INFO - 'USER': 'cltbld', 04:47:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:08 INFO - 'XPC_FLAGS': '0x0', 04:47:08 INFO - 'XPC_SERVICE_NAME': '0', 04:47:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:47:08 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:47:08 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:47:08 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:47:08 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:47:08 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:47:08 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:47:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:47:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:11 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:47:11 INFO - Installing collected packages: mozsystemmonitor 04:47:11 INFO - Running setup.py install for mozsystemmonitor 04:47:11 INFO - Successfully installed mozsystemmonitor 04:47:11 INFO - Cleaning up... 04:47:11 INFO - Return code: 0 04:47:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47: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:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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:47:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1024c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c25d38c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, '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.GMtbIkCCHO/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.ovVjQCos45/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:47:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:47:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:47:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:47:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:11 INFO - 'HOME': '/Users/cltbld', 04:47:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:11 INFO - 'LOGNAME': 'cltbld', 04:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:11 INFO - 'MOZ_NO_REMOTE': '1', 04:47:11 INFO - 'NO_EM_RESTART': '1', 04:47:11 INFO - 'PAGER': '/bin/cat', 04:47:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:11 INFO - 'PWD': '/builds/slave/test', 04:47:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:11 INFO - 'SHELL': '/bin/bash', 04:47:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:47:11 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:47:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:11 INFO - 'USER': 'cltbld', 04:47:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:11 INFO - 'XPC_FLAGS': '0x0', 04:47:11 INFO - 'XPC_SERVICE_NAME': '0', 04:47:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:11 INFO - Downloading/unpacking blobuploader==1.2.4 04:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:14 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:15 INFO - Downloading docopt-0.6.1.tar.gz 04:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:15 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:15 INFO - Running setup.py install for blobuploader 04:47:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:15 INFO - Running setup.py install for requests 04:47:16 INFO - Running setup.py install for docopt 04:47:16 INFO - Successfully installed blobuploader requests docopt 04:47:16 INFO - Cleaning up... 04:47:16 INFO - Return code: 0 04:47:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:16 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:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:16 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:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:16 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 0x1024c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c25d38c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, '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.GMtbIkCCHO/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.ovVjQCos45/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:47:16 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:47:16 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:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:16 INFO - 'HOME': '/Users/cltbld', 04:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:16 INFO - 'LOGNAME': 'cltbld', 04:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:16 INFO - 'MOZ_NO_REMOTE': '1', 04:47:16 INFO - 'NO_EM_RESTART': '1', 04:47:16 INFO - 'PAGER': '/bin/cat', 04:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:16 INFO - 'PWD': '/builds/slave/test', 04:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:16 INFO - 'SHELL': '/bin/bash', 04:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:47:16 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:16 INFO - 'USER': 'cltbld', 04:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:16 INFO - 'XPC_FLAGS': '0x0', 04:47:16 INFO - 'XPC_SERVICE_NAME': '0', 04:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:16 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-LdxA6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:16 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-XWgYfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:16 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Z9Cg0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-CIqCUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-G4xOxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-LxtLvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-BYzPiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-zfNMqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-t8Pwzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Za_vmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-W1pSEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-mZyFZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:17 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-4vXI7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:18 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-O0Zbwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:18 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-8acaFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:18 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-HLooGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:18 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-FbNR0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:18 INFO - Running setup.py install for manifestparser 04:47:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Running setup.py install for mozcrash 04:47:18 INFO - Running setup.py install for mozdebug 04:47:18 INFO - Running setup.py install for mozdevice 04:47:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozfile 04:47:19 INFO - Running setup.py install for mozhttpd 04:47:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozinfo 04:47:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozInstall 04:47:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozleak 04:47:19 INFO - Running setup.py install for mozlog 04:47:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for moznetwork 04:47:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Running setup.py install for mozprocess 04:47:20 INFO - Running setup.py install for mozprofile 04:47:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Running setup.py install for mozrunner 04:47:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:20 INFO - Running setup.py install for mozscreenshot 04:47:20 INFO - Running setup.py install for moztest 04:47:20 INFO - Running setup.py install for mozversion 04:47:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:21 INFO - Cleaning up... 04:47:21 INFO - Return code: 0 04:47:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:21 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 0x1024c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c25d38c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, '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.GMtbIkCCHO/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.ovVjQCos45/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:47:21 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:47:21 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:47:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:47:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:21 INFO - 'HOME': '/Users/cltbld', 04:47:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:21 INFO - 'LOGNAME': 'cltbld', 04:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:21 INFO - 'MOZ_NO_REMOTE': '1', 04:47:21 INFO - 'NO_EM_RESTART': '1', 04:47:21 INFO - 'PAGER': '/bin/cat', 04:47:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:21 INFO - 'PWD': '/builds/slave/test', 04:47:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:21 INFO - 'SHELL': '/bin/bash', 04:47:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:47:21 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:47:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:21 INFO - 'USER': 'cltbld', 04:47:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:21 INFO - 'XPC_FLAGS': '0x0', 04:47:21 INFO - 'XPC_SERVICE_NAME': '0', 04:47:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:21 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-NTh1w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:21 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:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:21 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-5l5eez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:21 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:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:21 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Mo1GVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:21 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:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:21 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Vn3uGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:21 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:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:21 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-69kVF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:21 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:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:21 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-L_Fw7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:21 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:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:21 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-_0C9W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:22 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:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:22 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-bxuh8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:22 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:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:22 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-XkgBCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:22 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:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:22 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-c5dEOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:22 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:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:22 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-xV5cOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:22 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:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:22 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-SzkyFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:22 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:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:22 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-mHkC8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:22 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:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:22 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Ofpg9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:22 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:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:22 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-6Jw8GU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:22 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:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:22 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-sIOFIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:23 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:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:23 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-MOsJ7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:23 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:47:23 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:47:23 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:47:23 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:47:23 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:47:23 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:47:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47:23 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:47:23 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:47:23 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:47:23 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:47:23 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:47:23 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:47:25 INFO - Downloading blessings-1.6.tar.gz 04:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:25 INFO - Installing collected packages: blessings 04:47:25 INFO - Running setup.py install for blessings 04:47:25 INFO - Successfully installed blessings 04:47:25 INFO - Cleaning up... 04:47:25 INFO - Return code: 0 04:47:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:26 INFO - Reading from file tmpfile_stdout 04:47:26 INFO - Current package versions: 04:47:26 INFO - blessings == 1.6 04:47:26 INFO - blobuploader == 1.2.4 04:47:26 INFO - docopt == 0.6.1 04:47:26 INFO - manifestparser == 1.1 04:47:26 INFO - mozInstall == 1.12 04:47:26 INFO - mozcrash == 0.17 04:47:26 INFO - mozdebug == 0.1 04:47:26 INFO - mozdevice == 0.48 04:47:26 INFO - mozfile == 1.2 04:47:26 INFO - mozhttpd == 0.7 04:47:26 INFO - mozinfo == 0.9 04:47:26 INFO - mozleak == 0.1 04:47:26 INFO - mozlog == 3.1 04:47:26 INFO - moznetwork == 0.27 04:47:26 INFO - mozprocess == 0.22 04:47:26 INFO - mozprofile == 0.28 04:47:26 INFO - mozrunner == 6.11 04:47:26 INFO - mozscreenshot == 0.1 04:47:26 INFO - mozsystemmonitor == 0.0 04:47:26 INFO - moztest == 0.7 04:47:26 INFO - mozversion == 1.4 04:47:26 INFO - psutil == 3.1.1 04:47:26 INFO - requests == 1.2.3 04:47:26 INFO - wsgiref == 0.1.2 04:47:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:26 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 0x1024c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c25d38c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, '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.GMtbIkCCHO/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.ovVjQCos45/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:47:26 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:47:26 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:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:26 INFO - 'HOME': '/Users/cltbld', 04:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:26 INFO - 'LOGNAME': 'cltbld', 04:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:26 INFO - 'MOZ_NO_REMOTE': '1', 04:47:26 INFO - 'NO_EM_RESTART': '1', 04:47:26 INFO - 'PAGER': '/bin/cat', 04:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:26 INFO - 'PWD': '/builds/slave/test', 04:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:26 INFO - 'SHELL': '/bin/bash', 04:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:47:26 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:26 INFO - 'USER': 'cltbld', 04:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:26 INFO - 'XPC_FLAGS': '0x0', 04:47:26 INFO - 'XPC_SERVICE_NAME': '0', 04:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:26 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:47:26 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:47:26 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:47:26 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:47:26 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:47:26 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:47:26 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:47:26 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:47:26 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:47:26 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:47:26 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:47:26 INFO - Cleaning up... 04:47:26 INFO - Return code: 0 04:47:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47: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:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c25d38c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, '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.GMtbIkCCHO/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.ovVjQCos45/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:47: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', 'jsonschema'] in /builds/slave/test/build 04:47: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 jsonschema 04:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:26 INFO - 'HOME': '/Users/cltbld', 04:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:26 INFO - 'LOGNAME': 'cltbld', 04:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:26 INFO - 'MOZ_NO_REMOTE': '1', 04:47:26 INFO - 'NO_EM_RESTART': '1', 04:47:26 INFO - 'PAGER': '/bin/cat', 04:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:26 INFO - 'PWD': '/builds/slave/test', 04:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:26 INFO - 'SHELL': '/bin/bash', 04:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:47:26 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:26 INFO - 'USER': 'cltbld', 04:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:26 INFO - 'XPC_FLAGS': '0x0', 04:47:26 INFO - 'XPC_SERVICE_NAME': '0', 04:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:26 INFO - Downloading/unpacking jsonschema 04:47: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:47: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:47: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:47: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:47: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:47: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:47:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:29 INFO - Downloading/unpacking functools32 (from jsonschema) 04:47: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:47: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:47: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:47: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:47: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:47: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:47:29 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:29 INFO - warning: no files found matching '*.txt' 04:47:29 INFO - no previously-included directories found matching 'build' 04:47:29 INFO - no previously-included directories found matching 'dist' 04:47:29 INFO - no previously-included directories found matching '.git*' 04:47:29 INFO - Installing collected packages: jsonschema, functools32 04:47:29 INFO - Running setup.py install for functools32 04:47:29 INFO - warning: no files found matching '*.txt' 04:47:29 INFO - no previously-included directories found matching 'build' 04:47:29 INFO - no previously-included directories found matching 'dist' 04:47:29 INFO - no previously-included directories found matching '.git*' 04:47:29 INFO - Successfully installed jsonschema functools32 04:47:29 INFO - Cleaning up... 04:47:29 INFO - Return code: 0 04:47:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:47: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:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c25d38c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, '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.GMtbIkCCHO/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.ovVjQCos45/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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:47: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', 'flake8'] in /builds/slave/test/build 04:47: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 flake8 04:47:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:47:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:29 INFO - 'HOME': '/Users/cltbld', 04:47:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:29 INFO - 'LOGNAME': 'cltbld', 04:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:29 INFO - 'MOZ_NO_REMOTE': '1', 04:47:29 INFO - 'NO_EM_RESTART': '1', 04:47:29 INFO - 'PAGER': '/bin/cat', 04:47:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:29 INFO - 'PWD': '/builds/slave/test', 04:47:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:29 INFO - 'SHELL': '/bin/bash', 04:47:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:47:29 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:47:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:29 INFO - 'USER': 'cltbld', 04:47:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:29 INFO - 'XPC_FLAGS': '0x0', 04:47:29 INFO - 'XPC_SERVICE_NAME': '0', 04:47:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:30 INFO - Downloading/unpacking flake8 04:47:30 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:47:30 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:47:30 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:47:30 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:47:30 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:47:30 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:47:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:47:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:47: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:47: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:47: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:47: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:47: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:47: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:47:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:47:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:47:33 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:47:33 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:47:33 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:47:33 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:47:33 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:47:33 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:47:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:47:33 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:47:33 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:47:33 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:47:33 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:47:33 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:47:33 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:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:47:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:47:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:47:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:47:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:47:33 INFO - no previously-included directories found matching 'docs/_build' 04:47:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:47:34 INFO - Running setup.py install for pep8 04:47:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:47:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:47:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:47:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:47:34 INFO - no previously-included directories found matching 'docs/_build' 04:47:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:47:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:47:34 INFO - Cleaning up... 04:47:34 INFO - Return code: 0 04:47:34 INFO - Running post-action listener: _resource_record_post_action 04:47:34 INFO - Running post-action listener: _start_resource_monitoring 04:47:34 INFO - Starting resource monitoring. 04:47:34 INFO - ##### 04:47:34 INFO - ##### Running install step. 04:47:34 INFO - ##### 04:47:34 INFO - Running pre-action listener: _resource_record_pre_action 04:47:34 INFO - Running main action method: install 04:47:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:34 INFO - Reading from file tmpfile_stdout 04:47:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:47:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:47:34 INFO - Reading from file tmpfile_stdout 04:47:34 INFO - Output received: 04:47:34 INFO - Usage: mozinstall [options] installer 04:47:34 INFO - Options: 04:47:34 INFO - -h, --help show this help message and exit 04:47:34 INFO - -d DEST, --destination=DEST 04:47:34 INFO - Directory to install application into. [default: 04:47:34 INFO - "/builds/slave/test"] 04:47:34 INFO - --app=APP Application being installed. [default: firefox] 04:47:34 INFO - mkdir: /builds/slave/test/build/application 04:47:34 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:47:34 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:48:08 INFO - Reading from file tmpfile_stdout 04:48:08 INFO - Output received: 04:48:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:48:08 INFO - Running post-action listener: _resource_record_post_action 04:48:08 INFO - ##### 04:48:08 INFO - ##### Running run-tests step. 04:48:08 INFO - ##### 04:48:08 INFO - Running pre-action listener: _resource_record_pre_action 04:48:08 INFO - Running main action method: run_tests 04:48:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:08 INFO - 2016-04-25 04:48:08.220 screenresolution[1920:18121] starting screenresolution argv=screenresolution get 04:48:08 INFO - 2016-04-25 04:48:08.223 screenresolution[1920:18121] Display 0: 1600x1200x32@60 04:48:08 INFO - 2016-04-25 04:48:08.229 screenresolution[1921:18123] starting screenresolution argv=screenresolution list 04:48:08 INFO - Available Modes on Display 0 04:48:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:08 INFO - Intel Iris: 04:48:08 INFO - Chipset Model: Intel Iris 04:48:08 INFO - Type: GPU 04:48:08 INFO - Bus: Built-In 04:48:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:08 INFO - Vendor: Intel (0x8086) 04:48:08 INFO - Device ID: 0x0a2e 04:48:08 INFO - Revision ID: 0x0009 04:48:08 INFO - Displays: 04:48:08 INFO - Display: 04:48:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:08 INFO - Display Serial Number: 1600x1200 60 04:48:08 INFO - Main Display: Yes 04:48:08 INFO - Mirror: Off 04:48:08 INFO - Online: Yes 04:48:08 INFO - Rotation: Supported 04:48:08 INFO - Return code: 0 04:48:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:48:08 INFO - Python 2.7.3 04:48:08 INFO - Return code: 0 04:48:08 INFO - grabbing minidump binary from tooltool 04:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:08 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 0x102362e00>, '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 0x10218bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10235db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:48:08 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:48:08 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:48:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:48:08 INFO - Return code: 0 04:48:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:48:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:48:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:48:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:48:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:48:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:48:09 INFO - Return code: 0 04:48:09 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/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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:48:09 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/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0012 --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:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GMtbIkCCHO/Render', 04:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:09 INFO - 'HOME': '/Users/cltbld', 04:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:09 INFO - 'LOGNAME': 'cltbld', 04:48:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:48:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:09 INFO - 'MOZ_NO_REMOTE': '1', 04:48:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:48:09 INFO - 'NO_EM_RESTART': '1', 04:48:09 INFO - 'PAGER': '/bin/cat', 04:48:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:09 INFO - 'PWD': '/builds/slave/test', 04:48:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:09 INFO - 'SHELL': '/bin/bash', 04:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ovVjQCos45/Listeners', 04:48:09 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:09 INFO - 'USER': 'cltbld', 04:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:09 INFO - 'XPC_FLAGS': '0x0', 04:48:09 INFO - 'XPC_SERVICE_NAME': '0', 04:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:09 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/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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:48:09 INFO - mozversion application_buildid: 20160422035709 04:48:09 INFO - mozversion application_changeset: 80598be5d80e695727ff74e712faa623b02f3c70 04:48:09 INFO - mozversion application_display_name: Nightly 04:48:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:48:09 INFO - mozversion application_name: Firefox 04:48:09 INFO - mozversion application_remotingname: firefox 04:48:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:48:09 INFO - mozversion application_vendor: Mozilla 04:48:09 INFO - mozversion application_version: 48.0a1 04:48:09 INFO - mozversion platform_buildid: 20160422035709 04:48:09 INFO - mozversion platform_changeset: 80598be5d80e695727ff74e712faa623b02f3c70 04:48:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:48:09 INFO - mozversion platform_version: 48.0a1 04:48:09 INFO - using testdate: 1461584889 04:48:09 INFO - actual date: 1461584889 04:48:09 INFO - starting webserver on 'localhost:55534' 04:48:09 INFO - SUITE-START | Running 2 tests 04:48:09 INFO - TEST-START | dromaeo_css 04:48:09 INFO - operating with platform_type : mac_ 04:48:09 INFO - Initialising browser for dromaeo_css test... 04:48:09 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpRYZ8cd/profile http://localhost:55534/getInfo.html) 04:48:12 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 04:48:12 INFO - PROCESS | 1939 | colorDepth:24 04:48:12 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 04:48:12 INFO - PROCESS | 1939 | __metrics 04:48:12 INFO - PROCESS | 1939 | 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:48:12 ERROR - PROCESS | 1939 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:48:12 INFO - TEST-INFO | 1939: exit 0 04:48:12 INFO - Browser initialized. 04:48:12 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:12 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpRYZ8cd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:24 INFO - PROCESS | 1941 | RSS: Main: 237588480 04:48:24 INFO - PROCESS | 1941 | 04:49:15 INFO - PROCESS | 1941 | 1461584955689 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:51:27 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55534/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55534/tests/dromaeo/cssquery-ext.html) 04:51:28 INFO - PROCESS | 1941 | RSS: Main: 366718976 04:51:28 INFO - PROCESS | 1941 | 04:54:31 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55534/tests/dromaeo/cssquery-ext.html (next: http://localhost:55534/tests/dromaeo/cssquery-jquery.html) 04:54:32 INFO - PROCESS | 1941 | RSS: Main: 380026880 04:54:32 INFO - PROCESS | 1941 | 04:57:34 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55534/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55534/tests/dromaeo/cssquery-mootools.html) 04:57:34 INFO - PROCESS | 1941 | RSS: Main: 377892864 04:57:34 INFO - PROCESS | 1941 | 05:00:37 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55534/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55534/tests/dromaeo/cssquery-prototype.html) 05:00:37 INFO - PROCESS | 1941 | RSS: Main: 379015168 05:00:37 INFO - PROCESS | 1941 | 05:03:40 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55534/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55534/tests/dromaeo/cssquery-yui.html) 05:03:41 INFO - PROCESS | 1941 | RSS: Main: 351776768 05:03:41 INFO - PROCESS | 1941 | 05:06:44 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55534/tests/dromaeo/cssquery-yui.html (next: http://localhost:55534/tests/dromaeo/cssquery-dojo.html) 05:06:45 INFO - PROCESS | 1941 | RSS: Main: 385388544 05:06:45 INFO - PROCESS | 1941 | 05:06:45 INFO - PROCESS | 1941 | __start_tp_report 05:06:45 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 05:06:45 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 05:06:45 INFO - PROCESS | 1941 | |i|pagename|runs| 05:06:45 INFO - PROCESS | 1941 | |0;dojo.html;3378.2736711607;3397.4224381855083;3444.4833275008737;3451.5512983312165;3484.930301393972;5636.549076073915;5661.547076233896;5688.488036076752;5700.572457065716;5710.828675139747;4821.156297647915;5613.578981576377;5710.657360558359;5716.199732037508;5720;5122.154844450671;5122.410922743877;5167.612429067816;5175.896482070375;5226.425093239741;5222.712750798704;5227.921581176285;5229.660072095321;5233.4243233244315;5234.895302093956;5131.538161565477;5164.147915593922;5170.586353091744;5179.326687530615;5260.395054568736;5160.948390516103;5199.870003249911;5230.398504172031;5231.267622532849;5285.392179899322;5060.2409638554145;5071.467495912912;5075.923861142086;5136.280920671109;5136.5890728741615;4641.883952901171;5607.299087614048;5618.073017952033;5638.069718496443;5649.180868774005;6219.1604133442315;6242.750289988395;6245.56281060326;6256.5307601930035;6258.4680302174365;6105.938940610581;6194.938050619527;6198.876022479525;6217.875642487125;6222.284437289724;5815.069588865804;5864.413558644102;5875.148103524965;5913.438223368773;5914.408559144051;6270.372962703785;6284.183056202756;6285.43431091204;6288.779892703734;6296.024116261987;6047.697615119227;6166.599171053867;6167.629942203482;6191.938080619227;6218.409251121137;6160.722767475474;6162.075688646739;6234.470070044006;6247.9062814057825;6248.250209974741;6095.542834287447;6145.354737752472;6159.6612186330185;6174.38256174379;6175.073738939195;6080.9391906081255;6086.391360863879;6162.969185154046;6184.536159788034;6206.565540412128;4909.3126962225315;4913.631477639191;4940.1601727706375;4979.800807967713;4995.275685025651;4443.755593442392;4448.5773851484055;4458.08609235106;4459.554044595579;4466.9106617867465;5582.358028826737;5693.060644993529;5750.1761328716775;5777.711114444345;5786.855328616734;6209.751609935552;6226.844328891723;6265.655388953652;6288.842778930563;6298.275698294663;5714.428557144252;5717;5718.656880587178;5735.799247026334;5753.769849206069;4613.653975951817;5415.566754659618;5416.756245968941;5429.321334833146;5465.535429488458;6141.946315431883;6239;6291.937080629135;6303.212098487689;6307.148534947723;6239.407256310643;6250.218722659668;6258.186435763321;6271.247450305992;6271.905921411091;6200.410960958701;6304.590201636879;6316.810495685137;6332.430081292706;6333.936660633519;6144.1567529740005;6152.200213972155;6233.189685341058;6234.781782637586;6244.281907580505;6245.3754624539;6256.624602523863;6269.749210031548;6289.716962736779;6303.36966303366;5970.402959703995;6027.819165425044;6146;6154.692265386802;6190.81427557174;5914.645121292736;6020.39796020412;6042.1540984261355;6080.087986802015;6132.816015519542;5921.970390148022;5965.940340596539;5972.641641501524;5980.222571065734;5980.700964951648;4518.728876267434;5996.730147143302;6111.54163437735;6133.723982420891;6151.9384806151365;4922.975385123052;5942.494887934574;6013.218281623361;6027.879442411215;6051.818445446644 05:06:45 INFO - PROCESS | 1941 | |1;ext.html;484.21073649950574;516.3004129404657;527.3855957809153;579.1313030454318;611.8684482836189;27077;30833;37691;38890.416643750366;39230.607693923055;33573.49639755406;41368.586314136846;41495;41570.792146039304;41718.58281417185;9618.75953101171;9821.950890245538;9849.803003939916;9849.950750246238;9862.211023118134;8542.0176679682;8886.600102995348;11432.542698292058;11787.998020168296;11815.704607384798;11549.133814963869;11660.883391166108;11661.941690291536;11672.649820505356;11684.766304673902;8612.1387861214;11447.427628618578;11503.252288601298;11567.942160289145;11592.246503977214;11394.943025284903;11553.13351498641;11571.247868888495;11581.76836463266;11591.07271418293;38441.42337864934;38998.415023774665;39039.60960390388;39044.80477597622;39045.41431878524;3149.401613693391;3212.4699424594914;3355.6979871811654;3372.7976321420547;3480.6693364130365;27192.728072719416;28795.70419331135;28841.85579072091;28997;29382.853085734434;2665.9600105998425;2703.0539311241105;2734.8222365546176;2807.3402750353653;2908.9854550727323;496634;503333;503429;504314;504958.99999999633;34885.65114348824;35892.64107358945;59961.70019149876;61130.388696112466;61446.69276653678;29120.563191552144;34520;34622.65377346244;34741.652583474344;34773.47839782405;14794.186319752516;15022.849771502364;15031.699366012837;15034.248287585577;15034.473793417397;11484;11773.352465614302;11784.528618855149;12669.556565520163;12755.93622031884;7957.721479748297;8114.391420643476;8166.918330816735;8188.959055204686;8225.95887020561;3285.178705323669;3355.647656996029;3362.327534493062;3373.4602463605706;3396;967.1102585621245;982.8083523712951;1005.9789313846452;1060.877999030106;1062.5324857062867;28245.858770706014;28819.402806092057;29915.25211869677;30173.35156335781;30244.84877575642;1068.903798658109;1079.2553138334524;1163.3892206591438;1179.3159967218992;1216.8466544185544;15277.694446111016;15283.312250948511;15337.463188788563;15770.684586308209;15778.368865245491;525888;758738;778369;782235;782308;61160.69419652962;61761.691191544654;62525;62678.746425071244;62733;1331.181323486049;1375.3810785146925;1493.1862135136378;1500.1524138861507;1516.3782849032063;24953.625695614217;26477.602835957092;31167.532487013166;31605;32105.67894321027;56591;57596.71201643965;57863;58116.54708632318;58189.4181058184;582678.0866095643;587952;588112.4160654356;589033.0548347231;589542;23840.880795596604;24488;26492.867535662197;26880.59679104854;27430.17709468719;33315.33369332648;34442.96671099871;36970.8151459241;42417.575824241365;43991.56008439875;34402.82798585991;34810.82594587112;34816.825915871275;35402.46896296506;35650.8217458911;34192.31615367728;34213;34751.4787278186;35075;35138.47292290669;34938.65061349354;35291.64708352986;36975.63024369722;38136.618633813305;38656.2268754629;30505.54241686332;31353.05940821779;31490.527642085846;31512.52731208988;31587.052388428383;19757.40727778169;23210.3036908893;23364.53270934606;23491.70795606258;24067.638985415582 05:06:45 INFO - PROCESS | 1941 | |2;jquery.html;350.84387447585834;390.9179072394797;391.3112921258585;405.8457786041304;412.729662071343;20029.499262518446;21439.67840482394;21840;21924.78075219249;21941.67087493687;11810.58662946797;12312;12326.568570100048;20922.163113475443;22034.4491387715;5188.2995795567585;5337.70642614656;5380.435054319299;5385.7307134643115;5681.57388195887;5820.912686309709;6065.3934606539215;6067.423594758491;6068.939310606882;6070.21087258658;5497.175423686459;5535.114381698929;6027.457528822405;6028.728707208186;6052.485538729214;5190.532852043315;5318.1225097858805;5357.1696387060065;5362.329708786402;5388.515033646951;5005.57452616528;5203.16749320107;5220.399654039796;5377.381601115882;5392.083345831218;13808;13954;14027.719445611132;14400;25405.491890162277;2158.6869903863853;2165.07984106754;2325.5581439526604;2717.8641067946523;2794.580812878064;7957.960210198969;9861.753456163582;10214.28500005;12463.75072498545;14386.35261413239;1692.7545505901576;1705.5139285303628;1737.860971122307;1743.8517725993306;1765.5939133999125;125604;127719;128554.71445285427;129090;129273;9360.191942417248;10103.696889093339;11552.422378881023;11752.23610465317;11966.461509232106;15274.541763747106;15346.539603811903;15382.3077961492;15394.307256173499;15454.459093931659;8567.357448191411;8743.256823169975;8788.296936245086;8888.866671998674;8911.643534258557;8609.698660546976;8643.870341944876;8644.610992505352;8748.8687669685;8752.8687069694;4530.912580980541;4610.561996610317;4625.953740462553;4629.65277604181;4651.67438279324;1623.5778697538724;1881.6424879273168;1889.3387314439835;1957.0997341222912;1992.900354982245;920.1396694091046;945.7730144765344;1013.9847902281472;1024.8104100741386;1046.2571574182264;16722.665546688997;19375.51561210981;19461;20258.59482810365;20527.692084618742;846.9153084691604;854.8974123105268;967.9370840895319;999.4702807512031;1006.3810756384771;10315.84526232107;10584.523696433525;10608.57565697379;10616.946915265375;10623.043926046694;567586;575761;581455;583334;586015;71312;71618;132083.71665133288;134663.98004029947;137774;924.5423515359805;1013.5591017907234;1036.6993571864225;1057.2123767793198;1061.9522121504551;13975.510857119953;14377.436253941622;17613.647727045132;17839.46481605554;18163.36428225006;34491.655083448844;42917.78541107274;50388;50925;53008.46991530035;76650;76973;78438.21561784309;78505;80522;7866.528008319519;8080.87878681832;8145.551994640352;8261.13258107914;8448.704295349633;7524.887126693214;10986.780264394507;11321.603743868925;12589.874101259236;13222.074454788073;10800.891991079989;11025.503852326505;11048.895110488887;11114.163964890968;11494.770104598028;10833.133349332193;10835.783284334428;10933.507992140532;11120.277181983145;11287.322760634388;6119.3268740437625;9827.754306142118;10287.691369258935;11375;11561.942190288995;8154.510729356258;8214.917850821654;8331.875021874797;8410.116937721696;8426.957865210634;8497.107803680525;9296.442213467213;10935.28920620157;11518.654440366803;11616.12879034059 05:06:45 INFO - PROCESS | 1941 | |3;mootools.html;1411.6612013116855;1551.9534413967579;1570.4503423801668;1815.2557451444911;1972.5068732816787;8097.87853182202;8943.686970956018;9177.128172823588;9208.355415120952;9238;8778.6927457539;9030.367874248805;9061.592228349724;9087.681931132413;9095.90904090959;2173.315405647224;2376.1089591403224;2470.629405589167;2471.6045432730766;2498.3379404457833;2502.0867383405107;2524.3184340228127;2524.6970363556306;2525.734797846227;2526.507331070442;2095.224766836273;2425.599776036952;2468.4075821802726;2471.221565206954;2475.8762061896923;2148.677698345245;2363.5036642305136;2444.5721998650165;2451.8161137914726;2452.4849781545718;2447.617096340562;2458.766417190364;2480.0203919451715;2480.1567467061286;2487.838290511111;9009;9021.819563608691;9093.227075698573;9096.454212747256;9099.090090990849;5249.396319423277;5428.267183930158;5430.6741595504;5444.754986025599;5458.918116228259;3543.8759643412614;3562.982185089058;3585.8027808470524;3651.2697460508007;3655.250673611903;1017.7557386227326;1025.7025462615832;1029.526417847791;1050.2438244463974;1052.0478966535222;12113.15207935445;12138.939305303506;12174.452149653287;12177.634670959886;12233;4691.319758634979;4831.931723355695;4858.4898585648;4953.108440480748;4966.801327946841;10110.443925584017;10113.089821915915;10134.949325253474;10135.543900524493;10168.28821982454;5488.451154884479;5494.340679118532;5497.230387745719;5520.723963801875;5536.474034966672;4068.532118806316;4543.4775000875325;4603.769811509411;4725.243960966229;5001.524855138756;3965.940510892339;4068.959310406917;4088.3049881520215;4108.36320370343;4219.51475580306;1336.5121730568467;1339.6650837290676;1350.797380392949;1353.1948490648049;1371.2184055088703;878.5036454403241;897.7665806890255;905.23055402907;950.6387572722322;969.3844408800321;5938.881222375615;5950.85122871923;6089.543284253698;6098.085287206865;6260.718267677966;909.3907082254905;918.3387960668308;952.5713428956858;963.2350382957991;1031.2677998620914;3327.234736010731;3960.8415663373144;3988.062805240766;4050.959490405058;4070.287699652549;8851;11094.223404361617;11163.609273675382;11245.381504017194;11272.54909803615;10720.463976801284;11051;11173.553057877594;11178.44107794607;11178.944105279421;8414.957925210334;8444.535550544657;8582.013068497203;8583.484990900566;8601.354898382771;5807.477327040587;5862.912056319074;5862.912056319244;6015.187949626745;6071.058985857199;9741.172000380047;10351.06840384369;10416.166706663296;10522.894771052192;10544.367337959748;10283.691489255334;10342.37945723259;10376.325538839952;10386.221033422675;10396.532156053147;6982.35308164394;7031.894521582078;7369.963150184215;7480;7657.042869641295;8069.919300806917;8139.715109971359;8154.633041513265;8211.58942052907;8673.696420625249;6875.1749790025515;7624.733134340271;7792.18182090895;7907.48601340911;7917.485363451357;7489.70041198346;7514.699412023458;7599.962000189964;7622.809429764078;7631.54210747357;7093.716251349888;7127.572345659277;7220.458465614995;7221;7226.783196504113;4963.677360971526;5002.399712034579;5014.749262536786;5072.797088116434;5284.735763211747;4936.777844996912;5008.9248661269385;5035.748212589283;5105.591552675703;5296.841094767163 05:06:45 INFO - PROCESS | 1941 | |4;prototype.html;310.1130766009213;315.61810209646325;324.3237849084658;328.14845475989796;328.46788203110964;4974.477679843619;5894.58737888348;6058.363871793465;6068.87316624695;6087.878242435148;4727;4917.606591472682;4935.223319604948;5673.801416950408;6004.099385092239;4445.466544014706;4489.079738653568;4490.50604433512;4521.751303678297;4536.5236650151755;3943.507061617298;4506.436695413074;4517.186906356855;4518.356761294646;4546.158960592301;4251.957480425187;4415.646748260131;4439.467263928317;4443.733375997451;4494.910101797962;4382.583654552796;4426.004149066466;4428.092241090569;4442.267025940716;4454.153710794939;2978.5978892849403;3577.409727395003;3801.7528860624247;3805.6955443564448;3808.21931504041;4593.471750748673;5641.238432811559;5821.621594596338;5851.459866780621;5852.385499522574;4885.291632713237;5067.594592432597;5117.462666420047;5122.052420302256;5144.06479043532;6035.668038257894;6298.748050077991;6322.629014119705;6339.270983836872;6351.714172862232;3723.888283351504;4073;4226.704130710821;4228.321360416427;4236.420224909977;6630.900536491957;6652.368025037614;6676.232233293184;6709.161354830646;6712.463002959751;3797.9050523736573;3992.181602771425;4249.87250382489;4274.1451709658195;4290.442242508454;4199.4120823084795;4315.913681726348;4334.241507736134;4338.956610433918;4348.652107831366;5061.215511595708;5099.934526516767;5114.33513643224;5136.743162841843;5139.3040296702;5153.819616313411;6367.108604795332;6380.904286435707;6413.070104834773;6456.548041637135;1312.343828085957;1408.7534681430914;1438;1456.5848733111013;1507.1785876697227;1009.6314845081492;1012.5645972232011;1025.8307379282485;1036.2746027863673;1036.512838965685;5859.121131830259;5906.202662640574;5913.586048976616;5940;5961;6189.535784816067;6212.968935155295;6492.902606460907;6498.415142637091;6515.8696826063215;5010.774515146827;5649.237352957381;5716.1997320375185;5850.385709500526;5865.061590145556;2994.7604191664614;3173.1758734726113;3414.7974556411473;3434.9313013739584;3444.380011597886;6501;6777.949417840243;6810.182778066664;6810.863782724317;6887.139107611549;6239.875202495925;6840.931590684129;6841.62371069586;6863.862722745617;6881.449484041264;6741.696623652046;6750.966245168841;6932.445404367639;6963.1644202696;6964;5795.94204057954;6342.619442833445;6607.438367738796;6616.90074648871;6657.700403481758;6028.33688294296;6246.719422518282;6508.707108180238;6534.869302614016;6542.574732642363;6210.4100110489435;6497.447716943923;6601.53789234749;6916.827079323057;6932.202796678446;4209.684273679425;4257.489101307863;4262.445882035316;4295.355696645528;4304.849330273425;5361.678299302054;5477.0830209426385;5748.683822389641;6612.8346791330605;6627.403533681992;6414.166158399378;6421.486281097407;6427.93572064292;6429.6142231466265;6454.386833250834;6200.720967556561;6398.872022559616;6400.423961843457;6411.326810684997;6428.903566446601;5279.419263881046;6233.906491402723;6269.247690277196;6287.993920972623;6301.684915754286;6354.4916406686425;6383.489081005521;6396.136521569529;6406.359364063556;6410.455111315404;5998.88002239944;6268.216472940882;6276.309605943434;6292.339304373158;6309.242890853127 05:06:45 INFO - PROCESS | 1941 | |5;yui.html;665.4510029225888;741.8627553902528;744.4602663069276;749.067411073214;753.2431301436741;14874.77687834682;15138.924305378485;15369.385224591017;15699.744020478363;15992.12043337616;14316.498922537714;14454.927725361358;14501.824726291035;14548.490802821903;14580.198089105095;736.7458226911707;754.9886751698729;912.4114945859916;917.954102294886;918.8024574716435;742.7363286033461;931.9860202096974;934.7522906429801;936.4053825820619;936.4100616611526;968.8885778135534;976;976.497103991445;977.941323520591;982.8181786369508;751.8421131562351;941.5574679900436;969.3747532841313;974.6345120579782;974.8878878928944;731.1920328037548;735.2647352647352;735.2867718313228;735.7939776862487;738.6491416577082;15095.396184152622;15178.848211517854;15190.088594684354;15201.543953681406;15223.847761522355;9862.9506852466;12406.19359741623;12511.124221304512;12751.42618582166;12990.805137922938;8584.22741953221;9436.339456238065;9923.55344009514;10075.093241608218;10722.370040903877;3446.7759595626453;3540.5928318243473;3580.7314451415987;3679.1537946272238;3783.4703141560203;84769;85141.72287415694;85354;85828.57085714531;87891.12108878957;9399.201067909304;9661.13049825519;9758.46328451942;9830.066143716336;10047.497625118862;22190.667139992915;22212.88935553216;22217.777822221895;22231.888840555694;22238.444038899157;7160.462965277625;7350.705971761176;7542.057242844645;7593.240675932473;7624.961875190588;3987.960120398817;4038.6749404101233;4061.7156799024374;4135.958640413617;4145.8963525912095;3600.3879340512176;3676.8345424455965;3676.889693309205;3678.576963649161;3679.8712045078296;601.8736065426309;610.7373829253464;611.6758118197363;621.8725161341914;623.7380300273896;570.7745440551042;581.912713093031;582.7355923818947;614.9108379285068;630.168178005029;7879.290863822283;7900.407469439815;7909.24862138096;8001.679932802623;8315.2617233353;541.8049502179213;544.9754761035763;577.3591313641854;578.8466056495032;610.3713175429315;3845.1540661054523;3891.213807017328;4049.7367671101283;4149.585041495826;4224.239636865394;87557.5622121898;87856.12143878607;89830.55084724535;94484;94850.5257473722;70269;74019.62990185122;81280.78078828822;82360.58819705983;83162;48721.512784871695;52490.482709005824;62495.812646558064;64721.352786471536;67243.61185687353;8075.394345424234;8117.512949223065;8220.136885626924;8236.382271329554;8369.246767790684;40156.79921600373;40626.374945001866;42040.1591968165;42340.1531969365;44684.55315446804;56473;56677.716611416676;57338.13992790028;58654.706726466095;59371.10943335767;11025.558977640805;11238.044766194638;11269.267497612629;11281.66155015318;11578.768424631291;12243.32661703615;12455.626331210078;12466.812997804858;12586.056045796786;13003.934980325037;11398.544058237578;11633.127515436207;11873.821892671443;11913.106517011085;12014.219075760047;11481.36852473122;11533.365664888175;11586.42067896585;11985.820212696992;12105.576304829288;11326.603568875049;11404.828927565926;11504.769904602028;11599.76800464003;11873.346965916957;9528.666496672584;9715.319927605282;9741.659041933499;9927.751806204902;10136.797264054825;7899.921000789918;8619.793228948078;9028.683996060106;9077.75318894979;9629.951850240705 05:06:45 INFO - PROCESS | 1941 | __end_tp_report 05:06:45 INFO - PROCESS | 1941 | __start_cc_report 05:06:45 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1234 05:06:45 INFO - PROCESS | 1941 | __end_cc_report 05:06:45 INFO - PROCESS | 1941 | __startTimestamp1461586005061__endTimestamp 05:06:45 INFO - PROCESS | 1941 | 05:06:45 INFO - PROCESS | 1941 | ------- Summary: start ------- 05:06:45 INFO - PROCESS | 1941 | Number of tests: 6 05:06:45 INFO - PROCESS | 1941 | 05:06:45 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5713.44 Median:5972.64 stddev:652.32 (10.9%) stddev-sans-first:628.07 05:06:45 INFO - PROCESS | 1941 | Values: 3378.3 3397.4 3444.5 3451.6 3484.9 5636.5 5661.5 5688.5 5700.6 5710.8 4821.2 5613.6 5710.7 5716.2 5720.0 5122.2 5122.4 5167.6 5175.9 5226.4 5222.7 5227.9 5229.7 5233.4 5234.9 5131.5 5164.1 5170.6 5179.3 5260.4 5160.9 5199.9 5230.4 5231.3 5285.4 5060.2 5071.5 5075.9 5136.3 5136.6 4641.9 5607.3 5618.1 5638.1 5649.2 6219.2 6242.8 6245.6 6256.5 6258.5 6105.9 6194.9 6198.9 6217.9 6222.3 5815.1 5864.4 5875.1 5913.4 5914.4 6270.4 6284.2 6285.4 6288.8 6296.0 6047.7 6166.6 6167.6 6191.9 6218.4 6160.7 6162.1 6234.5 6247.9 6248.3 6095.5 6145.4 6159.7 6174.4 6175.1 6080.9 6086.4 6163.0 6184.5 6206.6 4909.3 4913.6 4940.2 4979.8 4995.3 4443.8 4448.6 4458.1 4459.6 4466.9 5582.4 5693.1 5750.2 5777.7 5786.9 6209.8 6226.8 6265.7 6288.8 6298.3 5714.4 5717.0 5718.7 5735.8 5753.8 4613.7 5415.6 5416.8 5429.3 5465.5 6141.9 6239.0 6291.9 6303.2 6307.1 6239.4 6250.2 6258.2 6271.2 6271.9 6200.4 6304.6 6316.8 6332.4 6333.9 6144.2 6152.2 6233.2 6234.8 6244.3 6245.4 6256.6 6269.7 6289.7 6303.4 5970.4 6027.8 6146.0 6154.7 6190.8 5914.6 6020.4 6042.2 6080.1 6132.8 5922.0 5965.9 5972.6 5980.2 5980.7 4518.7 5996.7 6111.5 6133.7 6151.9 4923.0 5942.5 6013.2 6027.9 6051.8 05:06:45 INFO - PROCESS | 1941 | 05:06:45 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:71218.45 Median:3367.56 stddev:165297.30 (4908.5%) stddev-sans-first:165675.68 05:06:45 INFO - PROCESS | 1941 | Values: 484.2 516.3 527.4 579.1 611.9 27077.0 30833.0 37691.0 38890.4 39230.6 33573.5 41368.6 41495.0 41570.8 41718.6 9618.8 9822.0 9849.8 9850.0 9862.2 8542.0 8886.6 11432.5 11788.0 11815.7 11549.1 11660.9 11661.9 11672.6 11684.8 8612.1 11447.4 11503.3 11567.9 11592.2 11394.9 11553.1 11571.2 11581.8 11591.1 38441.4 38998.4 39039.6 39044.8 39045.4 3149.4 3212.5 3355.7 3372.8 3480.7 27192.7 28795.7 28841.9 28997.0 29382.9 2666.0 2703.1 2734.8 2807.3 2909.0 496634.0 503333.0 503429.0 504314.0 504959.0 34885.7 35892.6 59961.7 61130.4 61446.7 29120.6 34520.0 34622.7 34741.7 34773.5 14794.2 15022.8 15031.7 15034.2 15034.5 11484.0 11773.4 11784.5 12669.6 12755.9 7957.7 8114.4 8166.9 8189.0 8226.0 3285.2 3355.6 3362.3 3373.5 3396.0 967.1 982.8 1006.0 1060.9 1062.5 28245.9 28819.4 29915.3 30173.4 30244.8 1068.9 1079.3 1163.4 1179.3 1216.8 15277.7 15283.3 15337.5 15770.7 15778.4 525888.0 758738.0 778369.0 782235.0 782308.0 61160.7 61761.7 62525.0 62678.7 62733.0 1331.2 1375.4 1493.2 1500.2 1516.4 24953.6 26477.6 31167.5 31605.0 32105.7 56591.0 57596.7 57863.0 58116.5 58189.4 582678.1 587952.0 588112.4 589033.1 589542.0 23840.9 24488.0 26492.9 26880.6 27430.2 33315.3 34443.0 36970.8 42417.6 43991.6 34402.8 34810.8 34816.8 35402.5 35650.8 34192.3 34213.0 34751.5 35075.0 35138.5 34938.7 35291.6 36975.6 38136.6 38656.2 30505.5 31353.1 31490.5 31512.5 31587.1 19757.4 23210.3 23364.5 23491.7 24067.6 05:06:45 INFO - PROCESS | 1941 | 05:06:45 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:33533.81 Median:14061.68 stddev:96820.13 (688.5%) stddev-sans-first:97059.67 05:06:45 INFO - PROCESS | 1941 | Values: 350.8 390.9 391.3 405.8 412.7 20029.5 21439.7 21840.0 21924.8 21941.7 11810.6 12312.0 12326.6 20922.2 22034.4 5188.3 5337.7 5380.4 5385.7 5681.6 5820.9 6065.4 6067.4 6068.9 6070.2 5497.2 5535.1 6027.5 6028.7 6052.5 5190.5 5318.1 5357.2 5362.3 5388.5 5005.6 5203.2 5220.4 5377.4 5392.1 13808.0 13954.0 14027.7 14400.0 25405.5 2158.7 2165.1 2325.6 2717.9 2794.6 7958.0 9861.8 10214.3 12463.8 14386.4 1692.8 1705.5 1737.9 1743.9 1765.6 125604.0 127719.0 128554.7 129090.0 129273.0 9360.2 10103.7 11552.4 11752.2 11966.5 15274.5 15346.5 15382.3 15394.3 15454.5 8567.4 8743.3 8788.3 8888.9 8911.6 8609.7 8643.9 8644.6 8748.9 8752.9 4530.9 4610.6 4626.0 4629.7 4651.7 1623.6 1881.6 1889.3 1957.1 1992.9 920.1 945.8 1014.0 1024.8 1046.3 16722.7 19375.5 19461.0 20258.6 20527.7 846.9 854.9 967.9 999.5 1006.4 10315.8 10584.5 10608.6 10616.9 10623.0 567586.0 575761.0 581455.0 583334.0 586015.0 71312.0 71618.0 132083.7 134664.0 137774.0 924.5 1013.6 1036.7 1057.2 1062.0 13975.5 14377.4 17613.6 17839.5 18163.4 34491.7 42917.8 50388.0 50925.0 53008.5 76650.0 76973.0 78438.2 78505.0 80522.0 7866.5 8080.9 8145.6 8261.1 8448.7 7524.9 10986.8 11321.6 12589.9 13222.1 10800.9 11025.5 11048.9 11114.2 11494.8 10833.1 10835.8 10933.5 11120.3 11287.3 6119.3 9827.8 10287.7 11375.0 11561.9 8154.5 8214.9 8331.9 8410.1 8427.0 8497.1 9296.4 10935.3 11518.7 11616.1 05:06:45 INFO - PROCESS | 1941 | 05:06:45 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:5843.23 Median:4897.63 stddev:3291.62 (67.2%) stddev-sans-first:3284.00 05:06:45 INFO - PROCESS | 1941 | Values: 1411.7 1552.0 1570.5 1815.3 1972.5 8097.9 8943.7 9177.1 9208.4 9238.0 8778.7 9030.4 9061.6 9087.7 9095.9 2173.3 2376.1 2470.6 2471.6 2498.3 2502.1 2524.3 2524.7 2525.7 2526.5 2095.2 2425.6 2468.4 2471.2 2475.9 2148.7 2363.5 2444.6 2451.8 2452.5 2447.6 2458.8 2480.0 2480.2 2487.8 9009.0 9021.8 9093.2 9096.5 9099.1 5249.4 5428.3 5430.7 5444.8 5458.9 3543.9 3563.0 3585.8 3651.3 3655.3 1017.8 1025.7 1029.5 1050.2 1052.0 12113.2 12138.9 12174.5 12177.6 12233.0 4691.3 4831.9 4858.5 4953.1 4966.8 10110.4 10113.1 10134.9 10135.5 10168.3 5488.5 5494.3 5497.2 5520.7 5536.5 4068.5 4543.5 4603.8 4725.2 5001.5 3965.9 4069.0 4088.3 4108.4 4219.5 1336.5 1339.7 1350.8 1353.2 1371.2 878.5 897.8 905.2 950.6 969.4 5938.9 5950.9 6089.5 6098.1 6260.7 909.4 918.3 952.6 963.2 1031.3 3327.2 3960.8 3988.1 4051.0 4070.3 8851.0 11094.2 11163.6 11245.4 11272.5 10720.5 11051.0 11173.6 11178.4 11178.9 8415.0 8444.5 8582.0 8583.5 8601.4 5807.5 5862.9 5862.9 6015.2 6071.1 9741.2 10351.1 10416.2 10522.9 10544.4 10283.7 10342.4 10376.3 10386.2 10396.5 6982.4 7031.9 7370.0 7480.0 7657.0 8069.9 8139.7 8154.6 8211.6 8673.7 6875.2 7624.7 7792.2 7907.5 7917.5 7489.7 7514.7 7600.0 7622.8 7631.5 7093.7 7127.6 7220.5 7221.0 7226.8 4963.7 5002.4 5014.7 5072.8 5284.7 4936.8 5008.9 5035.7 5105.6 5296.8 05:06:45 INFO - PROCESS | 1941 | 05:06:45 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:5145.23 Median:5808.78 stddev:1638.64 (28.2%) stddev-sans-first:1602.55 05:06:45 INFO - PROCESS | 1941 | Values: 310.1 315.6 324.3 328.1 328.5 4974.5 5894.6 6058.4 6068.9 6087.9 4727.0 4917.6 4935.2 5673.8 6004.1 4445.5 4489.1 4490.5 4521.8 4536.5 3943.5 4506.4 4517.2 4518.4 4546.2 4252.0 4415.6 4439.5 4443.7 4494.9 4382.6 4426.0 4428.1 4442.3 4454.2 2978.6 3577.4 3801.8 3805.7 3808.2 4593.5 5641.2 5821.6 5851.5 5852.4 4885.3 5067.6 5117.5 5122.1 5144.1 6035.7 6298.7 6322.6 6339.3 6351.7 3723.9 4073.0 4226.7 4228.3 4236.4 6630.9 6652.4 6676.2 6709.2 6712.5 3797.9 3992.2 4249.9 4274.1 4290.4 4199.4 4315.9 4334.2 4339.0 4348.7 5061.2 5099.9 5114.3 5136.7 5139.3 5153.8 6367.1 6380.9 6413.1 6456.5 1312.3 1408.8 1438.0 1456.6 1507.2 1009.6 1012.6 1025.8 1036.3 1036.5 5859.1 5906.2 5913.6 5940.0 5961.0 6189.5 6213.0 6492.9 6498.4 6515.9 5010.8 5649.2 5716.2 5850.4 5865.1 2994.8 3173.2 3414.8 3434.9 3444.4 6501.0 6777.9 6810.2 6810.9 6887.1 6239.9 6840.9 6841.6 6863.9 6881.4 6741.7 6751.0 6932.4 6963.2 6964.0 5795.9 6342.6 6607.4 6616.9 6657.7 6028.3 6246.7 6508.7 6534.9 6542.6 6210.4 6497.4 6601.5 6916.8 6932.2 4209.7 4257.5 4262.4 4295.4 4304.8 5361.7 5477.1 5748.7 6612.8 6627.4 6414.2 6421.5 6427.9 6429.6 6454.4 6200.7 6398.9 6400.4 6411.3 6428.9 5279.4 6233.9 6269.2 6288.0 6301.7 6354.5 6383.5 6396.1 6406.4 6410.5 5998.9 6268.2 6276.3 6292.3 6309.2 05:06:45 INFO - PROCESS | 1941 | 05:06:45 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:17669.00 Median:59012.91 stddev:24687.06 (41.8%) stddev-sans-first:24723.29 05:06:45 INFO - PROCESS | 1941 | Values: 665.5 741.9 744.5 749.1 753.2 14874.8 15138.9 15369.4 15699.7 15992.1 14316.5 14454.9 14501.8 14548.5 14580.2 736.7 755.0 912.4 918.0 918.8 742.7 932.0 934.8 936.4 936.4 968.9 976.0 976.5 977.9 982.8 751.8 941.6 969.4 974.6 974.9 731.2 735.3 735.3 735.8 738.6 15095.4 15178.8 15190.1 15201.5 15223.8 9863.0 12406.2 12511.1 12751.4 12990.8 8584.2 9436.3 9923.6 10075.1 10722.4 3446.8 3540.6 3580.7 3679.2 3783.5 84769.0 85141.7 85354.0 85828.6 87891.1 9399.2 9661.1 9758.5 9830.1 10047.5 22190.7 22212.9 22217.8 22231.9 22238.4 7160.5 7350.7 7542.1 7593.2 7625.0 3988.0 4038.7 4061.7 4136.0 4145.9 3600.4 3676.8 3676.9 3678.6 3679.9 601.9 610.7 611.7 621.9 623.7 570.8 581.9 582.7 614.9 630.2 7879.3 7900.4 7909.2 8001.7 8315.3 541.8 545.0 577.4 578.8 610.4 3845.2 3891.2 4049.7 4149.6 4224.2 87557.6 87856.1 89830.6 94484.0 94850.5 70269.0 74019.6 81280.8 82360.6 83162.0 48721.5 52490.5 62495.8 64721.4 67243.6 8075.4 8117.5 8220.1 8236.4 8369.2 40156.8 40626.4 42040.2 42340.2 44684.6 56473.0 56677.7 57338.1 58654.7 59371.1 11025.6 11238.0 11269.3 11281.7 11578.8 12243.3 12455.6 12466.8 12586.1 13003.9 11398.5 11633.1 11873.8 11913.1 12014.2 11481.4 11533.4 11586.4 11985.8 12105.6 11326.6 11404.8 11504.8 11599.8 11873.3 9528.7 9715.3 9741.7 9927.8 10136.8 7899.9 8619.8 9028.7 9077.8 9630.0 05:06:45 INFO - PROCESS | 1941 | -------- Summary: end -------- 05:06:45 INFO - PROCESS | 1941 | 05:06:45 INFO - TEST-INFO | 1941: exit 0 05:06:45 INFO - TEST-OK | dromaeo_css | took 1115700ms 05:06:45 INFO - TEST-START | kraken 05:06:45 INFO - operating with platform_type : mac_ 05:06:45 INFO - Initialising browser for kraken test... 05:06:45 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpe2CTgB/profile http://localhost:55534/getInfo.html) 05:06:47 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 05:06:47 INFO - PROCESS | 1944 | colorDepth:24 05:06:47 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 05:06:47 INFO - PROCESS | 1944 | __metrics 05:06:48 INFO - PROCESS | 1944 | 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:06:48 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:06:48 INFO - TEST-INFO | 1944: exit 0 05:06:48 INFO - Browser initialized. 05:06:48 INFO - Running cycle 1/1 for kraken test... 05:06:48 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpe2CTgB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:00 INFO - PROCESS | 1946 | RSS: Main: 238903296 05:07:00 INFO - PROCESS | 1946 | 05:07:03 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55534/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:04 INFO - PROCESS | 1946 | RSS: Main: 286035968 05:07:04 INFO - PROCESS | 1946 | 05:07:06 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55534/tests/kraken/driver.html?testName=audio-dft) 05:07:07 INFO - PROCESS | 1946 | RSS: Main: 298987520 05:07:07 INFO - PROCESS | 1946 | 05:07:10 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55534/tests/kraken/driver.html?testName=audio-fft) 05:07:11 INFO - PROCESS | 1946 | RSS: Main: 387604480 05:07:11 INFO - PROCESS | 1946 | 05:07:13 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55534/tests/kraken/driver.html?testName=audio-oscillator) 05:07:14 INFO - PROCESS | 1946 | RSS: Main: 287178752 05:07:14 INFO - PROCESS | 1946 | 05:07:16 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55534/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:16 INFO - PROCESS | 1946 | RSS: Main: 291729408 05:07:16 INFO - PROCESS | 1946 | 05:07:19 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55534/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:20 INFO - PROCESS | 1946 | RSS: Main: 330702848 05:07:20 INFO - PROCESS | 1946 | 05:07:23 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55534/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:23 INFO - PROCESS | 1946 | RSS: Main: 331882496 05:07:23 INFO - PROCESS | 1946 | 05:07:26 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55534/tests/kraken/driver.html?testName=json-parse-financial) 05:07:27 INFO - PROCESS | 1946 | RSS: Main: 327704576 05:07:27 INFO - PROCESS | 1946 | 05:07:28 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55534/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:29 INFO - PROCESS | 1946 | RSS: Main: 320073728 05:07:29 INFO - PROCESS | 1946 | 05:07:31 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55534/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:31 INFO - PROCESS | 1946 | RSS: Main: 326828032 05:07:31 INFO - PROCESS | 1946 | 05:07:33 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55534/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:07:34 INFO - PROCESS | 1946 | RSS: Main: 330158080 05:07:34 INFO - PROCESS | 1946 | 05:07:37 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55534/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:07:38 INFO - PROCESS | 1946 | RSS: Main: 334700544 05:07:38 INFO - PROCESS | 1946 | 05:07:40 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55534/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:07:41 INFO - PROCESS | 1946 | RSS: Main: 332410880 05:07:41 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:55534/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55534/tests/kraken/driver.html?testName=ai-astar) 05:07:43 INFO - PROCESS | 1946 | RSS: Main: 397189120 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | __start_tp_report 05:07:43 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 05:07:43 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 05:07:43 INFO - PROCESS | 1946 | |i|pagename|runs| 05:07:43 INFO - PROCESS | 1946 | |0;ai-astar;113;111;116;114;113;114;118;112;114;113 05:07:43 INFO - PROCESS | 1946 | |1;audio-beat-detection;159;122;144;167;119;142;150;106;198;149 05:07:43 INFO - PROCESS | 1946 | |2;audio-dft;188;185;183;183;184;180;184;183;184;184 05:07:43 INFO - PROCESS | 1946 | |3;audio-fft;76;72;86;89;56;57;61;78;84;55 05:07:43 INFO - PROCESS | 1946 | |4;audio-oscillator;88;95;145;80;77;78;124;78;78;129 05:07:43 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;87;85;89;86;87;84;88;85;88;84 05:07:43 INFO - PROCESS | 1946 | |6;imaging-darkroom;93;94;94;93;93;105;148;111;93;94 05:07:43 INFO - PROCESS | 1946 | |7;imaging-desaturate;85;86;83;86;85;83;84;83;84;83 05:07:43 INFO - PROCESS | 1946 | |8;json-parse-financial;59;60;60;61;61;62;59;77;61;59 05:07:43 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;67;65;70;70;71;68;67;68;67;69 05:07:43 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;82;83;82;81;82;79;80;80;82;83 05:07:43 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;142;140;159;152;136;135;147;158;180;140 05:07:43 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;170;172;176;170;166;166;169;169;169;168 05:07:43 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;68;68;70;92;94;68;69;71;66;86 05:07:43 INFO - PROCESS | 1946 | __end_tp_report 05:07:43 INFO - PROCESS | 1946 | __start_cc_report 05:07:43 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,1993 05:07:43 INFO - PROCESS | 1946 | __end_cc_report 05:07:43 INFO - PROCESS | 1946 | __startTimestamp1461586063550__endTimestamp 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | ------- Summary: start ------- 05:07:43 INFO - PROCESS | 1946 | Number of tests: 14 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.99 (1.7%) stddev-sans-first:2.09 05:07:43 INFO - PROCESS | 1946 | Values: 113.0 111.0 116.0 114.0 113.0 114.0 118.0 112.0 114.0 113.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:145.60 Median:149.50 stddev:26.38 (17.6%) stddev-sans-first:27.53 05:07:43 INFO - PROCESS | 1946 | Values: 159.0 122.0 144.0 167.0 119.0 142.0 150.0 106.0 198.0 149.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.99 (1.1%) stddev-sans-first:1.41 05:07:43 INFO - PROCESS | 1946 | Values: 188.0 185.0 183.0 183.0 184.0 180.0 184.0 183.0 184.0 184.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:71.40 Median:77.00 stddev:13.20 (17.1%) stddev-sans-first:13.90 05:07:43 INFO - PROCESS | 1946 | Values: 76.0 72.0 86.0 89.0 56.0 57.0 61.0 78.0 84.0 55.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:78.00 stddev:25.63 (32.9%) stddev-sans-first:26.97 05:07:43 INFO - PROCESS | 1946 | Values: 88.0 95.0 145.0 80.0 77.0 78.0 124.0 78.0 78.0 129.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.77 (2.0%) stddev-sans-first:1.86 05:07:43 INFO - PROCESS | 1946 | Values: 87.0 85.0 89.0 86.0 87.0 84.0 88.0 85.0 88.0 84.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:101.80 Median:93.00 stddev:17.39 (18.7%) stddev-sans-first:18.15 05:07:43 INFO - PROCESS | 1946 | Values: 93.0 94.0 94.0 93.0 93.0 105.0 148.0 111.0 93.0 94.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.50 stddev:1.23 (1.5%) stddev-sans-first:1.27 05:07:43 INFO - PROCESS | 1946 | Values: 85.0 86.0 83.0 86.0 85.0 83.0 84.0 83.0 84.0 83.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:61.00 stddev:5.40 (8.9%) stddev-sans-first:5.63 05:07:43 INFO - PROCESS | 1946 | Values: 59.0 60.0 60.0 61.0 61.0 62.0 59.0 77.0 61.0 59.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.50 stddev:1.81 (2.6%) stddev-sans-first:1.87 05:07:43 INFO - PROCESS | 1946 | Values: 67.0 65.0 70.0 70.0 71.0 68.0 67.0 68.0 67.0 69.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:82.00 stddev:1.35 (1.6%) stddev-sans-first:1.41 05:07:43 INFO - PROCESS | 1946 | Values: 82.0 83.0 82.0 81.0 82.0 79.0 80.0 80.0 82.0 83.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:149.50 stddev:13.87 (9.3%) stddev-sans-first:14.48 05:07:43 INFO - PROCESS | 1946 | Values: 142.0 140.0 159.0 152.0 136.0 135.0 147.0 158.0 180.0 140.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:169.50 stddev:2.92 (1.7%) stddev-sans-first:3.09 05:07:43 INFO - PROCESS | 1946 | Values: 170.0 172.0 176.0 170.0 166.0 166.0 169.0 169.0 169.0 168.0 05:07:43 INFO - PROCESS | 1946 | 05:07:43 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:70.50 stddev:10.93 (15.5%) stddev-sans-first:11.28 05:07:43 INFO - PROCESS | 1946 | Values: 68.0 68.0 70.0 92.0 94.0 68.0 69.0 71.0 66.0 86.0 05:07:43 INFO - PROCESS | 1946 | -------- Summary: end -------- 05:07:43 INFO - PROCESS | 1946 | 05:07:44 INFO - TEST-INFO | 1946: exit 0 05:07:44 INFO - TEST-OK | kraken | took 58762ms 05:07:44 INFO - SUITE-END | took 1174s 05:07:44 INFO - Completed test suite (00:19:34) 05:07:44 INFO - javascript benchmark 05:07:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.2736711607, 3397.4224381855083, 3444.4833275008737, 3451.5512983312165, 3484.930301393972, 5636.549076073915, 5661.547076233896, 5688.488036076752, 5700.572457065716, 5710.828675139747, 4821.156297647915, 5613.578981576377, 5710.657360558359, 5716.199732037508, 5720.0, 5122.154844450671, 5122.410922743877, 5167.612429067816, 5175.896482070375, 5226.425093239741, 5222.712750798704, 5227.921581176285, 5229.660072095321, 5233.4243233244315, 5234.895302093956, 5131.538161565477, 5164.147915593922, 5170.586353091744, 5179.326687530615, 5260.395054568736, 5160.948390516103, 5199.870003249911, 5230.398504172031, 5231.267622532849, 5285.392179899322, 5060.2409638554145, 5071.467495912912, 5075.923861142086, 5136.280920671109, 5136.5890728741615, 4641.883952901171, 5607.299087614048, 5618.073017952033, 5638.069718496443, 5649.180868774005, 6219.1604133442315, 6242.750289988395, 6245.56281060326, 6256.5307601930035, 6258.4680302174365, 6105.938940610581, 6194.938050619527, 6198.876022479525, 6217.875642487125, 6222.284437289724, 5815.069588865804, 5864.413558644102, 5875.148103524965, 5913.438223368773, 5914.408559144051, 6270.372962703785, 6284.183056202756, 6285.43431091204, 6288.779892703734, 6296.024116261987, 6047.697615119227, 6166.599171053867, 6167.629942203482, 6191.938080619227, 6218.409251121137, 6160.722767475474, 6162.075688646739, 6234.470070044006, 6247.9062814057825, 6248.250209974741, 6095.542834287447, 6145.354737752472, 6159.6612186330185, 6174.38256174379, 6175.073738939195, 6080.9391906081255, 6086.391360863879, 6162.969185154046, 6184.536159788034, 6206.565540412128, 4909.3126962225315, 4913.631477639191, 4940.1601727706375, 4979.800807967713, 4995.275685025651, 4443.755593442392, 4448.5773851484055, 4458.08609235106, 4459.554044595579, 4466.9106617867465, 5582.358028826737, 5693.060644993529, 5750.1761328716775, 5777.711114444345, 5786.855328616734, 6209.751609935552, 6226.844328891723, 6265.655388953652, 6288.842778930563, 6298.275698294663, 5714.428557144252, 5717.0, 5718.656880587178, 5735.799247026334, 5753.769849206069, 4613.653975951817, 5415.566754659618, 5416.756245968941, 5429.321334833146, 5465.535429488458, 6141.946315431883, 6239.0, 6291.937080629135, 6303.212098487689, 6307.148534947723, 6239.407256310643, 6250.218722659668, 6258.186435763321, 6271.247450305992, 6271.905921411091, 6200.410960958701, 6304.590201636879, 6316.810495685137, 6332.430081292706, 6333.936660633519, 6144.1567529740005, 6152.200213972155, 6233.189685341058, 6234.781782637586, 6244.281907580505, 6245.3754624539, 6256.624602523863, 6269.749210031548, 6289.716962736779, 6303.36966303366, 5970.402959703995, 6027.819165425044, 6146.0, 6154.692265386802, 6190.81427557174, 5914.645121292736, 6020.39796020412, 6042.1540984261355, 6080.087986802015, 6132.816015519542, 5921.970390148022, 5965.940340596539, 5972.641641501524, 5980.222571065734, 5980.700964951648, 4518.728876267434, 5996.730147143302, 6111.54163437735, 6133.723982420891, 6151.9384806151365, 4922.975385123052, 5942.494887934574, 6013.218281623361, 6027.879442411215, 6051.818445446644], "value": 5673.353981694126, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.21073649950574, 516.3004129404657, 527.3855957809153, 579.1313030454318, 611.8684482836189, 27077.0, 30833.0, 37691.0, 38890.416643750366, 39230.607693923055, 33573.49639755406, 41368.586314136846, 41495.0, 41570.792146039304, 41718.58281417185, 9618.75953101171, 9821.950890245538, 9849.803003939916, 9849.950750246238, 9862.211023118134, 8542.0176679682, 8886.600102995348, 11432.542698292058, 11787.998020168296, 11815.704607384798, 11549.133814963869, 11660.883391166108, 11661.941690291536, 11672.649820505356, 11684.766304673902, 8612.1387861214, 11447.427628618578, 11503.252288601298, 11567.942160289145, 11592.246503977214, 11394.943025284903, 11553.13351498641, 11571.247868888495, 11581.76836463266, 11591.07271418293, 38441.42337864934, 38998.415023774665, 39039.60960390388, 39044.80477597622, 39045.41431878524, 3149.401613693391, 3212.4699424594914, 3355.6979871811654, 3372.7976321420547, 3480.6693364130365, 27192.728072719416, 28795.70419331135, 28841.85579072091, 28997.0, 29382.853085734434, 2665.9600105998425, 2703.0539311241105, 2734.8222365546176, 2807.3402750353653, 2908.9854550727323, 496634.0, 503333.0, 503429.0, 504314.0, 504958.99999999633, 34885.65114348824, 35892.64107358945, 59961.70019149876, 61130.388696112466, 61446.69276653678, 29120.563191552144, 34520.0, 34622.65377346244, 34741.652583474344, 34773.47839782405, 14794.186319752516, 15022.849771502364, 15031.699366012837, 15034.248287585577, 15034.473793417397, 11484.0, 11773.352465614302, 11784.528618855149, 12669.556565520163, 12755.93622031884, 7957.721479748297, 8114.391420643476, 8166.918330816735, 8188.959055204686, 8225.95887020561, 3285.178705323669, 3355.647656996029, 3362.327534493062, 3373.4602463605706, 3396.0, 967.1102585621245, 982.8083523712951, 1005.9789313846452, 1060.877999030106, 1062.5324857062867, 28245.858770706014, 28819.402806092057, 29915.25211869677, 30173.35156335781, 30244.84877575642, 1068.903798658109, 1079.2553138334524, 1163.3892206591438, 1179.3159967218992, 1216.8466544185544, 15277.694446111016, 15283.312250948511, 15337.463188788563, 15770.684586308209, 15778.368865245491, 525888.0, 758738.0, 778369.0, 782235.0, 782308.0, 61160.69419652962, 61761.691191544654, 62525.0, 62678.746425071244, 62733.0, 1331.181323486049, 1375.3810785146925, 1493.1862135136378, 1500.1524138861507, 1516.3782849032063, 24953.625695614217, 26477.602835957092, 31167.532487013166, 31605.0, 32105.67894321027, 56591.0, 57596.71201643965, 57863.0, 58116.54708632318, 58189.4181058184, 582678.0866095643, 587952.0, 588112.4160654356, 589033.0548347231, 589542.0, 23840.880795596604, 24488.0, 26492.867535662197, 26880.59679104854, 27430.17709468719, 33315.33369332648, 34442.96671099871, 36970.8151459241, 42417.575824241365, 43991.56008439875, 34402.82798585991, 34810.82594587112, 34816.825915871275, 35402.46896296506, 35650.8217458911, 34192.31615367728, 34213.0, 34751.4787278186, 35075.0, 35138.47292290669, 34938.65061349354, 35291.64708352986, 36975.63024369722, 38136.618633813305, 38656.2268754629, 30505.54241686332, 31353.05940821779, 31490.527642085846, 31512.52731208988, 31587.052388428383, 19757.40727778169, 23210.3036908893, 23364.53270934606, 23491.70795606258, 24067.638985415582], "value": 18627.153890332727, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.84387447585834, 390.9179072394797, 391.3112921258585, 405.8457786041304, 412.729662071343, 20029.499262518446, 21439.67840482394, 21840.0, 21924.78075219249, 21941.67087493687, 11810.58662946797, 12312.0, 12326.568570100048, 20922.163113475443, 22034.4491387715, 5188.2995795567585, 5337.70642614656, 5380.435054319299, 5385.7307134643115, 5681.57388195887, 5820.912686309709, 6065.3934606539215, 6067.423594758491, 6068.939310606882, 6070.21087258658, 5497.175423686459, 5535.114381698929, 6027.457528822405, 6028.728707208186, 6052.485538729214, 5190.532852043315, 5318.1225097858805, 5357.1696387060065, 5362.329708786402, 5388.515033646951, 5005.57452616528, 5203.16749320107, 5220.399654039796, 5377.381601115882, 5392.083345831218, 13808.0, 13954.0, 14027.719445611132, 14400.0, 25405.491890162277, 2158.6869903863853, 2165.07984106754, 2325.5581439526604, 2717.8641067946523, 2794.580812878064, 7957.960210198969, 9861.753456163582, 10214.28500005, 12463.75072498545, 14386.35261413239, 1692.7545505901576, 1705.5139285303628, 1737.860971122307, 1743.8517725993306, 1765.5939133999125, 125604.0, 127719.0, 128554.71445285427, 129090.0, 129273.0, 9360.191942417248, 10103.696889093339, 11552.422378881023, 11752.23610465317, 11966.461509232106, 15274.541763747106, 15346.539603811903, 15382.3077961492, 15394.307256173499, 15454.459093931659, 8567.357448191411, 8743.256823169975, 8788.296936245086, 8888.866671998674, 8911.643534258557, 8609.698660546976, 8643.870341944876, 8644.610992505352, 8748.8687669685, 8752.8687069694, 4530.912580980541, 4610.561996610317, 4625.953740462553, 4629.65277604181, 4651.67438279324, 1623.5778697538724, 1881.6424879273168, 1889.3387314439835, 1957.0997341222912, 1992.900354982245, 920.1396694091046, 945.7730144765344, 1013.9847902281472, 1024.8104100741386, 1046.2571574182264, 16722.665546688997, 19375.51561210981, 19461.0, 20258.59482810365, 20527.692084618742, 846.9153084691604, 854.8974123105268, 967.9370840895319, 999.4702807512031, 1006.3810756384771, 10315.84526232107, 10584.523696433525, 10608.57565697379, 10616.946915265375, 10623.043926046694, 567586.0, 575761.0, 581455.0, 583334.0, 586015.0, 71312.0, 71618.0, 132083.71665133288, 134663.98004029947, 137774.0, 924.5423515359805, 1013.5591017907234, 1036.6993571864225, 1057.2123767793198, 1061.9522121504551, 13975.510857119953, 14377.436253941622, 17613.647727045132, 17839.46481605554, 18163.36428225006, 34491.655083448844, 42917.78541107274, 50388.0, 50925.0, 53008.46991530035, 76650.0, 76973.0, 78438.21561784309, 78505.0, 80522.0, 7866.528008319519, 8080.87878681832, 8145.551994640352, 8261.13258107914, 8448.704295349633, 7524.887126693214, 10986.780264394507, 11321.603743868925, 12589.874101259236, 13222.074454788073, 10800.891991079989, 11025.503852326505, 11048.895110488887, 11114.163964890968, 11494.770104598028, 10833.133349332193, 10835.783284334428, 10933.507992140532, 11120.277181983145, 11287.322760634388, 6119.3268740437625, 9827.754306142118, 10287.691369258935, 11375.0, 11561.942190288995, 8154.510729356258, 8214.917850821654, 8331.875021874797, 8410.116937721696, 8426.957865210634, 8497.107803680525, 9296.442213467213, 10935.28920620157, 11518.654440366803, 11616.12879034059], "value": 9206.760588987863, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.6612013116855, 1551.9534413967579, 1570.4503423801668, 1815.2557451444911, 1972.5068732816787, 8097.87853182202, 8943.686970956018, 9177.128172823588, 9208.355415120952, 9238.0, 8778.6927457539, 9030.367874248805, 9061.592228349724, 9087.681931132413, 9095.90904090959, 2173.315405647224, 2376.1089591403224, 2470.629405589167, 2471.6045432730766, 2498.3379404457833, 2502.0867383405107, 2524.3184340228127, 2524.6970363556306, 2525.734797846227, 2526.507331070442, 2095.224766836273, 2425.599776036952, 2468.4075821802726, 2471.221565206954, 2475.8762061896923, 2148.677698345245, 2363.5036642305136, 2444.5721998650165, 2451.8161137914726, 2452.4849781545718, 2447.617096340562, 2458.766417190364, 2480.0203919451715, 2480.1567467061286, 2487.838290511111, 9009.0, 9021.819563608691, 9093.227075698573, 9096.454212747256, 9099.090090990849, 5249.396319423277, 5428.267183930158, 5430.6741595504, 5444.754986025599, 5458.918116228259, 3543.8759643412614, 3562.982185089058, 3585.8027808470524, 3651.2697460508007, 3655.250673611903, 1017.7557386227326, 1025.7025462615832, 1029.526417847791, 1050.2438244463974, 1052.0478966535222, 12113.15207935445, 12138.939305303506, 12174.452149653287, 12177.634670959886, 12233.0, 4691.319758634979, 4831.931723355695, 4858.4898585648, 4953.108440480748, 4966.801327946841, 10110.443925584017, 10113.089821915915, 10134.949325253474, 10135.543900524493, 10168.28821982454, 5488.451154884479, 5494.340679118532, 5497.230387745719, 5520.723963801875, 5536.474034966672, 4068.532118806316, 4543.4775000875325, 4603.769811509411, 4725.243960966229, 5001.524855138756, 3965.940510892339, 4068.959310406917, 4088.3049881520215, 4108.36320370343, 4219.51475580306, 1336.5121730568467, 1339.6650837290676, 1350.797380392949, 1353.1948490648049, 1371.2184055088703, 878.5036454403241, 897.7665806890255, 905.23055402907, 950.6387572722322, 969.3844408800321, 5938.881222375615, 5950.85122871923, 6089.543284253698, 6098.085287206865, 6260.718267677966, 909.3907082254905, 918.3387960668308, 952.5713428956858, 963.2350382957991, 1031.2677998620914, 3327.234736010731, 3960.8415663373144, 3988.062805240766, 4050.959490405058, 4070.287699652549, 8851.0, 11094.223404361617, 11163.609273675382, 11245.381504017194, 11272.54909803615, 10720.463976801284, 11051.0, 11173.553057877594, 11178.44107794607, 11178.944105279421, 8414.957925210334, 8444.535550544657, 8582.013068497203, 8583.484990900566, 8601.354898382771, 5807.477327040587, 5862.912056319074, 5862.912056319244, 6015.187949626745, 6071.058985857199, 9741.172000380047, 10351.06840384369, 10416.166706663296, 10522.894771052192, 10544.367337959748, 10283.691489255334, 10342.37945723259, 10376.325538839952, 10386.221033422675, 10396.532156053147, 6982.35308164394, 7031.894521582078, 7369.963150184215, 7480.0, 7657.042869641295, 8069.919300806917, 8139.715109971359, 8154.633041513265, 8211.58942052907, 8673.696420625249, 6875.1749790025515, 7624.733134340271, 7792.18182090895, 7907.48601340911, 7917.485363451357, 7489.70041198346, 7514.699412023458, 7599.962000189964, 7622.809429764078, 7631.54210747357, 7093.716251349888, 7127.572345659277, 7220.458465614995, 7221.0, 7226.783196504113, 4963.677360971526, 5002.399712034579, 5014.749262536786, 5072.797088116434, 5284.735763211747, 4936.777844996912, 5008.9248661269385, 5035.748212589283, 5105.591552675703, 5296.841094767163], "value": 4691.943880207651, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.1130766009213, 315.61810209646325, 324.3237849084658, 328.14845475989796, 328.46788203110964, 4974.477679843619, 5894.58737888348, 6058.363871793465, 6068.87316624695, 6087.878242435148, 4727.0, 4917.606591472682, 4935.223319604948, 5673.801416950408, 6004.099385092239, 4445.466544014706, 4489.079738653568, 4490.50604433512, 4521.751303678297, 4536.5236650151755, 3943.507061617298, 4506.436695413074, 4517.186906356855, 4518.356761294646, 4546.158960592301, 4251.957480425187, 4415.646748260131, 4439.467263928317, 4443.733375997451, 4494.910101797962, 4382.583654552796, 4426.004149066466, 4428.092241090569, 4442.267025940716, 4454.153710794939, 2978.5978892849403, 3577.409727395003, 3801.7528860624247, 3805.6955443564448, 3808.21931504041, 4593.471750748673, 5641.238432811559, 5821.621594596338, 5851.459866780621, 5852.385499522574, 4885.291632713237, 5067.594592432597, 5117.462666420047, 5122.052420302256, 5144.06479043532, 6035.668038257894, 6298.748050077991, 6322.629014119705, 6339.270983836872, 6351.714172862232, 3723.888283351504, 4073.0, 4226.704130710821, 4228.321360416427, 4236.420224909977, 6630.900536491957, 6652.368025037614, 6676.232233293184, 6709.161354830646, 6712.463002959751, 3797.9050523736573, 3992.181602771425, 4249.87250382489, 4274.1451709658195, 4290.442242508454, 4199.4120823084795, 4315.913681726348, 4334.241507736134, 4338.956610433918, 4348.652107831366, 5061.215511595708, 5099.934526516767, 5114.33513643224, 5136.743162841843, 5139.3040296702, 5153.819616313411, 6367.108604795332, 6380.904286435707, 6413.070104834773, 6456.548041637135, 1312.343828085957, 1408.7534681430914, 1438.0, 1456.5848733111013, 1507.1785876697227, 1009.6314845081492, 1012.5645972232011, 1025.8307379282485, 1036.2746027863673, 1036.512838965685, 5859.121131830259, 5906.202662640574, 5913.586048976616, 5940.0, 5961.0, 6189.535784816067, 6212.968935155295, 6492.902606460907, 6498.415142637091, 6515.8696826063215, 5010.774515146827, 5649.237352957381, 5716.1997320375185, 5850.385709500526, 5865.061590145556, 2994.7604191664614, 3173.1758734726113, 3414.7974556411473, 3434.9313013739584, 3444.380011597886, 6501.0, 6777.949417840243, 6810.182778066664, 6810.863782724317, 6887.139107611549, 6239.875202495925, 6840.931590684129, 6841.62371069586, 6863.862722745617, 6881.449484041264, 6741.696623652046, 6750.966245168841, 6932.445404367639, 6963.1644202696, 6964.0, 5795.94204057954, 6342.619442833445, 6607.438367738796, 6616.90074648871, 6657.700403481758, 6028.33688294296, 6246.719422518282, 6508.707108180238, 6534.869302614016, 6542.574732642363, 6210.4100110489435, 6497.447716943923, 6601.53789234749, 6916.827079323057, 6932.202796678446, 4209.684273679425, 4257.489101307863, 4262.445882035316, 4295.355696645528, 4304.849330273425, 5361.678299302054, 5477.0830209426385, 5748.683822389641, 6612.8346791330605, 6627.403533681992, 6414.166158399378, 6421.486281097407, 6427.93572064292, 6429.6142231466265, 6454.386833250834, 6200.720967556561, 6398.872022559616, 6400.423961843457, 6411.326810684997, 6428.903566446601, 5279.419263881046, 6233.906491402723, 6269.247690277196, 6287.993920972623, 6301.684915754286, 6354.4916406686425, 6383.489081005521, 6396.136521569529, 6406.359364063556, 6410.455111315404, 5998.88002239944, 6268.216472940882, 6276.309605943434, 6292.339304373158, 6309.242890853127], "value": 4613.038757396116, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.4510029225888, 741.8627553902528, 744.4602663069276, 749.067411073214, 753.2431301436741, 14874.77687834682, 15138.924305378485, 15369.385224591017, 15699.744020478363, 15992.12043337616, 14316.498922537714, 14454.927725361358, 14501.824726291035, 14548.490802821903, 14580.198089105095, 736.7458226911707, 754.9886751698729, 912.4114945859916, 917.954102294886, 918.8024574716435, 742.7363286033461, 931.9860202096974, 934.7522906429801, 936.4053825820619, 936.4100616611526, 968.8885778135534, 976.0, 976.497103991445, 977.941323520591, 982.8181786369508, 751.8421131562351, 941.5574679900436, 969.3747532841313, 974.6345120579782, 974.8878878928944, 731.1920328037548, 735.2647352647352, 735.2867718313228, 735.7939776862487, 738.6491416577082, 15095.396184152622, 15178.848211517854, 15190.088594684354, 15201.543953681406, 15223.847761522355, 9862.9506852466, 12406.19359741623, 12511.124221304512, 12751.42618582166, 12990.805137922938, 8584.22741953221, 9436.339456238065, 9923.55344009514, 10075.093241608218, 10722.370040903877, 3446.7759595626453, 3540.5928318243473, 3580.7314451415987, 3679.1537946272238, 3783.4703141560203, 84769.0, 85141.72287415694, 85354.0, 85828.57085714531, 87891.12108878957, 9399.201067909304, 9661.13049825519, 9758.46328451942, 9830.066143716336, 10047.497625118862, 22190.667139992915, 22212.88935553216, 22217.777822221895, 22231.888840555694, 22238.444038899157, 7160.462965277625, 7350.705971761176, 7542.057242844645, 7593.240675932473, 7624.961875190588, 3987.960120398817, 4038.6749404101233, 4061.7156799024374, 4135.958640413617, 4145.8963525912095, 3600.3879340512176, 3676.8345424455965, 3676.889693309205, 3678.576963649161, 3679.8712045078296, 601.8736065426309, 610.7373829253464, 611.6758118197363, 621.8725161341914, 623.7380300273896, 570.7745440551042, 581.912713093031, 582.7355923818947, 614.9108379285068, 630.168178005029, 7879.290863822283, 7900.407469439815, 7909.24862138096, 8001.679932802623, 8315.2617233353, 541.8049502179213, 544.9754761035763, 577.3591313641854, 578.8466056495032, 610.3713175429315, 3845.1540661054523, 3891.213807017328, 4049.7367671101283, 4149.585041495826, 4224.239636865394, 87557.5622121898, 87856.12143878607, 89830.55084724535, 94484.0, 94850.5257473722, 70269.0, 74019.62990185122, 81280.78078828822, 82360.58819705983, 83162.0, 48721.512784871695, 52490.482709005824, 62495.812646558064, 64721.352786471536, 67243.61185687353, 8075.394345424234, 8117.512949223065, 8220.136885626924, 8236.382271329554, 8369.246767790684, 40156.79921600373, 40626.374945001866, 42040.1591968165, 42340.1531969365, 44684.55315446804, 56473.0, 56677.716611416676, 57338.13992790028, 58654.706726466095, 59371.10943335767, 11025.558977640805, 11238.044766194638, 11269.267497612629, 11281.66155015318, 11578.768424631291, 12243.32661703615, 12455.626331210078, 12466.812997804858, 12586.056045796786, 13003.934980325037, 11398.544058237578, 11633.127515436207, 11873.821892671443, 11913.106517011085, 12014.219075760047, 11481.36852473122, 11533.365664888175, 11586.42067896585, 11985.820212696992, 12105.576304829288, 11326.603568875049, 11404.828927565926, 11504.769904602028, 11599.76800464003, 11873.346965916957, 9528.666496672584, 9715.319927605282, 9741.659041933499, 9927.751806204902, 10136.797264054825, 7899.921000789918, 8619.793228948078, 9028.683996060106, 9077.75318894979, 9629.951850240705], "value": 6850.681036510207, "unit": "score"}], "name": "dromaeo_css", "value": 7242.147558361401, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 116.0, 114.0, 113.0, 114.0, 118.0, 112.0, 114.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 122.0, 144.0, 167.0, 119.0, 142.0, 150.0, 106.0, 198.0, 149.0], "value": 145.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 185.0, 183.0, 183.0, 184.0, 180.0, 184.0, 183.0, 184.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 86.0, 89.0, 56.0, 57.0, 61.0, 78.0, 84.0, 55.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 95.0, 145.0, 80.0, 77.0, 78.0, 124.0, 78.0, 78.0, 129.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 86.0, 87.0, 84.0, 88.0, 85.0, 88.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 93.0, 105.0, 148.0, 111.0, 93.0, 94.0], "value": 101.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 86.0, 85.0, 83.0, 84.0, 83.0, 84.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 61.0, 61.0, 62.0, 59.0, 77.0, 61.0, 59.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 70.0, 70.0, 71.0, 68.0, 67.0, 68.0, 67.0, 69.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 81.0, 82.0, 79.0, 80.0, 80.0, 82.0, 83.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 159.0, 152.0, 136.0, 135.0, 147.0, 158.0, 180.0, 140.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 176.0, 170.0, 166.0, 166.0, 169.0, 169.0, 169.0, 168.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 92.0, 94.0, 68.0, 69.0, 71.0, 66.0, 86.0], "value": 75.2, "unit": "score"}], "name": "kraken", "value": 1489.2000000000003, "alertThreshold": 2.0}]} 05:07:44 INFO - Return code: 0 05:07:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:07:44 ERROR - # TBPL SUCCESS # 05:07:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:44 INFO - 2016-04-25 05:07:44.866 screenresolution[1950:20004] starting screenresolution argv=screenresolution get 05:07:44 INFO - 2016-04-25 05:07:44.869 screenresolution[1950:20004] Display 0: 1600x1200x32@60 05:07:44 INFO - 2016-04-25 05:07:44.875 screenresolution[1951:20007] starting screenresolution argv=screenresolution list 05:07:44 INFO - Available Modes on Display 0 05:07:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:45 INFO - Intel Iris: 05:07:45 INFO - Chipset Model: Intel Iris 05:07:45 INFO - Type: GPU 05:07:45 INFO - Bus: Built-In 05:07:45 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:45 INFO - Vendor: Intel (0x8086) 05:07:45 INFO - Device ID: 0x0a2e 05:07:45 INFO - Revision ID: 0x0009 05:07:45 INFO - Displays: 05:07:45 INFO - Display: 05:07:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:45 INFO - Display Serial Number: 1600x1200 60 05:07:45 INFO - Main Display: Yes 05:07:45 INFO - Mirror: Off 05:07:45 INFO - Online: Yes 05:07:45 INFO - Rotation: Supported 05:07:45 INFO - Return code: 0 05:07:45 INFO - Running post-action listener: _resource_record_post_action 05:07:45 INFO - Running post-run listener: _resource_record_post_run 05:07:46 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 393216; Write bytes: 692687872; Read time: 7; Write time: 2447 05:07:46 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375622144; Write bytes: 369952256; Read time: 28248; Write time: 681 05:07:46 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 393216; Write bytes: 295403008; Read time: 7; Write time: 1715 05:07:46 INFO - Running post-run listener: _upload_blobber_files 05:07:46 INFO - Blob upload gear active. 05:07:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:07:46 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:07:46 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:07:46 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:07:46 INFO - (blobuploader) - INFO - Open directory for files ... 05:07:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:07:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:07:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:47 INFO - (blobuploader) - INFO - Done attempting. 05:07:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:07:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:07:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:48 INFO - (blobuploader) - INFO - Done attempting. 05:07:48 INFO - (blobuploader) - INFO - Iteration through files over. 05:07:48 INFO - Return code: 0 05:07:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:07:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:07:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/abff5d9dc2c991d65f19ce31ac9c9f1ec23552649d6aa7a11b1e6e0d842e213996b587f689a673c5841504845ada812686a0683e2e39a569d4a64e962e3f652f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ba24c89b90c4994cd5781d2c0333427cc6b7823cbf7527e7ef726cb8932d228b475ccba509198ceb3b05e07d77ed4005b60f500c4eb028ca71641035bad8f44f"} 05:07:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:07:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:07:48 INFO - Contents: 05:07:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/abff5d9dc2c991d65f19ce31ac9c9f1ec23552649d6aa7a11b1e6e0d842e213996b587f689a673c5841504845ada812686a0683e2e39a569d4a64e962e3f652f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ba24c89b90c4994cd5781d2c0333427cc6b7823cbf7527e7ef726cb8932d228b475ccba509198ceb3b05e07d77ed4005b60f500c4eb028ca71641035bad8f44f"} 05:07:48 INFO - Running post-run listener: copy_logs_to_upload_dir 05:07:48 INFO - Copying logs to upload dir... 05:07:48 INFO - mkdir: /builds/slave/test/build/upload/logs 05:07:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.375773 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-04-25 05:07:48.259522) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:48.263272) ========= 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.GMtbIkCCHO/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.ovVjQCos45/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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/abff5d9dc2c991d65f19ce31ac9c9f1ec23552649d6aa7a11b1e6e0d842e213996b587f689a673c5841504845ada812686a0683e2e39a569d4a64e962e3f652f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ba24c89b90c4994cd5781d2c0333427cc6b7823cbf7527e7ef726cb8932d228b475ccba509198ceb3b05e07d77ed4005b60f500c4eb028ca71641035bad8f44f"} build_url:https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010776 build_url: 'https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/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/abff5d9dc2c991d65f19ce31ac9c9f1ec23552649d6aa7a11b1e6e0d842e213996b587f689a673c5841504845ada812686a0683e2e39a569d4a64e962e3f652f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ba24c89b90c4994cd5781d2c0333427cc6b7823cbf7527e7ef726cb8932d228b475ccba509198ceb3b05e07d77ed4005b60f500c4eb028ca71641035bad8f44f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:48.291424) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:48.291750) ========= 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.GMtbIkCCHO/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.ovVjQCos45/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.004981 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:48.340591) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:07:48.340922) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:07:48.341287) ========= ========= Total master_lag: 2.10 =========