builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0173 starttime: 1464027099.43 results: success (0) buildid: 20160522193137 builduid: c858fb39af334cefa248ec2a3b22030a revision: 2e8129bd6590404ff56ea35ac30d719cf7f566ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.434579) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.434989) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.435259) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.628313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.628597) ========= 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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-23 11:11:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.43M=0.001s 2016-05-23 11:11:39 (8.43 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161411 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.810281) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.810607) ========= 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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.020025 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.858896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 11:11:39.859222) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2e8129bd6590404ff56ea35ac30d719cf7f566ae --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2e8129bd6590404ff56ea35ac30d719cf7f566ae --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2GhN7OVkqJ/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.iMITHnIDVc/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-23 11:11:39,933 truncating revision to first 12 chars 2016-05-23 11:11:39,933 Setting DEBUG logging. 2016-05-23 11:11:39,933 attempt 1/10 2016-05-23 11:11:39,934 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2e8129bd6590?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 11:11:41,446 unpacking tar archive at: mozilla-beta-2e8129bd6590/testing/mozharness/ program finished with exit code 0 elapsedTime=1.761807 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 11:11:41.638919) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:41.639220) ========= script_repo_revision: 2e8129bd6590404ff56ea35ac30d719cf7f566ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:41.639617) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:41.639887) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:41.654774) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 47 secs) (at 2016-05-23 11:11:41.655084) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2GhN7OVkqJ/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.iMITHnIDVc/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:11:41 INFO - MultiFileLogger online at 20160523 11:11:41 in /builds/slave/test 11:11:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 11:11:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:11:41 INFO - {'append_to_log': False, 11:11:41 INFO - 'base_work_dir': '/builds/slave/test', 11:11:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:11:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:11:41 INFO - 'branch': 'Mozilla-Beta', 11:11:41 INFO - 'buildbot_json_path': 'buildprops.json', 11:11:41 INFO - 'config_files': ('talos/mac_config.py',), 11:11:41 INFO - 'default_actions': ('clobber', 11:11:41 INFO - 'read-buildbot-config', 11:11:41 INFO - 'download-and-extract', 11:11:41 INFO - 'populate-webroot', 11:11:41 INFO - 'create-virtualenv', 11:11:41 INFO - 'install', 11:11:41 INFO - 'run-tests'), 11:11:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:11:41 INFO - 'download_minidump_stackwalk': True, 11:11:41 INFO - 'download_symbols': 'ondemand', 11:11:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:11:41 INFO - 'tooltool.py': '/tools/tooltool.py', 11:11:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:11:41 INFO - '/tools/misc-python/virtualenv.py')}, 11:11:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:11:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:11:41 INFO - 'installer_path': 'installer.exe', 11:11:41 INFO - 'log_level': 'info', 11:11:41 INFO - 'log_name': 'talos', 11:11:41 INFO - 'log_to_console': True, 11:11:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:11:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:11:41 INFO - 'opt_config_files': (), 11:11:41 INFO - 'pip_index': False, 11:11:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:41 INFO - 'cmd': ('bash', 11:11:41 INFO - '-c', 11:11:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:41 INFO - 'enabled': True, 11:11:41 INFO - 'halt_on_failure': False, 11:11:41 INFO - 'name': 'check_screen_resolution'},), 11:11:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:41 INFO - 'cmd': ('bash', 11:11:41 INFO - '-c', 11:11:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:41 INFO - 'enabled': True, 11:11:41 INFO - 'halt_on_failure': False, 11:11:41 INFO - 'name': 'check_screen_resolution'},), 11:11:41 INFO - 'run_cmd_checks_enabled': True, 11:11:41 INFO - 'sps_profile': False, 11:11:41 INFO - 'sps_profile_interval': 0, 11:11:41 INFO - 'suite': 'dromaeojs', 11:11:41 INFO - 'system_bits': '32', 11:11:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:11:41 INFO - 'title': 't-yosemite-r7-0173', 11:11:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:11:41 INFO - 'use_talos_json': True, 11:11:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:11:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:11:41 INFO - 'work_dir': 'build'} 11:11:41 INFO - ##### 11:11:41 INFO - ##### Running clobber step. 11:11:41 INFO - ##### 11:11:41 INFO - Running pre-action listener: _resource_record_pre_action 11:11:41 INFO - Running main action method: clobber 11:11:41 INFO - rmtree: /builds/slave/test/build 11:11:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:11:43 INFO - Running post-action listener: _resource_record_post_action 11:11:43 INFO - ##### 11:11:43 INFO - ##### Running read-buildbot-config step. 11:11:43 INFO - ##### 11:11:43 INFO - Running pre-action listener: _resource_record_pre_action 11:11:43 INFO - Running main action method: read_buildbot_config 11:11:43 INFO - Using buildbot properties: 11:11:43 INFO - { 11:11:43 INFO - "project": "", 11:11:43 INFO - "product": "firefox", 11:11:43 INFO - "script_repo_revision": "production", 11:11:43 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 11:11:43 INFO - "repository": "", 11:11:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 11:11:43 INFO - "buildid": "20160522193137", 11:11:43 INFO - "pgo_build": "False", 11:11:43 INFO - "basedir": "/builds/slave/test", 11:11:43 INFO - "buildnumber": 2, 11:11:43 INFO - "slavename": "t-yosemite-r7-0173", 11:11:43 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:11:43 INFO - "platform": "yosemite_r7", 11:11:43 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:11:43 INFO - "branch": "mozilla-beta", 11:11:43 INFO - "repo_path": "releases/mozilla-beta", 11:11:43 INFO - "revision": "2e8129bd6590404ff56ea35ac30d719cf7f566ae", 11:11:43 INFO - "stage_platform": "macosx64", 11:11:43 INFO - "builduid": "c858fb39af334cefa248ec2a3b22030a", 11:11:43 INFO - "slavebuilddir": "test" 11:11:43 INFO - } 11:11:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 11:11:43 INFO - Running post-action listener: _resource_record_post_action 11:11:43 INFO - ##### 11:11:43 INFO - ##### Running download-and-extract step. 11:11:43 INFO - ##### 11:11:43 INFO - Running pre-action listener: _resource_record_pre_action 11:11:43 INFO - Running main action method: download_and_extract 11:11:43 INFO - mkdir: /builds/slave/test/build/tests 11:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:43 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:11:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 11:11:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 11:11:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 11:11:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 11:12:00 INFO - Downloaded 1241 bytes. 11:12:00 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 11:12:00 INFO - Using the following test package requirements: 11:12:00 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 11:12:00 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:12:00 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 11:12:00 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:12:00 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 11:12:00 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:12:00 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:12:00 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 11:12:00 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 11:12:00 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:12:00 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 11:12:00 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:12:00 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 11:12:00 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:12:00 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 11:12:00 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 11:12:00 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:12:00 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 11:12:00 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 11:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:12:00 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:12:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 11:12:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 11:12:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 11:12:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 11:12:25 INFO - retry: attempt #1 caught exception: '' 11:12:25 INFO - retry: Failed, sleeping 30 seconds before retrying 11:12:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #2 11:13:25 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip: timed out 11:13:25 INFO - retry: attempt #2 caught exception: timed out 11:13:25 INFO - retry: Failed, sleeping 60 seconds before retrying 11:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #3 11:14:42 INFO - Downloaded 17747767 bytes. 11:14:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:14:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:14:45 INFO - Return code: 0 11:14:45 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 11:14: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')]} 11:14:45 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:14:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 11:14:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 11:14:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 11:14:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 11:14:47 INFO - Downloaded 17747767 bytes. 11:14:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:14:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:14:51 INFO - Return code: 0 11:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:51 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:14:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 11:14:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 11:14:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 11:14:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 11:14:52 INFO - Downloaded 10845194 bytes. 11:14:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:14:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:14:52 INFO - Return code: 0 11:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:52 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 11:14:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 11:14:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 11:14:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 11:14:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 11:14:55 INFO - Downloaded 88423479 bytes. 11:14:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 11:14:55 INFO - mkdir: /builds/slave/test/properties 11:14:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:14:55 INFO - Writing to file /builds/slave/test/properties/build_url 11:14:55 INFO - Contents: 11:14:55 INFO - build_url:https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 11:14:55 INFO - Running post-action listener: _resource_record_post_action 11:14:55 INFO - Running post-action listener: set_extra_try_arguments 11:14:55 INFO - ##### 11:14:55 INFO - ##### Running populate-webroot step. 11:14:55 INFO - ##### 11:14:55 INFO - Running pre-action listener: _resource_record_pre_action 11:14:55 INFO - Running main action method: populate_webroot 11:14:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:14:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:14:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:14:55 INFO - '--tppagecycles', 11:14:55 INFO - '7'], 11:14:55 INFO - 'tests': ['tsvgm']}}, 11:14:55 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 11:14:55 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 11:14:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:14:55 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 11:14:55 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 11:14:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:14:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:14:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:55 INFO - 'talos_options': ['--e10s'], 11:14:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:14:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:55 INFO - 'tests': ['damp', 'tps']}, 11:14:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:55 INFO - 'talos_options': ['--e10s'], 11:14:55 INFO - 'tests': ['damp', 'tps']}, 11:14:55 INFO - 'g3': {'tests': ['dromaeo_dom']}, 11:14:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:14:55 INFO - 'other': {'tests': ['a11yr', 11:14:55 INFO - 'ts_paint', 11:14:55 INFO - 'tpaint', 11:14:55 INFO - 'sessionrestore', 11:14:55 INFO - 'sessionrestore_no_auto_restore', 11:14:55 INFO - 'tabpaint']}, 11:14:55 INFO - 'other-e10s': {'talos_options': ['--e10s'], 11:14:55 INFO - 'tests': ['a11yr', 11:14:55 INFO - 'ts_paint', 11:14:55 INFO - 'tpaint', 11:14:55 INFO - 'sessionrestore', 11:14:55 INFO - 'sessionrestore_no_auto_restore', 11:14:55 INFO - 'tabpaint']}, 11:14:55 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 11:14:55 INFO - 'tests': ['a11yr', 11:14:55 INFO - 'ts_paint', 11:14:55 INFO - 'tpaint', 11:14:55 INFO - 'sessionrestore', 11:14:55 INFO - 'sessionrestore_no_auto_restore', 11:14:55 INFO - 'tabpaint']}, 11:14:55 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 11:14:55 INFO - 'tests': ['a11yr', 11:14:55 INFO - 'ts_paint', 11:14:55 INFO - 'tpaint', 11:14:55 INFO - 'sessionrestore', 11:14:55 INFO - 'sessionrestore_no_auto_restore', 11:14:55 INFO - 'tabpaint']}, 11:14:55 INFO - 'other_l64': {'tests': ['a11yr', 11:14:55 INFO - 'ts_paint', 11:14:55 INFO - 'tpaint', 11:14:55 INFO - 'sessionrestore', 11:14:55 INFO - 'sessionrestore_no_auto_restore', 11:14:55 INFO - 'tabpaint']}, 11:14:55 INFO - 'other_nol64': {'tests': ['a11yr', 11:14:55 INFO - 'ts_paint', 11:14:55 INFO - 'tpaint', 11:14:55 INFO - 'sessionrestore', 11:14:55 INFO - 'sessionrestore_no_auto_restore', 11:14:55 INFO - 'tabpaint']}, 11:14:55 INFO - 'svgr': {'tests': ['tsvgx', 11:14:55 INFO - 'tsvgr_opacity', 11:14:55 INFO - 'tart', 11:14:55 INFO - 'tscrollx', 11:14:55 INFO - 'cart']}, 11:14:55 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 11:14:55 INFO - 'tests': ['tsvgx', 11:14:55 INFO - 'tsvgr_opacity', 11:14:55 INFO - 'tart', 11:14:55 INFO - 'tscrollx', 11:14:55 INFO - 'cart']}, 11:14:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:55 INFO - 'tests': ['tp5o']}, 11:14:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:55 INFO - 'talos_options': ['--e10s'], 11:14:55 INFO - 'tests': ['tp5o']}, 11:14:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:55 INFO - 'talos_options': ['--xperf_path', 11:14:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:14:55 INFO - 'tests': ['tp5n']}, 11:14:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:55 INFO - 'talos_options': ['--e10s', 11:14:55 INFO - '--xperf_path', 11:14:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:14:55 INFO - 'tests': ['tp5n']}}, 11:14:55 INFO - 'talos.zip': {'path': '', 11:14:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:14:55 INFO - Running post-action listener: _resource_record_post_action 11:14:55 INFO - ##### 11:14:55 INFO - ##### Running create-virtualenv step. 11:14:55 INFO - ##### 11:14:55 INFO - Running pre-action listener: _resource_record_pre_action 11:14:55 INFO - Running main action method: create_virtualenv 11:14:55 INFO - Creating virtualenv /builds/slave/test/build/venv 11:14:55 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 11:14:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:14:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:14:56 INFO - Using real prefix '/tools/python27' 11:14:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:14:56 INFO - Installing distribute.............................................................................................................................................................................................done. 11:14:59 INFO - Installing pip.................done. 11:14:59 INFO - Return code: 0 11:14:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f2ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f324c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8543c4aae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, '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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:14:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:14:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:59 INFO - 'HOME': '/Users/cltbld', 11:14:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:59 INFO - 'LOGNAME': 'cltbld', 11:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:59 INFO - 'MOZ_NO_REMOTE': '1', 11:14:59 INFO - 'NO_EM_RESTART': '1', 11:14:59 INFO - 'PAGER': '/bin/cat', 11:14:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:59 INFO - 'PWD': '/builds/slave/test', 11:14:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:59 INFO - 'SHELL': '/bin/bash', 11:14:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:14:59 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:14:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:59 INFO - 'USER': 'cltbld', 11:14:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:59 INFO - 'XPC_FLAGS': '0x0', 11:14:59 INFO - 'XPC_SERVICE_NAME': '0', 11:14:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:59 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 11:14:59 INFO - Cleaning up... 11:14:59 INFO - Return code: 0 11:14:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106f2ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f324c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8543c4aae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, '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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 11:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 11:14:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:14:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:59 INFO - 'HOME': '/Users/cltbld', 11:14:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:59 INFO - 'LOGNAME': 'cltbld', 11:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:59 INFO - 'MOZ_NO_REMOTE': '1', 11:14:59 INFO - 'NO_EM_RESTART': '1', 11:14:59 INFO - 'PAGER': '/bin/cat', 11:14:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:59 INFO - 'PWD': '/builds/slave/test', 11:14:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:59 INFO - 'SHELL': '/bin/bash', 11:14:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:14:59 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:14:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:59 INFO - 'USER': 'cltbld', 11:14:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:59 INFO - 'XPC_FLAGS': '0x0', 11:14:59 INFO - 'XPC_SERVICE_NAME': '0', 11:14:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:00 INFO - Downloading/unpacking psutil>=0.7.1 11:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:15:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:03 INFO - Installing collected packages: psutil 11:15:03 INFO - Running setup.py install for psutil 11:15:03 INFO - building 'psutil._psutil_osx' extension 11:15:03 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 11:15:04 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 11:15:04 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 11:15:04 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 11:15:04 INFO - building 'psutil._psutil_posix' extension 11:15:04 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 11:15:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:15:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:15:04 INFO - ^ 11:15:04 INFO - 1 warning generated. 11:15:04 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 11:15:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:04 INFO - Successfully installed psutil 11:15:04 INFO - Cleaning up... 11:15:04 INFO - Return code: 0 11:15:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15: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')]} 11:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15: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', '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 0x106f2ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f324c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8543c4aae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, '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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:15: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 11:15: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 mozsystemmonitor==0.0.0 11:15:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:15:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:04 INFO - 'HOME': '/Users/cltbld', 11:15:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:04 INFO - 'LOGNAME': 'cltbld', 11:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:04 INFO - 'MOZ_NO_REMOTE': '1', 11:15:04 INFO - 'NO_EM_RESTART': '1', 11:15:04 INFO - 'PAGER': '/bin/cat', 11:15:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:04 INFO - 'PWD': '/builds/slave/test', 11:15:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:04 INFO - 'SHELL': '/bin/bash', 11:15:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:15:04 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:15:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:04 INFO - 'USER': 'cltbld', 11:15:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:04 INFO - 'XPC_FLAGS': '0x0', 11:15:04 INFO - 'XPC_SERVICE_NAME': '0', 11:15:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:15:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:15:07 INFO - Installing collected packages: mozsystemmonitor 11:15:07 INFO - Running setup.py install for mozsystemmonitor 11:15:07 INFO - Successfully installed mozsystemmonitor 11:15:07 INFO - Cleaning up... 11:15:07 INFO - Return code: 0 11:15:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:07 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 0x106f2ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f324c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8543c4aae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, '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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:15:07 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 11:15:07 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 11:15:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:15:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:07 INFO - 'HOME': '/Users/cltbld', 11:15:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:07 INFO - 'LOGNAME': 'cltbld', 11:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:07 INFO - 'MOZ_NO_REMOTE': '1', 11:15:07 INFO - 'NO_EM_RESTART': '1', 11:15:07 INFO - 'PAGER': '/bin/cat', 11:15:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:07 INFO - 'PWD': '/builds/slave/test', 11:15:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:07 INFO - 'SHELL': '/bin/bash', 11:15:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:15:07 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:15:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:07 INFO - 'USER': 'cltbld', 11:15:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:07 INFO - 'XPC_FLAGS': '0x0', 11:15:07 INFO - 'XPC_SERVICE_NAME': '0', 11:15:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:07 INFO - Downloading/unpacking blobuploader==1.2.4 11:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:10 INFO - Downloading blobuploader-1.2.4.tar.gz 11:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:15:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:15:10 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 11:15:10 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 11:15:10 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 11:15:10 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 11:15:10 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 11:15:10 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 11:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:15:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15:11 INFO - Downloading docopt-0.6.1.tar.gz 11:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:15:11 INFO - Installing collected packages: blobuploader, requests, docopt 11:15:11 INFO - Running setup.py install for blobuploader 11:15:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:15:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:15:11 INFO - Running setup.py install for requests 11:15:12 INFO - Running setup.py install for docopt 11:15:12 INFO - Successfully installed blobuploader requests docopt 11:15:12 INFO - Cleaning up... 11:15:12 INFO - Return code: 0 11:15:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:12 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')]} 11:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:12 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')]} 11:15:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:12 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 0x106f2ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f324c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8543c4aae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, '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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:15:12 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 11:15:12 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 11:15:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:15:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:12 INFO - 'HOME': '/Users/cltbld', 11:15:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:12 INFO - 'LOGNAME': 'cltbld', 11:15:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:12 INFO - 'MOZ_NO_REMOTE': '1', 11:15:12 INFO - 'NO_EM_RESTART': '1', 11:15:12 INFO - 'PAGER': '/bin/cat', 11:15:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:12 INFO - 'PWD': '/builds/slave/test', 11:15:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:12 INFO - 'SHELL': '/bin/bash', 11:15:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:15:12 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:15:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:12 INFO - 'USER': 'cltbld', 11:15:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:12 INFO - 'XPC_FLAGS': '0x0', 11:15:12 INFO - 'XPC_SERVICE_NAME': '0', 11:15:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:15:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-hxV57N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:15:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-leACsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:15:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-9iAY5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-9XHhU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-8A1Bdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-DSm7Hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-DCP57Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-BpQVnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-dQ2fMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Q0fFox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-rbUPbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-3Gr2jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:15:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-FWJBWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:15:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-PCHyNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-onSoyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:15:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-dURj23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:15:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-YsTppA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:15:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:15:14 INFO - Running setup.py install for manifestparser 11:15:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:15:14 INFO - Running setup.py install for mozcrash 11:15:14 INFO - Running setup.py install for mozdebug 11:15:14 INFO - Running setup.py install for mozdevice 11:15:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:15:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:15:15 INFO - Running setup.py install for mozfile 11:15:15 INFO - Running setup.py install for mozhttpd 11:15:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:15:15 INFO - Running setup.py install for mozinfo 11:15:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:15:15 INFO - Running setup.py install for mozInstall 11:15:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:15:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:15:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:15:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:15:15 INFO - Running setup.py install for mozleak 11:15:15 INFO - Running setup.py install for mozlog 11:15:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:15:15 INFO - Running setup.py install for moznetwork 11:15:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:15:16 INFO - Running setup.py install for mozprocess 11:15:16 INFO - Running setup.py install for mozprofile 11:15:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:15:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:15:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:15:16 INFO - Running setup.py install for mozrunner 11:15:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:15:16 INFO - Running setup.py install for mozscreenshot 11:15:16 INFO - Running setup.py install for moztest 11:15:16 INFO - Running setup.py install for mozversion 11:15:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:15:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:15:17 INFO - Cleaning up... 11:15:17 INFO - Return code: 0 11:15:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:17 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')]} 11:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:17 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')]} 11:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:17 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 0x106f2ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f324c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8543c4aae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, '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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:15:17 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 11:15:17 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 11:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:17 INFO - 'HOME': '/Users/cltbld', 11:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:17 INFO - 'LOGNAME': 'cltbld', 11:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:17 INFO - 'MOZ_NO_REMOTE': '1', 11:15:17 INFO - 'NO_EM_RESTART': '1', 11:15:17 INFO - 'PAGER': '/bin/cat', 11:15:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:17 INFO - 'PWD': '/builds/slave/test', 11:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:17 INFO - 'SHELL': '/bin/bash', 11:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:15:17 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:17 INFO - 'USER': 'cltbld', 11:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:17 INFO - 'XPC_FLAGS': '0x0', 11:15:17 INFO - 'XPC_SERVICE_NAME': '0', 11:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:15:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-ud8nBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:15:17 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)) 11:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:15:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-8e8wsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:15:17 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)) 11:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:15:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-BdalDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:15:17 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)) 11:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:15:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-pq4i1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:15:17 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)) 11:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:15:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-O9NZz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:15:17 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)) 11:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:15:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-WH7kwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:15:17 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)) 11:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:15:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-AoYQdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-1Ajya7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Bd9Odo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-RbCa7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-9FAsky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-GWebWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-nK1K50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-07yzY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-bkpe_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Xl4nQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:15:18 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)) 11:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:15:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Fo_3v4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:15:19 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)) 11:15:19 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)) 11:15:19 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)) 11:15:19 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)) 11:15:19 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)) 11:15:19 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)) 11:15:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:21 INFO - Downloading blessings-1.6.tar.gz 11:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:15:21 INFO - Installing collected packages: blessings 11:15:21 INFO - Running setup.py install for blessings 11:15:21 INFO - Successfully installed blessings 11:15:21 INFO - Cleaning up... 11:15:21 INFO - Return code: 0 11:15:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:15:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:22 INFO - Reading from file tmpfile_stdout 11:15:22 INFO - Current package versions: 11:15:22 INFO - blessings == 1.6 11:15:22 INFO - blobuploader == 1.2.4 11:15:22 INFO - docopt == 0.6.1 11:15:22 INFO - manifestparser == 1.1 11:15:22 INFO - mozInstall == 1.12 11:15:22 INFO - mozcrash == 0.17 11:15:22 INFO - mozdebug == 0.1 11:15:22 INFO - mozdevice == 0.48 11:15:22 INFO - mozfile == 1.2 11:15:22 INFO - mozhttpd == 0.7 11:15:22 INFO - mozinfo == 0.9 11:15:22 INFO - mozleak == 0.1 11:15:22 INFO - mozlog == 3.1 11:15:22 INFO - moznetwork == 0.27 11:15:22 INFO - mozprocess == 0.22 11:15:22 INFO - mozprofile == 0.28 11:15:22 INFO - mozrunner == 6.11 11:15:22 INFO - mozscreenshot == 0.1 11:15:22 INFO - mozsystemmonitor == 0.0 11:15:22 INFO - moztest == 0.7 11:15:22 INFO - mozversion == 1.4 11:15:22 INFO - psutil == 3.1.1 11:15:22 INFO - requests == 1.2.3 11:15:22 INFO - wsgiref == 0.1.2 11:15:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:22 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 0x106f2ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f324c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8543c4aae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, '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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:15:22 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 11:15:22 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 11:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:22 INFO - 'HOME': '/Users/cltbld', 11:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:22 INFO - 'LOGNAME': 'cltbld', 11:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:22 INFO - 'MOZ_NO_REMOTE': '1', 11:15:22 INFO - 'NO_EM_RESTART': '1', 11:15:22 INFO - 'PAGER': '/bin/cat', 11:15:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:22 INFO - 'PWD': '/builds/slave/test', 11:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:22 INFO - 'SHELL': '/bin/bash', 11:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:15:22 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:22 INFO - 'USER': 'cltbld', 11:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:22 INFO - 'XPC_FLAGS': '0x0', 11:15:22 INFO - 'XPC_SERVICE_NAME': '0', 11:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:22 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)) 11:15:22 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)) 11:15:22 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)) 11:15:22 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)) 11:15:22 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)) 11:15:22 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)) 11:15:22 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)) 11:15:22 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)) 11:15:22 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)) 11:15:22 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)) 11:15:22 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)) 11:15:22 INFO - Cleaning up... 11:15:22 INFO - Return code: 0 11:15:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f2ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f324c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8543c4aae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, '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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:15:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:22 INFO - 'HOME': '/Users/cltbld', 11:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:22 INFO - 'LOGNAME': 'cltbld', 11:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:22 INFO - 'MOZ_NO_REMOTE': '1', 11:15:22 INFO - 'NO_EM_RESTART': '1', 11:15:22 INFO - 'PAGER': '/bin/cat', 11:15:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:22 INFO - 'PWD': '/builds/slave/test', 11:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:22 INFO - 'SHELL': '/bin/bash', 11:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:15:22 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:22 INFO - 'USER': 'cltbld', 11:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:22 INFO - 'XPC_FLAGS': '0x0', 11:15:22 INFO - 'XPC_SERVICE_NAME': '0', 11:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:22 INFO - Downloading/unpacking jsonschema 11:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:15:25 INFO - Downloading/unpacking functools32 (from jsonschema) 11:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:25 INFO - Downloading functools32-3.2.3-2.tar.gz 11:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:15:25 INFO - warning: no files found matching '*.txt' 11:15:25 INFO - no previously-included directories found matching 'build' 11:15:25 INFO - no previously-included directories found matching 'dist' 11:15:25 INFO - no previously-included directories found matching '.git*' 11:15:25 INFO - Installing collected packages: jsonschema, functools32 11:15:25 INFO - Running setup.py install for functools32 11:15:25 INFO - warning: no files found matching '*.txt' 11:15:25 INFO - no previously-included directories found matching 'build' 11:15:25 INFO - no previously-included directories found matching 'dist' 11:15:25 INFO - no previously-included directories found matching '.git*' 11:15:25 INFO - Successfully installed jsonschema functools32 11:15:25 INFO - Cleaning up... 11:15:25 INFO - Return code: 0 11:15:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:25 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 0x106f2ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f324c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8543c4aae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, '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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 11:15:25 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 11:15:25 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 11:15:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:15:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:26 INFO - 'HOME': '/Users/cltbld', 11:15:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:26 INFO - 'LOGNAME': 'cltbld', 11:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:26 INFO - 'MOZ_NO_REMOTE': '1', 11:15:26 INFO - 'NO_EM_RESTART': '1', 11:15:26 INFO - 'PAGER': '/bin/cat', 11:15:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:26 INFO - 'PWD': '/builds/slave/test', 11:15:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:26 INFO - 'SHELL': '/bin/bash', 11:15:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:15:26 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:15:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:26 INFO - 'USER': 'cltbld', 11:15:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:26 INFO - 'XPC_FLAGS': '0x0', 11:15:26 INFO - 'XPC_SERVICE_NAME': '0', 11:15:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:26 INFO - Downloading/unpacking flake8 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:15:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:15:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15: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 11:15:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:15:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:15:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:15:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:15:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:15:30 INFO - no previously-included directories found matching 'docs/_build' 11:15:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:15:30 INFO - Running setup.py install for pep8 11:15:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:15:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:15:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:15:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:15:30 INFO - no previously-included directories found matching 'docs/_build' 11:15:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:15:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:15:30 INFO - Cleaning up... 11:15:30 INFO - Return code: 0 11:15:30 INFO - Running post-action listener: _resource_record_post_action 11:15:30 INFO - Running post-action listener: _start_resource_monitoring 11:15:30 INFO - Starting resource monitoring. 11:15:30 INFO - ##### 11:15:30 INFO - ##### Running install step. 11:15:30 INFO - ##### 11:15:30 INFO - Running pre-action listener: _resource_record_pre_action 11:15:30 INFO - Running main action method: install 11:15:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:31 INFO - Reading from file tmpfile_stdout 11:15:31 INFO - Detecting whether we're running mozinstall >=1.0... 11:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:15:31 INFO - Reading from file tmpfile_stdout 11:15:31 INFO - Output received: 11:15:31 INFO - Usage: mozinstall [options] installer 11:15:31 INFO - Options: 11:15:31 INFO - -h, --help show this help message and exit 11:15:31 INFO - -d DEST, --destination=DEST 11:15:31 INFO - Directory to install application into. [default: 11:15:31 INFO - "/builds/slave/test"] 11:15:31 INFO - --app=APP Application being installed. [default: firefox] 11:15:31 INFO - mkdir: /builds/slave/test/build/application 11:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 11:15:54 INFO - Reading from file tmpfile_stdout 11:15:54 INFO - Output received: 11:15:54 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:15:54 INFO - Running post-action listener: _resource_record_post_action 11:15:54 INFO - ##### 11:15:54 INFO - ##### Running run-tests step. 11:15:54 INFO - ##### 11:15:54 INFO - Running pre-action listener: _resource_record_pre_action 11:15:54 INFO - Running main action method: run_tests 11:15:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:15:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:15:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:15:54 INFO - 2016-05-23 11:15:54.124 screenresolution[1925:11335] starting screenresolution argv=screenresolution get 11:15:54 INFO - 2016-05-23 11:15:54.127 screenresolution[1925:11335] Display 0: 1600x1200x32@60 11:15:54 INFO - 2016-05-23 11:15:54.133 screenresolution[1926:11337] starting screenresolution argv=screenresolution list 11:15:54 INFO - Available Modes on Display 0 11:15:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:15:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:15:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:15:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:15:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:15:54 INFO - Intel Iris: 11:15:54 INFO - Chipset Model: Intel Iris 11:15:54 INFO - Type: GPU 11:15:54 INFO - Bus: Built-In 11:15:54 INFO - VRAM (Dynamic, Max): 1536 MB 11:15:54 INFO - Vendor: Intel (0x8086) 11:15:54 INFO - Device ID: 0x0a2e 11:15:54 INFO - Revision ID: 0x0009 11:15:54 INFO - Displays: 11:15:54 INFO - Display: 11:15:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:15:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:15:54 INFO - Display Serial Number: 1600x1200 60 11:15:54 INFO - Main Display: Yes 11:15:54 INFO - Mirror: Off 11:15:54 INFO - Online: Yes 11:15:54 INFO - Rotation: Supported 11:15:54 INFO - Return code: 0 11:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:15:54 INFO - Python 2.7.3 11:15:54 INFO - Return code: 0 11:15:54 INFO - grabbing minidump binary from tooltool 11:15: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')]} 11:15:54 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 0x106dcfd30>, '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 0x106c33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:15:54 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 11:15:54 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 11:15:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:15:54 INFO - Return code: 0 11:15:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:15:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:15:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:15:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:15:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:15:55 INFO - Return code: 0 11:15:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 11:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0173 --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 11:15:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2GhN7OVkqJ/Render', 11:15:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:55 INFO - 'HOME': '/Users/cltbld', 11:15:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:55 INFO - 'LOGNAME': 'cltbld', 11:15:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:15:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:55 INFO - 'MOZ_NO_REMOTE': '1', 11:15:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:15:55 INFO - 'NO_EM_RESTART': '1', 11:15:55 INFO - 'PAGER': '/bin/cat', 11:15:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:55 INFO - 'PWD': '/builds/slave/test', 11:15:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:15:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:55 INFO - 'SHELL': '/bin/bash', 11:15:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iMITHnIDVc/Listeners', 11:15:55 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 11:15:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:55 INFO - 'USER': 'cltbld', 11:15:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:55 INFO - 'XPC_FLAGS': '0x0', 11:15:55 INFO - 'XPC_SERVICE_NAME': '0', 11:15:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 11:15:55 INFO - mozversion application_buildid: 20160522193137 11:15:55 INFO - mozversion application_changeset: 2e8129bd6590404ff56ea35ac30d719cf7f566ae 11:15:55 INFO - mozversion application_display_name: Firefox 11:15:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:15:55 INFO - mozversion application_name: Firefox 11:15:55 INFO - mozversion application_remotingname: firefox 11:15:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:15:55 INFO - mozversion application_vendor: Mozilla 11:15:55 INFO - mozversion application_version: 47.0 11:15:55 INFO - mozversion platform_buildid: 20160522193137 11:15:55 INFO - mozversion platform_changeset: 2e8129bd6590404ff56ea35ac30d719cf7f566ae 11:15:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:15:55 INFO - mozversion platform_version: 47.0 11:15:55 INFO - using testdate: 1464027355 11:15:55 INFO - actual date: 1464027355 11:15:55 INFO - starting webserver on 'localhost:49715' 11:15:55 INFO - SUITE-START | Running 2 tests 11:15:55 INFO - TEST-START | dromaeo_css 11:15:55 INFO - operating with platform_type : mac_ 11:15:55 INFO - Initialising browser for dromaeo_css test... 11:15:55 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpH5UzNf/profile http://localhost:49715/getInfo.html) 11:15:58 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 11:15:58 INFO - PROCESS | 1944 | colorDepth:24 11:15:58 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 11:15:58 INFO - PROCESS | 1944 | __metrics 11:15:58 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" 11:15:58 INFO - PROCESS | 1944 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:15:58 INFO - TEST-INFO | 1944: exit 0 11:15:58 INFO - Browser initialized. 11:15:58 INFO - Running cycle 1/1 for dromaeo_css test... 11:15:58 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpH5UzNf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:10 INFO - PROCESS | 1947 | RSS: Main: 157003776 11:16:10 INFO - PROCESS | 1947 | 11:17:02 INFO - PROCESS | 1947 | 1464027422268 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] 11:19:11 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49715/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49715/tests/dromaeo/cssquery-ext.html) 11:19:12 INFO - PROCESS | 1947 | RSS: Main: 283181056 11:19:12 INFO - PROCESS | 1947 | 11:22:14 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49715/tests/dromaeo/cssquery-ext.html (next: http://localhost:49715/tests/dromaeo/cssquery-jquery.html) 11:22:15 INFO - PROCESS | 1947 | RSS: Main: 294277120 11:22:15 INFO - PROCESS | 1947 | 11:25:17 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49715/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49715/tests/dromaeo/cssquery-mootools.html) 11:25:17 INFO - PROCESS | 1947 | RSS: Main: 297558016 11:25:17 INFO - PROCESS | 1947 | 11:28:19 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49715/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49715/tests/dromaeo/cssquery-prototype.html) 11:28:20 INFO - PROCESS | 1947 | RSS: Main: 254808064 11:28:20 INFO - PROCESS | 1947 | 11:31:23 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49715/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49715/tests/dromaeo/cssquery-yui.html) 11:31:24 INFO - PROCESS | 1947 | RSS: Main: 256008192 11:31:24 INFO - PROCESS | 1947 | 11:34:26 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49715/tests/dromaeo/cssquery-yui.html (next: http://localhost:49715/tests/dromaeo/cssquery-dojo.html) 11:34:26 INFO - PROCESS | 1947 | RSS: Main: 301154304 11:34:26 INFO - PROCESS | 1947 | 11:34:26 INFO - PROCESS | 1947 | __start_tp_report 11:34:26 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 11:34:26 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 11:34:26 INFO - PROCESS | 1947 | |i|pagename|runs| 11:34:26 INFO - PROCESS | 1947 | |0;dojo.html;3539.876104336348;3570.9107272318206;3574.0707416071828;3615.4576813477975;3648;5696.658200507962;5706.0585003474425;5723.4848863602265;5741.311042674874;5752.079667253241;5700.8859822803515;5726.513246374063;5752.165935939286;5752.453516915886;5752.769889204426;5133.897322053556;5165.38015438147;5187.351581052369;5200.297959775438;5284.603654725892;5275.235090911816;5281;5287.180487024498;5288.286081379022;5289.418164001957;5238.764255608507;5281.63028587999;5284.128118860383;5310.150375939831;5313;5298.973505132488;5326.520613144816;5331.653442526223;5334.413214546396;5342.038433082043;5065.442801291855;5234.42421333653;5234.92147617786;5250.947490525123;5252.133397989365;5552.944470555283;5631.943680563224;5639.436056394369;5646.096624540095;5659.4340565943485;6112.017184454403;6294.968525157391;6321.557490975633;6328.335524769925;6329.145565348711;6240.625562466266;6265.906011409833;6303.905441418382;6344.746210151635;6350.301466838644;5828.475437210629;5852.385499522574;5852.736626851759;5858.384869588717;5892.587518873724;6225.750969961241;6244.12582238487;6329.905051424233;6332.936670633327;6339.492840572743;6163.106349579286;6186.81439556814;6197.566170368031;6199.721012554446;6208.348123446973;6298.433141017239;6331.588446750947;6332.683365831782;6334.3032266450655;6336.1762970814425;6116.204893363834;6166.321704612579;6186.4741496973265;6186.721597528122;6253.6873156342;6245.531585131133;6270.9372906271265;6294;6296.150019747367;6335.936640633627;4894.755262236874;4907.435644900811;4914.852554423373;4916.115099282092;4933.4819843915875;4356.237658409766;4439.245328294198;4439.911201775946;4479.7984090716;4486.506484286726;5842.065269556935;5865.530757539386;5867.794627188028;5872.0898260769645;5886.381929897385;6265.8120256392385;6277.96861015692;6285.151504546919;6288.779892703825;6300.779472718525;5770.769169233267;5834.154047663065;5867.765289388462;5874.1776151338845;5955.106733989936;5388.649737767033;5397.676139431646;5406.675599464044;5408.1887716842;5422.322209723785;5325.946740532545;6406.935930640634;6409.262934762562;6416.262129855127;6438.742450302029;6166.784162554289;6227.06594010902;6308.842278943063;6329.145565348619;6339.587926784744;5907.91138132937;5945.738405460974;6385.584936979081;6422.42198202164;6424.325445828121;6235.158253635701;6262.5929314594405;6272.40412160844;6276.560640755103;6285.5600107992;6125.540694081788;6240.500759939103;6268.843278918063;6328.936710632835;6331.556791024584;5953.642781433128;6149.200603921461;6176.104464852661;6176.907346389898;6184.536159787944;5987.760489580368;6148.723307451086;6160.414760597736;6167.568270221221;6178;5979.162917191684;6025.397460253939;6030.095485677008;6048.6975651218145;6092.208012958287;6144.815655530341;6168.9691551541955;6180.443760061616;6198.845028874314;6216.658083805434;5836.766529338779;5988.041913293852;6067.393260673897;6086.391360863879;6114.144019837316 11:34:26 INFO - PROCESS | 1947 | |1;ext.html;500.9155179037384;585.9970700146498;599.6851652882234;659.8119535932259;672.3243140643652;40759.573414930484;41489.79255103724;41512.37731434027;41647;41749.37375939363;43996.780016099954;44672.3299150513;44680.88297792557;44687.77656111723;44795.104097918025;9831.950840245807;10040.347377420481;10064.04391582802;10079.344842585246;10087.495625218746;9942.701718948443;10078.143357814595;10085.991400859892;10099.646512372068;10099.798004039914;10080.243981701402;10155.796884062314;10202.846957295647;10204.489775511194;10216.744581385488;10096.545655445485;10179.338343007681;10192.898071019306;10210.795784084352;10250.794984100276;9974.301798874081;10058.547365368577;10100.141487973531;10112.292139550234;10131;43352.3497147543;43705.34441983373;43875.341869871976;43938.12123757507;43991.56008439907;3265.9183520411943;3424.6746559077096;3448.1207292140343;3686.041629176407;3895.5130608673917;29261;30071.69928300733;30279.848600757075;30463;30770;2778.458200650874;2795.6784969728333;2800.80394372394;2859.3280579063903;2876.554134109216;382121.2681809803;385442;385641.1435885633;385753;385858;69072.30927690658;69646;69681.65159174273;69736.25659358557;69736.95394569085;78722;79668;79758.80361794577;79895.20104798878;79919.60040199762;18930.43208703741;19242;19254.71117933232;19286.8071319285;19288.1320340585;14586.781198282035;14670.119792812466;14684.853151468562;14686.853131468763;14697.77953330701;21295.61578497393;21325;22503.987320570614;22819.543609128057;23189;9182.724518264462;9426;9472.584490954763;9479.289053321028;9492.006678597572;973.7711637765261;1028.3469996552244;1123.662189162995;1186.8753780853058;1227.220714846079;30865.228369290948;30882.536761948588;30905.389328860045;31095.378092438026;31443.842780786406;989.4063561862824;1149.6263714292888;1164.3828770751516;1195.5217912834935;1263.298869127643;15810.972286801321;16791.58021049459;16961.830381696273;17099.23053462597;17145.828541714673;563071;683149;684630;684718.5764071147;685152;64198.679006605606;65703.01445478322;65717.67141164263;65730.67134664296;65912.67043664752;1441.3730027438098;1503.4812989518598;1542.452429387596;1562.7499600063936;1692.280780668211;29717.405651887275;29927.850360748056;30007;30683.386332272785;30739.846300769248;52199.47800521946;52236.21645675394;52275;52354.21468678049;52620.842749435156;374508;376358;377507;377589;379013.1049344736;29537;34312;34749.305013900084;35090.473642895886;35241.471377928836;43037.35443968406;45044.64866054024;45055;45386;45776.08447830958;35761.1059723509;36619.63380366162;37201.81399092987;37585;37956.43065353967;36666;36696.81651591832;37103.62896371002;37353.81323093367;37467.43798842965;36712.449313259785;36996.815015924745;37034;37076.62923370732;37891.62108378881;26618;31617.5257371135;33168.50247246341;33362;33457.83271083726;24710.876445617658;24935.50128997446;24977.875110625057;25182.74817251804;25487 11:34:26 INFO - PROCESS | 1947 | |2;jquery.html;351.6724365520161;361.8787706118451;381.51738051365027;384.5366333568049;393.6476853216373;18608.25566977321;20705.896470517644;22383.992720327606;22620;23079.076836926502;18920.526986825334;19556.41330760076;19752.802471975312;19941.90029049853;22530;5920.703964801764;6091.421314975092;6124.693765311739;6135.233095863017;6135.693215339215;5928.081147422156;6028.125921741344;6101.633901965874;6106.328303886569;6140.570160088796;6043.818685439422;6073.696315184246;6077.483413909823;6083;6105.633661980295;5800.564957628195;6079.787207447718;6095.786647467362;6096.207493025879;6106.938930610704;5806.245188125559;5819.82540523785;5840.240768700084;5844.824655260349;5862.618929769551;13958.441662333495;14255.50105746294;14302.64243393913;14308.713825723531;14308.92845535776;2183;2208.3374987503844;2229.3646310801505;2270.5458908218257;2277.36233854521;8964.686235981775;9954.90045099547;10226.233032522516;10266.948665256701;10692.679219623424;1790.185465613143;1875.3811242290012;1896.345760712552;1973.9111739971736;1988.1053525913396;97041.51479242559;108030;109025.45487272512;110120.89879101186;112191.87808121897;11207.66377008691;11223;11230.606928757454;11297.943510282395;11392.316461012366;14556.417743290362;14558.199299038442;14614.415423382945;14638.341274642667;14662.120272783666;8925.598348074222;9098.454092754457;9098.590563424661;9202.631894724136;9232.261419086457;9411.858822117674;9432.622695092257;9441.90558094424;9480.525973701288;9498.430094194233;4525.343825145402;4592.402987611589;4595.425571803525;4646.558576935186;4646.83736069236;1738.9043602601978;1916.3292847503542;1948.0454577257326;1965.88204707718;1979.2676709617533;907.7140700679265;955.302629080775;976.6874600128033;999.7200783780553;1127.5884302229626;21046.67272483504;21328;21452.570948580942;21486.677699834516;21587.460313491974;957.7940742740344;973.5959576775559;1000.1698590170141;1014.5333146752577;1031.6440827914357;10253.58985640581;10726.873678263826;11800.411317213557;11991.280523168638;12040.157188996862;668374;678808;681594;692104;697935.5103224452;121090;121523.39238303751;126283.3685831565;129564;133690.66309336977;1083.285031878972;1095;1107.9612213572486;1110.0120892405766;1114.5844777132995;19346.129424175666;19758.90120549388;20152.798472015093;20231.23976567455;20323.187072516932;52164.21753673622;54265;54646;55179;55538;76210.61894690491;77147;77616.2238377609;77679.83480247688;79631;7847.725329613436;8229.506229626242;8427.115152909102;8434.24091831738;8645.221930026328;10965.232433729563;12561.87438125607;13462.596122116352;13648.317584120952;13885.444582217002;11149.219554631098;11385.601503947653;11846.881531184577;11903.76192476163;12132.332721700392;11666;11669.76660466803;11717.121215909012;11984.520619175135;12003.1597788154;11607.955284024478;11734;11898.881011189776;12170.939145304217;12175;7530.171681114964;8131.512109273463;8344.998600168019;8401.15988401155;8532.872006919777;10141.391516509033;11827.526898923947;11881.405929703307;12051.397430128283;12091.214071085351 11:34:26 INFO - PROCESS | 1947 | |3;mootools.html;1312.665270356059;1589.3563106941688;1617;1992.2827781998487;2183.8471307008517;6913.861722765541;8812.427192232508;9055.003949565542;9059.456432614032;9095.226905713005;7726.961365193166;8775.210231079202;9023.413478123917;9036;9085.636574537028;2404.471016376399;2426.1144682191048;2427.8057755379527;2428.5264373447185;2433.2335314376005;2396.077510158584;2416.6979127609047;2447.1434997750735;2450.82844200906;2455.287966489716;2396.4847557775074;2409.180878501318;2417.492326611414;2428.344347026302;2429.0405289910473;2044.94887627809;2385.3082606044227;2401.9519609607796;2407.9157229496973;2409.7469765674527;2351.564960482316;2396.26270953559;2400.7119145702454;2400.9039638414442;2406.0856874387796;9007.234385077276;9041.050689677555;9041.593128309241;9051.04963978786;9056.818863622691;5373.274607927919;5506.063969125258;5506.752196151183;5511.614187006911;5522.447755224485;3498.597661268961;3554.111472131967;3609.5127157833617;3626.3110009098186;3643.744937854351;1046.712154157605;1071.464267866067;1072.2708558180434;1077.5851297250535;1085.6851513061204;12301.69245768854;12316.953058989997;12319.815202771966;12322.75354492914;12327.952124069465;4727.976360118178;4788.162071637449;4802.807887684454;4804.399450068741;4964.60283177345;10228.846567301496;10232.925542818046;10233.59065637381;10241.692749217535;10266.435346056038;5487.067198576252;5493.972530137404;5497.285352904097;5501.889962200734;5504.889902201933;4476.70901391415;4597.149527337453;4647.512011238839;4649.86050418488;5031.6729412588065;3316.602007759036;3713.665770080706;3827.138893748884;3850.1914597934747;3902;1178.8997935175605;1247.7379750252549;1305.1712162776705;1312.2126723965735;1318.8615195404536;1036.6941752183125;1192.24292574216;1201.4593432955205;1237.2761934268553;1239.5909349914507;8333.125021872738;8421.452605580618;8425.199606037417;8442.915570844212;8635.093315201939;1082.5182793656747;1255.3158528601934;1260.4139075330015;1268.0806415348982;1271.3706715176047;6374.58565193261;6388.84027899297;6443.452306553995;6460.063290822898;6469;10447.529861156267;10779.946100269448;10906.5092070857;10909.672709818718;10918.836217456745;10538.051575358102;10711.143108551298;10839.67480975572;10847.783044339227;11016.944915275373;8527.10465401124;8655.783605409915;8685.869711954325;8689.348298877483;8691.130886911258;8046.356291496549;8053.597320134087;8147.307478864363;8340.6246718899;8370.414071014971;9325.766855828659;9519.143277105093;9632.373895696757;9655.517224138905;9880.061394167542;9751.463669498246;9994.60021599157;10026.298159128792;10099.646512372032;10201.693949181536;6842.7605033823575;7632.313091821763;7663.693452261847;7667.616619168908;7804.84390312202;7858.449908506578;7927.682892684459;7928.167542407964;7932.6033698313695;8205.179482051746;6543.70553325111;7375.336219740249;7587.430942679211;7677.884831727417;7739;6485.6108633482145;7454.552726836408;7455.888161677471;7551.244875512405;7618.771436856903;7822.335101516434;7915.525068495908;7959.124496305518;7961.243681850215;7970.481918675499;7167.211606723152;7247.9637601811655;7296.4162866969455;7296.96351518239;7304.452166087458;7156.212816590275;7317.9754834322175;7332.670029848563;7347.971284020135;7365.337119659256 11:34:26 INFO - PROCESS | 1947 | |4;prototype.html;284.42120285219585;343.0788333325024;343.142144638404;346.2244572158364;346.7555373461709;6715.73137074517;6720.563163394376;6724.529282950195;6734.0572319875255;6773.322667733224;6736.023276624886;6736.922092465206;6766.830829229271;6788.898166527506;6791.354821291981;4298.806553705075;4546.227141385956;4885.780139893695;4916.704997700132;5029.270755740425;4997.975010124963;5004.424491183506;5008.824691135811;5015.348004759395;5015.39815222172;4093.6111069448357;4923.901521969558;5003.1994880819075;5005.324281222043;5014.598832093424;3840.2703486337787;4800.159972004885;4917.72952487613;4997.925031124554;5012.448630650625;4211.957880421187;4219.78901054946;4226.936595951063;4232.682548808852;4236.322188449864;6337.873242535124;6395.360463953614;6396.1045453636525;6404.071409645575;6417.03744438338;5223.973880130575;5547.751755854941;5840.8247752567495;5853.3903176626345;5886.529077673776;5688.829335119953;6589.769358072444;7049.400800931927;7099.148102227766;7102.325279098477;3809.29528037314;4737;4763;4828.855134345975;4849.248366503162;7440.404767618577;7576.318131368148;7598.392128629696;7608.2391760823475;7635.350995165362;4304.913901721948;4954.00919816031;4955.6531042826655;4958.9008219835405;4972.154733695281;5024.598032157419;5041.395032596112;5045.899082018339;5051.848444546669;5055.317532133188;5925.644461332334;5941.257342832146;5947.821565353046;5954.761809527657;5957.582969192202;5890.4698577128265;6926.861462770717;7210.531315464478;7240.674169662378;7243.710251589878;1723.526030341666;1736.7308067249596;1751.2207067854938;1751.6321572469924;1762.9471115866545;1125.0774365020604;1128.4245035032193;1146.9598564050218;1151.1308961734005;1154.6132045764596;6649.767258145942;6720.260771315151;6813.625250611264;6879.380855722909;6917.584944903322;6548.9017664735065;6748.089007983957;7513.2111128331835;7523.699052037857;7553.206913274137;6883.8967415488805;6894.276101009422;6922.238553759083;6927.896081558781;6935.965320173468;3953.5651078381356;3983.900402489961;3994.8601798936897;4000.3799411091327;4002.8799136025964;6246.500279977591;6253.874922501524;7632.274933881273;7846.568438735811;7857;6257.937420625735;7722.961385193151;7729.265719756614;7740.26467485588;7743.341815945708;7875.094364148082;7890;7890.171531989174;7900.2889739923685;7901;7278.595365736396;7417.406607471282;7504.962475187589;7519.887201692089;7529.284717951786;7336.37640800538;7366.116066072105;7380.5940673263485;7385.7045718170675;7387.224341444071;7849.921500784919;7870.488418252795;7882.881756773768;7882.960585197037;7888.171741967011;4326.502452218036;4638.768061596839;4673.813047478063;4696.600788932979;4702.177119004229;6834.965825171042;7143.464240181903;7431.29402706742;7439.665215065227;7468.10382754073;7176.7846964591145;7185.317394847481;7209.71161153548;7227.205007449282;7258.3104605062435;7186.24544422849;7191.20896701352;7195.532290401107;7198.172210195895;7211.944595790174;7016.719331226898;7030.472714546328;7039.753608623879;7047.224805271314;7060.399866011447;6884.105066341343;7122.287771223044;7171.928280717335;7181.964090179516;7200.567965922061;6816.795496135124;6971.9651401744695;6979;7011.228764835762;7083.574985500886 11:34:26 INFO - PROCESS | 1947 | |5;yui.html;721.0014130429354;756.3948840927256;779.7582749347703;798.6805277888843;826.9338452923765;15666.295016724247;15787.526374208792;16028.59928501788;16028.839711602881;16048.91975540122;14897.180655063967;14938.775918361232;14967.02714323571;14992.700145997074;14995.700085998273;816.542736067804;840.4831028917203;990.4849478271294;998.6904059741468;1020.9693709188699;1024.6106479537766;1029.2743615750878;1030.407515678484;1030.5774632400717;1030.804147212031;1074.6883403812924;1075.6826736112826;1079.703081652544;1081.3565928272665;1082.3019152646539;839.7396806989822;1055.1347894726327;1070.550368845087;1077.4558847781898;1080.195254535376;804.7183485779989;805.375833728859;805.9032916050112;806.209914284005;806.8265322955534;13965.930170349084;15150.848491515164;15907.522774316672;15943.123128227942;15949.282282297325;13043.478260869553;13099.41052652633;13299.401526931311;13356;13376.197428154343;10196.439195844225;10437.843432348522;10465.424401657905;10614.734631634194;10754.354738715701;3609.3142302962615;3616.168281295317;3639.344917914774;3713.461548075541;3870.6903447724117;82181.17818821795;83597;91829.08170918339;92037;92771.53614231886;9996.400215986918;10040.297179197532;10219.540120694586;10238.385696858064;10500.789984200273;23104.306870793902;23136;23140.53718925612;23145;23146.65280020801;7733.613319334011;7944.126146123922;8025.23760242776;8064.193580642006;8069.991251093613;4193.580641935843;4224;4242.384854196124;4271.359296105609;4316.6546676265825;3493.3013397320638;3551.804650744234;3557.9822100889846;3559.394902866519;3566.1262990566956;564.520157865819;650.3204151661525;696.1611258433576;710.4529512275519;711.6263961420295;514.3596222702685;591.7544219149005;604.8366940925926;626.4143026270451;628.867937733072;8330.416870819106;8469.3647976402;8514.020887597882;8521.275691566163;8560.871586926201;515.6880087547055;599.9310079340844;613.2671457608116;628.5254632752334;641.5060403489288;3868.38506154479;3999.7200195986;4014.879553613396;4081.6326530611796;4085.9795701021303;84602;84983.5750821242;85037;87920.56039719761;89721;77613.61193193997;77793;78273;78806;79502;67265.66367168231;67692;67942.66028669923;68767;70889.64555177192;8545.871811922701;8656.956715216384;8888.422252553823;8960.659303381419;8961.507117108622;39992.80003599964;49308.50691493183;50800;51445.97108057893;53131;69173;69506.60986780337;70088.59822803413;70636;70964.29035709577;10944.288621239593;11275.567788052222;11287.492062857027;11559.537618495166;11619.5352185915;11972.763287389156;12054.276743395425;12125.514979401078;12343;12650.177738446971;9922.603095876084;11279.830802538132;11519.88480115188;11895;12274.877251227374;11406.82889756671;11455.713607159887;11728.70678233051;11769.470373833026;11845.23006004607;11451.312921224753;11581.826272606086;11642.76714465723;11773.705657358634;11910.642680719246;9716.514174291398;9880.40717556949;9947.813927938218;10120.79758404813;10426.791464170827;9779.630851680913;10075.546600402853;10081.991800820153;10090.293679442368;10303.278770486293 11:34:26 INFO - PROCESS | 1947 | __end_tp_report 11:34:26 INFO - PROCESS | 1947 | __start_cc_report 11:34:26 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,991 11:34:26 INFO - PROCESS | 1947 | __end_cc_report 11:34:26 INFO - PROCESS | 1947 | __startTimestamp1464028466768__endTimestamp 11:34:26 INFO - PROCESS | 1947 | 11:34:26 INFO - PROCESS | 1947 | ------- Summary: start ------- 11:34:26 INFO - PROCESS | 1947 | Number of tests: 6 11:34:26 INFO - PROCESS | 1947 | 11:34:26 INFO - PROCESS | 1947 | [#0] dojo.html Cycles:165 Average:5793.05 Median:5988.04 stddev:623.20 (10.4%) stddev-sans-first:599.52 11:34:26 INFO - PROCESS | 1947 | Values: 3539.9 3570.9 3574.1 3615.5 3648.0 5696.7 5706.1 5723.5 5741.3 5752.1 5700.9 5726.5 5752.2 5752.5 5752.8 5133.9 5165.4 5187.4 5200.3 5284.6 5275.2 5281.0 5287.2 5288.3 5289.4 5238.8 5281.6 5284.1 5310.2 5313.0 5299.0 5326.5 5331.7 5334.4 5342.0 5065.4 5234.4 5234.9 5250.9 5252.1 5552.9 5631.9 5639.4 5646.1 5659.4 6112.0 6295.0 6321.6 6328.3 6329.1 6240.6 6265.9 6303.9 6344.7 6350.3 5828.5 5852.4 5852.7 5858.4 5892.6 6225.8 6244.1 6329.9 6332.9 6339.5 6163.1 6186.8 6197.6 6199.7 6208.3 6298.4 6331.6 6332.7 6334.3 6336.2 6116.2 6166.3 6186.5 6186.7 6253.7 6245.5 6270.9 6294.0 6296.2 6335.9 4894.8 4907.4 4914.9 4916.1 4933.5 4356.2 4439.2 4439.9 4479.8 4486.5 5842.1 5865.5 5867.8 5872.1 5886.4 6265.8 6278.0 6285.2 6288.8 6300.8 5770.8 5834.2 5867.8 5874.2 5955.1 5388.6 5397.7 5406.7 5408.2 5422.3 5325.9 6406.9 6409.3 6416.3 6438.7 6166.8 6227.1 6308.8 6329.1 6339.6 5907.9 5945.7 6385.6 6422.4 6424.3 6235.2 6262.6 6272.4 6276.6 6285.6 6125.5 6240.5 6268.8 6328.9 6331.6 5953.6 6149.2 6176.1 6176.9 6184.5 5987.8 6148.7 6160.4 6167.6 6178.0 5979.2 6025.4 6030.1 6048.7 6092.2 6144.8 6169.0 6180.4 6198.8 6216.7 5836.8 5988.0 6067.4 6086.4 6114.1 11:34:26 INFO - PROCESS | 1947 | 11:34:26 INFO - PROCESS | 1947 | [#1] ext.html Cycles:180 Average:63899.60 Median:34919.89 stddev:131492.19 (376.6%) stddev-sans-first:131774.91 11:34:26 INFO - PROCESS | 1947 | Values: 500.9 586.0 599.7 659.8 672.3 40759.6 41489.8 41512.4 41647.0 41749.4 43996.8 44672.3 44680.9 44687.8 44795.1 9832.0 10040.3 10064.0 10079.3 10087.5 9942.7 10078.1 10086.0 10099.6 10099.8 10080.2 10155.8 10202.8 10204.5 10216.7 10096.5 10179.3 10192.9 10210.8 10250.8 9974.3 10058.5 10100.1 10112.3 10131.0 43352.3 43705.3 43875.3 43938.1 43991.6 3265.9 3424.7 3448.1 3686.0 3895.5 29261.0 30071.7 30279.8 30463.0 30770.0 2778.5 2795.7 2800.8 2859.3 2876.6 382121.3 385442.0 385641.1 385753.0 385858.0 69072.3 69646.0 69681.7 69736.3 69737.0 78722.0 79668.0 79758.8 79895.2 79919.6 18930.4 19242.0 19254.7 19286.8 19288.1 14586.8 14670.1 14684.9 14686.9 14697.8 21295.6 21325.0 22504.0 22819.5 23189.0 9182.7 9426.0 9472.6 9479.3 9492.0 973.8 1028.3 1123.7 1186.9 1227.2 30865.2 30882.5 30905.4 31095.4 31443.8 989.4 1149.6 1164.4 1195.5 1263.3 15811.0 16791.6 16961.8 17099.2 17145.8 563071.0 683149.0 684630.0 684718.6 685152.0 64198.7 65703.0 65717.7 65730.7 65912.7 1441.4 1503.5 1542.5 1562.7 1692.3 29717.4 29927.9 30007.0 30683.4 30739.8 52199.5 52236.2 52275.0 52354.2 52620.8 374508.0 376358.0 377507.0 377589.0 379013.1 29537.0 34312.0 34749.3 35090.5 35241.5 43037.4 45044.6 45055.0 45386.0 45776.1 35761.1 36619.6 37201.8 37585.0 37956.4 36666.0 36696.8 37103.6 37353.8 37467.4 36712.4 36996.8 37034.0 37076.6 37891.6 26618.0 31617.5 33168.5 33362.0 33457.8 24710.9 24935.5 24977.9 25182.7 25487.0 11:34:26 INFO - PROCESS | 1947 | 11:34:26 INFO - PROCESS | 1947 | [#2] jquery.html Cycles:180 Average:37031.30 Median:21537.07 stddev:113175.72 (525.5%) stddev-sans-first:113459.69 11:34:26 INFO - PROCESS | 1947 | Values: 351.7 361.9 381.5 384.5 393.6 18608.3 20705.9 22384.0 22620.0 23079.1 18920.5 19556.4 19752.8 19941.9 22530.0 5920.7 6091.4 6124.7 6135.2 6135.7 5928.1 6028.1 6101.6 6106.3 6140.6 6043.8 6073.7 6077.5 6083.0 6105.6 5800.6 6079.8 6095.8 6096.2 6106.9 5806.2 5819.8 5840.2 5844.8 5862.6 13958.4 14255.5 14302.6 14308.7 14308.9 2183.0 2208.3 2229.4 2270.5 2277.4 8964.7 9954.9 10226.2 10266.9 10692.7 1790.2 1875.4 1896.3 1973.9 1988.1 97041.5 108030.0 109025.5 110120.9 112191.9 11207.7 11223.0 11230.6 11297.9 11392.3 14556.4 14558.2 14614.4 14638.3 14662.1 8925.6 9098.5 9098.6 9202.6 9232.3 9411.9 9432.6 9441.9 9480.5 9498.4 4525.3 4592.4 4595.4 4646.6 4646.8 1738.9 1916.3 1948.0 1965.9 1979.3 907.7 955.3 976.7 999.7 1127.6 21046.7 21328.0 21452.6 21486.7 21587.5 957.8 973.6 1000.2 1014.5 1031.6 10253.6 10726.9 11800.4 11991.3 12040.2 668374.0 678808.0 681594.0 692104.0 697935.5 121090.0 121523.4 126283.4 129564.0 133690.7 1083.3 1095.0 1108.0 1110.0 1114.6 19346.1 19758.9 20152.8 20231.2 20323.2 52164.2 54265.0 54646.0 55179.0 55538.0 76210.6 77147.0 77616.2 77679.8 79631.0 7847.7 8229.5 8427.1 8434.2 8645.2 10965.2 12561.9 13462.6 13648.3 13885.4 11149.2 11385.6 11846.9 11903.8 12132.3 11666.0 11669.8 11717.1 11984.5 12003.2 11608.0 11734.0 11898.9 12170.9 12175.0 7530.2 8131.5 8345.0 8401.2 8532.9 10141.4 11827.5 11881.4 12051.4 12091.2 11:34:26 INFO - PROCESS | 1947 | 11:34:26 INFO - PROCESS | 1947 | [#3] mootools.html Cycles:180 Average:6133.11 Median:4884.50 stddev:3269.52 (66.9%) stddev-sans-first:3258.62 11:34:26 INFO - PROCESS | 1947 | Values: 1312.7 1589.4 1617.0 1992.3 2183.8 6913.9 8812.4 9055.0 9059.5 9095.2 7727.0 8775.2 9023.4 9036.0 9085.6 2404.5 2426.1 2427.8 2428.5 2433.2 2396.1 2416.7 2447.1 2450.8 2455.3 2396.5 2409.2 2417.5 2428.3 2429.0 2044.9 2385.3 2402.0 2407.9 2409.7 2351.6 2396.3 2400.7 2400.9 2406.1 9007.2 9041.1 9041.6 9051.0 9056.8 5373.3 5506.1 5506.8 5511.6 5522.4 3498.6 3554.1 3609.5 3626.3 3643.7 1046.7 1071.5 1072.3 1077.6 1085.7 12301.7 12317.0 12319.8 12322.8 12328.0 4728.0 4788.2 4802.8 4804.4 4964.6 10228.8 10232.9 10233.6 10241.7 10266.4 5487.1 5494.0 5497.3 5501.9 5504.9 4476.7 4597.1 4647.5 4649.9 5031.7 3316.6 3713.7 3827.1 3850.2 3902.0 1178.9 1247.7 1305.2 1312.2 1318.9 1036.7 1192.2 1201.5 1237.3 1239.6 8333.1 8421.5 8425.2 8442.9 8635.1 1082.5 1255.3 1260.4 1268.1 1271.4 6374.6 6388.8 6443.5 6460.1 6469.0 10447.5 10779.9 10906.5 10909.7 10918.8 10538.1 10711.1 10839.7 10847.8 11016.9 8527.1 8655.8 8685.9 8689.3 8691.1 8046.4 8053.6 8147.3 8340.6 8370.4 9325.8 9519.1 9632.4 9655.5 9880.1 9751.5 9994.6 10026.3 10099.6 10201.7 6842.8 7632.3 7663.7 7667.6 7804.8 7858.4 7927.7 7928.2 7932.6 8205.2 6543.7 7375.3 7587.4 7677.9 7739.0 6485.6 7454.6 7455.9 7551.2 7618.8 7822.3 7915.5 7959.1 7961.2 7970.5 7167.2 7248.0 7296.4 7297.0 7304.5 7156.2 7318.0 7332.7 7348.0 7365.3 11:34:26 INFO - PROCESS | 1947 | 11:34:26 INFO - PROCESS | 1947 | [#4] prototype.html Cycles:180 Average:5870.39 Median:6722.55 stddev:1868.98 (27.8%) stddev-sans-first:1826.59 11:34:26 INFO - PROCESS | 1947 | Values: 284.4 343.1 343.1 346.2 346.8 6715.7 6720.6 6724.5 6734.1 6773.3 6736.0 6736.9 6766.8 6788.9 6791.4 4298.8 4546.2 4885.8 4916.7 5029.3 4998.0 5004.4 5008.8 5015.3 5015.4 4093.6 4923.9 5003.2 5005.3 5014.6 3840.3 4800.2 4917.7 4997.9 5012.4 4212.0 4219.8 4226.9 4232.7 4236.3 6337.9 6395.4 6396.1 6404.1 6417.0 5224.0 5547.8 5840.8 5853.4 5886.5 5688.8 6589.8 7049.4 7099.1 7102.3 3809.3 4737.0 4763.0 4828.9 4849.2 7440.4 7576.3 7598.4 7608.2 7635.4 4304.9 4954.0 4955.7 4958.9 4972.2 5024.6 5041.4 5045.9 5051.8 5055.3 5925.6 5941.3 5947.8 5954.8 5957.6 5890.5 6926.9 7210.5 7240.7 7243.7 1723.5 1736.7 1751.2 1751.6 1762.9 1125.1 1128.4 1147.0 1151.1 1154.6 6649.8 6720.3 6813.6 6879.4 6917.6 6548.9 6748.1 7513.2 7523.7 7553.2 6883.9 6894.3 6922.2 6927.9 6936.0 3953.6 3983.9 3994.9 4000.4 4002.9 6246.5 6253.9 7632.3 7846.6 7857.0 6257.9 7723.0 7729.3 7740.3 7743.3 7875.1 7890.0 7890.2 7900.3 7901.0 7278.6 7417.4 7505.0 7519.9 7529.3 7336.4 7366.1 7380.6 7385.7 7387.2 7849.9 7870.5 7882.9 7883.0 7888.2 4326.5 4638.8 4673.8 4696.6 4702.2 6835.0 7143.5 7431.3 7439.7 7468.1 7176.8 7185.3 7209.7 7227.2 7258.3 7186.2 7191.2 7195.5 7198.2 7211.9 7016.7 7030.5 7039.8 7047.2 7060.4 6884.1 7122.3 7171.9 7182.0 7200.6 6816.8 6972.0 6979.0 7011.2 7083.6 11:34:26 INFO - PROCESS | 1947 | 11:34:26 INFO - PROCESS | 1947 | [#5] yui.html Cycles:180 Average:18632.37 Median:21996.26 stddev:25783.96 (117.2%) stddev-sans-first:25821.21 11:34:26 INFO - PROCESS | 1947 | Values: 721.0 756.4 779.8 798.7 826.9 15666.3 15787.5 16028.6 16028.8 16048.9 14897.2 14938.8 14967.0 14992.7 14995.7 816.5 840.5 990.5 998.7 1021.0 1024.6 1029.3 1030.4 1030.6 1030.8 1074.7 1075.7 1079.7 1081.4 1082.3 839.7 1055.1 1070.6 1077.5 1080.2 804.7 805.4 805.9 806.2 806.8 13965.9 15150.8 15907.5 15943.1 15949.3 13043.5 13099.4 13299.4 13356.0 13376.2 10196.4 10437.8 10465.4 10614.7 10754.4 3609.3 3616.2 3639.3 3713.5 3870.7 82181.2 83597.0 91829.1 92037.0 92771.5 9996.4 10040.3 10219.5 10238.4 10500.8 23104.3 23136.0 23140.5 23145.0 23146.7 7733.6 7944.1 8025.2 8064.2 8070.0 4193.6 4224.0 4242.4 4271.4 4316.7 3493.3 3551.8 3558.0 3559.4 3566.1 564.5 650.3 696.2 710.5 711.6 514.4 591.8 604.8 626.4 628.9 8330.4 8469.4 8514.0 8521.3 8560.9 515.7 599.9 613.3 628.5 641.5 3868.4 3999.7 4014.9 4081.6 4086.0 84602.0 84983.6 85037.0 87920.6 89721.0 77613.6 77793.0 78273.0 78806.0 79502.0 67265.7 67692.0 67942.7 68767.0 70889.6 8545.9 8657.0 8888.4 8960.7 8961.5 39992.8 49308.5 50800.0 51446.0 53131.0 69173.0 69506.6 70088.6 70636.0 70964.3 10944.3 11275.6 11287.5 11559.5 11619.5 11972.8 12054.3 12125.5 12343.0 12650.2 9922.6 11279.8 11519.9 11895.0 12274.9 11406.8 11455.7 11728.7 11769.5 11845.2 11451.3 11581.8 11642.8 11773.7 11910.6 9716.5 9880.4 9947.8 10120.8 10426.8 9779.6 10075.5 10082.0 10090.3 10303.3 11:34:26 INFO - PROCESS | 1947 | -------- Summary: end -------- 11:34:26 INFO - PROCESS | 1947 | 11:34:27 INFO - TEST-INFO | 1947: exit 0 11:34:27 INFO - TEST-OK | dromaeo_css | took 1111398ms 11:34:27 INFO - TEST-START | kraken 11:34:27 INFO - operating with platform_type : mac_ 11:34:27 INFO - Initialising browser for kraken test... 11:34:27 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpg2Omgm/profile http://localhost:49715/getInfo.html) 11:34:29 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 11:34:29 INFO - PROCESS | 1953 | colorDepth:24 11:34:29 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 11:34:29 INFO - PROCESS | 1953 | __metrics 11:34:29 INFO - PROCESS | 1953 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:34:29 INFO - PROCESS | 1953 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:34:29 INFO - TEST-INFO | 1953: exit 0 11:34:29 INFO - Browser initialized. 11:34:29 INFO - Running cycle 1/1 for kraken test... 11:34:29 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpg2Omgm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:34:41 INFO - PROCESS | 1956 | RSS: Main: 156880896 11:34:41 INFO - PROCESS | 1956 | 11:34:44 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49715/tests/kraken/driver.html?testName=audio-beat-detection) 11:34:44 INFO - PROCESS | 1956 | RSS: Main: 213196800 11:34:44 INFO - PROCESS | 1956 | 11:34:47 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49715/tests/kraken/driver.html?testName=audio-dft) 11:34:48 INFO - PROCESS | 1956 | RSS: Main: 223432704 11:34:48 INFO - PROCESS | 1956 | 11:34:51 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49715/tests/kraken/driver.html?testName=audio-fft) 11:34:51 INFO - PROCESS | 1956 | RSS: Main: 214106112 11:34:51 INFO - PROCESS | 1956 | 11:34:53 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49715/tests/kraken/driver.html?testName=audio-oscillator) 11:34:54 INFO - PROCESS | 1956 | RSS: Main: 216109056 11:34:54 INFO - PROCESS | 1956 | 11:34:56 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49715/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:34:56 INFO - PROCESS | 1956 | RSS: Main: 216031232 11:34:56 INFO - PROCESS | 1956 | 11:34:59 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49715/tests/kraken/driver.html?testName=imaging-darkroom) 11:35:00 INFO - PROCESS | 1956 | RSS: Main: 215859200 11:35:00 INFO - PROCESS | 1956 | 11:35:03 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49715/tests/kraken/driver.html?testName=imaging-desaturate) 11:35:03 INFO - PROCESS | 1956 | RSS: Main: 216039424 11:35:03 INFO - PROCESS | 1956 | 11:35:06 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49715/tests/kraken/driver.html?testName=json-parse-financial) 11:35:06 INFO - PROCESS | 1956 | RSS: Main: 216371200 11:35:06 INFO - PROCESS | 1956 | 11:35:08 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49715/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:35:09 INFO - PROCESS | 1956 | RSS: Main: 250167296 11:35:09 INFO - PROCESS | 1956 | 11:35:10 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49715/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:35:11 INFO - PROCESS | 1956 | RSS: Main: 251342848 11:35:11 INFO - PROCESS | 1956 | 11:35:13 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49715/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:35:14 INFO - PROCESS | 1956 | RSS: Main: 256303104 11:35:14 INFO - PROCESS | 1956 | 11:35:16 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49715/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:35:17 INFO - PROCESS | 1956 | RSS: Main: 257720320 11:35:17 INFO - PROCESS | 1956 | 11:35:19 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49715/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:35:20 INFO - PROCESS | 1956 | RSS: Main: 257732608 11:35:20 INFO - PROCESS | 1956 | 11:35:21 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49715/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49715/tests/kraken/driver.html?testName=ai-astar) 11:35:22 INFO - PROCESS | 1956 | RSS: Main: 320262144 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | __start_tp_report 11:35:22 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 11:35:22 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 11:35:22 INFO - PROCESS | 1956 | |i|pagename|runs| 11:35:22 INFO - PROCESS | 1956 | |0;ai-astar;112;108;111;111;112;113;121;110;110;111 11:35:22 INFO - PROCESS | 1956 | |1;audio-beat-detection;155;118;156;148;114;140;119;149;149;119 11:35:22 INFO - PROCESS | 1956 | |2;audio-dft;186;186;185;184;187;183;185;186;186;184 11:35:22 INFO - PROCESS | 1956 | |3;audio-fft;74;73;74;106;56;49;64;75;150;82 11:35:22 INFO - PROCESS | 1956 | |4;audio-oscillator;89;93;127;77;80;83;118;75;78;116 11:35:22 INFO - PROCESS | 1956 | |5;imaging-gaussian-blur;89;87;90;87;89;86;90;87;93;129 11:35:22 INFO - PROCESS | 1956 | |6;imaging-darkroom;94;94;97;95;93;94;95;93;94;93 11:35:22 INFO - PROCESS | 1956 | |7;imaging-desaturate;85;85;84;84;84;85;85;85;86;87 11:35:22 INFO - PROCESS | 1956 | |8;json-parse-financial;54;58;57;56;56;56;58;56;54;53 11:35:22 INFO - PROCESS | 1956 | |9;json-stringify-tinderbox;62;61;68;70;75;71;68;66;66;65 11:35:22 INFO - PROCESS | 1956 | |10;stanford-crypto-aes;81;82;81;82;81;80;80;81;80;83 11:35:22 INFO - PROCESS | 1956 | |11;stanford-crypto-ccm;119;119;118;118;119;127;102;129;124;126 11:35:22 INFO - PROCESS | 1956 | |12;stanford-crypto-pbkdf2;133;117;133;135;134;123;134;136;134;133 11:35:22 INFO - PROCESS | 1956 | |13;stanford-crypto-sha256-iterative;66;65;64;66;65;66;64;65;65;65 11:35:22 INFO - PROCESS | 1956 | __end_tp_report 11:35:22 INFO - PROCESS | 1956 | __start_cc_report 11:35:22 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1196 11:35:22 INFO - PROCESS | 1956 | __end_cc_report 11:35:22 INFO - PROCESS | 1956 | __startTimestamp1464028522195__endTimestamp 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | ------- Summary: start ------- 11:35:22 INFO - PROCESS | 1956 | Number of tests: 14 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.50 stddev:3.48 (3.1%) stddev-sans-first:3.69 11:35:22 INFO - PROCESS | 1956 | Values: 112.0 108.0 111.0 111.0 112.0 113.0 121.0 110.0 110.0 111.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:148.50 stddev:17.13 (11.5%) stddev-sans-first:16.84 11:35:22 INFO - PROCESS | 1956 | Values: 155.0 118.0 156.0 148.0 114.0 140.0 119.0 149.0 149.0 119.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#2] audio-dft Cycles:10 Average:185.20 Median:186.00 stddev:1.23 (0.7%) stddev-sans-first:1.27 11:35:22 INFO - PROCESS | 1956 | Values: 186.0 186.0 185.0 184.0 187.0 183.0 185.0 186.0 186.0 184.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#3] audio-fft Cycles:10 Average:80.30 Median:73.50 stddev:28.90 (39.3%) stddev-sans-first:30.57 11:35:22 INFO - PROCESS | 1956 | Values: 74.0 73.0 74.0 106.0 56.0 49.0 64.0 75.0 150.0 82.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:79.00 stddev:19.43 (24.6%) stddev-sans-first:20.53 11:35:22 INFO - PROCESS | 1956 | Values: 89.0 93.0 127.0 77.0 80.0 83.0 118.0 75.0 78.0 116.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:89.00 stddev:12.92 (14.5%) stddev-sans-first:13.63 11:35:22 INFO - PROCESS | 1956 | Values: 89.0 87.0 90.0 87.0 89.0 86.0 90.0 87.0 93.0 129.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:1.23 (1.3%) stddev-sans-first:1.30 11:35:22 INFO - PROCESS | 1956 | Values: 94.0 94.0 97.0 95.0 93.0 94.0 95.0 93.0 94.0 93.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:0.94 (1.1%) stddev-sans-first:1.00 11:35:22 INFO - PROCESS | 1956 | Values: 85.0 85.0 84.0 84.0 84.0 85.0 85.0 85.0 86.0 87.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.69 (3.0%) stddev-sans-first:1.66 11:35:22 INFO - PROCESS | 1956 | Values: 54.0 58.0 57.0 56.0 56.0 56.0 58.0 56.0 54.0 53.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:67.20 Median:68.00 stddev:4.18 (6.2%) stddev-sans-first:3.99 11:35:22 INFO - PROCESS | 1956 | Values: 62.0 61.0 68.0 70.0 75.0 71.0 68.0 66.0 66.0 65.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.00 stddev:0.99 (1.2%) stddev-sans-first:1.05 11:35:22 INFO - PROCESS | 1956 | Values: 81.0 82.0 81.0 82.0 81.0 80.0 80.0 81.0 80.0 83.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:120.10 Median:121.50 stddev:7.58 (6.2%) stddev-sans-first:8.03 11:35:22 INFO - PROCESS | 1956 | Values: 119.0 119.0 118.0 118.0 119.0 127.0 102.0 129.0 124.0 126.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.20 Median:134.00 stddev:6.14 (4.6%) stddev-sans-first:6.48 11:35:22 INFO - PROCESS | 1956 | Values: 133.0 117.0 133.0 135.0 134.0 123.0 134.0 136.0 134.0 133.0 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - PROCESS | 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 11:35:22 INFO - PROCESS | 1956 | Values: 66.0 65.0 64.0 66.0 65.0 66.0 64.0 65.0 65.0 65.0 11:35:22 INFO - PROCESS | 1956 | -------- Summary: end -------- 11:35:22 INFO - PROCESS | 1956 | 11:35:22 INFO - TEST-INFO | 1956: exit 0 11:35:22 INFO - TEST-OK | kraken | took 55641ms 11:35:22 INFO - SUITE-END | took 1167s 11:35:23 INFO - Completed test suite (00:19:27) 11:35:23 INFO - javascript benchmark 11:35:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3539.876104336348, 3570.9107272318206, 3574.0707416071828, 3615.4576813477975, 3648.0, 5696.658200507962, 5706.0585003474425, 5723.4848863602265, 5741.311042674874, 5752.079667253241, 5700.8859822803515, 5726.513246374063, 5752.165935939286, 5752.453516915886, 5752.769889204426, 5133.897322053556, 5165.38015438147, 5187.351581052369, 5200.297959775438, 5284.603654725892, 5275.235090911816, 5281.0, 5287.180487024498, 5288.286081379022, 5289.418164001957, 5238.764255608507, 5281.63028587999, 5284.128118860383, 5310.150375939831, 5313.0, 5298.973505132488, 5326.520613144816, 5331.653442526223, 5334.413214546396, 5342.038433082043, 5065.442801291855, 5234.42421333653, 5234.92147617786, 5250.947490525123, 5252.133397989365, 5552.944470555283, 5631.943680563224, 5639.436056394369, 5646.096624540095, 5659.4340565943485, 6112.017184454403, 6294.968525157391, 6321.557490975633, 6328.335524769925, 6329.145565348711, 6240.625562466266, 6265.906011409833, 6303.905441418382, 6344.746210151635, 6350.301466838644, 5828.475437210629, 5852.385499522574, 5852.736626851759, 5858.384869588717, 5892.587518873724, 6225.750969961241, 6244.12582238487, 6329.905051424233, 6332.936670633327, 6339.492840572743, 6163.106349579286, 6186.81439556814, 6197.566170368031, 6199.721012554446, 6208.348123446973, 6298.433141017239, 6331.588446750947, 6332.683365831782, 6334.3032266450655, 6336.1762970814425, 6116.204893363834, 6166.321704612579, 6186.4741496973265, 6186.721597528122, 6253.6873156342, 6245.531585131133, 6270.9372906271265, 6294.0, 6296.150019747367, 6335.936640633627, 4894.755262236874, 4907.435644900811, 4914.852554423373, 4916.115099282092, 4933.4819843915875, 4356.237658409766, 4439.245328294198, 4439.911201775946, 4479.7984090716, 4486.506484286726, 5842.065269556935, 5865.530757539386, 5867.794627188028, 5872.0898260769645, 5886.381929897385, 6265.8120256392385, 6277.96861015692, 6285.151504546919, 6288.779892703825, 6300.779472718525, 5770.769169233267, 5834.154047663065, 5867.765289388462, 5874.1776151338845, 5955.106733989936, 5388.649737767033, 5397.676139431646, 5406.675599464044, 5408.1887716842, 5422.322209723785, 5325.946740532545, 6406.935930640634, 6409.262934762562, 6416.262129855127, 6438.742450302029, 6166.784162554289, 6227.06594010902, 6308.842278943063, 6329.145565348619, 6339.587926784744, 5907.91138132937, 5945.738405460974, 6385.584936979081, 6422.42198202164, 6424.325445828121, 6235.158253635701, 6262.5929314594405, 6272.40412160844, 6276.560640755103, 6285.5600107992, 6125.540694081788, 6240.500759939103, 6268.843278918063, 6328.936710632835, 6331.556791024584, 5953.642781433128, 6149.200603921461, 6176.104464852661, 6176.907346389898, 6184.536159787944, 5987.760489580368, 6148.723307451086, 6160.414760597736, 6167.568270221221, 6178.0, 5979.162917191684, 6025.397460253939, 6030.095485677008, 6048.6975651218145, 6092.208012958287, 6144.815655530341, 6168.9691551541955, 6180.443760061616, 6198.845028874314, 6216.658083805434, 5836.766529338779, 5988.041913293852, 6067.393260673897, 6086.391360863879, 6114.144019837316], "unit": "score", "name": "dojo.html", "value": 5755.156510279288}, {"lowerIsBetter": false, "replicates": [500.9155179037384, 585.9970700146498, 599.6851652882234, 659.8119535932259, 672.3243140643652, 40759.573414930484, 41489.79255103724, 41512.37731434027, 41647.0, 41749.37375939363, 43996.780016099954, 44672.3299150513, 44680.88297792557, 44687.77656111723, 44795.104097918025, 9831.950840245807, 10040.347377420481, 10064.04391582802, 10079.344842585246, 10087.495625218746, 9942.701718948443, 10078.143357814595, 10085.991400859892, 10099.646512372068, 10099.798004039914, 10080.243981701402, 10155.796884062314, 10202.846957295647, 10204.489775511194, 10216.744581385488, 10096.545655445485, 10179.338343007681, 10192.898071019306, 10210.795784084352, 10250.794984100276, 9974.301798874081, 10058.547365368577, 10100.141487973531, 10112.292139550234, 10131.0, 43352.3497147543, 43705.34441983373, 43875.341869871976, 43938.12123757507, 43991.56008439907, 3265.9183520411943, 3424.6746559077096, 3448.1207292140343, 3686.041629176407, 3895.5130608673917, 29261.0, 30071.69928300733, 30279.848600757075, 30463.0, 30770.0, 2778.458200650874, 2795.6784969728333, 2800.80394372394, 2859.3280579063903, 2876.554134109216, 382121.2681809803, 385442.0, 385641.1435885633, 385753.0, 385858.0, 69072.30927690658, 69646.0, 69681.65159174273, 69736.25659358557, 69736.95394569085, 78722.0, 79668.0, 79758.80361794577, 79895.20104798878, 79919.60040199762, 18930.43208703741, 19242.0, 19254.71117933232, 19286.8071319285, 19288.1320340585, 14586.781198282035, 14670.119792812466, 14684.853151468562, 14686.853131468763, 14697.77953330701, 21295.61578497393, 21325.0, 22503.987320570614, 22819.543609128057, 23189.0, 9182.724518264462, 9426.0, 9472.584490954763, 9479.289053321028, 9492.006678597572, 973.7711637765261, 1028.3469996552244, 1123.662189162995, 1186.8753780853058, 1227.220714846079, 30865.228369290948, 30882.536761948588, 30905.389328860045, 31095.378092438026, 31443.842780786406, 989.4063561862824, 1149.6263714292888, 1164.3828770751516, 1195.5217912834935, 1263.298869127643, 15810.972286801321, 16791.58021049459, 16961.830381696273, 17099.23053462597, 17145.828541714673, 563071.0, 683149.0, 684630.0, 684718.5764071147, 685152.0, 64198.679006605606, 65703.01445478322, 65717.67141164263, 65730.67134664296, 65912.67043664752, 1441.3730027438098, 1503.4812989518598, 1542.452429387596, 1562.7499600063936, 1692.280780668211, 29717.405651887275, 29927.850360748056, 30007.0, 30683.386332272785, 30739.846300769248, 52199.47800521946, 52236.21645675394, 52275.0, 52354.21468678049, 52620.842749435156, 374508.0, 376358.0, 377507.0, 377589.0, 379013.1049344736, 29537.0, 34312.0, 34749.305013900084, 35090.473642895886, 35241.471377928836, 43037.35443968406, 45044.64866054024, 45055.0, 45386.0, 45776.08447830958, 35761.1059723509, 36619.63380366162, 37201.81399092987, 37585.0, 37956.43065353967, 36666.0, 36696.81651591832, 37103.62896371002, 37353.81323093367, 37467.43798842965, 36712.449313259785, 36996.815015924745, 37034.0, 37076.62923370732, 37891.62108378881, 26618.0, 31617.5257371135, 33168.50247246341, 33362.0, 33457.83271083726, 24710.876445617658, 24935.50128997446, 24977.875110625057, 25182.74817251804, 25487.0], "unit": "score", "name": "ext.html", "value": 20867.912040606076}, {"lowerIsBetter": false, "replicates": [351.6724365520161, 361.8787706118451, 381.51738051365027, 384.5366333568049, 393.6476853216373, 18608.25566977321, 20705.896470517644, 22383.992720327606, 22620.0, 23079.076836926502, 18920.526986825334, 19556.41330760076, 19752.802471975312, 19941.90029049853, 22530.0, 5920.703964801764, 6091.421314975092, 6124.693765311739, 6135.233095863017, 6135.693215339215, 5928.081147422156, 6028.125921741344, 6101.633901965874, 6106.328303886569, 6140.570160088796, 6043.818685439422, 6073.696315184246, 6077.483413909823, 6083.0, 6105.633661980295, 5800.564957628195, 6079.787207447718, 6095.786647467362, 6096.207493025879, 6106.938930610704, 5806.245188125559, 5819.82540523785, 5840.240768700084, 5844.824655260349, 5862.618929769551, 13958.441662333495, 14255.50105746294, 14302.64243393913, 14308.713825723531, 14308.92845535776, 2183.0, 2208.3374987503844, 2229.3646310801505, 2270.5458908218257, 2277.36233854521, 8964.686235981775, 9954.90045099547, 10226.233032522516, 10266.948665256701, 10692.679219623424, 1790.185465613143, 1875.3811242290012, 1896.345760712552, 1973.9111739971736, 1988.1053525913396, 97041.51479242559, 108030.0, 109025.45487272512, 110120.89879101186, 112191.87808121897, 11207.66377008691, 11223.0, 11230.606928757454, 11297.943510282395, 11392.316461012366, 14556.417743290362, 14558.199299038442, 14614.415423382945, 14638.341274642667, 14662.120272783666, 8925.598348074222, 9098.454092754457, 9098.590563424661, 9202.631894724136, 9232.261419086457, 9411.858822117674, 9432.622695092257, 9441.90558094424, 9480.525973701288, 9498.430094194233, 4525.343825145402, 4592.402987611589, 4595.425571803525, 4646.558576935186, 4646.83736069236, 1738.9043602601978, 1916.3292847503542, 1948.0454577257326, 1965.88204707718, 1979.2676709617533, 907.7140700679265, 955.302629080775, 976.6874600128033, 999.7200783780553, 1127.5884302229626, 21046.67272483504, 21328.0, 21452.570948580942, 21486.677699834516, 21587.460313491974, 957.7940742740344, 973.5959576775559, 1000.1698590170141, 1014.5333146752577, 1031.6440827914357, 10253.58985640581, 10726.873678263826, 11800.411317213557, 11991.280523168638, 12040.157188996862, 668374.0, 678808.0, 681594.0, 692104.0, 697935.5103224452, 121090.0, 121523.39238303751, 126283.3685831565, 129564.0, 133690.66309336977, 1083.285031878972, 1095.0, 1107.9612213572486, 1110.0120892405766, 1114.5844777132995, 19346.129424175666, 19758.90120549388, 20152.798472015093, 20231.23976567455, 20323.187072516932, 52164.21753673622, 54265.0, 54646.0, 55179.0, 55538.0, 76210.61894690491, 77147.0, 77616.2238377609, 77679.83480247688, 79631.0, 7847.725329613436, 8229.506229626242, 8427.115152909102, 8434.24091831738, 8645.221930026328, 10965.232433729563, 12561.87438125607, 13462.596122116352, 13648.317584120952, 13885.444582217002, 11149.219554631098, 11385.601503947653, 11846.881531184577, 11903.76192476163, 12132.332721700392, 11666.0, 11669.76660466803, 11717.121215909012, 11984.520619175135, 12003.1597788154, 11607.955284024478, 11734.0, 11898.881011189776, 12170.939145304217, 12175.0, 7530.171681114964, 8131.512109273463, 8344.998600168019, 8401.15988401155, 8532.872006919777, 10141.391516509033, 11827.526898923947, 11881.405929703307, 12051.397430128283, 12091.214071085351], "unit": "score", "name": "jquery.html", "value": 9702.461434422521}, {"lowerIsBetter": false, "replicates": [1312.665270356059, 1589.3563106941688, 1617.0, 1992.2827781998487, 2183.8471307008517, 6913.861722765541, 8812.427192232508, 9055.003949565542, 9059.456432614032, 9095.226905713005, 7726.961365193166, 8775.210231079202, 9023.413478123917, 9036.0, 9085.636574537028, 2404.471016376399, 2426.1144682191048, 2427.8057755379527, 2428.5264373447185, 2433.2335314376005, 2396.077510158584, 2416.6979127609047, 2447.1434997750735, 2450.82844200906, 2455.287966489716, 2396.4847557775074, 2409.180878501318, 2417.492326611414, 2428.344347026302, 2429.0405289910473, 2044.94887627809, 2385.3082606044227, 2401.9519609607796, 2407.9157229496973, 2409.7469765674527, 2351.564960482316, 2396.26270953559, 2400.7119145702454, 2400.9039638414442, 2406.0856874387796, 9007.234385077276, 9041.050689677555, 9041.593128309241, 9051.04963978786, 9056.818863622691, 5373.274607927919, 5506.063969125258, 5506.752196151183, 5511.614187006911, 5522.447755224485, 3498.597661268961, 3554.111472131967, 3609.5127157833617, 3626.3110009098186, 3643.744937854351, 1046.712154157605, 1071.464267866067, 1072.2708558180434, 1077.5851297250535, 1085.6851513061204, 12301.69245768854, 12316.953058989997, 12319.815202771966, 12322.75354492914, 12327.952124069465, 4727.976360118178, 4788.162071637449, 4802.807887684454, 4804.399450068741, 4964.60283177345, 10228.846567301496, 10232.925542818046, 10233.59065637381, 10241.692749217535, 10266.435346056038, 5487.067198576252, 5493.972530137404, 5497.285352904097, 5501.889962200734, 5504.889902201933, 4476.70901391415, 4597.149527337453, 4647.512011238839, 4649.86050418488, 5031.6729412588065, 3316.602007759036, 3713.665770080706, 3827.138893748884, 3850.1914597934747, 3902.0, 1178.8997935175605, 1247.7379750252549, 1305.1712162776705, 1312.2126723965735, 1318.8615195404536, 1036.6941752183125, 1192.24292574216, 1201.4593432955205, 1237.2761934268553, 1239.5909349914507, 8333.125021872738, 8421.452605580618, 8425.199606037417, 8442.915570844212, 8635.093315201939, 1082.5182793656747, 1255.3158528601934, 1260.4139075330015, 1268.0806415348982, 1271.3706715176047, 6374.58565193261, 6388.84027899297, 6443.452306553995, 6460.063290822898, 6469.0, 10447.529861156267, 10779.946100269448, 10906.5092070857, 10909.672709818718, 10918.836217456745, 10538.051575358102, 10711.143108551298, 10839.67480975572, 10847.783044339227, 11016.944915275373, 8527.10465401124, 8655.783605409915, 8685.869711954325, 8689.348298877483, 8691.130886911258, 8046.356291496549, 8053.597320134087, 8147.307478864363, 8340.6246718899, 8370.414071014971, 9325.766855828659, 9519.143277105093, 9632.373895696757, 9655.517224138905, 9880.061394167542, 9751.463669498246, 9994.60021599157, 10026.298159128792, 10099.646512372032, 10201.693949181536, 6842.7605033823575, 7632.313091821763, 7663.693452261847, 7667.616619168908, 7804.84390312202, 7858.449908506578, 7927.682892684459, 7928.167542407964, 7932.6033698313695, 8205.179482051746, 6543.70553325111, 7375.336219740249, 7587.430942679211, 7677.884831727417, 7739.0, 6485.6108633482145, 7454.552726836408, 7455.888161677471, 7551.244875512405, 7618.771436856903, 7822.335101516434, 7915.525068495908, 7959.124496305518, 7961.243681850215, 7970.481918675499, 7167.211606723152, 7247.9637601811655, 7296.4162866969455, 7296.96351518239, 7304.452166087458, 7156.212816590275, 7317.9754834322175, 7332.670029848563, 7347.971284020135, 7365.337119659256], "unit": "score", "name": "mootools.html", "value": 4978.368500647951}, {"lowerIsBetter": false, "replicates": [284.42120285219585, 343.0788333325024, 343.142144638404, 346.2244572158364, 346.7555373461709, 6715.73137074517, 6720.563163394376, 6724.529282950195, 6734.0572319875255, 6773.322667733224, 6736.023276624886, 6736.922092465206, 6766.830829229271, 6788.898166527506, 6791.354821291981, 4298.806553705075, 4546.227141385956, 4885.780139893695, 4916.704997700132, 5029.270755740425, 4997.975010124963, 5004.424491183506, 5008.824691135811, 5015.348004759395, 5015.39815222172, 4093.6111069448357, 4923.901521969558, 5003.1994880819075, 5005.324281222043, 5014.598832093424, 3840.2703486337787, 4800.159972004885, 4917.72952487613, 4997.925031124554, 5012.448630650625, 4211.957880421187, 4219.78901054946, 4226.936595951063, 4232.682548808852, 4236.322188449864, 6337.873242535124, 6395.360463953614, 6396.1045453636525, 6404.071409645575, 6417.03744438338, 5223.973880130575, 5547.751755854941, 5840.8247752567495, 5853.3903176626345, 5886.529077673776, 5688.829335119953, 6589.769358072444, 7049.400800931927, 7099.148102227766, 7102.325279098477, 3809.29528037314, 4737.0, 4763.0, 4828.855134345975, 4849.248366503162, 7440.404767618577, 7576.318131368148, 7598.392128629696, 7608.2391760823475, 7635.350995165362, 4304.913901721948, 4954.00919816031, 4955.6531042826655, 4958.9008219835405, 4972.154733695281, 5024.598032157419, 5041.395032596112, 5045.899082018339, 5051.848444546669, 5055.317532133188, 5925.644461332334, 5941.257342832146, 5947.821565353046, 5954.761809527657, 5957.582969192202, 5890.4698577128265, 6926.861462770717, 7210.531315464478, 7240.674169662378, 7243.710251589878, 1723.526030341666, 1736.7308067249596, 1751.2207067854938, 1751.6321572469924, 1762.9471115866545, 1125.0774365020604, 1128.4245035032193, 1146.9598564050218, 1151.1308961734005, 1154.6132045764596, 6649.767258145942, 6720.260771315151, 6813.625250611264, 6879.380855722909, 6917.584944903322, 6548.9017664735065, 6748.089007983957, 7513.2111128331835, 7523.699052037857, 7553.206913274137, 6883.8967415488805, 6894.276101009422, 6922.238553759083, 6927.896081558781, 6935.965320173468, 3953.5651078381356, 3983.900402489961, 3994.8601798936897, 4000.3799411091327, 4002.8799136025964, 6246.500279977591, 6253.874922501524, 7632.274933881273, 7846.568438735811, 7857.0, 6257.937420625735, 7722.961385193151, 7729.265719756614, 7740.26467485588, 7743.341815945708, 7875.094364148082, 7890.0, 7890.171531989174, 7900.2889739923685, 7901.0, 7278.595365736396, 7417.406607471282, 7504.962475187589, 7519.887201692089, 7529.284717951786, 7336.37640800538, 7366.116066072105, 7380.5940673263485, 7385.7045718170675, 7387.224341444071, 7849.921500784919, 7870.488418252795, 7882.881756773768, 7882.960585197037, 7888.171741967011, 4326.502452218036, 4638.768061596839, 4673.813047478063, 4696.600788932979, 4702.177119004229, 6834.965825171042, 7143.464240181903, 7431.29402706742, 7439.665215065227, 7468.10382754073, 7176.7846964591145, 7185.317394847481, 7209.71161153548, 7227.205007449282, 7258.3104605062435, 7186.24544422849, 7191.20896701352, 7195.532290401107, 7198.172210195895, 7211.944595790174, 7016.719331226898, 7030.472714546328, 7039.753608623879, 7047.224805271314, 7060.399866011447, 6884.105066341343, 7122.287771223044, 7171.928280717335, 7181.964090179516, 7200.567965922061, 6816.795496135124, 6971.9651401744695, 6979.0, 7011.228764835762, 7083.574985500886], "unit": "score", "name": "prototype.html", "value": 5253.323184478286}, {"lowerIsBetter": false, "replicates": [721.0014130429354, 756.3948840927256, 779.7582749347703, 798.6805277888843, 826.9338452923765, 15666.295016724247, 15787.526374208792, 16028.59928501788, 16028.839711602881, 16048.91975540122, 14897.180655063967, 14938.775918361232, 14967.02714323571, 14992.700145997074, 14995.700085998273, 816.542736067804, 840.4831028917203, 990.4849478271294, 998.6904059741468, 1020.9693709188699, 1024.6106479537766, 1029.2743615750878, 1030.407515678484, 1030.5774632400717, 1030.804147212031, 1074.6883403812924, 1075.6826736112826, 1079.703081652544, 1081.3565928272665, 1082.3019152646539, 839.7396806989822, 1055.1347894726327, 1070.550368845087, 1077.4558847781898, 1080.195254535376, 804.7183485779989, 805.375833728859, 805.9032916050112, 806.209914284005, 806.8265322955534, 13965.930170349084, 15150.848491515164, 15907.522774316672, 15943.123128227942, 15949.282282297325, 13043.478260869553, 13099.41052652633, 13299.401526931311, 13356.0, 13376.197428154343, 10196.439195844225, 10437.843432348522, 10465.424401657905, 10614.734631634194, 10754.354738715701, 3609.3142302962615, 3616.168281295317, 3639.344917914774, 3713.461548075541, 3870.6903447724117, 82181.17818821795, 83597.0, 91829.08170918339, 92037.0, 92771.53614231886, 9996.400215986918, 10040.297179197532, 10219.540120694586, 10238.385696858064, 10500.789984200273, 23104.306870793902, 23136.0, 23140.53718925612, 23145.0, 23146.65280020801, 7733.613319334011, 7944.126146123922, 8025.23760242776, 8064.193580642006, 8069.991251093613, 4193.580641935843, 4224.0, 4242.384854196124, 4271.359296105609, 4316.6546676265825, 3493.3013397320638, 3551.804650744234, 3557.9822100889846, 3559.394902866519, 3566.1262990566956, 564.520157865819, 650.3204151661525, 696.1611258433576, 710.4529512275519, 711.6263961420295, 514.3596222702685, 591.7544219149005, 604.8366940925926, 626.4143026270451, 628.867937733072, 8330.416870819106, 8469.3647976402, 8514.020887597882, 8521.275691566163, 8560.871586926201, 515.6880087547055, 599.9310079340844, 613.2671457608116, 628.5254632752334, 641.5060403489288, 3868.38506154479, 3999.7200195986, 4014.879553613396, 4081.6326530611796, 4085.9795701021303, 84602.0, 84983.5750821242, 85037.0, 87920.56039719761, 89721.0, 77613.61193193997, 77793.0, 78273.0, 78806.0, 79502.0, 67265.66367168231, 67692.0, 67942.66028669923, 68767.0, 70889.64555177192, 8545.871811922701, 8656.956715216384, 8888.422252553823, 8960.659303381419, 8961.507117108622, 39992.80003599964, 49308.50691493183, 50800.0, 51445.97108057893, 53131.0, 69173.0, 69506.60986780337, 70088.59822803413, 70636.0, 70964.29035709577, 10944.288621239593, 11275.567788052222, 11287.492062857027, 11559.537618495166, 11619.5352185915, 11972.763287389156, 12054.276743395425, 12125.514979401078, 12343.0, 12650.177738446971, 9922.603095876084, 11279.830802538132, 11519.88480115188, 11895.0, 12274.877251227374, 11406.82889756671, 11455.713607159887, 11728.70678233051, 11769.470373833026, 11845.23006004607, 11451.312921224753, 11581.826272606086, 11642.76714465723, 11773.705657358634, 11910.642680719246, 9716.514174291398, 9880.40717556949, 9947.813927938218, 10120.79758404813, 10426.791464170827, 9779.630851680913, 10075.546600402853, 10081.991800820153, 10090.293679442368, 10303.278770486293], "unit": "score", "name": "yui.html", "value": 7210.494181413774}], "name": "dromaeo_css", "value": 7768.252429770973}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 108.0, 111.0, 111.0, 112.0, 113.0, 121.0, 110.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.9}, {"lowerIsBetter": true, "replicates": [155.0, 118.0, 156.0, 148.0, 114.0, 140.0, 119.0, 149.0, 149.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 136.7}, {"lowerIsBetter": true, "replicates": [186.0, 186.0, 185.0, 184.0, 187.0, 183.0, 185.0, 186.0, 186.0, 184.0], "unit": "score", "name": "audio-dft", "value": 185.2}, {"lowerIsBetter": true, "replicates": [74.0, 73.0, 74.0, 106.0, 56.0, 49.0, 64.0, 75.0, 150.0, 82.0], "unit": "score", "name": "audio-fft", "value": 80.3}, {"lowerIsBetter": true, "replicates": [89.0, 93.0, 127.0, 77.0, 80.0, 83.0, 118.0, 75.0, 78.0, 116.0], "unit": "score", "name": "audio-oscillator", "value": 93.6}, {"lowerIsBetter": true, "replicates": [89.0, 87.0, 90.0, 87.0, 89.0, 86.0, 90.0, 87.0, 93.0, 129.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 92.7}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 97.0, 95.0, 93.0, 94.0, 95.0, 93.0, 94.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 94.2}, {"lowerIsBetter": true, "replicates": [85.0, 85.0, 84.0, 84.0, 84.0, 85.0, 85.0, 85.0, 86.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 85.0}, {"lowerIsBetter": true, "replicates": [54.0, 58.0, 57.0, 56.0, 56.0, 56.0, 58.0, 56.0, 54.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 55.8}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 68.0, 70.0, 75.0, 71.0, 68.0, 66.0, 66.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.2}, {"lowerIsBetter": true, "replicates": [81.0, 82.0, 81.0, 82.0, 81.0, 80.0, 80.0, 81.0, 80.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.1}, {"lowerIsBetter": true, "replicates": [119.0, 119.0, 118.0, 118.0, 119.0, 127.0, 102.0, 129.0, 124.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.1}, {"lowerIsBetter": true, "replicates": [133.0, 117.0, 133.0, 135.0, 134.0, 123.0, 134.0, 136.0, 134.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.2}, {"lowerIsBetter": true, "replicates": [66.0, 65.0, 64.0, 66.0, 65.0, 66.0, 64.0, 65.0, 65.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.1}], "name": "kraken", "value": 1400.1}]} 11:35:23 INFO - Return code: 0 11:35:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:35:23 INFO - # TBPL SUCCESS # 11:35:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:35:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:35:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:35:23 INFO - 2016-05-23 11:35:23.510 screenresolution[1960:13317] starting screenresolution argv=screenresolution get 11:35:23 INFO - 2016-05-23 11:35:23.512 screenresolution[1960:13317] Display 0: 1600x1200x32@60 11:35:23 INFO - 2016-05-23 11:35:23.518 screenresolution[1961:13320] starting screenresolution argv=screenresolution list 11:35:23 INFO - Available Modes on Display 0 11:35:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:35:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:35:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:35:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:35:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:35:23 INFO - Intel Iris: 11:35:23 INFO - Chipset Model: Intel Iris 11:35:23 INFO - Type: GPU 11:35:23 INFO - Bus: Built-In 11:35:23 INFO - VRAM (Dynamic, Max): 1536 MB 11:35:23 INFO - Vendor: Intel (0x8086) 11:35:23 INFO - Device ID: 0x0a2e 11:35:23 INFO - Revision ID: 0x0009 11:35:23 INFO - Displays: 11:35:23 INFO - Display: 11:35:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:35:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:35:23 INFO - Display Serial Number: 1600x1200 60 11:35:23 INFO - Main Display: Yes 11:35:23 INFO - Mirror: Off 11:35:23 INFO - Online: Yes 11:35:23 INFO - Rotation: Supported 11:35:23 INFO - Return code: 0 11:35:23 INFO - Running post-action listener: _resource_record_post_action 11:35:23 INFO - Running post-run listener: _resource_record_post_run 11:35:24 INFO - Total resource usage - Wall time: 1192s; CPU: 52.0%; Read bytes: 1548288; Write bytes: 509488128; Read time: 20; Write time: 1978 11:35:24 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 202596864; Write bytes: 196259840; Read time: 19046; Write time: 363 11:35:24 INFO - run-tests - Wall time: 1170s; CPU: 52.0%; Read bytes: 1544192; Write bytes: 300532736; Read time: 20; Write time: 1525 11:35:24 INFO - Running post-run listener: _upload_blobber_files 11:35:24 INFO - Blob upload gear active. 11:35:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:35:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:35:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:35:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:35:25 INFO - (blobuploader) - INFO - Open directory for files ... 11:35:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:35:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:35:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:25 INFO - (blobuploader) - INFO - Done attempting. 11:35:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:35:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:35:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:27 INFO - (blobuploader) - INFO - Done attempting. 11:35:27 INFO - (blobuploader) - INFO - Iteration through files over. 11:35:27 INFO - Return code: 0 11:35:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:35:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:35:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f94ef1bb237985e3fef9b661104f424ea071d4c17c9086435cc212ec4b0bc0580d682c23c0edf6bd92f63b7070a07ae9f62feae15bf0d8805ddd4fd544f6ce46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b57b2987a844287fc11812828151ce76b2937ba047da14cff9b3bae9e6a487348ecc838fb5b68f17ea4e631d057411bd30ed649a08832a46cd3f9d9c0d43f3fd"} 11:35:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:35:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:35:27 INFO - Contents: 11:35:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f94ef1bb237985e3fef9b661104f424ea071d4c17c9086435cc212ec4b0bc0580d682c23c0edf6bd92f63b7070a07ae9f62feae15bf0d8805ddd4fd544f6ce46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b57b2987a844287fc11812828151ce76b2937ba047da14cff9b3bae9e6a487348ecc838fb5b68f17ea4e631d057411bd30ed649a08832a46cd3f9d9c0d43f3fd"} 11:35:27 INFO - Running post-run listener: copy_logs_to_upload_dir 11:35:27 INFO - Copying logs to upload dir... 11:35:27 INFO - mkdir: /builds/slave/test/build/upload/logs 11:35:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1425.488920 ========= master_lag: 1.54 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 47 secs) (at 2016-05-23 11:35:28.680036) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 11:35:28.685848) ========= 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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f94ef1bb237985e3fef9b661104f424ea071d4c17c9086435cc212ec4b0bc0580d682c23c0edf6bd92f63b7070a07ae9f62feae15bf0d8805ddd4fd544f6ce46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b57b2987a844287fc11812828151ce76b2937ba047da14cff9b3bae9e6a487348ecc838fb5b68f17ea4e631d057411bd30ed649a08832a46cd3f9d9c0d43f3fd"} build_url:https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010952 build_url: 'https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f94ef1bb237985e3fef9b661104f424ea071d4c17c9086435cc212ec4b0bc0580d682c23c0edf6bd92f63b7070a07ae9f62feae15bf0d8805ddd4fd544f6ce46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b57b2987a844287fc11812828151ce76b2937ba047da14cff9b3bae9e6a487348ecc838fb5b68f17ea4e631d057411bd30ed649a08832a46cd3f9d9c0d43f3fd"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 11:35:28.762735) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:35:28.763055) ========= 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.2GhN7OVkqJ/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.iMITHnIDVc/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.005417 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:35:28.811498) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 11:35:28.811813) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 11:35:28.812765) ========= ========= Total master_lag: 1.71 =========