builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0026 starttime: 1470964594.62 results: failure (2) buildid: 20160811160617 builduid: 1c7dd2feab5348269d96038226bbbb15 revision: d8dc463ad2af6a33780c45ddac7474be277bb83a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:34.615628) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:34.615990) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:34.616255) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:34.658172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:34.658520) ========= 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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-11 18:16:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.20M=0.001s 2016-08-11 18:16:35 (8.20 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.625423 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:35.306505) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:35.306806) ========= 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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.020375 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:35.355323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 18:16:35.355683) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d8dc463ad2af6a33780c45ddac7474be277bb83a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev d8dc463ad2af6a33780c45ddac7474be277bb83a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cA2XBYFggT/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.Q5J8A6AwNc/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-11 18:16:35,432 truncating revision to first 12 chars 2016-08-11 18:16:35,432 Setting DEBUG logging. 2016-08-11 18:16:35,432 attempt 1/10 2016-08-11 18:16:35,432 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d8dc463ad2af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-11 18:16:36,581 unpacking tar archive at: autoland-d8dc463ad2af/testing/mozharness/ program finished with exit code 0 elapsedTime=1.407800 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 18:16:36.787432) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:36.787763) ========= script_repo_revision: d8dc463ad2af6a33780c45ddac7474be277bb83a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:36.788137) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:36.788399) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 18:16:36.806847) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 1 hrs, 20 mins, 15 secs) (at 2016-08-11 18:16:36.807231) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cA2XBYFggT/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.Q5J8A6AwNc/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:16:36 INFO - MultiFileLogger online at 20160811 18:16:36 in /builds/slave/test 18:16:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:16:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:16:36 INFO - {'append_to_log': False, 18:16:36 INFO - 'base_work_dir': '/builds/slave/test', 18:16:36 INFO - 'blob_upload_branch': 'Autoland', 18:16:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:16:36 INFO - 'branch': 'Autoland', 18:16:36 INFO - 'buildbot_json_path': 'buildprops.json', 18:16:36 INFO - 'config_files': ('talos/mac_config.py',), 18:16:36 INFO - 'default_actions': ('clobber', 18:16:36 INFO - 'read-buildbot-config', 18:16:36 INFO - 'download-and-extract', 18:16:36 INFO - 'populate-webroot', 18:16:36 INFO - 'create-virtualenv', 18:16:36 INFO - 'install', 18:16:36 INFO - 'run-tests'), 18:16:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:16:36 INFO - 'download_minidump_stackwalk': True, 18:16:36 INFO - 'download_symbols': 'ondemand', 18:16:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:16:36 INFO - 'tooltool.py': '/tools/tooltool.py', 18:16:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:16:36 INFO - '/tools/misc-python/virtualenv.py')}, 18:16:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:16:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:16:36 INFO - 'installer_path': 'installer.exe', 18:16:36 INFO - 'log_level': 'info', 18:16:36 INFO - 'log_name': 'talos', 18:16:36 INFO - 'log_to_console': True, 18:16:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:16:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:16:36 INFO - 'opt_config_files': (), 18:16:36 INFO - 'pip_index': False, 18:16:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:16:36 INFO - 'cmd': ('bash', 18:16:36 INFO - '-c', 18:16:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:16:36 INFO - 'enabled': True, 18:16:36 INFO - 'halt_on_failure': False, 18:16:36 INFO - 'name': 'check_screen_resolution'},), 18:16:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:16:36 INFO - 'cmd': ('bash', 18:16:36 INFO - '-c', 18:16:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:16:36 INFO - 'enabled': True, 18:16:36 INFO - 'halt_on_failure': False, 18:16:36 INFO - 'name': 'check_screen_resolution'},), 18:16:36 INFO - 'run_cmd_checks_enabled': True, 18:16:36 INFO - 'sps_profile': False, 18:16:36 INFO - 'sps_profile_interval': 0, 18:16:36 INFO - 'suite': 'dromaeojs-e10s', 18:16:36 INFO - 'system_bits': '32', 18:16:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:16:36 INFO - 'title': 't-yosemite-r7-0026', 18:16:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:16:36 INFO - 'use_talos_json': True, 18:16:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:16:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:16:36 INFO - 'work_dir': 'build'} 18:16:36 INFO - [mozharness: 2016-08-12 01:16:36.996614Z] Running clobber step. 18:16:36 INFO - Running pre-action listener: _resource_record_pre_action 18:16:36 INFO - Running main action method: clobber 18:16:36 INFO - rmtree: /builds/slave/test/build 18:16:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:16:37 INFO - Running post-action listener: _resource_record_post_action 18:16:37 INFO - [mozharness: 2016-08-12 01:16:37.183030Z] Finished clobber step (success) 18:16:37 INFO - [mozharness: 2016-08-12 01:16:37.183161Z] Running read-buildbot-config step. 18:16:37 INFO - Running pre-action listener: _resource_record_pre_action 18:16:37 INFO - Running main action method: read_buildbot_config 18:16:37 INFO - Using buildbot properties: 18:16:37 INFO - { 18:16:37 INFO - "project": "", 18:16:37 INFO - "product": "firefox", 18:16:37 INFO - "script_repo_revision": "production", 18:16:37 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 18:16:37 INFO - "repository": "", 18:16:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 18:16:37 INFO - "buildid": "20160811160617", 18:16:37 INFO - "pgo_build": "False", 18:16:37 INFO - "basedir": "/builds/slave/test", 18:16:37 INFO - "buildnumber": 109, 18:16:37 INFO - "slavename": "t-yosemite-r7-0026", 18:16:37 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:16:37 INFO - "platform": "yosemite_r7", 18:16:37 INFO - "scheduler": "tests-autoland-macosx64-talos", 18:16:37 INFO - "branch": "autoland", 18:16:37 INFO - "repo_path": "integration/autoland", 18:16:37 INFO - "revision": "d8dc463ad2af6a33780c45ddac7474be277bb83a", 18:16:37 INFO - "stage_platform": "macosx64", 18:16:37 INFO - "builduid": "1c7dd2feab5348269d96038226bbbb15", 18:16:37 INFO - "slavebuilddir": "test" 18:16:37 INFO - } 18:16:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:16:37 INFO - Running post-action listener: _resource_record_post_action 18:16:37 INFO - [mozharness: 2016-08-12 01:16:37.186504Z] Finished read-buildbot-config step (success) 18:16:37 INFO - [mozharness: 2016-08-12 01:16:37.186634Z] Running download-and-extract step. 18:16:37 INFO - Running pre-action listener: _resource_record_pre_action 18:16:37 INFO - Running main action method: download_and_extract 18:16:37 INFO - mkdir: /builds/slave/test/build/tests 18:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:37 INFO - trying https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:16:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:16:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 18:16:38 INFO - Downloaded 1199 bytes. 18:16:38 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:16:38 INFO - Using the following test package requirements: 18:16:38 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:16:38 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:16:38 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:16:38 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:16:38 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:16:38 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:16:38 INFO - u'jsshell-mac.zip'], 18:16:38 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:16:38 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:16:38 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:16:38 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:16:38 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:16:38 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:16:38 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:16:38 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:16:38 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:16:38 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:16:38 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:16:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:38 INFO - trying https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:16:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:16:40 INFO - Downloaded 16328710 bytes. 18:16:40 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:16:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:47 INFO - trying https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:16:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:16:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:16:48 INFO - Downloaded 16328710 bytes. 18:16:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:54 INFO - trying https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:16:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:16:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:16:56 INFO - Downloaded 14413719 bytes. 18:16:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:56 INFO - trying https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:16:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:16:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 18:16:59 INFO - Downloaded 110974551 bytes. 18:16:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:16:59 INFO - mkdir: /builds/slave/test/properties 18:16:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:16:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:16:59 INFO - Contents: 18:16:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:16:59 INFO - Running post-action listener: _resource_record_post_action 18:16:59 INFO - Running post-action listener: set_extra_try_arguments 18:16:59 INFO - [mozharness: 2016-08-12 01:16:59.824869Z] Finished download-and-extract step (success) 18:16:59 INFO - [mozharness: 2016-08-12 01:16:59.825039Z] Running populate-webroot step. 18:16:59 INFO - Running pre-action listener: _resource_record_pre_action 18:16:59 INFO - Running main action method: populate_webroot 18:16:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:16:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:16:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:16:59 INFO - '--tppagecycles', 18:16:59 INFO - '7'], 18:16:59 INFO - 'tests': ['tsvgm']}}, 18:16:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:16:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:16:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:16:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:16:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:59 INFO - 'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:59 INFO - 'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['damp', 'tps']}, 18:16:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:59 INFO - 'tests': ['damp', 'tps']}, 18:16:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['dromaeo_dom']}, 18:16:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:16:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['basic_compositor_video']}, 18:16:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:16:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['a11yr', 18:16:59 INFO - 'ts_paint', 18:16:59 INFO - 'tpaint', 18:16:59 INFO - 'sessionrestore', 18:16:59 INFO - 'sessionrestore_no_auto_restore', 18:16:59 INFO - 'tabpaint']}, 18:16:59 INFO - 'other-e10s': {'tests': ['a11yr', 18:16:59 INFO - 'ts_paint', 18:16:59 INFO - 'tpaint', 18:16:59 INFO - 'sessionrestore', 18:16:59 INFO - 'sessionrestore_no_auto_restore', 18:16:59 INFO - 'tabpaint']}, 18:16:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:16:59 INFO - 'ts_paint', 18:16:59 INFO - 'tpaint', 18:16:59 INFO - 'sessionrestore', 18:16:59 INFO - 'sessionrestore_no_auto_restore', 18:16:59 INFO - 'tabpaint']}, 18:16:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:16:59 INFO - 'ts_paint', 18:16:59 INFO - 'tpaint', 18:16:59 INFO - 'sessionrestore', 18:16:59 INFO - 'sessionrestore_no_auto_restore', 18:16:59 INFO - 'tabpaint']}, 18:16:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['a11yr', 18:16:59 INFO - 'ts_paint', 18:16:59 INFO - 'tpaint', 18:16:59 INFO - 'sessionrestore', 18:16:59 INFO - 'sessionrestore_no_auto_restore', 18:16:59 INFO - 'tabpaint']}, 18:16:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['a11yr', 18:16:59 INFO - 'ts_paint', 18:16:59 INFO - 'tpaint', 18:16:59 INFO - 'sessionrestore', 18:16:59 INFO - 'sessionrestore_no_auto_restore', 18:16:59 INFO - 'tabpaint']}, 18:16:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['tsvgx', 18:16:59 INFO - 'tsvgr_opacity', 18:16:59 INFO - 'tart', 18:16:59 INFO - 'tscrollx', 18:16:59 INFO - 'cart']}, 18:16:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:16:59 INFO - 'tsvgr_opacity', 18:16:59 INFO - 'tart', 18:16:59 INFO - 'tscrollx', 18:16:59 INFO - 'cart']}, 18:16:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:59 INFO - 'talos_options': ['--disable-e10s'], 18:16:59 INFO - 'tests': ['tp5o']}, 18:16:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:59 INFO - 'tests': ['tp5o']}, 18:16:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:59 INFO - 'talos_options': ['--disable-e10s', 18:16:59 INFO - '--xperf_path', 18:16:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:59 INFO - 'tests': ['tp5n']}, 18:16:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:16:59 INFO - 'talos_options': ['--xperf_path', 18:16:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:59 INFO - 'tests': ['tp5n']}}, 18:16:59 INFO - 'talos.zip': {'path': '', 18:16:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:16:59 INFO - Running post-action listener: _resource_record_post_action 18:16:59 INFO - [mozharness: 2016-08-12 01:16:59.843385Z] Finished populate-webroot step (success) 18:16:59 INFO - [mozharness: 2016-08-12 01:16:59.843493Z] Running create-virtualenv step. 18:16:59 INFO - Running pre-action listener: _resource_record_pre_action 18:16:59 INFO - Running main action method: create_virtualenv 18:16:59 INFO - Creating virtualenv /builds/slave/test/build/venv 18:16:59 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 18:16:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:16:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:16:59 INFO - Using real prefix '/tools/python27' 18:16:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:17:00 INFO - Installing distribute.............................................................................................................................................................................................done. 18:17:03 INFO - Installing pip.................done. 18:17:03 INFO - Return code: 0 18:17:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:17:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:03 INFO - 'HOME': '/Users/cltbld', 18:17:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:03 INFO - 'LOGNAME': 'cltbld', 18:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:03 INFO - 'MOZ_NO_REMOTE': '1', 18:17:03 INFO - 'NO_EM_RESTART': '1', 18:17:03 INFO - 'PAGER': '/bin/cat', 18:17:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:03 INFO - 'PWD': '/builds/slave/test', 18:17:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:03 INFO - 'SHELL': '/bin/bash', 18:17:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:03 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:03 INFO - 'USER': 'cltbld', 18:17:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:03 INFO - 'XPC_FLAGS': '0x0', 18:17:03 INFO - 'XPC_SERVICE_NAME': '0', 18:17:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:03 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 18:17:03 INFO - Cleaning up... 18:17:03 INFO - Return code: 0 18:17:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:17:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:03 INFO - 'HOME': '/Users/cltbld', 18:17:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:03 INFO - 'LOGNAME': 'cltbld', 18:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:03 INFO - 'MOZ_NO_REMOTE': '1', 18:17:03 INFO - 'NO_EM_RESTART': '1', 18:17:03 INFO - 'PAGER': '/bin/cat', 18:17:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:03 INFO - 'PWD': '/builds/slave/test', 18:17:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:03 INFO - 'SHELL': '/bin/bash', 18:17:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:03 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:03 INFO - 'USER': 'cltbld', 18:17:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:03 INFO - 'XPC_FLAGS': '0x0', 18:17:03 INFO - 'XPC_SERVICE_NAME': '0', 18:17:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:04 INFO - Downloading/unpacking psutil>=3.1.1 18:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:17:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:17:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:17:07 INFO - Installing collected packages: psutil 18:17:07 INFO - Running setup.py install for psutil 18:17:07 INFO - building 'psutil._psutil_osx' extension 18:17:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:17:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:17:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:17:07 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 18:17:07 INFO - building 'psutil._psutil_posix' extension 18:17:07 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 18:17:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:17:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:17:07 INFO - ^ 18:17:07 INFO - 1 warning generated. 18:17:07 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 18:17:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:17:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:17:07 INFO - Successfully installed psutil 18:17:07 INFO - Cleaning up... 18:17:08 INFO - Return code: 0 18:17:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:08 INFO - 'HOME': '/Users/cltbld', 18:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:08 INFO - 'LOGNAME': 'cltbld', 18:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:08 INFO - 'MOZ_NO_REMOTE': '1', 18:17:08 INFO - 'NO_EM_RESTART': '1', 18:17:08 INFO - 'PAGER': '/bin/cat', 18:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:08 INFO - 'PWD': '/builds/slave/test', 18:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:08 INFO - 'SHELL': '/bin/bash', 18:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:08 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:08 INFO - 'USER': 'cltbld', 18:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:08 INFO - 'XPC_FLAGS': '0x0', 18:17:08 INFO - 'XPC_SERVICE_NAME': '0', 18:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:17:10 INFO - Installing collected packages: mozsystemmonitor 18:17:10 INFO - Running setup.py install for mozsystemmonitor 18:17:10 INFO - Successfully installed mozsystemmonitor 18:17:10 INFO - Cleaning up... 18:17:11 INFO - Return code: 0 18:17:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:17: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')]} 18:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17: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')]} 18:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:11 INFO - 'HOME': '/Users/cltbld', 18:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:11 INFO - 'LOGNAME': 'cltbld', 18:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:11 INFO - 'MOZ_NO_REMOTE': '1', 18:17:11 INFO - 'NO_EM_RESTART': '1', 18:17:11 INFO - 'PAGER': '/bin/cat', 18:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:11 INFO - 'PWD': '/builds/slave/test', 18:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:11 INFO - 'SHELL': '/bin/bash', 18:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:11 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:11 INFO - 'USER': 'cltbld', 18:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:11 INFO - 'XPC_FLAGS': '0x0', 18:17:11 INFO - 'XPC_SERVICE_NAME': '0', 18:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:11 INFO - Downloading/unpacking jsonschema==2.5.1 18:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:17:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:14 INFO - Downloading functools32-3.2.3-2.tar.gz 18:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:17:14 INFO - warning: no files found matching '*.txt' 18:17:14 INFO - no previously-included directories found matching 'build' 18:17:14 INFO - no previously-included directories found matching 'dist' 18:17:14 INFO - no previously-included directories found matching '.git*' 18:17:14 INFO - Installing collected packages: jsonschema, functools32 18:17:14 INFO - Running setup.py install for functools32 18:17:14 INFO - warning: no files found matching '*.txt' 18:17:14 INFO - no previously-included directories found matching 'build' 18:17:14 INFO - no previously-included directories found matching 'dist' 18:17:14 INFO - no previously-included directories found matching '.git*' 18:17:14 INFO - Successfully installed jsonschema functools32 18:17:14 INFO - Cleaning up... 18:17:14 INFO - Return code: 0 18:17:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:17:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:14 INFO - 'HOME': '/Users/cltbld', 18:17:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:14 INFO - 'LOGNAME': 'cltbld', 18:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:14 INFO - 'MOZ_NO_REMOTE': '1', 18:17:14 INFO - 'NO_EM_RESTART': '1', 18:17:14 INFO - 'PAGER': '/bin/cat', 18:17:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:14 INFO - 'PWD': '/builds/slave/test', 18:17:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:14 INFO - 'SHELL': '/bin/bash', 18:17:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:14 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:14 INFO - 'USER': 'cltbld', 18:17:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:14 INFO - 'XPC_FLAGS': '0x0', 18:17:14 INFO - 'XPC_SERVICE_NAME': '0', 18:17:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:17:15 INFO - Cleaning up... 18:17:15 INFO - Return code: 0 18:17:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:17:15 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')]} 18:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:15 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')]} 18:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:15 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 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:15 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 18:17:15 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 18:17:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:15 INFO - 'HOME': '/Users/cltbld', 18:17:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:15 INFO - 'LOGNAME': 'cltbld', 18:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:15 INFO - 'MOZ_NO_REMOTE': '1', 18:17:15 INFO - 'NO_EM_RESTART': '1', 18:17:15 INFO - 'PAGER': '/bin/cat', 18:17:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:15 INFO - 'PWD': '/builds/slave/test', 18:17:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:15 INFO - 'SHELL': '/bin/bash', 18:17:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:15 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:15 INFO - 'USER': 'cltbld', 18:17:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:15 INFO - 'XPC_FLAGS': '0x0', 18:17:15 INFO - 'XPC_SERVICE_NAME': '0', 18:17:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:15 INFO - Downloading/unpacking blobuploader==1.2.4 18:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:17 INFO - Downloading blobuploader-1.2.4.tar.gz 18:17:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:17:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:17:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17:18 INFO - Downloading docopt-0.6.1.tar.gz 18:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:17:19 INFO - Installing collected packages: blobuploader, requests, docopt 18:17:19 INFO - Running setup.py install for blobuploader 18:17:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:17:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:17:19 INFO - Running setup.py install for requests 18:17:19 INFO - Running setup.py install for docopt 18:17:19 INFO - Successfully installed blobuploader requests docopt 18:17:19 INFO - Cleaning up... 18:17:19 INFO - Return code: 0 18:17:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:19 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')]} 18:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:19 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')]} 18:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:19 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 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:19 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 18:17:19 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 18:17:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:19 INFO - 'HOME': '/Users/cltbld', 18:17:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:19 INFO - 'LOGNAME': 'cltbld', 18:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:19 INFO - 'MOZ_NO_REMOTE': '1', 18:17:19 INFO - 'NO_EM_RESTART': '1', 18:17:19 INFO - 'PAGER': '/bin/cat', 18:17:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:19 INFO - 'PWD': '/builds/slave/test', 18:17:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:19 INFO - 'SHELL': '/bin/bash', 18:17:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:19 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:19 INFO - 'USER': 'cltbld', 18:17:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:19 INFO - 'XPC_FLAGS': '0x0', 18:17:19 INFO - 'XPC_SERVICE_NAME': '0', 18:17:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:20 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-s2tlzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:20 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-T6WntS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:20 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-mriodG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:20 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-cXLAx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:20 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-yumwI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:20 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-QTXfDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:20 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-MG5bQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:20 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-4L5aQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:21 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-owQq0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:21 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-3cHIdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:21 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-smnO2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:21 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-89eA4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:21 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-YuJbRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:21 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-7GZg3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:21 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-X6sqmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:21 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-ZmBRDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:21 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-T0FfSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:17:22 INFO - Running setup.py install for manifestparser 18:17:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:17:22 INFO - Running setup.py install for mozcrash 18:17:22 INFO - Running setup.py install for mozdebug 18:17:22 INFO - Running setup.py install for mozdevice 18:17:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:17:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:17:22 INFO - Running setup.py install for mozfile 18:17:22 INFO - Running setup.py install for mozhttpd 18:17:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:17:22 INFO - Running setup.py install for mozinfo 18:17:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Running setup.py install for mozInstall 18:17:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Running setup.py install for mozleak 18:17:23 INFO - Running setup.py install for mozlog 18:17:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Running setup.py install for moznetwork 18:17:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Running setup.py install for mozprocess 18:17:23 INFO - Running setup.py install for mozprofile 18:17:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:17:23 INFO - Running setup.py install for mozrunner 18:17:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:17:24 INFO - Running setup.py install for mozscreenshot 18:17:24 INFO - Running setup.py install for moztest 18:17:24 INFO - Running setup.py install for mozversion 18:17:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:17:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:17:24 INFO - Cleaning up... 18:17:24 INFO - Return code: 0 18:17:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:24 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')]} 18:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:24 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')]} 18:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:24 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 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:24 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 18:17:24 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 18:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:24 INFO - 'HOME': '/Users/cltbld', 18:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:24 INFO - 'LOGNAME': 'cltbld', 18:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:24 INFO - 'MOZ_NO_REMOTE': '1', 18:17:24 INFO - 'NO_EM_RESTART': '1', 18:17:24 INFO - 'PAGER': '/bin/cat', 18:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:24 INFO - 'PWD': '/builds/slave/test', 18:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:24 INFO - 'SHELL': '/bin/bash', 18:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:24 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:24 INFO - 'USER': 'cltbld', 18:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:24 INFO - 'XPC_FLAGS': '0x0', 18:17:24 INFO - 'XPC_SERVICE_NAME': '0', 18:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:24 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-TOwe_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:24 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)) 18:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-QK2SNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-gl6D2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:25 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)) 18:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-WseBCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-4Vs_ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:25 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)) 18:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-SJhbCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:25 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)) 18:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-U7eyZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:25 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)) 18:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-mMUxTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:25 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)) 18:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-QkOtga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:25 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)) 18:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-_LgLy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 18:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:25 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Qh9VCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:26 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)) 18:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:26 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-kGBfYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:26 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-0_biWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:26 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)) 18:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:26 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-oJMBwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:26 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-xgFcTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:26 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)) 18:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:26 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-aU8vrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:26 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-yYBX6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:26 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)) 18:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:17:26 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)) 18:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:17:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:17:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:29 INFO - Downloading blessings-1.6.tar.gz 18:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:17:29 INFO - Installing collected packages: blessings 18:17:29 INFO - Running setup.py install for blessings 18:17:29 INFO - Successfully installed blessings 18:17:29 INFO - Cleaning up... 18:17:29 INFO - Return code: 0 18:17:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:17:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:17:29 INFO - Reading from file tmpfile_stdout 18:17:29 INFO - Current package versions: 18:17:29 INFO - blessings == 1.6 18:17:29 INFO - blobuploader == 1.2.4 18:17:29 INFO - docopt == 0.6.1 18:17:29 INFO - functools32 == 3.2.3-2 18:17:29 INFO - jsonschema == 2.5.1 18:17:29 INFO - manifestparser == 1.1 18:17:29 INFO - mozInstall == 1.12 18:17:29 INFO - mozcrash == 0.17 18:17:29 INFO - mozdebug == 0.1 18:17:29 INFO - mozdevice == 0.48 18:17:29 INFO - mozfile == 1.2 18:17:29 INFO - mozhttpd == 0.7 18:17:29 INFO - mozinfo == 0.9 18:17:29 INFO - mozleak == 0.1 18:17:29 INFO - mozlog == 3.3 18:17:29 INFO - moznetwork == 0.27 18:17:29 INFO - mozprocess == 0.23 18:17:29 INFO - mozprofile == 0.28 18:17:29 INFO - mozrunner == 6.12 18:17:29 INFO - mozscreenshot == 0.1 18:17:29 INFO - mozsystemmonitor == 0.3 18:17:29 INFO - moztest == 0.7 18:17:29 INFO - mozversion == 1.4 18:17:29 INFO - psutil == 3.1.1 18:17:29 INFO - requests == 1.2.3 18:17:29 INFO - wsgiref == 0.1.2 18:17:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:29 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 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:29 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 18:17:29 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 18:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:29 INFO - 'HOME': '/Users/cltbld', 18:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:29 INFO - 'LOGNAME': 'cltbld', 18:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:29 INFO - 'MOZ_NO_REMOTE': '1', 18:17:29 INFO - 'NO_EM_RESTART': '1', 18:17:29 INFO - 'PAGER': '/bin/cat', 18:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:29 INFO - 'PWD': '/builds/slave/test', 18:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:29 INFO - 'SHELL': '/bin/bash', 18:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:29 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:29 INFO - 'USER': 'cltbld', 18:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:29 INFO - 'XPC_FLAGS': '0x0', 18:17:29 INFO - 'XPC_SERVICE_NAME': '0', 18:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:30 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)) 18:17:30 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)) 18:17:30 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)) 18:17:30 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)) 18:17:30 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)) 18:17:30 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)) 18:17:30 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)) 18:17:30 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)) 18:17:30 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)) 18:17:30 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)) 18:17:30 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)) 18:17:30 INFO - Cleaning up... 18:17:30 INFO - Return code: 0 18:17:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:17:30 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')]} 18:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:30 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')]} 18:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:30 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 0x10d74fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd631d125e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, '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.cA2XBYFggT/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.Q5J8A6AwNc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 18:17:30 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 18:17:30 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 18:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:30 INFO - 'HOME': '/Users/cltbld', 18:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:30 INFO - 'LOGNAME': 'cltbld', 18:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:30 INFO - 'MOZ_NO_REMOTE': '1', 18:17:30 INFO - 'NO_EM_RESTART': '1', 18:17:30 INFO - 'PAGER': '/bin/cat', 18:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:30 INFO - 'PWD': '/builds/slave/test', 18:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:30 INFO - 'SHELL': '/bin/bash', 18:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:17:30 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:30 INFO - 'USER': 'cltbld', 18:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:30 INFO - 'XPC_FLAGS': '0x0', 18:17:30 INFO - 'XPC_SERVICE_NAME': '0', 18:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:17:30 INFO - Cleaning up... 18:17:30 INFO - Return code: 0 18:17:30 INFO - Running post-action listener: _resource_record_post_action 18:17:30 INFO - Running post-action listener: _start_resource_monitoring 18:17:30 INFO - Starting resource monitoring. 18:17:30 INFO - [mozharness: 2016-08-12 01:17:30.330135Z] Finished create-virtualenv step (success) 18:17:30 INFO - [mozharness: 2016-08-12 01:17:30.331099Z] Running install step. 18:17:30 INFO - Running pre-action listener: _resource_record_pre_action 18:17:30 INFO - Running main action method: install 18:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:17:30 INFO - Reading from file tmpfile_stdout 18:17:30 INFO - Detecting whether we're running mozinstall >=1.0... 18:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:17:30 INFO - Reading from file tmpfile_stdout 18:17:30 INFO - Output received: 18:17:30 INFO - Usage: mozinstall [options] installer 18:17:30 INFO - Options: 18:17:30 INFO - -h, --help show this help message and exit 18:17:30 INFO - -d DEST, --destination=DEST 18:17:30 INFO - Directory to install application into. [default: 18:17:30 INFO - "/builds/slave/test"] 18:17:30 INFO - --app=APP Application being installed. [default: firefox] 18:17:30 INFO - mkdir: /builds/slave/test/build/application 18:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:18:04 INFO - Reading from file tmpfile_stdout 18:18:04 INFO - Output received: 18:18:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:18:04 INFO - Running post-action listener: _resource_record_post_action 18:18:04 INFO - [mozharness: 2016-08-12 01:18:04.416216Z] Finished install step (success) 18:18:04 INFO - [mozharness: 2016-08-12 01:18:04.416377Z] Running run-tests step. 18:18:04 INFO - Running pre-action listener: _resource_record_pre_action 18:18:04 INFO - Running main action method: run_tests 18:18:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:18:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:18:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:18:04 INFO - 2016-08-11 18:18:04.430 screenresolution[2076:23989] starting screenresolution argv=screenresolution get 18:18:04 INFO - 2016-08-11 18:18:04.433 screenresolution[2076:23989] Display 0: 1600x1200x32@60 18:18:04 INFO - 2016-08-11 18:18:04.439 screenresolution[2077:23991] starting screenresolution argv=screenresolution list 18:18:04 INFO - Available Modes on Display 0 18:18:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:18:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:18:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:18:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:18:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:18:04 INFO - Intel Iris: 18:18:04 INFO - Chipset Model: Intel Iris 18:18:04 INFO - Type: GPU 18:18:04 INFO - Bus: Built-In 18:18:04 INFO - VRAM (Dynamic, Max): 1536 MB 18:18:04 INFO - Vendor: Intel (0x8086) 18:18:04 INFO - Device ID: 0x0a2e 18:18:04 INFO - Revision ID: 0x0009 18:18:04 INFO - Displays: 18:18:04 INFO - Display: 18:18:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:18:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:18:04 INFO - Display Serial Number: 1600x1200 60 18:18:04 INFO - Main Display: Yes 18:18:04 INFO - Mirror: Off 18:18:04 INFO - Online: Yes 18:18:04 INFO - Rotation: Supported 18:18:04 INFO - Return code: 0 18:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:18:04 INFO - Python 2.7.3 18:18:04 INFO - Return code: 0 18:18:04 INFO - grabbing minidump binary from tooltool 18:18: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')]} 18:18:04 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 0x10d6b3d30>, '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 0x10d418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d6abb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:18:04 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 18:18:04 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 18:18:04 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 18:18:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:18:04 INFO - Return code: 0 18:18:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:18:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:18:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:18:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:18:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--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 18:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0026 --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 18:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cA2XBYFggT/Render', 18:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:04 INFO - 'HOME': '/Users/cltbld', 18:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:04 INFO - 'LOGNAME': 'cltbld', 18:18:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:18:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:04 INFO - 'MOZ_NO_REMOTE': '1', 18:18:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:18:04 INFO - 'NO_EM_RESTART': '1', 18:18:04 INFO - 'PAGER': '/bin/cat', 18:18:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:04 INFO - 'PWD': '/builds/slave/test', 18:18:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:04 INFO - 'SHELL': '/bin/bash', 18:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners', 18:18:04 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 18:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:04 INFO - 'USER': 'cltbld', 18:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:04 INFO - 'XPC_FLAGS': '0x0', 18:18:04 INFO - 'XPC_SERVICE_NAME': '0', 18:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--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 18:18:05 INFO - mozversion application_buildid: 20160811160617 18:18:05 INFO - mozversion application_changeset: d8dc463ad2af6a33780c45ddac7474be277bb83a 18:18:05 INFO - mozversion application_display_name: Nightly 18:18:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:18:05 INFO - mozversion application_name: Firefox 18:18:05 INFO - mozversion application_remotingname: firefox 18:18:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:18:05 INFO - mozversion application_vendor: Mozilla 18:18:05 INFO - mozversion application_version: 51.0a1 18:18:05 INFO - mozversion platform_buildid: 20160811160617 18:18:05 INFO - mozversion platform_changeset: d8dc463ad2af6a33780c45ddac7474be277bb83a 18:18:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:18:05 INFO - mozversion platform_version: 51.0a1 18:18:05 INFO - using testdate: 1470964685 18:18:05 INFO - actual date: 1470964685 18:18:05 INFO - starting webserver on 'localhost:49357' 18:18:05 INFO - SUITE-START | Running 2 tests 18:18:05 INFO - TEST-START | dromaeo_css 18:18:05 INFO - operating with platform_type : mac_ 18:18:05 INFO - Initialising browser for dromaeo_css test... 18:18:05 INFO - TEST-INFO | started process 2087 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp6u8DF4/profile http://localhost:49357/getInfo.html) 18:18:07 INFO - PROCESS | 2087 | __metrics Screen width/height:1600/1200 18:18:07 INFO - PROCESS | 2087 | colorDepth:24 18:18:07 INFO - PROCESS | 2087 | Browser inner width/height: 1024/689 18:18:07 INFO - PROCESS | 2087 | __metrics 18:18:08 ERROR - PROCESS | 2087 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 18:18:08 INFO - TEST-INFO | 2087: exit 0 18:18:08 INFO - Browser initialized. 18:18:08 INFO - Running cycle 1/1 for dromaeo_css test... 18:18:08 INFO - TEST-INFO | started process 2091 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp6u8DF4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:18:20 INFO - PROCESS | 2091 | RSS: Main: 254717952 18:18:20 INFO - PROCESS | 2091 | 18:19:10 INFO - PROCESS | 2091 | 1470964750549 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:21:22 INFO - PROCESS | 2091 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49357/tests/dromaeo/cssquery-ext.html) 18:21:24 INFO - PROCESS | 2091 | RSS: Main: 267296768 18:21:24 INFO - PROCESS | 2091 | 18:24:26 INFO - PROCESS | 2091 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-ext.html (next: http://localhost:49357/tests/dromaeo/cssquery-jquery.html) 18:24:27 INFO - PROCESS | 2091 | RSS: Main: 278052864 18:24:27 INFO - PROCESS | 2091 | 18:27:29 INFO - PROCESS | 2091 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49357/tests/dromaeo/cssquery-mootools.html) 18:27:30 INFO - PROCESS | 2091 | RSS: Main: 282193920 18:27:30 INFO - PROCESS | 2091 | 18:30:31 INFO - PROCESS | 2091 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49357/tests/dromaeo/cssquery-prototype.html) 18:30:32 INFO - PROCESS | 2091 | RSS: Main: 280686592 18:30:32 INFO - PROCESS | 2091 | 18:33:35 INFO - PROCESS | 2091 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49357/tests/dromaeo/cssquery-yui.html) 18:33:36 INFO - PROCESS | 2091 | RSS: Main: 285020160 18:33:36 INFO - PROCESS | 2091 | 18:36:38 INFO - PROCESS | 2091 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-yui.html (next: http://localhost:49357/tests/dromaeo/cssquery-dojo.html) 18:36:38 INFO - PROCESS | 2091 | RSS: Main: 286679040 18:36:38 INFO - PROCESS | 2091 | 18:36:38 INFO - PROCESS | 2091 | __start_tp_report 18:36:38 INFO - PROCESS | 2091 | _x_x_mozilla_page_load 18:36:38 INFO - PROCESS | 2091 | _x_x_mozilla_page_load_details 18:36:38 INFO - PROCESS | 2091 | |i|pagename|runs| 18:36:38 INFO - PROCESS | 2091 | |0;dojo.html;3364.461686130219;3388.8136152511615;3389.57630296213;3403.472461768426;3430.176757578182;5468.26178465907;5568.582356323277;5600;5630;5643.68959707216;4575.245084561052;5434.619576629638;5585.525230355424;5682.659040457575;5684.062129748597;5122.948770512303;5206.869828254305;5269.3676758789;5286.550643195333;5318.335208098988;4818.3495228144275;5038.571721403667;5226.843194704165;5233.607479439057;5266.104762190437;5156.458571849939;5175.974120129413;5180.818671346485;5195.844124676266;5212.270282160501;5145.6398052136365;5172.250023746573;5178.300929374524;5191.0656081905245;5223.764930578133;5027.069992051483;5049.939512702337;5098.490150984909;5116.872078198037;5144.3312369392115;3861.7449328968196;4772.880677983078;4904.264360345941;5411.863508663186;5731.455511726379;6173.228346456693;6186.072089186658;6209.844753881144;6228.252609686867;6237.719302631393;6072.696365181723;6123.908141377883;6274.2784579773;6283.465905398047;6312.021636646281;5787.623804452697;5841.824745257649;5862.5896187267335;5913.172155898178;5918.99377105893;5247.842564723064;6165.167702360213;6247.468965137923;6247.75008999644;6297.779577714849;5064.873378165576;5832.270966129234;5954.642721436641;6044.516438684895;6137.140800287963;6115.938840611537;6154.230721159855;6163.476104531165;6171.660558669317;6177.227846519185;6112.235970503687;6132.969335153296;6141.631502109888;6146.5390095743;6170.228721409824;5948.10778383246;5993.61041532299;6135.662538560333;6167.784127555514;6170.290416602058;4189.790510474464;4846.103470857903;4968.22992436173;4972.72650004246;4985.875353116128;4378.781060947003;4413.646908247332;4415.911681766347;4418.094290670405;4459.197344478025;5575.247341608912;5637.323521177403;5719.685417302088;5735.11105778605;5739.225204597409;6154.261488621394;6174.5060395168275;6185.690715464209;6266.373362663697;6277.937220627827;5700.087985922232;5720.656760594377;5728.684922329312;5736.569757268222;5765.538756899438;5268.2361057647195;5408.4050754416985;5415.187721841677;5415.296011518478;5417.83746487606;6208.161898143783;6239.220097487814;6248.343923888017;6325.746970121328;6371.936280637135;6184.103305020837;6203.16257305258;6265.8120256392385;6270.184875966095;6286.371362863677;6217.533684973555;6257.53068519872;6307.211598550181;6309.463695585927;6316.021016742412;6100.267967843887;6101.51991978472;6115;6157.96921015392;6174.66039367839;6156.78451254204;6194.163787888578;6197.225346831646;6210.192674952227;6261.8747625046335;5738.741756620879;6017.819465416044;6031.668258245839;6034.357875386352;6066.4540191382985;6003.87992240144;6021.217241758719;6028.367021462684;6034.788782392595;6058.454739073328;5324.387695414922;5894.410558944071;5899.233099697012;5928.347881733092;5931.555133364928;5878.059510478303;6058.969705151446;6096.268447786294;6115.327313995546;6212.006079027334;5818.650880947157;5847.94152058474;5879;5908.734106965283;5947.226860508106 18:36:38 INFO - PROCESS | 2091 | |1;ext.html;479.6139108018044;513.4121430961549;514.6268955007617;536.919462080688;546.5217934307481;38185;40829.18341633165;40949.59050409495;40974.59025409745;41012.58987410125;41211;41495.96260093499;41853.58146418535;41857.37213941786;41890.58109418905;9505.429674219551;9649.855252171223;9718.562664680107;9728.367656102366;9730.708078757649;9677.806443871119;9724.65963691271;9782.168515676176;9788.559514821814;9796.069373409551;8518.849955256053;9600.183984361338;9696;9789.706308810748;9804.31369804114;9716.854247186298;9726.270529710264;9778.559964801601;9795.90204097957;9817.803643927153;8517.787055323604;9618.326717129803;9685.70942871715;9697.981711920218;9718.222542196678;39511.01222469433;39717.80141099276;39835.40246896299;39974.80012599919;40287.7985610073;3452.2405070884365;3599.4960705501253;3621.655942685467;3709.851605935786;4279.037216626265;31054.378912421624;32109.357812843613;32658.510122348183;32707.18232044194;32712.018639440856;3202.1354234356595;3311.9006429807146;3329.6337402885665;3344.7993120412852;3365.5456513370873;375473;376919;379104;379265;379939;58697;58994.70502647459;59161.11258331128;59310.70344648249;59435.702821486484;61558.69220653868;62235.688821556505;62359.752804944554;62389.376106239266;62469;15939.36242550285;16225.837741622432;16372.426965056165;16389.754153687703;16462.012279263283;12873.03452241067;12970.675733106558;12975.805362919564;12982.935085324512;13000.869991299965;15230.543083707507;15743.370265189493;15781.28984195714;15785.447509337348;15851.762223566655;5746;6998.825029374307;7041.5422997505;7044.22513523512;7068.399186069241;839.1482645115213;912.6623149434619;958.1568219966385;987.4717026390847;1080.9729756755987;27136;28057.43885122286;30584.54123188154;30811.537826932614;30927.536086958713;1006.6929586476049;1046.3198920701604;1046.5238316565944;1052.1898138433364;1162.4885050577718;13833.377498012613;15848.41171091473;15854.44509442164;16014.359425623077;16161.919190403973;626082;632544;632584;634365;637716.8114159399;60238;60252;60658.69670651619;60749.48126296878;60945.39054609397;1335.3990704183157;1339.9933000334936;1361.149281698958;1366.1052010932856;1368.4047439363908;29835;30580.541291880196;30630;30892.691073088983;31138.377232455678;50452.49547504478;51115.23327150021;51793;51939;52058;626905;629298;631549;633568;634714.8264258833;26508;26750.331241719112;26937;26953.326166845985;27129.59305610378;41883.58116418797;48782.75608622076;49162.262566060825;49532;50034.24880129178;40291.395629065;40349.39475907922;40366.59633403629;40688.76341106615;41921.37117943172;39837;40218.39672404975;41008.384874226314;41324.58675413328;41442.792786035876;35041.948741537795;38175;38868.80565597154;39182.80408597939;40628.59371406367;34118.488222676184;34833.12917177091;35605.82197088998;35676.929692109275;35733.46399804057;21924.780752192273;25172.874135629205;25301.493970120126;26382.868085659447;26572.734272657028 18:36:38 INFO - PROCESS | 2091 | |2;jquery.html;350.6616115448592;362.3477740067877;374.515003071023;385.3276033321853;404.07870056271713;20294.797052029477;20768.89615551924;21401.892990535045;21447.14211431543;21566.460838479044;20869.895650521765;21272.36182914515;21411.464713382175;21606;21858.89070554649;5994.490468310199;6109.5723299369065;6181.629102253857;6240.968795156018;6252.155958945529;4291.8283268669375;6125.938740612582;6251;6728.697208625599;7029;6057.151998720183;7079;7086.2913708628985;7100.360967512923;7102.609356485391;4489.595936365727;5281.260623512711;6225.5019598432245;6251.656158911258;6275.843103922392;5293.073712100406;5736.4550367715;6031.909521357183;6085.574009819315;6126.5602583392865;13496.325183740773;13702.93148534251;13730.382132804049;13734.725305493834;21838;2187.3109970359287;2401.735809061002;2431.3070774829266;2447.645091461746;2955.9113226603235;10190.796184076276;10547.68356949285;13703.725925481534;15690.607734806721;16681.582960425963;1910.9331173409003;1954.882707037568;1962.2347284559035;1984.2459865251255;1984.7419835421304;128974;129519;130255;132787.33606332002;133596.33201834027;11191.608293709682;11547.76904461906;11742.00192983606;11750.647480575597;11924.642260732191;14817.77773333401;14828.258587070603;14852;14853.628659283604;14859.777103343458;8354.707585234488;8588.828223435496;8605.010423801343;8720.084391138966;8784.429012114193;8490.193431623986;8500.27747641445;8563.357748168788;8721.476711397336;8743.081976392386;4496.527864574238;4530.728156310566;4597.241455159928;4656.4645065817185;4688.929666055012;1648.4312912045325;1708.7864016997876;1846.1507706454913;1928.9421317360502;1970.231609672229;880.4541184465672;890.9331800115017;959.8080383923243;962.7159987803615;1044.8119338519139;21830;22142.778572214393;22186.112555497923;22339.77660223377;22371.10515579391;977.5307852230969;981.2640519610293;990.3414229537422;993.4933184076028;995.6913356859396;11264.324140551593;11527.13546484017;11703.23928944616;11782.941085294518;11941.64175074749;538433;598089;610880;619391;628870;106787;124682.75317246711;139859;139972;140082;866.6879923227633;933.262722449271;976.8192884316425;999.1607050077824;1013.3312014070679;18804.435866924014;20111;20453.79546204519;20469.488262793548;20528.692069619265;43129;44665;54793;56528.43471565396;56969.43030569641;81974;82358.58820705858;82945;84259.57870210856;85039.72440413522;7967.960160199394;8064.233897779702;8341.95829020851;8456.746297611082;8477.448965817202;11285.153613478858;11562.710932226762;12031.398430078287;13115.08194426381;13245.27151006704;11215.327080375204;11291.3789741565;11492.885071149181;11523.250988685708;11613.593524226611;9550.426974381558;10336.741581460523;10506.579736810441;10749.838752418877;11111.83332250033;10615.575376984834;11108.944455277671;11252.212345135762;11331.263467874562;11752.708221896648;6793.422559082534;6971.337722916315;8096.230858068474;8182.263596276363;8269.669213231644;10411.94794026025;10450.477476126316;10715.624953126604;11511.654650360502;11573.421328933351 18:36:38 INFO - PROCESS | 2091 | |3;mootools.html;1406.9296535173241;1523.3068953626098;1707.0611163859883;1842.530154810523;1864.1611274926286;7607.771766846997;7885.526868387887;8378.581070946451;8635.00697419797;8970.641174353026;7891.9210807891905;8590.312774978003;8674.696385626505;8886.822263554724;8908.777280567972;2323.883805809707;2342.355852140658;2343.121329501437;2346.6128088865316;2350.388898886293;2315.2128276386024;2337.6259798432257;2360.0323867214447;2368.336865677613;2377.3462297868136;2351.1653363056125;2358.5047140100514;2360.8819559022068;2372.8932198051043;2380.0836677879056;2037.8879161646103;2293.151533932439;2328.2433209207074;2330.9650355244685;2345.437095097173;2293.7591552886875;2326.174208156114;2337.439014636492;2339.181286549711;2348.4481146930457;8838.116188381175;8958.686445974425;9015.639374425014;9022.14289642483;9047.502387368691;5144.794208231666;5290.312259406252;5299.682019078868;5307.495787900143;5311.601629877775;3429.331280400323;3442.5524681791208;3526.717862570988;3527.1887465882733;3600.4059330210484;1028.2624141469955;1055.4195192644015;1069.5828626835541;1081.4376524207446;1084.9891501084967;11590.478428470655;11607.825882611767;11699.415029248503;11707.239029463057;11709.53161873524;4513.480949690762;4584.770761461914;4761.40482439695;4772.809087636456;4776.593989510861;9740.805183896282;9836.852447213298;9856.852147217798;9860.211183105333;9863.408195508293;5278.630495865253;5298.364196296392;5304.628675992643;5317;5338.145896656516;4370.519242883281;4705.835295764632;4808.35087263215;4856.514348565115;4878.02439512099;3806.6764325032127;3914.921701565953;3927.2930872443235;3947.5657677655436;4006.2187873364414;1273.1724379153438;1285.2930888011556;1288.5554483703295;1296.7730647136714;1304.5955753716378;722.8301349182938;822.4900561651694;950.3062764182185;977.3680379856554;980.774421882971;5779.133130030529;5998.25021872266;6039.969800150971;6140.5701600887505;6168.19813424261;385.0796596135239;436.23440861288867;440.26915320568344;443.3615593545284;450.95264997174826;3256.892656496755;3608.8556457741397;3882.456456096149;4040.91918161641;4121.690873184523;10592.993665601756;10878.347299161916;11088.833667494993;11100.222984391015;11109.277896936674;10665.573377064986;10957.726056848642;11035.39305338214;11064.391458469861;11082.944585277022;7472.663730132157;8326.29226515753;8331.083580806106;8378.53918034514;8478.576071196292;5068.569171620454;5688.459596338506;5909.497692696169;5910.970445147746;6120.54095942815;8521.488710677379;9835.803283934425;9910.15763660097;9937.354071985297;9990.700278991642;9984.950075249577;10115.241356898408;10147;10165.745856353651;10193.694189174337;7704.30661240491;7776.766696999099;7777.844443110993;7839.804004899923;7901.881471777813;8498.787530311793;8530.274926631306;8542.572871356531;8545.957270213608;8554.18735220153;7513;7624;7654.387648988179;7728.26581474759;7744.65149068282;7398.260173982559;7481.850362992818;7504;7527.548347099191;7572.7728168155045;7047.365737083482;7158.856822863618;7183.928160718326;7190.820229494305;7196.460265480215;4983.601311894967;5022.974885125551;5109.335786347751;5129.845784698413;5206.55744261742;5029.899402011866;5075.086484432837;5098.974505127599;5199.220116982483;5309.097453432849 18:36:38 INFO - PROCESS | 2091 | |4;prototype.html;271.6102393065952;310.7436364998876;312.2957730318132;313.4389442897214;318.2521075472638;5116.539511443969;5534.529564986976;5535;5537.556995440365;5605.915911261329;4216.3043097888885;5452.236686863842;5453.400125986138;5493.670379777206;5521.779128834852;3947.9802600986955;4088.5911408859024;4110.856120035799;4161.771102589357;4190.952261934516;4140.689448291375;4151.543330233671;4188.706790524665;4188.769617671027;4223.704340696153;4015.454050190683;4181.728187667807;4188.72773269738;4200;4200.894977625553;3718.40505519117;4050.392441133839;4172.395002724588;4183;4191.7484950902735;3066.2487690515745;3573.4639804029343;3591.838367273479;3594.537741646954;3605.369060414417;4153.73000754953;4651.534846515321;4892.996935628188;5333.706646134461;5398.568114550866;3710.4990826238654;4226.070264541796;4231.555686652888;4814.579698988771;4823.180059389877;4775.185429536754;5449.618526703132;5468.425815289417;5725.885482290331;5786.594938354317;3999.200159967989;4010.598940105995;4011.2178125265586;4025.698072644534;4030.133521292936;4831.487626434269;5856.80369861302;6080.844639518522;6087.4825639820665;6165.876682466325;3557.715382769424;3563.322968635951;3923.431102490123;4077.347624380085;4110.671146308288;3602.369585322558;3832.7508711934192;4141.171765646823;4158.502938941979;4180.456540649696;4789.377380940525;4819.734914579732;4837.443693975168;4839.6932828136205;4842.830500932521;5527.535203171163;5671.376148623648;5807.245058142499;5845.386234445407;6056.36408177135;1350.2851378748933;1386.3622733542481;1407.2189934586197;1410.3512384303335;1412.69627030189;755.928186822251;777.6034222546429;873.0222151190707;878.3017501086651;881.6297155194706;4585.67015565489;5250.369955605352;5307.787688492494;5445.90332721897;5490.1764735289225;5290.576753859682;5472.7263636818;5505.697186654773;5655.462731040545;5862.530997520188;5072.442031376546;5159.17453207485;5387.111126664135;5411.594130440233;5441.673499590037;2536.8731563421757;3131;3163.2724473371254;3163.920901977469;3194.105650417887;4658.161530924398;5168.3539557555305;5469.425710300441;6212.844678883064;6337;4629.259318509023;5476.534494568006;5950.404959504015;6111.877762444815;6197.287311959182;5187.974060129751;5898.20374249488;6187.659678717714;6201.56589038772;6203.906941395882;5769.884602308014;5907.940920590739;5911.207884255839;5976.223090998143;5977.372375900642;5536.307961504812;5794.768209271582;5881.735321910439;5933.554983376178;5986.844735895162;5510.724463776875;5930.762769489172;6159.384061593805;6159.907601386072;6178.351273116258;2942.4556457055514;3069.155982104939;3714.0900479382726;4044.483318755467;4099.569545197712;5200.349956255468;5595.300587426572;5669.953741170444;5730;5909.763609455574;4524.864254072383;4934.5312195341385;5763.579296695481;5795.942040579709;5799.623024503394;5334.546563541987;5739.253896993364;5830.358660547421;5836.299644042742;5852.268466441695;4575.58819706228;5663.603547751618;5731.914021289767;5756.107803290497;5773.393793651774;5547.722613869209;5587.161925711176;5743.4543718346695;5784.8553786154;5926.407359264039;4554.339620755039;5008.247113510242;5612.438756124355;5754.647657800498;5800.50695690871 18:36:38 INFO - PROCESS | 2091 | |5;yui.html;634.5938599296451;646.3601034975375;736.3630459652395;784.1922819495921;827.2761333832897;15045.849541504582;15376.61558461039;15579.376824927018;15642.765358519617;15740.763888541682;14135.151890886555;14137.010409271355;14137.222452765094;14229.288535573232;14240.501582444616;676.5230512488683;816.302061737215;832.604512856393;835.052215735142;840.4033136473112;832.8375966686499;844.6959094725894;850.4344610833788;855.110684887716;855.6064210463195;880.1990188928045;902.62992173209;909.8862642169729;910.4036855859423;910.4810258152822;895.6014573514789;898.8966268879032;901.1529162587148;901.9909800902005;902.251131560803;649.1431310669917;649.1755470552372;655.8229278094888;657.4608820766973;673.8618583190483;12271.447784849614;13964.301784910715;14366.066205696596;14487.985840991134;14571.522841117036;13272.734545309137;13710.971677124255;14826.703465930621;14879.776803347959;14885.925570372186;11980.58067967617;12245.08161887862;12523.624291271277;12532.561360352343;12612.747745045139;3825.827837747308;3826.655600995896;3984.4820173377566;4000.5399379071196;4023.376376661622;80958;85741;105899;109232.4538377311;109249;11332.150088743378;11343.376114313793;11435.656930292105;11449.427528623703;11551.884481155248;22752;22807.31578052661;22838.858057097077;22870.771292287198;22872.65691014636;8014.879776803353;8056.879146812803;8071.717489887943;8180.877286840702;8318.792030199293;4367.650587952956;4425.137098265872;4459.977700111479;4473.35136405226;4505.7296562206375;3643.890683279506;3690.2435000824767;3778.3576791945434;3781.0358358618378;3781.3949768036982;583.2854752927715;667.1627107979455;668.8261052126385;701.0780823217452;710.6269208665389;567.3645517020881;604.2778879239355;611.6483022262217;640.3820313397594;660.7291010685692;8769.561521923879;9188.678396256098;9293.442393456415;9315.72052838416;9360.204382627553;530.8938212357544;613.285522366447;615.1050221927086;658.5061204096927;659.8020593821931;4365.716228445141;4500.144972455223;4506.481754598263;4532.4334458192725;4586;123265.76734232543;142226;144223.557764421;145372;150126;131582;132747;132765;134431;134688;117743;118161;119587;120238.3988080054;124118;6915.446764258948;8524.829503410021;8569.785755356166;9014.2788576915;9104.089591040844;40418.59581404148;56338.718306408206;62183.756324872345;65893.67053164703;66633;68956;77695;85822;92369;96863.51568242113;13384.330783460982;13659.248741319323;13682.657933551742;13800.240986745424;14065.718685626434;14819.33313000896;15261.6184595386;15356.923215383851;15420.306086225923;15543.689126217638;14088.577342679737;14120.717585648024;14271.500497482539;14316.642083947985;14674.706505870035;14099.295035247991;14221.288935553388;14244.21656808844;14255.711072604592;14734.484293049692;13820;13876.58370248894;13907.235102069078;14471.348789304297;14491.637709057359;11499.425028748696;11781;11785.116116291483;11863.466144023712;11901.523939042341;9817.69284218194;10350.482475876326;10869.456527173768;11636.709082273012;11659.242149260272 18:36:38 INFO - PROCESS | 2091 | __end_tp_report 18:36:38 INFO - PROCESS | 2091 | __start_cc_report 18:36:38 INFO - PROCESS | 2091 | _x_x_mozilla_cycle_collect,409 18:36:38 INFO - PROCESS | 2091 | __end_cc_report 18:36:38 INFO - PROCESS | 2091 | __startTimestamp1470965798813__endTimestamp 18:36:38 INFO - PROCESS | 2091 | 18:36:38 INFO - PROCESS | 2091 | ------- Summary: start ------- 18:36:38 INFO - PROCESS | 2091 | Number of tests: 6 18:36:38 INFO - PROCESS | 2091 | 18:36:38 INFO - PROCESS | 2091 | [#0] dojo.html Cycles:165 Average:5658.80 Median:5894.41 stddev:662.28 (11.2%) stddev-sans-first:639.38 18:36:38 INFO - PROCESS | 2091 | Values: 3364.5 3388.8 3389.6 3403.5 3430.2 5468.3 5568.6 5600.0 5630.0 5643.7 4575.2 5434.6 5585.5 5682.7 5684.1 5122.9 5206.9 5269.4 5286.6 5318.3 4818.3 5038.6 5226.8 5233.6 5266.1 5156.5 5176.0 5180.8 5195.8 5212.3 5145.6 5172.3 5178.3 5191.1 5223.8 5027.1 5049.9 5098.5 5116.9 5144.3 3861.7 4772.9 4904.3 5411.9 5731.5 6173.2 6186.1 6209.8 6228.3 6237.7 6072.7 6123.9 6274.3 6283.5 6312.0 5787.6 5841.8 5862.6 5913.2 5919.0 5247.8 6165.2 6247.5 6247.8 6297.8 5064.9 5832.3 5954.6 6044.5 6137.1 6115.9 6154.2 6163.5 6171.7 6177.2 6112.2 6133.0 6141.6 6146.5 6170.2 5948.1 5993.6 6135.7 6167.8 6170.3 4189.8 4846.1 4968.2 4972.7 4985.9 4378.8 4413.6 4415.9 4418.1 4459.2 5575.2 5637.3 5719.7 5735.1 5739.2 6154.3 6174.5 6185.7 6266.4 6277.9 5700.1 5720.7 5728.7 5736.6 5765.5 5268.2 5408.4 5415.2 5415.3 5417.8 6208.2 6239.2 6248.3 6325.7 6371.9 6184.1 6203.2 6265.8 6270.2 6286.4 6217.5 6257.5 6307.2 6309.5 6316.0 6100.3 6101.5 6115.0 6158.0 6174.7 6156.8 6194.2 6197.2 6210.2 6261.9 5738.7 6017.8 6031.7 6034.4 6066.5 6003.9 6021.2 6028.4 6034.8 6058.5 5324.4 5894.4 5899.2 5928.3 5931.6 5878.1 6059.0 6096.3 6115.3 6212.0 5818.7 5847.9 5879.0 5908.7 5947.2 18:36:38 INFO - PROCESS | 2091 | 18:36:38 INFO - PROCESS | 2091 | [#1] ext.html Cycles:180 Average:68633.64 Median:40658.68 stddev:149930.64 (368.8%) stddev-sans-first:150263.91 18:36:38 INFO - PROCESS | 2091 | Values: 479.6 513.4 514.6 536.9 546.5 38185.0 40829.2 40949.6 40974.6 41012.6 41211.0 41496.0 41853.6 41857.4 41890.6 9505.4 9649.9 9718.6 9728.4 9730.7 9677.8 9724.7 9782.2 9788.6 9796.1 8518.8 9600.2 9696.0 9789.7 9804.3 9716.9 9726.3 9778.6 9795.9 9817.8 8517.8 9618.3 9685.7 9698.0 9718.2 39511.0 39717.8 39835.4 39974.8 40287.8 3452.2 3599.5 3621.7 3709.9 4279.0 31054.4 32109.4 32658.5 32707.2 32712.0 3202.1 3311.9 3329.6 3344.8 3365.5 375473.0 376919.0 379104.0 379265.0 379939.0 58697.0 58994.7 59161.1 59310.7 59435.7 61558.7 62235.7 62359.8 62389.4 62469.0 15939.4 16225.8 16372.4 16389.8 16462.0 12873.0 12970.7 12975.8 12982.9 13000.9 15230.5 15743.4 15781.3 15785.4 15851.8 5746.0 6998.8 7041.5 7044.2 7068.4 839.1 912.7 958.2 987.5 1081.0 27136.0 28057.4 30584.5 30811.5 30927.5 1006.7 1046.3 1046.5 1052.2 1162.5 13833.4 15848.4 15854.4 16014.4 16161.9 626082.0 632544.0 632584.0 634365.0 637716.8 60238.0 60252.0 60658.7 60749.5 60945.4 1335.4 1340.0 1361.1 1366.1 1368.4 29835.0 30580.5 30630.0 30892.7 31138.4 50452.5 51115.2 51793.0 51939.0 52058.0 626905.0 629298.0 631549.0 633568.0 634714.8 26508.0 26750.3 26937.0 26953.3 27129.6 41883.6 48782.8 49162.3 49532.0 50034.2 40291.4 40349.4 40366.6 40688.8 41921.4 39837.0 40218.4 41008.4 41324.6 41442.8 35041.9 38175.0 38868.8 39182.8 40628.6 34118.5 34833.1 35605.8 35676.9 35733.5 21924.8 25172.9 25301.5 26382.9 26572.7 18:36:38 INFO - PROCESS | 2091 | 18:36:38 INFO - PROCESS | 2091 | [#2] jquery.html Cycles:180 Average:35575.51 Median:22355.44 stddev:100584.95 (449.9%) stddev-sans-first:100832.34 18:36:38 INFO - PROCESS | 2091 | Values: 350.7 362.3 374.5 385.3 404.1 20294.8 20768.9 21401.9 21447.1 21566.5 20869.9 21272.4 21411.5 21606.0 21858.9 5994.5 6109.6 6181.6 6241.0 6252.2 4291.8 6125.9 6251.0 6728.7 7029.0 6057.2 7079.0 7086.3 7100.4 7102.6 4489.6 5281.3 6225.5 6251.7 6275.8 5293.1 5736.5 6031.9 6085.6 6126.6 13496.3 13702.9 13730.4 13734.7 21838.0 2187.3 2401.7 2431.3 2447.6 2955.9 10190.8 10547.7 13703.7 15690.6 16681.6 1910.9 1954.9 1962.2 1984.2 1984.7 128974.0 129519.0 130255.0 132787.3 133596.3 11191.6 11547.8 11742.0 11750.6 11924.6 14817.8 14828.3 14852.0 14853.6 14859.8 8354.7 8588.8 8605.0 8720.1 8784.4 8490.2 8500.3 8563.4 8721.5 8743.1 4496.5 4530.7 4597.2 4656.5 4688.9 1648.4 1708.8 1846.2 1928.9 1970.2 880.5 890.9 959.8 962.7 1044.8 21830.0 22142.8 22186.1 22339.8 22371.1 977.5 981.3 990.3 993.5 995.7 11264.3 11527.1 11703.2 11782.9 11941.6 538433.0 598089.0 610880.0 619391.0 628870.0 106787.0 124682.8 139859.0 139972.0 140082.0 866.7 933.3 976.8 999.2 1013.3 18804.4 20111.0 20453.8 20469.5 20528.7 43129.0 44665.0 54793.0 56528.4 56969.4 81974.0 82358.6 82945.0 84259.6 85039.7 7968.0 8064.2 8342.0 8456.7 8477.4 11285.2 11562.7 12031.4 13115.1 13245.3 11215.3 11291.4 11492.9 11523.3 11613.6 9550.4 10336.7 10506.6 10749.8 11111.8 10615.6 11108.9 11252.2 11331.3 11752.7 6793.4 6971.3 8096.2 8182.3 8269.7 10411.9 10450.5 10715.6 11511.7 11573.4 18:36:38 INFO - PROCESS | 2091 | 18:36:38 INFO - PROCESS | 2091 | [#3] mootools.html Cycles:180 Average:5723.81 Median:5026.44 stddev:3247.53 (64.6%) stddev-sans-first:3240.43 18:36:38 INFO - PROCESS | 2091 | Values: 1406.9 1523.3 1707.1 1842.5 1864.2 7607.8 7885.5 8378.6 8635.0 8970.6 7891.9 8590.3 8674.7 8886.8 8908.8 2323.9 2342.4 2343.1 2346.6 2350.4 2315.2 2337.6 2360.0 2368.3 2377.3 2351.2 2358.5 2360.9 2372.9 2380.1 2037.9 2293.2 2328.2 2331.0 2345.4 2293.8 2326.2 2337.4 2339.2 2348.4 8838.1 8958.7 9015.6 9022.1 9047.5 5144.8 5290.3 5299.7 5307.5 5311.6 3429.3 3442.6 3526.7 3527.2 3600.4 1028.3 1055.4 1069.6 1081.4 1085.0 11590.5 11607.8 11699.4 11707.2 11709.5 4513.5 4584.8 4761.4 4772.8 4776.6 9740.8 9836.9 9856.9 9860.2 9863.4 5278.6 5298.4 5304.6 5317.0 5338.1 4370.5 4705.8 4808.4 4856.5 4878.0 3806.7 3914.9 3927.3 3947.6 4006.2 1273.2 1285.3 1288.6 1296.8 1304.6 722.8 822.5 950.3 977.4 980.8 5779.1 5998.3 6040.0 6140.6 6168.2 385.1 436.2 440.3 443.4 451.0 3256.9 3608.9 3882.5 4040.9 4121.7 10593.0 10878.3 11088.8 11100.2 11109.3 10665.6 10957.7 11035.4 11064.4 11082.9 7472.7 8326.3 8331.1 8378.5 8478.6 5068.6 5688.5 5909.5 5911.0 6120.5 8521.5 9835.8 9910.2 9937.4 9990.7 9985.0 10115.2 10147.0 10165.7 10193.7 7704.3 7776.8 7777.8 7839.8 7901.9 8498.8 8530.3 8542.6 8546.0 8554.2 7513.0 7624.0 7654.4 7728.3 7744.7 7398.3 7481.9 7504.0 7527.5 7572.8 7047.4 7158.9 7183.9 7190.8 7196.5 4983.6 5023.0 5109.3 5129.8 5206.6 5029.9 5075.1 5099.0 5199.2 5309.1 18:36:38 INFO - PROCESS | 2091 | 18:36:38 INFO - PROCESS | 2091 | [#4] prototype.html Cycles:180 Average:4639.01 Median:5270.47 stddev:1443.13 (27.4%) stddev-sans-first:1409.45 18:36:38 INFO - PROCESS | 2091 | Values: 271.6 310.7 312.3 313.4 318.3 5116.5 5534.5 5535.0 5537.6 5605.9 4216.3 5452.2 5453.4 5493.7 5521.8 3948.0 4088.6 4110.9 4161.8 4191.0 4140.7 4151.5 4188.7 4188.8 4223.7 4015.5 4181.7 4188.7 4200.0 4200.9 3718.4 4050.4 4172.4 4183.0 4191.7 3066.2 3573.5 3591.8 3594.5 3605.4 4153.7 4651.5 4893.0 5333.7 5398.6 3710.5 4226.1 4231.6 4814.6 4823.2 4775.2 5449.6 5468.4 5725.9 5786.6 3999.2 4010.6 4011.2 4025.7 4030.1 4831.5 5856.8 6080.8 6087.5 6165.9 3557.7 3563.3 3923.4 4077.3 4110.7 3602.4 3832.8 4141.2 4158.5 4180.5 4789.4 4819.7 4837.4 4839.7 4842.8 5527.5 5671.4 5807.2 5845.4 6056.4 1350.3 1386.4 1407.2 1410.4 1412.7 755.9 777.6 873.0 878.3 881.6 4585.7 5250.4 5307.8 5445.9 5490.2 5290.6 5472.7 5505.7 5655.5 5862.5 5072.4 5159.2 5387.1 5411.6 5441.7 2536.9 3131.0 3163.3 3163.9 3194.1 4658.2 5168.4 5469.4 6212.8 6337.0 4629.3 5476.5 5950.4 6111.9 6197.3 5188.0 5898.2 6187.7 6201.6 6203.9 5769.9 5907.9 5911.2 5976.2 5977.4 5536.3 5794.8 5881.7 5933.6 5986.8 5510.7 5930.8 6159.4 6159.9 6178.4 2942.5 3069.2 3714.1 4044.5 4099.6 5200.3 5595.3 5670.0 5730.0 5909.8 4524.9 4934.5 5763.6 5795.9 5799.6 5334.5 5739.3 5830.4 5836.3 5852.3 4575.6 5663.6 5731.9 5756.1 5773.4 5547.7 5587.2 5743.5 5784.9 5926.4 4554.3 5008.2 5612.4 5754.6 5800.5 18:36:38 INFO - PROCESS | 2091 | 18:36:38 INFO - PROCESS | 2091 | [#5] yui.html Cycles:180 Average:24529.71 Median:3781.22 stddev:38910.78 (1029.1%) stddev-sans-first:38978.57 18:36:38 INFO - PROCESS | 2091 | Values: 634.6 646.4 736.4 784.2 827.3 15045.8 15376.6 15579.4 15642.8 15740.8 14135.2 14137.0 14137.2 14229.3 14240.5 676.5 816.3 832.6 835.1 840.4 832.8 844.7 850.4 855.1 855.6 880.2 902.6 909.9 910.4 910.5 895.6 898.9 901.2 902.0 902.3 649.1 649.2 655.8 657.5 673.9 12271.4 13964.3 14366.1 14488.0 14571.5 13272.7 13711.0 14826.7 14879.8 14885.9 11980.6 12245.1 12523.6 12532.6 12612.7 3825.8 3826.7 3984.5 4000.5 4023.4 80958.0 85741.0 105899.0 109232.5 109249.0 11332.2 11343.4 11435.7 11449.4 11551.9 22752.0 22807.3 22838.9 22870.8 22872.7 8014.9 8056.9 8071.7 8180.9 8318.8 4367.7 4425.1 4460.0 4473.4 4505.7 3643.9 3690.2 3778.4 3781.0 3781.4 583.3 667.2 668.8 701.1 710.6 567.4 604.3 611.6 640.4 660.7 8769.6 9188.7 9293.4 9315.7 9360.2 530.9 613.3 615.1 658.5 659.8 4365.7 4500.1 4506.5 4532.4 4586.0 123265.8 142226.0 144223.6 145372.0 150126.0 131582.0 132747.0 132765.0 134431.0 134688.0 117743.0 118161.0 119587.0 120238.4 124118.0 6915.4 8524.8 8569.8 9014.3 9104.1 40418.6 56338.7 62183.8 65893.7 66633.0 68956.0 77695.0 85822.0 92369.0 96863.5 13384.3 13659.2 13682.7 13800.2 14065.7 14819.3 15261.6 15356.9 15420.3 15543.7 14088.6 14120.7 14271.5 14316.6 14674.7 14099.3 14221.3 14244.2 14255.7 14734.5 13820.0 13876.6 13907.2 14471.3 14491.6 11499.4 11781.0 11785.1 11863.5 11901.5 9817.7 10350.5 10869.5 11636.7 11659.2 18:36:38 INFO - PROCESS | 2091 | -------- Summary: end -------- 18:36:38 INFO - PROCESS | 2091 | 18:36:39 INFO - TEST-INFO | 2091: exit 0 18:36:39 INFO - TEST-OK | dromaeo_css | took 1114457ms 18:36:39 INFO - TEST-START | kraken 18:36:39 INFO - operating with platform_type : mac_ 18:36:39 INFO - Initialising browser for kraken test... 18:36:39 INFO - TEST-INFO | started process 2099 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpyK2Jnm/profile http://localhost:49357/getInfo.html) 18:36:42 INFO - PROCESS | 2099 | __metrics Screen width/height:1600/1200 18:36:42 INFO - PROCESS | 2099 | colorDepth:24 18:36:42 INFO - PROCESS | 2099 | Browser inner width/height: 1024/689 18:36:42 INFO - PROCESS | 2099 | __metrics 18:36:42 ERROR - PROCESS | 2099 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 18:36:42 INFO - TEST-INFO | 2099: exit 0 18:36:42 INFO - Browser initialized. 18:36:42 INFO - Running cycle 1/1 for kraken test... 18:36:42 INFO - TEST-INFO | started process 2101 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpyK2Jnm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:36:55 INFO - PROCESS | 2101 | RSS: Main: 254722048 18:36:55 INFO - PROCESS | 2101 | 18:37:44 INFO - PROCESS | 2101 | 1470965864760 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:36:42 INFO - Terminating psutil.Process(pid=2101, name='firefox') 19:36:42 INFO - PROCESS | 2101 | [Child 2102] ###!!! ABORT: Aborting on channel error.: file /builds/slave/autoland-m64-00000000000000000/build/src/ipc/glue/MessageChannel.cpp, line 2057 19:36:42 INFO - PROCESS | 2101 | [Child 2102] ###!!! ABORT: Aborting on channel error.: file /builds/slave/autoland-m64-00000000000000000/build/src/ipc/glue/MessageChannel.cpp, line 2057 19:36:42 INFO - TEST-UNEXPECTED-ERROR | kraken | timeout 19:36:42 ERROR - Traceback (most recent call last): 19:36:42 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 19:36:42 INFO - talos_results.add(mytest.runTest(browser_config, test)) 19:36:42 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 19:36:42 INFO - return self._runTest(browser_config, test_config, setup) 19:36:42 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 174, in _runTest 19:36:42 INFO - if counter_management else None), 19:36:42 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 117, in run_browser 19:36:42 INFO - raise TalosError("timeout") 19:36:42 INFO - TalosError: timeout 19:36:42 INFO - TEST-INFO took 3603447ms 19:36:42 INFO - SUITE-END | took 4717s 19:36:43 ERROR - Return code: 2 19:36:43 WARNING - setting return code to 2 19:36:43 ERROR - # TBPL FAILURE # 19:36:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:36:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:36:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:36:43 INFO - 2016-08-11 19:36:43.288 screenresolution[2109:28667] starting screenresolution argv=screenresolution get 19:36:43 INFO - 2016-08-11 19:36:43.291 screenresolution[2109:28667] Display 0: 1600x1200x32@60 19:36:43 INFO - 2016-08-11 19:36:43.299 screenresolution[2110:28670] starting screenresolution argv=screenresolution list 19:36:43 INFO - Available Modes on Display 0 19:36:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:36:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:36:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:36:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:36:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:36:43 INFO - Intel Iris: 19:36:43 INFO - Chipset Model: Intel Iris 19:36:43 INFO - Type: GPU 19:36:43 INFO - Bus: Built-In 19:36:43 INFO - VRAM (Dynamic, Max): 1536 MB 19:36:43 INFO - Vendor: Intel (0x8086) 19:36:43 INFO - Device ID: 0x0a2e 19:36:43 INFO - Revision ID: 0x0009 19:36:43 INFO - Displays: 19:36:43 INFO - Display: 19:36:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:36:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:36:43 INFO - Display Serial Number: 1600x1200 60 19:36:43 INFO - Main Display: Yes 19:36:43 INFO - Mirror: Off 19:36:43 INFO - Online: Yes 19:36:43 INFO - Rotation: Supported 19:36:43 INFO - Return code: 0 19:36:43 INFO - Running post-action listener: _resource_record_post_action 19:36:43 INFO - [mozharness: 2016-08-12 02:36:43.571271Z] Finished run-tests step (success) 19:36:43 INFO - Running post-run listener: _resource_record_post_run 19:36:43 INFO - Total resource usage - Wall time: 4753s; CPU: 41.0%; Read bytes: 30436352; Write bytes: 518221312; Read time: 288; Write time: 1524 19:36:43 INFO - TinderboxPrint: CPU usage
41.2% 19:36:43 INFO - TinderboxPrint: I/O read bytes / time
30,436,352 / 288 19:36:43 INFO - TinderboxPrint: I/O write bytes / time
518,221,312 / 1,524 19:36:43 INFO - TinderboxPrint: CPU idle
11,178.8 (58.8%) 19:36:43 INFO - TinderboxPrint: CPU system
4,256.5 (22.4%) 19:36:43 INFO - TinderboxPrint: CPU user
3,574.6 (18.8%) 19:36:43 INFO - TinderboxPrint: Swap in / out
429,256,704 / 0 19:36:43 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404775424; Write bytes: 408518656; Read time: 29789; Write time: 802 19:36:43 INFO - run-tests - Wall time: 4719s; CPU: 41.0%; Read bytes: 28507136; Write bytes: 108142080; Read time: 276; Write time: 717 19:36:45 INFO - Running post-run listener: _upload_blobber_files 19:36:45 INFO - Blob upload gear active. 19:36:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:36:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:36:46 INFO - (blobuploader) - INFO - Open directory for files ... 19:36:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:36:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:36:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:46 INFO - (blobuploader) - INFO - Done attempting. 19:36:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:36:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:36:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:47 INFO - (blobuploader) - INFO - Done attempting. 19:36:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:36:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:36:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:51 INFO - (blobuploader) - INFO - Done attempting. 19:36:51 INFO - (blobuploader) - INFO - Iteration through files over. 19:36:51 INFO - Return code: 0 19:36:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:36:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:36:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e44559ba8670a5b198e0f68befce8c03c5d73241ae3b20f0b24bb36493d490a9c9b5edc220ff93e1dbdc95fbdc87daa474058be7766c9532932b011709527bc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/847b1de9ebf2f500af315452515b9a96f9d6d5f09d259e3ed78e7db845e62fbce0b21123b60bbdde6cebc45f897c5e4d5063b50e91480ad934fa26de039bb907", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/63c1b25d1cdf322612c9475812d296cd3ac402be3978d589802b1f629a01d5211fb580914edd47f2f80428c07fc85146d82092825bb1e532c8387a5064097aad"} 19:36:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:36:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:36:51 INFO - Contents: 19:36:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e44559ba8670a5b198e0f68befce8c03c5d73241ae3b20f0b24bb36493d490a9c9b5edc220ff93e1dbdc95fbdc87daa474058be7766c9532932b011709527bc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/847b1de9ebf2f500af315452515b9a96f9d6d5f09d259e3ed78e7db845e62fbce0b21123b60bbdde6cebc45f897c5e4d5063b50e91480ad934fa26de039bb907", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/63c1b25d1cdf322612c9475812d296cd3ac402be3978d589802b1f629a01d5211fb580914edd47f2f80428c07fc85146d82092825bb1e532c8387a5064097aad"} 19:36:51 INFO - Running post-run listener: copy_logs_to_upload_dir 19:36:51 INFO - Copying logs to upload dir... 19:36:51 INFO - mkdir: /builds/slave/test/build/upload/logs 19:36:51 INFO - Copying logs to upload dir... 19:36:51 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=4815.174730 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 1 hrs, 20 mins, 15 secs) (at 2016-08-11 19:36:52.016822) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 19:36:52.020087) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cA2XBYFggT/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Q5J8A6AwNc/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e44559ba8670a5b198e0f68befce8c03c5d73241ae3b20f0b24bb36493d490a9c9b5edc220ff93e1dbdc95fbdc87daa474058be7766c9532932b011709527bc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/847b1de9ebf2f500af315452515b9a96f9d6d5f09d259e3ed78e7db845e62fbce0b21123b60bbdde6cebc45f897c5e4d5063b50e91480ad934fa26de039bb907", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/63c1b25d1cdf322612c9475812d296cd3ac402be3978d589802b1f629a01d5211fb580914edd47f2f80428c07fc85146d82092825bb1e532c8387a5064097aad"} build_url:https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010878 build_url: 'https://queue.taskcluster.net/v1/task/Vs3ME_S9TzSDIQAEcqfNjA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e44559ba8670a5b198e0f68befce8c03c5d73241ae3b20f0b24bb36493d490a9c9b5edc220ff93e1dbdc95fbdc87daa474058be7766c9532932b011709527bc8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/847b1de9ebf2f500af315452515b9a96f9d6d5f09d259e3ed78e7db845e62fbce0b21123b60bbdde6cebc45f897c5e4d5063b50e91480ad934fa26de039bb907", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/63c1b25d1cdf322612c9475812d296cd3ac402be3978d589802b1f629a01d5211fb580914edd47f2f80428c07fc85146d82092825bb1e532c8387a5064097aad"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 19:36:52.055685) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 19:36:52.055971) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cA2XBYFggT/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.Q5J8A6AwNc/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.005143 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 19:36:52.106972) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 19:36:52.107681) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 19:36:52.108289) ========= ========= Total master_lag: 0.18 =========