builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0368 starttime: 1493425207.85 results: retry (5) buildid: 20170428170353 builduid: 53247c3959594f148c52ffa6948bba0f revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:07.847854) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:07.848359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:07.848636) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:07.874560) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:07.874953) ========= 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.NuOJQhTRdN/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.hy2koElZYC/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 --2017-04-28 17:20:07-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.11M=0.002s 2017-04-28 17:20:07 (7.11 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128111 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:08.031514) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:08.032370) ========= 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.NuOJQhTRdN/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.hy2koElZYC/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.023075 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:08.083954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:20:08.084750) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b3b32894d9326d147238d9f071e1814ab3fdc850 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b3b32894d9326d147238d9f071e1814ab3fdc850 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NuOJQhTRdN/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.hy2koElZYC/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 2017-04-28 17:20:08,132 truncating revision to first 12 chars 2017-04-28 17:20:08,132 Setting DEBUG logging. 2017-04-28 17:20:08,132 attempt 1/10 2017-04-28 17:20:08,133 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b3b32894d932?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-28 17:20:09,594 unpacking tar archive at: mozilla-central-b3b32894d932/testing/mozharness/ program finished with exit code 0 elapsedTime=1.743061 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:20:09.854063) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:09.854412) ========= script_repo_revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:09.854818) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:09.855085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:20:09.875135) ========= ========= Started '/tools/buildbot/bin/python -u ...' interrupted (results: 5, elapsed: 21 mins, 57 secs) (at 2017-04-28 17:20:09.875405) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NuOJQhTRdN/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.hy2koElZYC/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:20:10 INFO - MultiFileLogger online at 20170428 17:20:10 in /builds/slave/test 17:20:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:20:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:20:10 INFO - {'append_to_log': False, 17:20:10 INFO - 'base_work_dir': '/builds/slave/test', 17:20:10 INFO - 'blob_upload_branch': 'Firefox', 17:20:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:20:10 INFO - 'branch': 'Firefox', 17:20:10 INFO - 'buildbot_json_path': 'buildprops.json', 17:20:10 INFO - 'config_files': ('talos/mac_config.py',), 17:20:10 INFO - 'default_actions': ('clobber', 17:20:10 INFO - 'read-buildbot-config', 17:20:10 INFO - 'download-and-extract', 17:20:10 INFO - 'populate-webroot', 17:20:10 INFO - 'create-virtualenv', 17:20:10 INFO - 'install', 17:20:10 INFO - 'run-tests'), 17:20:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:20:10 INFO - 'download_minidump_stackwalk': True, 17:20:10 INFO - 'download_symbols': 'ondemand', 17:20:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:20:10 INFO - 'tooltool.py': '/tools/tooltool.py', 17:20:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:20:10 INFO - '/tools/misc-python/virtualenv.py')}, 17:20:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:20:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:20:10 INFO - 'gecko_profile': False, 17:20:10 INFO - 'gecko_profile_interval': 0, 17:20:10 INFO - 'installer_path': 'installer.exe', 17:20:10 INFO - 'log_level': 'info', 17:20:10 INFO - 'log_name': 'talos', 17:20:10 INFO - 'log_to_console': True, 17:20:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:20:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:20:10 INFO - 'opt_config_files': (), 17:20:10 INFO - 'pip_index': False, 17:20:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:20:10 INFO - 'cmd': ('bash', 17:20:10 INFO - '-c', 17:20:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:20:10 INFO - 'enabled': True, 17:20:10 INFO - 'halt_on_failure': False, 17:20:10 INFO - 'name': 'check_screen_resolution'},), 17:20:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:20:10 INFO - 'cmd': ('bash', 17:20:10 INFO - '-c', 17:20:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:20:10 INFO - 'enabled': True, 17:20:10 INFO - 'halt_on_failure': False, 17:20:10 INFO - 'name': 'check_screen_resolution'},), 17:20:10 INFO - 'run_cmd_checks_enabled': True, 17:20:10 INFO - 'suite': 'dromaeojs-e10s', 17:20:10 INFO - 'system_bits': '32', 17:20:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:20:10 INFO - 'title': 't-yosemite-r7-0368', 17:20:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:20:10 INFO - 'use_talos_json': True, 17:20:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:20:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:20:10 INFO - 'work_dir': 'build'} 17:20:10 INFO - [mozharness: 2017-04-29 00:20:10.037931Z] Running clobber step. 17:20:10 INFO - Running pre-action listener: _resource_record_pre_action 17:20:10 INFO - Running main action method: clobber 17:20:10 INFO - rmtree: /builds/slave/test/build 17:20:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:20:11 INFO - Running post-action listener: _resource_record_post_action 17:20:11 INFO - [mozharness: 2017-04-29 00:20:11.939217Z] Finished clobber step (success) 17:20:11 INFO - [mozharness: 2017-04-29 00:20:11.939338Z] Running read-buildbot-config step. 17:20:11 INFO - Running pre-action listener: _resource_record_pre_action 17:20:11 INFO - Running main action method: read_buildbot_config 17:20:11 INFO - Using buildbot properties: 17:20:11 INFO - { 17:20:11 INFO - "project": "", 17:20:11 INFO - "product": "firefox", 17:20:11 INFO - "script_repo_revision": "production", 17:20:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:20:11 INFO - "repository": "", 17:20:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:20:11 INFO - "buildid": "20170428170353", 17:20:11 INFO - "stage_platform": "macosx64", 17:20:11 INFO - "basedir": "/builds/slave/test", 17:20:11 INFO - "buildnumber": 146, 17:20:11 INFO - "platform": "yosemite_r7", 17:20:11 INFO - "mozci_request": { 17:20:11 INFO - "priority": 0, 17:20:11 INFO - "type": "trigger_all_talos_jobs", 17:20:11 INFO - "times": 6 17:20:11 INFO - }, 17:20:11 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 17:20:11 INFO - "slavebuilddir": "test", 17:20:11 INFO - "branch": "mozilla-central", 17:20:11 INFO - "revision": "b3b32894d9326d147238d9f071e1814ab3fdc850", 17:20:11 INFO - "slavename": "t-yosemite-r7-0368", 17:20:11 INFO - "builduid": "53247c3959594f148c52ffa6948bba0f", 17:20:11 INFO - "repo_path": "mozilla-central" 17:20:11 INFO - } 17:20:11 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg. 17:20:11 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json. 17:20:11 INFO - Running post-action listener: _resource_record_post_action 17:20:11 INFO - [mozharness: 2017-04-29 00:20:11.943043Z] Finished read-buildbot-config step (success) 17:20:11 INFO - [mozharness: 2017-04-29 00:20:11.943150Z] Running download-and-extract step. 17:20:11 INFO - Running pre-action listener: _resource_record_pre_action 17:20:11 INFO - Running main action method: download_and_extract 17:20:11 INFO - mkdir: /builds/slave/test/build/tests 17:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:11 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json 17:20:11 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:20:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:20:12 INFO - Downloaded 1325 bytes. 17:20:12 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:20:12 INFO - Using the following test package requirements: 17:20:12 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:12 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:20:12 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:20:12 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:12 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:20:12 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:12 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:20:12 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:12 INFO - u'jsshell-mac.zip'], 17:20:12 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:12 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:20:12 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:20:12 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:12 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:20:12 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:12 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:20:12 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:12 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:20:12 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:20:12 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:20:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:20:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip 17:20:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:20:12 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:20:13 INFO - Content-Length response header: 52698163 17:20:13 INFO - Bytes received: 52698163 17:20:29 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:20:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip 17:20:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:20:29 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:20:30 INFO - Content-Length response header: 52698163 17:20:30 INFO - Bytes received: 52698163 17:20:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip 17:20:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:20:46 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:20:46 INFO - Content-Length response header: 14408809 17:20:46 INFO - Bytes received: 14408809 17:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:46 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:20:46 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:20:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:20:48 INFO - Downloaded 71219323 bytes. 17:20:48 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:20:48 INFO - mkdir: /builds/slave/test/properties 17:20:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:20:48 INFO - Writing to file /builds/slave/test/properties/build_url 17:20:48 INFO - Contents: 17:20:48 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:20:48 INFO - Running post-action listener: _resource_record_post_action 17:20:48 INFO - Running post-action listener: set_extra_try_arguments 17:20:48 INFO - [mozharness: 2017-04-29 00:20:48.653402Z] Finished download-and-extract step (success) 17:20:48 INFO - [mozharness: 2017-04-29 00:20:48.653515Z] Running populate-webroot step. 17:20:48 INFO - Running pre-action listener: _resource_record_pre_action 17:20:48 INFO - Running main action method: populate_webroot 17:20:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:20:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:20:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:20:48 INFO - '--tppagecycles', 17:20:48 INFO - '7'], 17:20:48 INFO - 'tests': ['tsvgm']}}, 17:20:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:20:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:20:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:20:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:20:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:20:48 INFO - 'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:20:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:20:48 INFO - 'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['damp', 'tps']}, 17:20:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:20:48 INFO - 'tests': ['damp', 'tps']}, 17:20:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['dromaeo_dom']}, 17:20:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:20:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:20:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:20:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['a11yr', 17:20:48 INFO - 'ts_paint', 17:20:48 INFO - 'tpaint', 17:20:48 INFO - 'sessionrestore', 17:20:48 INFO - 'sessionrestore_no_auto_restore', 17:20:48 INFO - 'tabpaint']}, 17:20:48 INFO - 'other-e10s': {'tests': ['a11yr', 17:20:48 INFO - 'ts_paint', 17:20:48 INFO - 'tpaint', 17:20:48 INFO - 'sessionrestore', 17:20:48 INFO - 'sessionrestore_no_auto_restore', 17:20:48 INFO - 'tabpaint']}, 17:20:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:20:48 INFO - 'ts_paint', 17:20:48 INFO - 'tpaint', 17:20:48 INFO - 'sessionrestore', 17:20:48 INFO - 'sessionrestore_no_auto_restore', 17:20:48 INFO - 'tabpaint']}, 17:20:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:20:48 INFO - 'ts_paint', 17:20:48 INFO - 'tpaint', 17:20:48 INFO - 'sessionrestore', 17:20:48 INFO - 'sessionrestore_no_auto_restore', 17:20:48 INFO - 'tabpaint']}, 17:20:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['a11yr', 17:20:48 INFO - 'ts_paint', 17:20:48 INFO - 'tpaint', 17:20:48 INFO - 'sessionrestore', 17:20:48 INFO - 'sessionrestore_no_auto_restore', 17:20:48 INFO - 'tabpaint']}, 17:20:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['a11yr', 17:20:48 INFO - 'ts_paint', 17:20:48 INFO - 'tpaint', 17:20:48 INFO - 'sessionrestore', 17:20:48 INFO - 'sessionrestore_no_auto_restore', 17:20:48 INFO - 'tabpaint']}, 17:20:48 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:20:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:20:48 INFO - 'bloom_basic_ref']}, 17:20:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['tsvgx', 17:20:48 INFO - 'tsvgr_opacity', 17:20:48 INFO - 'tart', 17:20:48 INFO - 'tscrollx', 17:20:48 INFO - 'cart', 17:20:48 INFO - 'tsvg_static']}, 17:20:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:20:48 INFO - 'tsvgr_opacity', 17:20:48 INFO - 'tart', 17:20:48 INFO - 'tscrollx', 17:20:48 INFO - 'cart', 17:20:48 INFO - 'tsvg_static']}, 17:20:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:20:48 INFO - 'talos_options': ['--disable-e10s'], 17:20:48 INFO - 'tests': ['tp5o']}, 17:20:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:20:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:20:48 INFO - 'talos_options': ['--disable-e10s', 17:20:48 INFO - '--xperf_path', 17:20:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:48 INFO - 'tests': ['tp5n']}, 17:20:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:20:48 INFO - 'talos_options': ['--xperf_path', 17:20:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:48 INFO - 'tests': ['tp5n']}}, 17:20:48 INFO - 'talos.zip': {'path': '', 17:20:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:20:48 INFO - Running post-action listener: _resource_record_post_action 17:20:48 INFO - [mozharness: 2017-04-29 00:20:48.672430Z] Finished populate-webroot step (success) 17:20:48 INFO - [mozharness: 2017-04-29 00:20:48.672546Z] Running create-virtualenv step. 17:20:48 INFO - Running pre-action listener: _resource_record_pre_action 17:20:48 INFO - Running main action method: create_virtualenv 17:20:48 INFO - Creating virtualenv /builds/slave/test/build/venv 17:20:48 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 17:20:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:20:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:20:48 INFO - Using real prefix '/tools/python27' 17:20:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:20:49 INFO - Installing distribute.............................................................................................................................................................................................done. 17:20:52 INFO - Installing pip.................done. 17:20:52 INFO - Return code: 0 17:20:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:20:52 INFO - Reading from file tmpfile_stdout 17:20:52 INFO - Output received: 17:20:52 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:20:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:20: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:20:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:20:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:20:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:52 INFO - 'HOME': '/Users/cltbld', 17:20:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:52 INFO - 'LOGNAME': 'cltbld', 17:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:52 INFO - 'MOZ_NO_REMOTE': '1', 17:20:52 INFO - 'NO_EM_RESTART': '1', 17:20:52 INFO - 'PAGER': '/bin/cat', 17:20:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:52 INFO - 'PWD': '/builds/slave/test', 17:20:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:52 INFO - 'SHELL': '/bin/bash', 17:20:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:20:52 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:20:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:52 INFO - 'USER': 'cltbld', 17:20:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:52 INFO - 'XPC_FLAGS': '0x0', 17:20:52 INFO - 'XPC_SERVICE_NAME': '0', 17:20:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:52 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 17:20:52 INFO - Cleaning up... 17:20:52 INFO - Return code: 0 17:20:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:20: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:20:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:20:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:20:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:52 INFO - 'HOME': '/Users/cltbld', 17:20:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:52 INFO - 'LOGNAME': 'cltbld', 17:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:52 INFO - 'MOZ_NO_REMOTE': '1', 17:20:52 INFO - 'NO_EM_RESTART': '1', 17:20:52 INFO - 'PAGER': '/bin/cat', 17:20:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:52 INFO - 'PWD': '/builds/slave/test', 17:20:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:52 INFO - 'SHELL': '/bin/bash', 17:20:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:20:52 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:20:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:52 INFO - 'USER': 'cltbld', 17:20:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:52 INFO - 'XPC_FLAGS': '0x0', 17:20:52 INFO - 'XPC_SERVICE_NAME': '0', 17:20:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:53 INFO - Downloading/unpacking psutil>=3.1.1 17:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:20:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:56 INFO - Installing collected packages: psutil 17:20:56 INFO - Running setup.py install for psutil 17:20:56 INFO - building 'psutil._psutil_osx' extension 17:20:56 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 17:20:57 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 17:20:57 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 17:20:57 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 17:20:57 INFO - building 'psutil._psutil_posix' extension 17:20:57 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 17:20:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:20:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:20:57 INFO - ^ 17:20:57 INFO - 1 warning generated. 17:20:57 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 17:20:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:57 INFO - Successfully installed psutil 17:20:57 INFO - Cleaning up... 17:20:57 INFO - Return code: 0 17:20:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:20:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:57 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:20:57 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.3'] in /builds/slave/test/build 17:20:57 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.3 17:20:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:20:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:57 INFO - 'HOME': '/Users/cltbld', 17:20:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:57 INFO - 'LOGNAME': 'cltbld', 17:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:57 INFO - 'MOZ_NO_REMOTE': '1', 17:20:57 INFO - 'NO_EM_RESTART': '1', 17:20:57 INFO - 'PAGER': '/bin/cat', 17:20:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:57 INFO - 'PWD': '/builds/slave/test', 17:20:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:57 INFO - 'SHELL': '/bin/bash', 17:20:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:20:57 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:20:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:57 INFO - 'USER': 'cltbld', 17:20:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:57 INFO - 'XPC_FLAGS': '0x0', 17:20:57 INFO - 'XPC_SERVICE_NAME': '0', 17:20:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:21:00 INFO - Installing collected packages: mozsystemmonitor 17:21:00 INFO - Running setup.py install for mozsystemmonitor 17:21:00 INFO - Successfully installed mozsystemmonitor 17:21:00 INFO - Cleaning up... 17:21:00 INFO - Return code: 0 17:21:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:21:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:21:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:00 INFO - 'HOME': '/Users/cltbld', 17:21:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:00 INFO - 'LOGNAME': 'cltbld', 17:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:00 INFO - 'MOZ_NO_REMOTE': '1', 17:21:00 INFO - 'NO_EM_RESTART': '1', 17:21:00 INFO - 'PAGER': '/bin/cat', 17:21:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:00 INFO - 'PWD': '/builds/slave/test', 17:21:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:00 INFO - 'SHELL': '/bin/bash', 17:21:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:21:00 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:21:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:00 INFO - 'USER': 'cltbld', 17:21:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:00 INFO - 'XPC_FLAGS': '0x0', 17:21:00 INFO - 'XPC_SERVICE_NAME': '0', 17:21:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:01 INFO - Downloading/unpacking jsonschema==2.5.1 17:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:21:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:21:03 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 17:21:03 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 17:21:03 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 17:21:03 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 17:21:03 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 17:21:03 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 17:21:04 INFO - Downloading functools32-3.2.3-2.tar.gz 17:21:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:21:04 INFO - warning: no files found matching '*.txt' 17:21:04 INFO - no previously-included directories found matching 'build' 17:21:04 INFO - no previously-included directories found matching 'dist' 17:21:04 INFO - no previously-included directories found matching '.git*' 17:21:04 INFO - Installing collected packages: jsonschema, functools32 17:21:04 INFO - Running setup.py install for functools32 17:21:04 INFO - warning: no files found matching '*.txt' 17:21:04 INFO - no previously-included directories found matching 'build' 17:21:04 INFO - no previously-included directories found matching 'dist' 17:21:04 INFO - no previously-included directories found matching '.git*' 17:21:04 INFO - Successfully installed jsonschema functools32 17:21:04 INFO - Cleaning up... 17:21:04 INFO - Return code: 0 17:21:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:05 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:21:05 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:21:05 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 functools32==3.2.3-2 17:21:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:21:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:05 INFO - 'HOME': '/Users/cltbld', 17:21:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:05 INFO - 'LOGNAME': 'cltbld', 17:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:05 INFO - 'MOZ_NO_REMOTE': '1', 17:21:05 INFO - 'NO_EM_RESTART': '1', 17:21:05 INFO - 'PAGER': '/bin/cat', 17:21:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:05 INFO - 'PWD': '/builds/slave/test', 17:21:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:05 INFO - 'SHELL': '/bin/bash', 17:21:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:21:05 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:21:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:05 INFO - 'USER': 'cltbld', 17:21:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:05 INFO - 'XPC_FLAGS': '0x0', 17:21:05 INFO - 'XPC_SERVICE_NAME': '0', 17:21:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:21:05 INFO - Cleaning up... 17:21:05 INFO - Return code: 0 17:21:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:06 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 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:21:06 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 17:21:06 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 17:21:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:21:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:06 INFO - 'HOME': '/Users/cltbld', 17:21:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:06 INFO - 'LOGNAME': 'cltbld', 17:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:06 INFO - 'MOZ_NO_REMOTE': '1', 17:21:06 INFO - 'NO_EM_RESTART': '1', 17:21:06 INFO - 'PAGER': '/bin/cat', 17:21:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:06 INFO - 'PWD': '/builds/slave/test', 17:21:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:06 INFO - 'SHELL': '/bin/bash', 17:21:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:21:06 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:21:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:06 INFO - 'USER': 'cltbld', 17:21:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:06 INFO - 'XPC_FLAGS': '0x0', 17:21:06 INFO - 'XPC_SERVICE_NAME': '0', 17:21:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:06 INFO - Downloading/unpacking blobuploader==1.2.4 17:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:09 INFO - Downloading blobuploader-1.2.4.tar.gz 17:21:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:21:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:21:09 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 17:21:09 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 17:21:09 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 17:21:09 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 17:21:09 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 17:21:09 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 17:21:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:21:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21:10 INFO - Downloading docopt-0.6.1.tar.gz 17:21:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:21:10 INFO - Installing collected packages: blobuploader, requests, docopt 17:21:10 INFO - Running setup.py install for blobuploader 17:21:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:21:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:21:11 INFO - Running setup.py install for requests 17:21:11 INFO - Running setup.py install for docopt 17:21:11 INFO - Successfully installed blobuploader requests docopt 17:21:11 INFO - Cleaning up... 17:21:11 INFO - Return code: 0 17:21:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:11 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 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:21:11 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 17:21:11 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 17:21:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:21:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:11 INFO - 'HOME': '/Users/cltbld', 17:21:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:11 INFO - 'LOGNAME': 'cltbld', 17:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:11 INFO - 'MOZ_NO_REMOTE': '1', 17:21:11 INFO - 'NO_EM_RESTART': '1', 17:21:11 INFO - 'PAGER': '/bin/cat', 17:21:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:11 INFO - 'PWD': '/builds/slave/test', 17:21:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:11 INFO - 'SHELL': '/bin/bash', 17:21:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:21:11 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:21:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:11 INFO - 'USER': 'cltbld', 17:21:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:11 INFO - 'XPC_FLAGS': '0x0', 17:21:11 INFO - 'XPC_SERVICE_NAME': '0', 17:21:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-I8lh2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-ZqAwjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-0zqqpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-K4Tewo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-S7VuFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Hr1ihs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-m4fVST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-c1wOVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-JQrnQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:12 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-pzPUub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:13 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-G4WtiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:13 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-9CQdEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:13 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-F5EEsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:13 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2hCc26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:13 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-u8wC7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:13 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-IcbVZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:13 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-sbBAQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:21:13 INFO - Running setup.py install for manifestparser 17:21:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:21:13 INFO - Running setup.py install for mozcrash 17:21:14 INFO - Running setup.py install for mozdebug 17:21:14 INFO - Running setup.py install for mozdevice 17:21:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:21:14 INFO - Running setup.py install for mozfile 17:21:14 INFO - Running setup.py install for mozhttpd 17:21:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:21:14 INFO - Running setup.py install for mozinfo 17:21:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:21:14 INFO - Running setup.py install for mozInstall 17:21:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:21:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:21:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:21:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:21:14 INFO - Running setup.py install for mozleak 17:21:15 INFO - Running setup.py install for mozlog 17:21:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:21:15 INFO - Running setup.py install for moznetwork 17:21:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:21:15 INFO - Running setup.py install for mozprocess 17:21:15 INFO - Running setup.py install for mozprofile 17:21:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:21:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:21:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:21:15 INFO - Running setup.py install for mozrunner 17:21:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:21:15 INFO - Running setup.py install for mozscreenshot 17:21:16 INFO - Running setup.py install for moztest 17:21:16 INFO - Running setup.py install for mozversion 17:21:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:21:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:21:16 INFO - Cleaning up... 17:21:16 INFO - Return code: 0 17:21:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:16 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 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:21:16 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 17:21:16 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 17:21:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:21:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:16 INFO - 'HOME': '/Users/cltbld', 17:21:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:16 INFO - 'LOGNAME': 'cltbld', 17:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:16 INFO - 'MOZ_NO_REMOTE': '1', 17:21:16 INFO - 'NO_EM_RESTART': '1', 17:21:16 INFO - 'PAGER': '/bin/cat', 17:21:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:16 INFO - 'PWD': '/builds/slave/test', 17:21:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:16 INFO - 'SHELL': '/bin/bash', 17:21:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:21:16 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:21:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:16 INFO - 'USER': 'cltbld', 17:21:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:16 INFO - 'XPC_FLAGS': '0x0', 17:21:16 INFO - 'XPC_SERVICE_NAME': '0', 17:21:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:16 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2AMixG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:16 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)) 17:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:16 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-YbHjbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 17:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:16 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-xerhwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:16 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)) 17:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:16 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-ikfv9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-1juQjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21: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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-7w5g2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21: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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Qcjg0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:17 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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-HzLQrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:17 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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-jT20SU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:17 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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-lY8A7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-KpUcCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:17 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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Nko3ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-5Qwy3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:17 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)) 17:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-hAC0hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 17:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:18 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-i_YjCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21: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)) 17:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:18 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-7NtHS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 17:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:18 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-YzOWeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:18 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)) 17:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:21:18 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)) 17:21:18 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:18 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:21 INFO - Downloading blessings-1.6.tar.gz 17:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:21:21 INFO - Installing collected packages: blessings 17:21:21 INFO - Running setup.py install for blessings 17:21:21 INFO - Successfully installed blessings 17:21:21 INFO - Cleaning up... 17:21:21 INFO - Return code: 0 17:21:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:21:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:21 INFO - Reading from file tmpfile_stdout 17:21:21 INFO - Current package versions: 17:21:21 INFO - blessings == 1.6 17:21:21 INFO - blobuploader == 1.2.4 17:21:21 INFO - docopt == 0.6.1 17:21:21 INFO - functools32 == 3.2.3-2 17:21:21 INFO - jsonschema == 2.5.1 17:21:21 INFO - manifestparser == 1.1 17:21:21 INFO - mozInstall == 1.12 17:21:21 INFO - mozcrash == 1.0 17:21:21 INFO - mozdebug == 0.1 17:21:21 INFO - mozdevice == 0.50 17:21:21 INFO - mozfile == 1.2 17:21:21 INFO - mozhttpd == 0.7 17:21:21 INFO - mozinfo == 0.9 17:21:21 INFO - mozleak == 0.1 17:21:21 INFO - mozlog == 3.4 17:21:21 INFO - moznetwork == 0.27 17:21:21 INFO - mozprocess == 0.25 17:21:21 INFO - mozprofile == 0.28 17:21:21 INFO - mozrunner == 6.13 17:21:21 INFO - mozscreenshot == 0.1 17:21:21 INFO - mozsystemmonitor == 0.3 17:21:21 INFO - moztest == 0.8 17:21:21 INFO - mozversion == 1.4 17:21:21 INFO - psutil == 3.1.1 17:21:21 INFO - requests == 1.2.3 17:21:21 INFO - wsgiref == 0.1.2 17:21:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:21 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 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:21:21 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 17:21:21 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 17:21:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:21:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:21 INFO - 'HOME': '/Users/cltbld', 17:21:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:21 INFO - 'LOGNAME': 'cltbld', 17:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:21 INFO - 'MOZ_NO_REMOTE': '1', 17:21:21 INFO - 'NO_EM_RESTART': '1', 17:21:21 INFO - 'PAGER': '/bin/cat', 17:21:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:21 INFO - 'PWD': '/builds/slave/test', 17:21:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:21 INFO - 'SHELL': '/bin/bash', 17:21:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:21:21 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:21:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:21 INFO - 'USER': 'cltbld', 17:21:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:21 INFO - 'XPC_FLAGS': '0x0', 17:21:21 INFO - 'XPC_SERVICE_NAME': '0', 17:21:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:21 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)) 17:21:21 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)) 17:21:21 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)) 17:21:21 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)) 17:21:21 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)) 17:21:21 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)) 17:21:21 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)) 17:21:21 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)) 17:21:21 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)) 17:21:21 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)) 17:21:21 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)) 17:21:21 INFO - Cleaning up... 17:21:21 INFO - Return code: 0 17:21:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d77b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d7a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82fa5797a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, '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.NuOJQhTRdN/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.hy2koElZYC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 17:21:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:21:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:21:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:21 INFO - 'HOME': '/Users/cltbld', 17:21:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:21 INFO - 'LOGNAME': 'cltbld', 17:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:22 INFO - 'MOZ_NO_REMOTE': '1', 17:21:22 INFO - 'NO_EM_RESTART': '1', 17:21:22 INFO - 'PAGER': '/bin/cat', 17:21:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:22 INFO - 'PWD': '/builds/slave/test', 17:21:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:22 INFO - 'SHELL': '/bin/bash', 17:21:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:21:22 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:21:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:22 INFO - 'USER': 'cltbld', 17:21:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:22 INFO - 'XPC_FLAGS': '0x0', 17:21:22 INFO - 'XPC_SERVICE_NAME': '0', 17:21:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:21:22 INFO - Cleaning up... 17:21:22 INFO - Return code: 0 17:21:22 INFO - Running post-action listener: _resource_record_post_action 17:21:22 INFO - Running post-action listener: _start_resource_monitoring 17:21:22 INFO - Starting resource monitoring. 17:21:22 INFO - [mozharness: 2017-04-29 00:21:22.283405Z] Finished create-virtualenv step (success) 17:21:22 INFO - [mozharness: 2017-04-29 00:21:22.284444Z] Running install step. 17:21:22 INFO - Running pre-action listener: _resource_record_pre_action 17:21:22 INFO - Running main action method: install 17:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:22 INFO - Reading from file tmpfile_stdout 17:21:22 INFO - Detecting whether we're running mozinstall >=1.0... 17:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:21:22 INFO - Reading from file tmpfile_stdout 17:21:22 INFO - Output received: 17:21:22 INFO - Usage: mozinstall [options] installer 17:21:22 INFO - Options: 17:21:22 INFO - -h, --help show this help message and exit 17:21:22 INFO - -d DEST, --destination=DEST 17:21:22 INFO - Directory to install application into. [default: 17:21:22 INFO - "/builds/slave/test"] 17:21:22 INFO - --app=APP Application being installed. [default: firefox] 17:21:22 INFO - mkdir: /builds/slave/test/build/application 17:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:21:42 INFO - Reading from file tmpfile_stdout 17:21:42 INFO - Output received: 17:21:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:21:42 INFO - Running post-action listener: _resource_record_post_action 17:21:42 INFO - [mozharness: 2017-04-29 00:21:42.465903Z] Finished install step (success) 17:21:42 INFO - [mozharness: 2017-04-29 00:21:42.466089Z] Running run-tests step. 17:21:42 INFO - Running pre-action listener: _resource_record_pre_action 17:21:42 INFO - Running main action method: run_tests 17:21:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:21:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:21:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:21:42 INFO - 2017-04-28 17:21:42.480 screenresolution[2465:36748] starting screenresolution argv=screenresolution get 17:21:42 INFO - 2017-04-28 17:21:42.483 screenresolution[2465:36748] Display 0: 1600x1200x32@60 17:21:42 INFO - 2017-04-28 17:21:42.489 screenresolution[2466:36751] starting screenresolution argv=screenresolution list 17:21:42 INFO - Available Modes on Display 0 17:21:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:21:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:21:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:21:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:21:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:21:42 INFO - Intel Iris: 17:21:42 INFO - Chipset Model: Intel Iris 17:21:42 INFO - Type: GPU 17:21:42 INFO - Bus: Built-In 17:21:42 INFO - VRAM (Dynamic, Max): 1536 MB 17:21:42 INFO - Vendor: Intel (0x8086) 17:21:42 INFO - Device ID: 0x0a2e 17:21:42 INFO - Revision ID: 0x0009 17:21:42 INFO - Displays: 17:21:42 INFO - Display: 17:21:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:21:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:21:42 INFO - Display Serial Number: 1600x1200 60 17:21:42 INFO - Main Display: Yes 17:21:42 INFO - Mirror: Off 17:21:42 INFO - Online: Yes 17:21:42 INFO - Rotation: Supported 17:21:42 INFO - Return code: 0 17:21:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:21:42 INFO - Python 2.7.3 17:21:42 INFO - Return code: 0 17:21:42 INFO - grabbing minidump binary from tooltool 17:21:42 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:42 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b55d30>, '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 0x100a7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b4abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:21:42 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 17:21:42 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 17:21:42 INFO - Calling ['/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'] with output_timeout 600 17:21:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:21:42 INFO - Return code: 0 17:21:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:21:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:21:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:21:42 INFO - ENV: RUST_BACKTRACE is now 1 17:21:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:21:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:21:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0368 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NuOJQhTRdN/Render', 17:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:42 INFO - 'HOME': '/Users/cltbld', 17:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:42 INFO - 'LOGNAME': 'cltbld', 17:21:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:21:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:42 INFO - 'MOZ_NO_REMOTE': '1', 17:21:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:21:42 INFO - 'NO_EM_RESTART': '1', 17:21:42 INFO - 'PAGER': '/bin/cat', 17:21:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:42 INFO - 'PWD': '/builds/slave/test', 17:21:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:42 INFO - 'RUST_BACKTRACE': '1', 17:21:42 INFO - 'SHELL': '/bin/bash', 17:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hy2koElZYC/Listeners', 17:21:42 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 17:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:42 INFO - 'USER': 'cltbld', 17:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:42 INFO - 'XPC_FLAGS': '0x0', 17:21:42 INFO - 'XPC_SERVICE_NAME': '0', 17:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:21:43 INFO - mozversion application_buildid: 20170428075320 17:21:43 INFO - mozversion application_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:21:43 INFO - mozversion application_display_name: Nightly 17:21:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:21:43 INFO - mozversion application_name: Firefox 17:21:43 INFO - mozversion application_remotingname: firefox 17:21:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:21:43 INFO - mozversion application_vendor: Mozilla 17:21:43 INFO - mozversion application_version: 55.0a1 17:21:43 INFO - mozversion platform_buildid: 20170428075320 17:21:43 INFO - mozversion platform_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:21:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:21:43 INFO - mozversion platform_version: 55.0a1 17:21:43 INFO - using testdate: 1493425303 17:21:43 INFO - actual date: 1493425303 17:21:43 INFO - starting webserver on 'localhost:60437' 17:21:43 INFO - SUITE-START | Running 2 tests 17:21:43 INFO - TEST-START | dromaeo_css 17:21:43 INFO - operating with platform_type : mac_ 17:21:43 INFO - Initialising browser for dromaeo_css test... 17:21:43 INFO - TEST-INFO | started process 2476 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmplupsVn/profile http://localhost:60437/getInfo.html) 17:21:45 INFO - PID 2476 | 2017-04-28 17:21:45.295 plugin-container[2478:36923] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 17:21:45 INFO - PID 2476 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:45 INFO - PID 2476 | 2017-04-28 17:21:45.297 plugin-container[2478:36923] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 17:21:45 INFO - PID 2476 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:45 INFO - PID 2476 | 2017-04-28 17:21:45.297 plugin-container[2478:36923] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:21:45 INFO - PID 2476 | __metrics Screen width/height:1600/1200 17:21:45 INFO - PID 2476 | colorDepth:24 17:21:45 INFO - PID 2476 | Browser inner width/height: 1024/689 17:21:45 INFO - PID 2476 | __metrics 17:21:45 ERROR - PID 2476 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:21:46 INFO - TEST-INFO | 2476: exit 0 17:21:46 INFO - Browser initialized. 17:21:46 INFO - Running cycle 1/1 for dromaeo_css test... 17:21:46 INFO - TEST-INFO | started process 2479 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmplupsVn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:48 INFO - PID 2479 | 2017-04-28 17:21:48.516 plugin-container[2480:37056] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:21:48 INFO - PID 2479 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:48 INFO - PID 2479 | 2017-04-28 17:21:48.516 plugin-container[2480:37056] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 17:21:48 INFO - PID 2479 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:48 INFO - PID 2479 | 2017-04-28 17:21:48.516 plugin-container[2480:37056] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:21:58 INFO - PID 2479 | RSS: Main: 251932672 17:21:58 INFO - PID 2479 | 17:25:02 INFO - PID 2479 | Cycle 1(1): loaded http://localhost:60437/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60437/tests/dromaeo/cssquery-ext.html) 17:25:04 INFO - PID 2479 | RSS: Main: 254238720 17:25:04 INFO - PID 2479 | 17:26:47 INFO - PID 2479 | ************************* 17:26:47 INFO - PID 2479 | A coding exception was thrown and uncaught in a Task. 17:26:47 INFO - PID 2479 | 17:26:47 ERROR - PID 2479 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:26:47 INFO - PID 2479 | Full stack: 17:26:47 INFO - PID 2479 | ************************* 17:26:47 INFO - PID 2479 | ************************* 17:26:47 INFO - PID 2479 | A coding exception was thrown and uncaught in a Task. 17:26:47 INFO - PID 2479 | 17:26:47 ERROR - PID 2479 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:26:47 INFO - PID 2479 | Full stack: 17:26:47 INFO - PID 2479 | ************************* 17:26:47 INFO - PID 2479 | ************************* 17:26:47 INFO - PID 2479 | A coding exception was thrown and uncaught in a Task. 17:26:47 INFO - PID 2479 | 17:26:47 ERROR - PID 2479 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:26:47 INFO - PID 2479 | Full stack: 17:26:47 INFO - PID 2479 | ************************* 17:26:47 INFO - PID 2479 | ************************* 17:26:47 INFO - PID 2479 | A coding exception was thrown and uncaught in a Task. 17:26:47 INFO - PID 2479 | 17:26:47 ERROR - PID 2479 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:26:47 INFO - PID 2479 | Full stack: 17:26:47 INFO - PID 2479 | ************************* 17:28:06 INFO - PID 2479 | Cycle 1(1): loaded http://localhost:60437/tests/dromaeo/cssquery-ext.html (next: http://localhost:60437/tests/dromaeo/cssquery-jquery.html) 17:28:06 INFO - PID 2479 | RSS: Main: 257904640 17:28:06 INFO - PID 2479 | 17:31:08 INFO - PID 2479 | Cycle 1(1): loaded http://localhost:60437/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60437/tests/dromaeo/cssquery-mootools.html) 17:31:09 INFO - PID 2479 | RSS: Main: 273555456 17:31:09 INFO - PID 2479 | 17:34:10 INFO - PID 2479 | Cycle 1(1): loaded http://localhost:60437/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60437/tests/dromaeo/cssquery-prototype.html) 17:34:11 INFO - PID 2479 | RSS: Main: 266354688 17:34:11 INFO - PID 2479 | 17:37:14 INFO - PID 2479 | Cycle 1(1): loaded http://localhost:60437/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60437/tests/dromaeo/cssquery-yui.html) 17:37:14 INFO - PID 2479 | RSS: Main: 275791872 17:37:14 INFO - PID 2479 | 17:40:16 INFO - PID 2479 | Cycle 1(1): loaded http://localhost:60437/tests/dromaeo/cssquery-yui.html (next: http://localhost:60437/tests/dromaeo/cssquery-dojo.html) 17:40:16 INFO - PID 2479 | RSS: Main: 270417920 17:40:16 INFO - PID 2479 | 17:40:16 INFO - PID 2479 | __start_tp_report 17:40:16 INFO - PID 2479 | _x_x_mozilla_page_load 17:40:16 INFO - PID 2479 | _x_x_mozilla_page_load_details 17:40:16 INFO - PID 2479 | |i|pagename|runs| 17:40:16 INFO - PID 2479 | |0;dojo.html;3580.051286409102;3586.1393265616252;3603.7657552259116;3625.3655610268197;3632.219072899326;6104.1759362486055;6152.5693201475915;6217.160683307752;6243.469305109065;6243.812685619428;6167.074938759189;6169.845753856156;6204.224471941007;6207.844803879905;6211.627302361861;4022.537408198051;4291.892702682427;5402.216678581603;5434.0218760623075;5475.425080366564;4935.53112454318;5474.72626368682;5497.505224529791;5549.334079910416;5621.297337832771;4825.131476334259;5315.734213289359;5460.563154947595;5465.480779325958;5567.805126820542;4983.6760610560195;5402.432744561804;5455.645383050089;5458.808941687022;5553.6390134641115;5366.06093933564;5382.30030096085;5409.594280428983;5417.4311697272005;5446.645968012067;5951.49412299955;6215.067739839015;6218.22272215973;6218.4714299284615;6229.4704950079295;6462.967685161544;6524.282328943812;6613.437857782094;6673.966630166867;6675.299093595151;5543.307086614173;6791.660416979181;6802.14973128354;6816.727330906808;6820.215675197316;6259.467945224706;6291.96854015727;6329.683515824237;6340.4293613575;6409.070684750685;5685.886282274331;6232.439080482778;6941.68762405693;6950.478714096463;6953.200381956039;6372.1716176897635;6433.099366088752;6492.188476440445;6512.869742605216;6514.837129071811;6734.326567343226;6736.494762892705;6737.461003119739;6763.2898545652;6764.763233286812;6588;6602.10871532337;6644.136262285969;6655.833604159935;6661.833454163685;6448.355164483608;6529.804105876831;6612.239592446834;6663.966680166568;6686.030525573765;5351.892962140813;5355.812546560852;5357.5446087082255;5367.75845086972;5374.328208973879;4718.103560323528;4743.976280118646;4744.786484608201;4752;4788.736619485892;6070.848228794227;6270.561060725797;6288.52836037299;6290.779822706183;6362.491000720024;5522.337319521683;6732.764353247613;6744.696488658022;6838.247792742794;6846.554973926679;6386.840328991812;6390.808275751735;6396.84007899797;6405.231372235362;6432.163818703632;5650.406707295675;5788.102844059177;5804.709764511842;5901.5868889177345;5914.733836977346;5841.4450627190345;6660.601273732376;6914.4468442525485;6991.615461149686;6999.895001574879;6542.83642908912;6775.966120169368;6804.693788779616;6807.0810440589885;6831.453483721191;6786.76246331376;6827.556208846409;6940.861182776417;6974.860502790018;6987.196472405739;5333.759980800795;6595.769148079794;6601.867962640817;6631.734730610721;6633.204015517976;6612.305707900794;6658.83352916181;6683.498737594797;6729.495287853529;6758.86482270342;5774.624649397776;6398.656282180794;6598.109255250672;6598.241202261609;6612.239592446737;6446.9355306448215;6540.8364790880605;6588.044733513518;6590.308017658077;6598.736050557924;4629.537046295343;6231.688415579112;6318.715657795318;6332.683365831782;6364.395382438661;6544.6727663617585;6549.574277671936;6565.835854103686;6588.868222635616;6589.077529145828;5493.642913210629;6312.905306420315;6426.293107758236;6427.582207156707;6445.742170313135 17:40:16 INFO - PID 2479 | |1;ext.html;769.2576663519702;793.9960300198499;830.4850992384722;1185.342135115011;1243.316176103143;37488.250234995285;38378.616213837886;38403.23193536128;38543;38792.61207387925;38678.80660596693;38701;38913;39091;39092;7886.171951945049;8044.557549334784;8358.205970432798;8592.355573331994;8614.353923455767;8480.78798030048;8579.699710510133;8581.871271930926;8593;8602.741917742447;8506.191911768372;8549.40154189207;8591.82816343676;8595.183457571551;8619.439736417144;8476.830463390728;8549.572521373937;8584.74245772628;8589.184027517376;8592.828143437127;8422.873656895152;8432.494050356998;8440.915590844135;8484.19400156984;8499.61751721174;37244.81377593095;37293.44059839105;37531.87404377873;37577.81211093954;37604.62395376066;3623;3635.8727444539577;3656.122530592665;3673.7612055216327;3682.705383569308;30861.074167775;30886.691133088832;31051.22371940697;31063.534046989087;31147;3150.7479401647815;3201.0396880935627;3218.91952701182;3230.7576931730214;3232.5797646305828;302021;304094;306070.40894386603;311303.4434827834;312657;48501.75749121232;48596;48735.75632121817;48790;48872.5112748875;49422.50577494251;50003;50102.74948625234;50633;50855.74572127116;14377.712445751236;14505.85494145045;14506;14530.418783248551;14536.27318634064;11719.121065919919;11726;11743;11743.354115523729;11759.823602645967;13391.92864570832;13535.796963045563;13541.796873046913;13563.59309220725;13661.521846735317;5957.463828255478;6020.277566692025;6021.789237376758;6038.879222415527;6088.939110608837;896.0233345653139;926.9629214831466;957.2533423929347;978.3493976505545;1010.6109147978082;24298.149564765503;24618.753812462004;24650;24684.25947221586;24690;1114.142110574869;1120.803859324615;1142.6914733022038;1185.3895243949373;1187.6674531131298;10828.620998265023;11785.587504437475;12635.74728505443;12651.367431628565;12659.746805063847;331945;334131;334557;334563;334590;52979.08615026133;53557.73221133869;53607.46392536103;53632.92734145373;53732;1349.8852597529124;1498.6178524476406;1526.1682383101236;1552.74379727346;1590.4115477273483;18608.534786630444;18609.255629775198;22991;24143.396415089763;27273.863630682514;46399.304010440545;46503.83740406517;46610.300845486665;46684.53315466802;46737;254133;255528.72235638704;257493;258936;259587;33630.49554256737;33979.98060058202;34179.82910085434;36007.09982250465;36237.731679391094;40997.1800563993;44405.777971109936;44472.33291500695;44472.555274446844;44558.66324010285;28662.42675146527;29494.852525737235;29507.557386639648;30333;30666.693333066385;29482;29822;29974.85012574923;30473.847630761706;30500;35040.29919401649;35180.472292916136;35459.29081418409;36397.818010909774;36398.63601363952;28808.711912880604;29653;29793.851030744707;30589.847050764605;30911.536326954665;22677.773222267566;23776.88111559431;23788.52422951566;24369.63445548283;24403.511929761662 17:40:16 INFO - PID 2479 | |2;jquery.html;413.7669744162398;436.8296364417878;438.71019203541584;456.56398139776513;456.81727309076365;19392.806071939278;20874.478138046557;21547.353579392642;21665.67501487479;21711;18243.635127297446;21993;22505.437364065867;22665.886670566622;23711.051557937662;5762.654240745569;6739.191297044349;6957.5129740918155;6973.58158510491;6973.860522789567;6962.547434416747;7137.10786151731;7157.749478768244;7175.390091842173;7196.172440169369;6787.287334829846;6821.590704557718;6828.351306625863;7113.181984071846;7186.389156921668;6692.297308782605;6814.591124532544;6815.454763618924;6884.724611015554;6885.449164066863;6953.548019378724;7024.192217894905;7028.789136325919;7036.226015138382;7038.15542134942;23397.76602233973;28675.426491470054;29844.70155298441;29969.250768730737;30349.393012139633;2100.8214301784365;2146.731658542682;2149.9355019349287;2151.4406254373976;2156.1052163352347;18263.63472730551;19304.034798260032;19396.22415103394;19574;19579.80420195794;2284.4288927768057;2320.3271051395077;2348.178137651825;2358.646203069536;2380.1669415704537;169594;171603.99999999875;171808;171875.28124718842;173818;24559.38601534976;25441;25865.74134258671;25903.74096259051;26198;42060;42307.7884610575;42342.57657423448;42614.57385426106;42615.57384426178;8850.468971861708;8964.910350896407;9147.862782058275;9270.490123043162;9339.252859771203;6576.04647326135;8518.446300990416;8665.783355416164;8700;8818.11818818113;8030.437869349206;8168.836623267502;8196.672133114727;8224.712135075244;8228.876566851503;2294.529621427603;2310.382732087512;2315.8494697844585;2319.362175401778;2464.112919349033;947.2800671489717;965.5234323959023;971.931964762474;985.9704208873745;1005.4168582222294;21065.368038958855;21452.89273553622;21547.78452215458;21642.35072947814;21785.673214901788;900.7658008917729;901.315000599536;1007.7883644434751;1047.460557812727;1051.6844946516014;8910.019897811235;9780.853287200698;9971.900280997243;11103.278286911325;11144.052755515871;487277;493409;496361;500921;502376.9762302403;111523;123071;129541;139571;140233.2988335052;1215.3680086355046;1266.468083404972;1267.7718010757967;1270.955516556916;1273.6561128495255;19219.711704324167;21207.469813254174;21486.06625403721;21849.890750546147;22966.770332296463;54503.72748136234;59393;59582;62388.37611624007;62741.74516509735;68338;76850;79696.20303796888;81298.59350703208;84016;7350.485466017328;8845.4250473719;8994;9259.953700231456;9366.2038726709;14340.784888226477;14628.926855365655;14765.335559900044;14862.256887155383;15023.248837558296;10683.57265709394;11895.632002319844;12031.796820317899;12250.938745306217;12297.692557686129;11188.720281993015;11309.491072901574;11586.246893951866;11700.765984680089;11800.881991179978;10236.641717539851;11815.995640370666;12329.383530823243;12463.937680311541;12721.55474558386;8841.60212790413;8914.01945785976;9002.774930626787;9184.678536251466;9353.20497757671;12081.154319197924;12234.204776689487;12234.632961011184;12266.730654828885;12290.569830055905 17:40:16 INFO - PID 2479 | |3;mootools.html;1324.2848861614727;1460.788185713071;1468.1778204205639;1475.7529887245273;1491.1500444746496;11258.493367798448;11577.710557236072;11727.706807329821;11729.47516822813;11942.462589183486;11566.53733850647;11725.824112638296;11742.765144697123;11756.470958806853;11774.646760597174;2468.3952431654225;2517.937051573707;2519.685039370079;2521.684789401325;2528.1783420388356;2448.155386391692;2495.413577809213;2495.6880389951257;2498.1880888711153;2508.598624220125;2451.6935383077116;2474.381404648838;2506.285708573057;2509.134348649722;2515.333436639292;2411.9517609647796;2420.636904464327;2430.586800243963;2460.9138680146198;2475.1337032038823;1980.6236815005248;2109.7573779015456;2468.5062987402594;2476.863772492512;2528.9114880979077;11580.710482237928;11583.94208028963;11671.299722016705;11690.707732306675;11707;6579.901301480481;6735.966320168417;6826.965865170692;6834.760783372608;6839.965800171017;4023.6982226333143;4352.847650332224;4386.583274588909;4389.978050109761;4399.340098985178;1217.707750139969;1241.596481143632;1251.5306759965013;1291.0768800307826;1301.271288078679;13453.529126480526;15324.233788310652;15786.447474338458;16341.59146021347;16488.17559122051;5527.226188333637;5570.860728481739;5638.126090455986;5769.798057067983;5776.33572139201;11567.42162891852;11616.361100139573;11646.53413863453;11649.825252621218;11662.416879156008;6502.601940582797;6529.249136349381;6564.638944857983;6573.967130164414;6578.243501997332;5006.249062640561;5928.110783382441;6133.202683651186;6199.256089269226;6206;3537.876174333886;4126.42230087788;4220.514640816284;4242.681798865097;4274.166537525152;1056.8573242612304;1256.3843716578992;1259.7669431155266;1262.9494820207271;1272.4337669736879;757.3108471291099;782.0966783365222;786.3119770201074;789.8420315936836;792.6393490961599;5521.978433989644;5999.220101386792;6041.879162416815;6080.14877917092;6177.1969643946895;711.1040089487283;818.2799136759733;834.8622477291179;853.7449948575584;868.1795703060548;3836.8081595920094;3855.7108216883844;3888.922221555553;3945.881623551298;3956.4460975463458;14590;14933.328000239799;15105.244737763287;15130.167840768643;15191;14304.927130465258;14857.405703771945;14970;15098.698026039418;15108.244587770567;7339.999999999894;8364.665413383396;8473.195046470575;8600.870986935075;8621.439606425562;6134.325224225421;6777.6272305023695;6939.548929319578;7153.177384600837;7184.533005354635;12336.75326493483;12766.68083297925;12981.870181298444;13035.934820325838;13082.084254102121;12944.611661650166;12983.675408114872;13214.405351759386;13301.60095197107;13533.390997404944;9283.86074208901;9561.952190239004;9571.712848614552;9584.616615335308;9595.376300540442;10131.949340253252;10431.739206519595;10633.255672102878;10800.513976870903;10883.782324353628;7344.706211751683;8909;8941.418807777474;8955.910440895508;8962.596683149144;8543.786405339917;8622.275544890921;8649.221570058726;8711.695090671796;8711.738647840575;7796.4932279401655;7970.601469926364;7980.521168729895;7982.082060562877;8012.559309237815;5535.169724541351;5931.495822855106;6036.275646922398;6058.4244496772735;6082.695865206811;5247.2129180623215;6046.939530604637;6068.423499767515;6131.570790044654;6153.753849845956 17:40:16 INFO - PID 2479 | |4;prototype.html;410.7412330231953;411.51852332770653;418.13654802832133;418.35364362060614;425.15182211488064;4446.733196008242;4584.266517357223;5324.8285377217;5402.6493376655835;6231.688415579214;4540.545945405458;5021.3221215135945;5564.33228012639;5658.632188907728;6131.957567213573;3628.365036118682;4036.950392897853;4679.391679081723;4699.154152252593;4728.527147285261;4581.228356844112;4700.600448961843;4701.200795864695;4726.645501587377;4734.384530011093;4614.423197100363;4629.7222166669935;4629.814807407716;4672.859814205579;4680.882977925545;4621.359417406806;4655.650826188049;4657.976710116444;4665.440147182326;4670.719756814568;3993.920121597581;4118.176364727036;4158.168366326747;4161.002718695013;4191.3293872980485;5067.569256613192;5565.026120428949;5986.2517185351835;6030.879382412327;6049.395060493959;4855.441624213226;5546.611737178399;5558.749856256478;5559.555235581144;5585.944140558624;5577.3028371453565;6528.412442880163;6574.934250657528;6638.771827211933;6681;3958.733205374281;4692.296155576691;4718.811247550094;4723.574878260939;4735.337052812609;6396.473393009782;6816.625085620289;6952.7485052691;6953.061336719579;6966.164060312744;4157.48031496063;4299.398084268205;4302.139572085595;4311.892202694958;4337.6963612546815;4357.455318085239;4362.840394004925;4381.6494680425485;4382.956170438319;4395.560443955643;3855.8650447234213;5061.822836200715;5193.169092945186;5220.243064755665;5238.397584277781;5070.61970352225;5661.490465858093;6525.249596296392;6564.343565643398;6589.143411356589;1209.5826939705787;1480.452232673896;1504.5110339139605;1508.6228442889278;1510.4411367793987;935.396669148409;971.4317124482116;986.5067466266867;987.3582171588525;994.6966175316597;5490.231367608538;5908.350081491032;6225;6279.434850863444;6332.461740752087;5363.329583802025;6701.396874281338;6841.452683785285;6882.277360877036;6891.897296432547;5619.662820230799;6071.389302599924;6166.784162554289;6256.937430625636;6292.622442653455;3517.6306487818924;3538.894357857071;3551.206640646457;3551.254236610289;3574.410222313289;6101.816945491553;6422.325655806167;6428.164338635947;6825.385715285549;6845;5241.8729973055215;6464.385883341075;6978.476614253951;6995.4753393494675;7038.436925045984;6280.398498382883;6343.5876668016435;7033.312005118837;7039.331263529956;7073.787786366417;6692.799216023527;6846.246912839484;6863.416609588241;6873.450123990169;6883.931160688329;5623.690697011703;6683.398494135551;6747.291534388818;6752.831179220559;6793.966030169818;6419.935800642121;6845.383915447634;7086.4685148613025;7091.404434002515;7096.8935465967015;3960.2475529649273;4284.871453856389;4288.292431748727;4296.806643700978;4330.047389574289;5390.892182156414;6115.960286751174;6662.500937289071;6854.828629284308;6886.03900322866;6506.934930650822;6513.967430163008;6557.639329836905;6637.734490620514;6640.933590664032;5265.552428043659;6271.498280137669;6279;6594.307597702172;6641.535092543669;4873.634477414279;5553.055980483408;6459.935400645933;6478.352164783484;6518.478521718346;5505.917411238915;5544.695041772741;6681.732730690718;6689.5986240825705;6700.765473208415;4963.8262660806695;5177.404598471224;6118.785842495491;6168.982117950488;6339.556231063966 17:40:16 INFO - PID 2479 | |5;yui.html;829.0755807274883;840.798208429977;854.3122786157141;854.9016863060749;869.5478351257344;16633.41783037594;17070;17127.143642817857;18548.81451185488;19154.521136971547;17643.823561764377;18041.639167216646;18194.181261843216;18250.452486425394;18557.443276701688;1007.7782887764698;1023.3961962442157;1024.0374048394522;1027.4349107990613;1028.9864483483761;1002.939823610582;1010.3382284603559;1010.9949450252739;1015.2588610314475;1019.3425240719731;1041.7603951091253;1051.1748277602085;1051.395447617619;1060.7136073260215;1060.809054370213;1043.2697112021615;1049.470017641094;1052.8052310322576;1055.0241027049985;1060.6340812419742;760.2321655128305;763.6563546404141;779.8479296537178;784.1766145547152;793.4049462902823;16837.119603503328;17281.395151169774;17355.21901514435;17428.651426971515;17499.5625109372;17088.743668844978;17445;17671.73492397615;17709.114544272863;17750.289988400447;13720.725585488235;14132.646683832883;14180.787288190686;14327.928360358235;14343.856561434357;2956.305268261957;3462.3594634992605;3476.4785282207617;3478.4086705260165;3479.304139172176;103126;104103.99999999924;128074;128345;129695.43654761395;13297.002724795679;13608.251546164858;13651.180929144282;13681.794773078413;13699.315034248248;24489.510209795706;25375.36561585975;25574.10490632856;25950.740492594832;25982.480350392885;8062.5162490249595;8163.38774591908;8187.877181842277;8195.631196596167;8202.26179643835;4004.279229738675;4008.4788977432745;4077.7961101944784;4078;4087.9795601021806;4285.571442855752;4424.867253982386;4448.154850578379;4496.0108776069865;4515.7967891444305;941.4116177389132;975.6877799104218;983.608194404909;983.6213057972732;985.8225519406433;557.7239266562996;622.3341025103097;658.3186402073926;660.313274194832;700.3381804195088;10218.824835143905;11246.325220486633;11258.761536231008;12013.279203247834;12117.27296362221;647.9578827376206;652.8726898254795;653.124812750916;661.7022339947043;683.2416018219872;5443.863903402446;5495.093309603951;5572.944270557324;5590.636608620427;5732.3407808101765;105335;106890.46554767332;107311.9268807302;107814;108349;77707.61146194233;84599;96714;96895;97653.5117324423;62615;74181.25818741851;81524;86133.13866861377;87571;10660.307080039773;11300.547978080785;11364.090872730327;11381.772364552828;11475.311481311148;40991.385129222486;41331.38002929898;41475.37786933138;42137;42215.788921055195;43598.564014360716;49615.767290471296;51625.225621614954;52156;52357;13832.723345533233;14187.077839940372;14320.928395357956;14355.928220358832;14481.05873118244;15387.153706546247;15403.6149096269;15517.689646207238;15720.371185152466;15821.129837858567;12745.61763147107;14475.986680932234;14659.193744344164;14710.190939498003;14796.852031479979;14146.858531414555;14274;14274.571762847132;14705.191214482878;14777.335019923914;14233.288335582973;14240.145591264143;14314.498992535628;14394.496192633207;14480.927595361956;8533.957330213309;10452.843207351743;10479.10927571165;10523.684289471328;10629.415382154055;10644.680659580225;11132.944335278271;11313.604023859125;11330.830037549607;11443.942780286045 17:40:16 INFO - PID 2479 | __end_tp_report 17:40:16 INFO - PID 2479 | __start_cc_report 17:40:16 INFO - PID 2479 | _x_x_mozilla_cycle_collect,429 17:40:16 INFO - PID 2479 | __end_cc_report 17:40:16 INFO - PID 2479 | __startTimestamp1493426416684__endTimestamp 17:40:16 INFO - PID 2479 | 17:40:16 INFO - PID 2479 | ------- Summary: start ------- 17:40:16 INFO - PID 2479 | Number of tests: 6 17:40:16 INFO - PID 2479 | 17:40:16 INFO - PID 2479 | [#0] dojo.html Cycles:165 Average:6099.14 Median:6364.40 stddev:764.76 (12.0%) stddev-sans-first:741.13 17:40:16 INFO - PID 2479 | Values: 3580.1 3586.1 3603.8 3625.4 3632.2 6104.2 6152.6 6217.2 6243.5 6243.8 6167.1 6169.8 6204.2 6207.8 6211.6 4022.5 4291.9 5402.2 5434.0 5475.4 4935.5 5474.7 5497.5 5549.3 5621.3 4825.1 5315.7 5460.6 5465.5 5567.8 4983.7 5402.4 5455.6 5458.8 5553.6 5366.1 5382.3 5409.6 5417.4 5446.6 5951.5 6215.1 6218.2 6218.5 6229.5 6463.0 6524.3 6613.4 6674.0 6675.3 5543.3 6791.7 6802.1 6816.7 6820.2 6259.5 6292.0 6329.7 6340.4 6409.1 5685.9 6232.4 6941.7 6950.5 6953.2 6372.2 6433.1 6492.2 6512.9 6514.8 6734.3 6736.5 6737.5 6763.3 6764.8 6588.0 6602.1 6644.1 6655.8 6661.8 6448.4 6529.8 6612.2 6664.0 6686.0 5351.9 5355.8 5357.5 5367.8 5374.3 4718.1 4744.0 4744.8 4752.0 4788.7 6070.8 6270.6 6288.5 6290.8 6362.5 5522.3 6732.8 6744.7 6838.2 6846.6 6386.8 6390.8 6396.8 6405.2 6432.2 5650.4 5788.1 5804.7 5901.6 5914.7 5841.4 6660.6 6914.4 6991.6 6999.9 6542.8 6776.0 6804.7 6807.1 6831.5 6786.8 6827.6 6940.9 6974.9 6987.2 5333.8 6595.8 6601.9 6631.7 6633.2 6612.3 6658.8 6683.5 6729.5 6758.9 5774.6 6398.7 6598.1 6598.2 6612.2 6446.9 6540.8 6588.0 6590.3 6598.7 4629.5 6231.7 6318.7 6332.7 6364.4 6544.7 6549.6 6565.8 6588.9 6589.1 5493.6 6312.9 6426.3 6427.6 6445.7 17:40:16 INFO - PID 2479 | 17:40:16 INFO - PID 2479 | [#1] ext.html Cycles:180 Average:45264.18 Median:34610.06 stddev:79003.36 (228.3%) stddev-sans-first:79154.35 17:40:16 INFO - PID 2479 | Values: 769.3 794.0 830.5 1185.3 1243.3 37488.3 38378.6 38403.2 38543.0 38792.6 38678.8 38701.0 38913.0 39091.0 39092.0 7886.2 8044.6 8358.2 8592.4 8614.4 8480.8 8579.7 8581.9 8593.0 8602.7 8506.2 8549.4 8591.8 8595.2 8619.4 8476.8 8549.6 8584.7 8589.2 8592.8 8422.9 8432.5 8440.9 8484.2 8499.6 37244.8 37293.4 37531.9 37577.8 37604.6 3623.0 3635.9 3656.1 3673.8 3682.7 30861.1 30886.7 31051.2 31063.5 31147.0 3150.7 3201.0 3218.9 3230.8 3232.6 302021.0 304094.0 306070.4 311303.4 312657.0 48501.8 48596.0 48735.8 48790.0 48872.5 49422.5 50003.0 50102.7 50633.0 50855.7 14377.7 14505.9 14506.0 14530.4 14536.3 11719.1 11726.0 11743.0 11743.4 11759.8 13391.9 13535.8 13541.8 13563.6 13661.5 5957.5 6020.3 6021.8 6038.9 6088.9 896.0 927.0 957.3 978.3 1010.6 24298.1 24618.8 24650.0 24684.3 24690.0 1114.1 1120.8 1142.7 1185.4 1187.7 10828.6 11785.6 12635.7 12651.4 12659.7 331945.0 334131.0 334557.0 334563.0 334590.0 52979.1 53557.7 53607.5 53632.9 53732.0 1349.9 1498.6 1526.2 1552.7 1590.4 18608.5 18609.3 22991.0 24143.4 27273.9 46399.3 46503.8 46610.3 46684.5 46737.0 254133.0 255528.7 257493.0 258936.0 259587.0 33630.5 33980.0 34179.8 36007.1 36237.7 40997.2 44405.8 44472.3 44472.6 44558.7 28662.4 29494.9 29507.6 30333.0 30666.7 29482.0 29822.0 29974.9 30473.8 30500.0 35040.3 35180.5 35459.3 36397.8 36398.6 28808.7 29653.0 29793.9 30589.8 30911.5 22677.8 23776.9 23788.5 24369.6 24403.5 17:40:16 INFO - PID 2479 | 17:40:16 INFO - PID 2479 | [#2] jquery.html Cycles:180 Average:36014.90 Median:26050.87 stddev:85599.47 (328.6%) stddev-sans-first:85797.86 17:40:16 INFO - PID 2479 | Values: 413.8 436.8 438.7 456.6 456.8 19392.8 20874.5 21547.4 21665.7 21711.0 18243.6 21993.0 22505.4 22665.9 23711.1 5762.7 6739.2 6957.5 6973.6 6973.9 6962.5 7137.1 7157.7 7175.4 7196.2 6787.3 6821.6 6828.4 7113.2 7186.4 6692.3 6814.6 6815.5 6884.7 6885.4 6953.5 7024.2 7028.8 7036.2 7038.2 23397.8 28675.4 29844.7 29969.3 30349.4 2100.8 2146.7 2149.9 2151.4 2156.1 18263.6 19304.0 19396.2 19574.0 19579.8 2284.4 2320.3 2348.2 2358.6 2380.2 169594.0 171604.0 171808.0 171875.3 173818.0 24559.4 25441.0 25865.7 25903.7 26198.0 42060.0 42307.8 42342.6 42614.6 42615.6 8850.5 8964.9 9147.9 9270.5 9339.3 6576.0 8518.4 8665.8 8700.0 8818.1 8030.4 8168.8 8196.7 8224.7 8228.9 2294.5 2310.4 2315.8 2319.4 2464.1 947.3 965.5 971.9 986.0 1005.4 21065.4 21452.9 21547.8 21642.4 21785.7 900.8 901.3 1007.8 1047.5 1051.7 8910.0 9780.9 9971.9 11103.3 11144.1 487277.0 493409.0 496361.0 500921.0 502377.0 111523.0 123071.0 129541.0 139571.0 140233.3 1215.4 1266.5 1267.8 1271.0 1273.7 19219.7 21207.5 21486.1 21849.9 22966.8 54503.7 59393.0 59582.0 62388.4 62741.7 68338.0 76850.0 79696.2 81298.6 84016.0 7350.5 8845.4 8994.0 9260.0 9366.2 14340.8 14628.9 14765.3 14862.3 15023.2 10683.6 11895.6 12031.8 12250.9 12297.7 11188.7 11309.5 11586.2 11700.8 11800.9 10236.6 11816.0 12329.4 12463.9 12721.6 8841.6 8914.0 9002.8 9184.7 9353.2 12081.2 12234.2 12234.6 12266.7 12290.6 17:40:16 INFO - PID 2479 | 17:40:16 INFO - PID 2479 | [#3] mootools.html Cycles:180 Average:6977.36 Median:3687.34 stddev:4411.48 (119.6%) stddev-sans-first:4403.41 17:40:16 INFO - PID 2479 | Values: 1324.3 1460.8 1468.2 1475.8 1491.2 11258.5 11577.7 11727.7 11729.5 11942.5 11566.5 11725.8 11742.8 11756.5 11774.6 2468.4 2517.9 2519.7 2521.7 2528.2 2448.2 2495.4 2495.7 2498.2 2508.6 2451.7 2474.4 2506.3 2509.1 2515.3 2412.0 2420.6 2430.6 2460.9 2475.1 1980.6 2109.8 2468.5 2476.9 2528.9 11580.7 11583.9 11671.3 11690.7 11707.0 6579.9 6736.0 6827.0 6834.8 6840.0 4023.7 4352.8 4386.6 4390.0 4399.3 1217.7 1241.6 1251.5 1291.1 1301.3 13453.5 15324.2 15786.4 16341.6 16488.2 5527.2 5570.9 5638.1 5769.8 5776.3 11567.4 11616.4 11646.5 11649.8 11662.4 6502.6 6529.2 6564.6 6574.0 6578.2 5006.2 5928.1 6133.2 6199.3 6206.0 3537.9 4126.4 4220.5 4242.7 4274.2 1056.9 1256.4 1259.8 1262.9 1272.4 757.3 782.1 786.3 789.8 792.6 5522.0 5999.2 6041.9 6080.1 6177.2 711.1 818.3 834.9 853.7 868.2 3836.8 3855.7 3888.9 3945.9 3956.4 14590.0 14933.3 15105.2 15130.2 15191.0 14304.9 14857.4 14970.0 15098.7 15108.2 7340.0 8364.7 8473.2 8600.9 8621.4 6134.3 6777.6 6939.5 7153.2 7184.5 12336.8 12766.7 12981.9 13035.9 13082.1 12944.6 12983.7 13214.4 13301.6 13533.4 9283.9 9562.0 9571.7 9584.6 9595.4 10131.9 10431.7 10633.3 10800.5 10883.8 7344.7 8909.0 8941.4 8955.9 8962.6 8543.8 8622.3 8649.2 8711.7 8711.7 7796.5 7970.6 7980.5 7982.1 8012.6 5535.2 5931.5 6036.3 6058.4 6082.7 5247.2 6046.9 6068.4 6131.6 6153.8 17:40:16 INFO - PID 2479 | 17:40:16 INFO - PID 2479 | [#4] prototype.html Cycles:180 Average:5225.73 Median:5581.62 stddev:1642.26 (29.4%) stddev-sans-first:1606.61 17:40:16 INFO - PID 2479 | Values: 410.7 411.5 418.1 418.4 425.2 4446.7 4584.3 5324.8 5402.6 6231.7 4540.5 5021.3 5564.3 5658.6 6132.0 3628.4 4037.0 4679.4 4699.2 4728.5 4581.2 4700.6 4701.2 4726.6 4734.4 4614.4 4629.7 4629.8 4672.9 4680.9 4621.4 4655.7 4658.0 4665.4 4670.7 3993.9 4118.2 4158.2 4161.0 4191.3 5067.6 5565.0 5986.3 6030.9 6049.4 4855.4 5546.6 5558.7 5559.6 5585.9 5577.3 6528.4 6574.9 6638.8 6681.0 3958.7 4692.3 4718.8 4723.6 4735.3 6396.5 6816.6 6952.7 6953.1 6966.2 4157.5 4299.4 4302.1 4311.9 4337.7 4357.5 4362.8 4381.6 4383.0 4395.6 3855.9 5061.8 5193.2 5220.2 5238.4 5070.6 5661.5 6525.2 6564.3 6589.1 1209.6 1480.5 1504.5 1508.6 1510.4 935.4 971.4 986.5 987.4 994.7 5490.2 5908.4 6225.0 6279.4 6332.5 5363.3 6701.4 6841.5 6882.3 6891.9 5619.7 6071.4 6166.8 6256.9 6292.6 3517.6 3538.9 3551.2 3551.3 3574.4 6101.8 6422.3 6428.2 6825.4 6845.0 5241.9 6464.4 6978.5 6995.5 7038.4 6280.4 6343.6 7033.3 7039.3 7073.8 6692.8 6846.2 6863.4 6873.5 6883.9 5623.7 6683.4 6747.3 6752.8 6794.0 6419.9 6845.4 7086.5 7091.4 7096.9 3960.2 4284.9 4288.3 4296.8 4330.0 5390.9 6116.0 6662.5 6854.8 6886.0 6506.9 6514.0 6557.6 6637.7 6640.9 5265.6 6271.5 6279.0 6594.3 6641.5 4873.6 5553.1 6459.9 6478.4 6518.5 5505.9 5544.7 6681.7 6689.6 6700.8 4963.8 5177.4 6118.8 6169.0 6339.6 17:40:16 INFO - PID 2479 | 17:40:16 INFO - PID 2479 | [#5] yui.html Cycles:180 Average:21129.70 Median:17391.94 stddev:30075.43 (172.9%) stddev-sans-first:30121.17 17:40:16 INFO - PID 2479 | Values: 829.1 840.8 854.3 854.9 869.5 16633.4 17070.0 17127.1 18548.8 19154.5 17643.8 18041.6 18194.2 18250.5 18557.4 1007.8 1023.4 1024.0 1027.4 1029.0 1002.9 1010.3 1011.0 1015.3 1019.3 1041.8 1051.2 1051.4 1060.7 1060.8 1043.3 1049.5 1052.8 1055.0 1060.6 760.2 763.7 779.8 784.2 793.4 16837.1 17281.4 17355.2 17428.7 17499.6 17088.7 17445.0 17671.7 17709.1 17750.3 13720.7 14132.6 14180.8 14327.9 14343.9 2956.3 3462.4 3476.5 3478.4 3479.3 103126.0 104104.0 128074.0 128345.0 129695.4 13297.0 13608.3 13651.2 13681.8 13699.3 24489.5 25375.4 25574.1 25950.7 25982.5 8062.5 8163.4 8187.9 8195.6 8202.3 4004.3 4008.5 4077.8 4078.0 4088.0 4285.6 4424.9 4448.2 4496.0 4515.8 941.4 975.7 983.6 983.6 985.8 557.7 622.3 658.3 660.3 700.3 10218.8 11246.3 11258.8 12013.3 12117.3 648.0 652.9 653.1 661.7 683.2 5443.9 5495.1 5572.9 5590.6 5732.3 105335.0 106890.5 107311.9 107814.0 108349.0 77707.6 84599.0 96714.0 96895.0 97653.5 62615.0 74181.3 81524.0 86133.1 87571.0 10660.3 11300.5 11364.1 11381.8 11475.3 40991.4 41331.4 41475.4 42137.0 42215.8 43598.6 49615.8 51625.2 52156.0 52357.0 13832.7 14187.1 14320.9 14355.9 14481.1 15387.2 15403.6 15517.7 15720.4 15821.1 12745.6 14476.0 14659.2 14710.2 14796.9 14146.9 14274.0 14274.6 14705.2 14777.3 14233.3 14240.1 14314.5 14394.5 14480.9 8534.0 10452.8 10479.1 10523.7 10629.4 10644.7 11132.9 11313.6 11330.8 11443.9 17:40:16 INFO - PID 2479 | -------- Summary: end -------- 17:40:16 INFO - PID 2479 | 17:40:17 INFO - TEST-INFO | 2479: exit 0 17:40:17 INFO - TEST-OK | dromaeo_css | took 1114324ms 17:40:17 INFO - TEST-START | kraken 17:40:17 INFO - operating with platform_type : mac_ 17:40:17 INFO - Initialising browser for kraken test... 17:40:17 INFO - TEST-INFO | started process 2486 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpL5XEsg/profile http://localhost:60437/getInfo.html) 17:40:19 INFO - PID 2486 | 2017-04-28 17:40:19.848 plugin-container[2487:39183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x932f, name = 'com.apple.tsm.portname' 17:40:19 INFO - PID 2486 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:40:19 INFO - PID 2486 | 2017-04-28 17:40:19.851 plugin-container[2487:39183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 17:40:19 INFO - PID 2486 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:40:19 INFO - PID 2486 | 2017-04-28 17:40:19.851 plugin-container[2487:39183] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:40:20 INFO - PID 2486 | __metrics Screen width/height:1600/1200 17:40:20 INFO - PID 2486 | colorDepth:24 17:40:20 INFO - PID 2486 | Browser inner width/height: 1024/689 17:40:20 INFO - PID 2486 | __metrics 17:40:20 ERROR - PID 2486 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:40:21 INFO - TEST-INFO | 2486: exit 0 17:40:21 INFO - Browser initialized. 17:40:21 INFO - Running cycle 1/1 for kraken test... 17:40:21 INFO - TEST-INFO | started process 2488 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpL5XEsg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:40:23 INFO - PID 2488 | 2017-04-28 17:40:23.162 plugin-container[2489:39303] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 17:40:23 INFO - PID 2488 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:40:23 INFO - PID 2488 | 2017-04-28 17:40:23.163 plugin-container[2489:39303] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923b, name = 'com.apple.CFPasteboardClient' 17:40:23 INFO - PID 2488 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:40:23 INFO - PID 2488 | 2017-04-28 17:40:23.163 plugin-container[2489:39303] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:40:33 INFO - PID 2488 | RSS: Main: 251555840 17:40:33 INFO - PID 2488 | 17:40:35 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60437/tests/kraken/driver.html?testName=audio-beat-detection) 17:40:36 INFO - PID 2488 | RSS: Main: 259567616 17:40:36 INFO - PID 2488 | 17:40:39 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60437/tests/kraken/driver.html?testName=audio-dft) 17:40:39 INFO - PID 2488 | RSS: Main: 266579968 17:40:39 INFO - PID 2488 | 17:40:43 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60437/tests/kraken/driver.html?testName=audio-fft) 17:40:43 INFO - PID 2488 | RSS: Main: 262283264 17:40:43 INFO - PID 2488 | 17:40:45 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60437/tests/kraken/driver.html?testName=audio-oscillator) 17:40:46 INFO - PID 2488 | RSS: Main: 264093696 17:40:46 INFO - PID 2488 | 17:40:48 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60437/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:40:49 INFO - PID 2488 | RSS: Main: 264232960 17:40:49 INFO - PID 2488 | 17:40:52 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60437/tests/kraken/driver.html?testName=imaging-darkroom) 17:40:52 INFO - PID 2488 | RSS: Main: 269578240 17:40:52 INFO - PID 2488 | 17:40:55 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60437/tests/kraken/driver.html?testName=imaging-desaturate) 17:40:56 INFO - PID 2488 | RSS: Main: 271130624 17:40:56 INFO - PID 2488 | 17:40:59 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60437/tests/kraken/driver.html?testName=json-parse-financial) 17:41:00 INFO - PID 2488 | RSS: Main: 270770176 17:41:00 INFO - PID 2488 | 17:41:01 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60437/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:41:02 INFO - PID 2488 | RSS: Main: 269975552 17:41:02 INFO - PID 2488 | 17:41:04 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60437/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:41:04 INFO - PID 2488 | RSS: Main: 270458880 17:41:04 INFO - PID 2488 | 17:41:06 INFO - PID 2488 | Cycle 1(1): loaded http://localhost:60437/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60437/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:41:07 INFO - PID 2488 | RSS: Main: 270802944 17:41:07 INFO - PID 2488 | remoteFailed: [Failure instance: Traceback (failure with no frames): : Connection to the other side was lost in a non-clean fashion. ] [Failure instance: Traceback (failure with no frames): : Connection to the other side was lost in a non-clean fashion. ] ========= Finished '/tools/buildbot/bin/python -u ...' interrupted (results: 5, elapsed: 21 mins, 57 secs) (at 2017-04-28 17:42:07.294114) ========= ========= Skipped (results: not started, elapsed: not started) ========= ========= Skipped (results: not started, elapsed: not started) ========= ========= Skipped (results: not started, elapsed: not started) ========= ========= Total master_lag: 0.08 =========