builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0074 starttime: 1468506258.86 results: success (0) buildid: 20160714024642 builduid: 612e797e353f43d18a53ee170858e551 revision: 08f8a5aacd8308a73f6040fe522be7ba38497561 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:18.862015) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:18.862508) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:18.862791) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:19.020303) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:19.020643) ========= 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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-07-14 07:24:19-- 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% 14.7M=0.001s 2016-07-14 07:24:19 (14.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131480 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:19.173964) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:19.174265) ========= 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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.028156 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:19.222468) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-14 07:24:19.222780) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 08f8a5aacd8308a73f6040fe522be7ba38497561 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 08f8a5aacd8308a73f6040fe522be7ba38497561 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-07-14 07:24:19,297 truncating revision to first 12 chars 2016-07-14 07:24:19,297 Setting DEBUG logging. 2016-07-14 07:24:19,297 attempt 1/10 2016-07-14 07:24:19,298 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/08f8a5aacd83?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-14 07:24:21,051 unpacking tar archive at: mozilla-central-08f8a5aacd83/testing/mozharness/ program finished with exit code 0 elapsedTime=2.011280 ========= master_lag: 0.17 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-14 07:24:21.399688) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:21.400142) ========= script_repo_revision: 08f8a5aacd8308a73f6040fe522be7ba38497561 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:21.400633) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:21.401085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 07:24:21.421644) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-14 07:24:21.421922) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:24:21 INFO - MultiFileLogger online at 20160714 07:24:21 in /builds/slave/test 07:24:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:24:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:24:21 INFO - {'append_to_log': False, 07:24:21 INFO - 'base_work_dir': '/builds/slave/test', 07:24:21 INFO - 'blob_upload_branch': 'Firefox', 07:24:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:24:21 INFO - 'branch': 'Firefox', 07:24:21 INFO - 'buildbot_json_path': 'buildprops.json', 07:24:21 INFO - 'config_files': ('talos/mac_config.py',), 07:24:21 INFO - 'default_actions': ('clobber', 07:24:21 INFO - 'read-buildbot-config', 07:24:21 INFO - 'download-and-extract', 07:24:21 INFO - 'populate-webroot', 07:24:21 INFO - 'create-virtualenv', 07:24:21 INFO - 'install', 07:24:21 INFO - 'run-tests'), 07:24:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:24:21 INFO - 'download_minidump_stackwalk': True, 07:24:21 INFO - 'download_symbols': 'ondemand', 07:24:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:24:21 INFO - 'tooltool.py': '/tools/tooltool.py', 07:24:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:24:21 INFO - '/tools/misc-python/virtualenv.py')}, 07:24:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:24:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:24:21 INFO - 'installer_path': 'installer.exe', 07:24:21 INFO - 'log_level': 'info', 07:24:21 INFO - 'log_name': 'talos', 07:24:21 INFO - 'log_to_console': True, 07:24:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:24:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:24:21 INFO - 'opt_config_files': (), 07:24:21 INFO - 'pip_index': False, 07:24:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:24:21 INFO - 'cmd': ('bash', 07:24:21 INFO - '-c', 07:24:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:24:21 INFO - 'enabled': True, 07:24:21 INFO - 'halt_on_failure': False, 07:24:21 INFO - 'name': 'check_screen_resolution'},), 07:24:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:24:21 INFO - 'cmd': ('bash', 07:24:21 INFO - '-c', 07:24:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:24:21 INFO - 'enabled': True, 07:24:21 INFO - 'halt_on_failure': False, 07:24:21 INFO - 'name': 'check_screen_resolution'},), 07:24:21 INFO - 'run_cmd_checks_enabled': True, 07:24:21 INFO - 'sps_profile': False, 07:24:21 INFO - 'sps_profile_interval': 0, 07:24:21 INFO - 'suite': 'dromaeojs', 07:24:21 INFO - 'system_bits': '32', 07:24:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:24:21 INFO - 'title': 't-yosemite-r7-0074', 07:24:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:24:21 INFO - 'use_talos_json': True, 07:24:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:24:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:24:21 INFO - 'work_dir': 'build'} 07:24:21 INFO - ##### 07:24:21 INFO - ##### Running clobber step. 07:24:21 INFO - ##### 07:24:21 INFO - Running pre-action listener: _resource_record_pre_action 07:24:21 INFO - Running main action method: clobber 07:24:21 INFO - rmtree: /builds/slave/test/build 07:24:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:24:23 INFO - Running post-action listener: _resource_record_post_action 07:24:23 INFO - ##### 07:24:23 INFO - ##### Finished clobber step (success) 07:24:23 INFO - ##### 07:24:23 INFO - ##### 07:24:23 INFO - ##### Running read-buildbot-config step. 07:24:23 INFO - ##### 07:24:23 INFO - Running pre-action listener: _resource_record_pre_action 07:24:23 INFO - Running main action method: read_buildbot_config 07:24:23 INFO - Using buildbot properties: 07:24:23 INFO - { 07:24:23 INFO - "project": "", 07:24:23 INFO - "product": "firefox", 07:24:23 INFO - "script_repo_revision": "production", 07:24:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:24:23 INFO - "repository": "", 07:24:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:24:23 INFO - "buildid": "20160714024642", 07:24:23 INFO - "pgo_build": "False", 07:24:23 INFO - "basedir": "/builds/slave/test", 07:24:23 INFO - "buildnumber": 294, 07:24:23 INFO - "slavename": "t-yosemite-r7-0074", 07:24:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:24:23 INFO - "platform": "yosemite_r7", 07:24:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:24:23 INFO - "branch": "mozilla-central", 07:24:23 INFO - "repo_path": "mozilla-central", 07:24:23 INFO - "revision": "08f8a5aacd8308a73f6040fe522be7ba38497561", 07:24:23 INFO - "stage_platform": "macosx64", 07:24:23 INFO - "builduid": "612e797e353f43d18a53ee170858e551", 07:24:23 INFO - "slavebuilddir": "test" 07:24:23 INFO - } 07:24:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 07:24:23 INFO - Running post-action listener: _resource_record_post_action 07:24:23 INFO - ##### 07:24:23 INFO - ##### Finished read-buildbot-config step (success) 07:24:23 INFO - ##### 07:24:23 INFO - ##### 07:24:23 INFO - ##### Running download-and-extract step. 07:24:23 INFO - ##### 07:24:23 INFO - Running pre-action listener: _resource_record_pre_action 07:24:23 INFO - Running main action method: download_and_extract 07:24:23 INFO - mkdir: /builds/slave/test/build/tests 07:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:23 INFO - https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:24:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:24:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:24:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 07:24:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 07:24:27 INFO - Downloaded 1199 bytes. 07:24:27 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 07:24:27 INFO - Using the following test package requirements: 07:24:27 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:24:27 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:24:27 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 07:24:27 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:24:27 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 07:24:27 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:24:27 INFO - u'jsshell-mac.zip'], 07:24:27 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:24:27 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 07:24:27 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:24:27 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:24:27 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 07:24:27 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:24:27 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 07:24:27 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:24:27 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 07:24:27 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:24:27 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 07:24:27 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:27 INFO - https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:24:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:24:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:24:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:24:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:24:28 INFO - Downloaded 18827991 bytes. 07:24:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:24:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:24:32 INFO - Return code: 0 07:24:32 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:32 INFO - https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:24:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:24:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:24:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:24:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:24:39 INFO - Downloaded 18827991 bytes. 07:24:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:24:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:24:42 INFO - Return code: 0 07:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:42 INFO - https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:24:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:24:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:24:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:24:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:24:43 INFO - Downloaded 14403317 bytes. 07:24:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:24:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:24:44 INFO - Return code: 0 07:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:44 INFO - https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:24:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:24:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:24:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 07:24:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 07:24:46 INFO - Downloaded 112756486 bytes. 07:24:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:24:46 INFO - mkdir: /builds/slave/test/properties 07:24:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:24:46 INFO - Writing to file /builds/slave/test/properties/build_url 07:24:46 INFO - Contents: 07:24:46 INFO - build_url:https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:24:48 INFO - Running post-action listener: _resource_record_post_action 07:24:48 INFO - Running post-action listener: set_extra_try_arguments 07:24:48 INFO - ##### 07:24:48 INFO - ##### Finished download-and-extract step (success) 07:24:48 INFO - ##### 07:24:48 INFO - ##### 07:24:48 INFO - ##### Running populate-webroot step. 07:24:48 INFO - ##### 07:24:48 INFO - Running pre-action listener: _resource_record_pre_action 07:24:48 INFO - Running main action method: populate_webroot 07:24:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:24:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:24:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:24:48 INFO - '--tppagecycles', 07:24:48 INFO - '7'], 07:24:48 INFO - 'tests': ['tsvgm']}}, 07:24:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:24:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:24:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:24:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:24:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:48 INFO - 'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:48 INFO - 'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['damp', 'tps']}, 07:24:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:48 INFO - 'tests': ['damp', 'tps']}, 07:24:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['dromaeo_dom']}, 07:24:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:24:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['basic_compositor_video']}, 07:24:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:24:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other-e10s': {'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['tsvgx', 07:24:48 INFO - 'tsvgr_opacity', 07:24:48 INFO - 'tart', 07:24:48 INFO - 'tscrollx', 07:24:48 INFO - 'cart']}, 07:24:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:24:48 INFO - 'tsvgr_opacity', 07:24:48 INFO - 'tart', 07:24:48 INFO - 'tscrollx', 07:24:48 INFO - 'cart']}, 07:24:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:48 INFO - 'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['tp5o']}, 07:24:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:48 INFO - 'tests': ['tp5o']}, 07:24:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:48 INFO - 'talos_options': ['--disable-e10s', 07:24:48 INFO - '--xperf_path', 07:24:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:48 INFO - 'tests': ['tp5n']}, 07:24:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:48 INFO - 'talos_options': ['--xperf_path', 07:24:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:48 INFO - 'tests': ['tp5n']}}, 07:24:48 INFO - 'talos.zip': {'path': '', 07:24:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:24:48 INFO - Running post-action listener: _resource_record_post_action 07:24:48 INFO - ##### 07:24:48 INFO - ##### Finished populate-webroot step (success) 07:24:48 INFO - ##### 07:24:48 INFO - ##### 07:24:48 INFO - ##### Running create-virtualenv step. 07:24:48 INFO - ##### 07:24:48 INFO - Running pre-action listener: _resource_record_pre_action 07:24:48 INFO - Running main action method: create_virtualenv 07:24:48 INFO - Creating virtualenv /builds/slave/test/build/venv 07:24:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:24:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:24:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:24:48 INFO - Using real prefix '/tools/python27' 07:24:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:24:48 INFO - Installing distribute.............................................................................................................................................................................................done. 07:24:51 INFO - Installing pip.................done. 07:24:51 INFO - Return code: 0 07:24:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:51 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 0x10ee9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef31618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a14c51e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, '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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:24:51 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 07:24:51 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 07:24:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:24:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:51 INFO - 'HOME': '/Users/cltbld', 07:24:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:51 INFO - 'LOGNAME': 'cltbld', 07:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:51 INFO - 'MOZ_NO_REMOTE': '1', 07:24:51 INFO - 'NO_EM_RESTART': '1', 07:24:51 INFO - 'PAGER': '/bin/cat', 07:24:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:51 INFO - 'PWD': '/builds/slave/test', 07:24:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:51 INFO - 'SHELL': '/bin/bash', 07:24:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:24:51 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:24:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:51 INFO - 'USER': 'cltbld', 07:24:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:51 INFO - 'XPC_FLAGS': '0x0', 07:24:51 INFO - 'XPC_SERVICE_NAME': '0', 07:24:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:24:52 INFO - Cleaning up... 07:24:52 INFO - Return code: 0 07:24:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef31618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a14c51e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, '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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:24:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:24:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:24:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:52 INFO - 'HOME': '/Users/cltbld', 07:24:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:52 INFO - 'LOGNAME': 'cltbld', 07:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:52 INFO - 'MOZ_NO_REMOTE': '1', 07:24:52 INFO - 'NO_EM_RESTART': '1', 07:24:52 INFO - 'PAGER': '/bin/cat', 07:24:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:52 INFO - 'PWD': '/builds/slave/test', 07:24:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:52 INFO - 'SHELL': '/bin/bash', 07:24:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:24:52 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:24:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:52 INFO - 'USER': 'cltbld', 07:24:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:52 INFO - 'XPC_FLAGS': '0x0', 07:24:52 INFO - 'XPC_SERVICE_NAME': '0', 07:24:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:52 INFO - Downloading/unpacking psutil>=3.1.1 07:24:52 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 07:24:52 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 07:24:52 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 07:24:52 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 07:24:52 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 07:24:52 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 07:24:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:24:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:55 INFO - Installing collected packages: psutil 07:24:55 INFO - Running setup.py install for psutil 07:24:55 INFO - building 'psutil._psutil_osx' extension 07:24:55 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 07:24:55 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 07:24:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:24:56 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 07:24:56 INFO - building 'psutil._psutil_posix' extension 07:24:56 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 07:24:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:24:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:24:56 INFO - ^ 07:24:56 INFO - 1 warning generated. 07:24:56 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 07:24:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:56 INFO - Successfully installed psutil 07:24:56 INFO - Cleaning up... 07:24:56 INFO - Return code: 0 07:24:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:24: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:56 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 0x10ee9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef31618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a14c51e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, '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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:24:56 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 07:24:56 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 07:24:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:24:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:56 INFO - 'HOME': '/Users/cltbld', 07:24:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:56 INFO - 'LOGNAME': 'cltbld', 07:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:56 INFO - 'MOZ_NO_REMOTE': '1', 07:24:56 INFO - 'NO_EM_RESTART': '1', 07:24:56 INFO - 'PAGER': '/bin/cat', 07:24:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:56 INFO - 'PWD': '/builds/slave/test', 07:24:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:56 INFO - 'SHELL': '/bin/bash', 07:24:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:24:56 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:24:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:56 INFO - 'USER': 'cltbld', 07:24:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:56 INFO - 'XPC_FLAGS': '0x0', 07:24:56 INFO - 'XPC_SERVICE_NAME': '0', 07:24:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:24:56 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 07:24:56 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 07:24:56 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 07:24:56 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 07:24:56 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 07:24:56 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 07:24:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:24:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:24:59 INFO - Installing collected packages: mozsystemmonitor 07:24:59 INFO - Running setup.py install for mozsystemmonitor 07:24:59 INFO - Successfully installed mozsystemmonitor 07:24:59 INFO - Cleaning up... 07:24:59 INFO - Return code: 0 07:24:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef31618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a14c51e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, '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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:24:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:24:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:24:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:59 INFO - 'HOME': '/Users/cltbld', 07:24:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:59 INFO - 'LOGNAME': 'cltbld', 07:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:59 INFO - 'MOZ_NO_REMOTE': '1', 07:24:59 INFO - 'NO_EM_RESTART': '1', 07:24:59 INFO - 'PAGER': '/bin/cat', 07:24:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:59 INFO - 'PWD': '/builds/slave/test', 07:24:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:59 INFO - 'SHELL': '/bin/bash', 07:24:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:24:59 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:24:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:59 INFO - 'USER': 'cltbld', 07:24:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:59 INFO - 'XPC_FLAGS': '0x0', 07:24:59 INFO - 'XPC_SERVICE_NAME': '0', 07:24:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:59 INFO - Downloading/unpacking jsonschema==2.5.1 07:24:59 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 07:24:59 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 07:24:59 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 07:24:59 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 07:24:59 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 07:24:59 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 07:25:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:25:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:25:02 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 07:25:02 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 07:25:02 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 07:25:02 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 07:25:02 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 07:25:02 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 07:25:02 INFO - Downloading functools32-3.2.3-2.tar.gz 07:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:25:02 INFO - warning: no files found matching '*.txt' 07:25:02 INFO - no previously-included directories found matching 'build' 07:25:02 INFO - no previously-included directories found matching 'dist' 07:25:02 INFO - no previously-included directories found matching '.git*' 07:25:02 INFO - Installing collected packages: jsonschema, functools32 07:25:02 INFO - Running setup.py install for functools32 07:25:02 INFO - warning: no files found matching '*.txt' 07:25:02 INFO - no previously-included directories found matching 'build' 07:25:02 INFO - no previously-included directories found matching 'dist' 07:25:02 INFO - no previously-included directories found matching '.git*' 07:25:02 INFO - Successfully installed jsonschema functools32 07:25:02 INFO - Cleaning up... 07:25:03 INFO - Return code: 0 07:25:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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', '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 0x10ee9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef31618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a14c51e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, '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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:25: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:25: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 blobuploader==1.2.4 07:25:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:25:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:03 INFO - 'HOME': '/Users/cltbld', 07:25:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:03 INFO - 'LOGNAME': 'cltbld', 07:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:03 INFO - 'MOZ_NO_REMOTE': '1', 07:25:03 INFO - 'NO_EM_RESTART': '1', 07:25:03 INFO - 'PAGER': '/bin/cat', 07:25:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:03 INFO - 'PWD': '/builds/slave/test', 07:25:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:03 INFO - 'SHELL': '/bin/bash', 07:25:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:25:03 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:25:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:03 INFO - 'USER': 'cltbld', 07:25:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:03 INFO - 'XPC_FLAGS': '0x0', 07:25:03 INFO - 'XPC_SERVICE_NAME': '0', 07:25:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:03 INFO - Downloading/unpacking blobuploader==1.2.4 07:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:05 INFO - Downloading blobuploader-1.2.4.tar.gz 07:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:25:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:25:05 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 07:25:05 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 07:25:05 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 07:25:05 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 07:25:05 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 07:25:05 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 07:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:25:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:06 INFO - Downloading docopt-0.6.1.tar.gz 07:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:25:07 INFO - Installing collected packages: blobuploader, requests, docopt 07:25:07 INFO - Running setup.py install for blobuploader 07:25:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:25:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:25:07 INFO - Running setup.py install for requests 07:25:07 INFO - Running setup.py install for docopt 07:25:07 INFO - Successfully installed blobuploader requests docopt 07:25:07 INFO - Cleaning up... 07:25:07 INFO - Return code: 0 07:25:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:07 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 0x10ee9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef31618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a14c51e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, '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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:25:07 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 07:25:07 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 07:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:07 INFO - 'HOME': '/Users/cltbld', 07:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:07 INFO - 'LOGNAME': 'cltbld', 07:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:07 INFO - 'MOZ_NO_REMOTE': '1', 07:25:07 INFO - 'NO_EM_RESTART': '1', 07:25:07 INFO - 'PAGER': '/bin/cat', 07:25:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:07 INFO - 'PWD': '/builds/slave/test', 07:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:07 INFO - 'SHELL': '/bin/bash', 07:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:25:07 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:07 INFO - 'USER': 'cltbld', 07:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:07 INFO - 'XPC_FLAGS': '0x0', 07:25:07 INFO - 'XPC_SERVICE_NAME': '0', 07:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-SMlVoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-VJiEDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-LexrnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-kFhyfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-FSNk0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BOs9Se-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-4MlLn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-jqb7qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-cibJ_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:09 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-jgueHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:09 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-x0q1OM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:09 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vSUB_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:09 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-UYd7oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:09 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ywnR0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:09 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-4z1um2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:09 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-8EsNFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:09 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Nd0_I6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:25:09 INFO - Running setup.py install for manifestparser 07:25:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:25:10 INFO - Running setup.py install for mozcrash 07:25:10 INFO - Running setup.py install for mozdebug 07:25:10 INFO - Running setup.py install for mozdevice 07:25:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:25:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:25:10 INFO - Running setup.py install for mozfile 07:25:10 INFO - Running setup.py install for mozhttpd 07:25:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:25:10 INFO - Running setup.py install for mozinfo 07:25:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:25:10 INFO - Running setup.py install for mozInstall 07:25:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:25:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:25:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:25:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:25:11 INFO - Running setup.py install for mozleak 07:25:11 INFO - Running setup.py install for mozlog 07:25:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:25:11 INFO - Running setup.py install for moznetwork 07:25:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:25:11 INFO - Running setup.py install for mozprocess 07:25:11 INFO - Running setup.py install for mozprofile 07:25:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:25:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:25:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:25:11 INFO - Running setup.py install for mozrunner 07:25:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:25:12 INFO - Running setup.py install for mozscreenshot 07:25:12 INFO - Running setup.py install for moztest 07:25:12 INFO - Running setup.py install for mozversion 07:25:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:25:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:25:12 INFO - Cleaning up... 07:25:12 INFO - Return code: 0 07:25:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:12 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 0x10ee9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef31618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a14c51e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, '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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:25:12 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 07:25:12 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 07:25:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:25:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:12 INFO - 'HOME': '/Users/cltbld', 07:25:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:12 INFO - 'LOGNAME': 'cltbld', 07:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:12 INFO - 'MOZ_NO_REMOTE': '1', 07:25:12 INFO - 'NO_EM_RESTART': '1', 07:25:12 INFO - 'PAGER': '/bin/cat', 07:25:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:12 INFO - 'PWD': '/builds/slave/test', 07:25:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:12 INFO - 'SHELL': '/bin/bash', 07:25:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:25:12 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:25:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:12 INFO - 'USER': 'cltbld', 07:25:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:12 INFO - 'XPC_FLAGS': '0x0', 07:25:12 INFO - 'XPC_SERVICE_NAME': '0', 07:25:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:13 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-gXiIoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:13 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)) 07:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:13 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-a2rXC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:13 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)) 07:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:13 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-pVMAh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:13 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)) 07:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:13 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vUUAJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:13 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)) 07:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:13 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-dHrt_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:13 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)) 07:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:13 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-R7edGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:13 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)) 07:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:13 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-8pf04Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:13 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)) 07:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:13 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-QkSemP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:13 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)) 07:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:13 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-F_BpbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:14 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)) 07:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:14 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-khYPkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 07:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:14 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-RfcxL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:14 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)) 07:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:14 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-s6dMb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:14 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)) 07:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:14 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vjxP1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:14 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)) 07:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:14 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-P0SEZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:14 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)) 07:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:14 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-objAEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:14 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)) 07:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:14 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-bt4orf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:14 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)) 07:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:14 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-6i9d_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:14 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)) 07:25:14 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)) 07:25:14 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)) 07:25:14 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)) 07:25:14 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)) 07:25:14 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)) 07:25:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25: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 07:25:17 INFO - Downloading blessings-1.6.tar.gz 07:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:25:17 INFO - Installing collected packages: blessings 07:25:17 INFO - Running setup.py install for blessings 07:25:18 INFO - Successfully installed blessings 07:25:18 INFO - Cleaning up... 07:25:18 INFO - Return code: 0 07:25:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:25:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:18 INFO - Reading from file tmpfile_stdout 07:25:18 INFO - Current package versions: 07:25:18 INFO - blessings == 1.6 07:25:18 INFO - blobuploader == 1.2.4 07:25:18 INFO - docopt == 0.6.1 07:25:18 INFO - functools32 == 3.2.3-2 07:25:18 INFO - jsonschema == 2.5.1 07:25:18 INFO - manifestparser == 1.1 07:25:18 INFO - mozInstall == 1.12 07:25:18 INFO - mozcrash == 0.17 07:25:18 INFO - mozdebug == 0.1 07:25:18 INFO - mozdevice == 0.48 07:25:18 INFO - mozfile == 1.2 07:25:18 INFO - mozhttpd == 0.7 07:25:18 INFO - mozinfo == 0.9 07:25:18 INFO - mozleak == 0.1 07:25:18 INFO - mozlog == 3.2 07:25:18 INFO - moznetwork == 0.27 07:25:18 INFO - mozprocess == 0.23 07:25:18 INFO - mozprofile == 0.28 07:25:18 INFO - mozrunner == 6.12 07:25:18 INFO - mozscreenshot == 0.1 07:25:18 INFO - mozsystemmonitor == 0.3 07:25:18 INFO - moztest == 0.7 07:25:18 INFO - mozversion == 1.4 07:25:18 INFO - psutil == 3.1.1 07:25:18 INFO - requests == 1.2.3 07:25:18 INFO - wsgiref == 0.1.2 07:25:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:18 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 0x10ee9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef31618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a14c51e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, '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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:25:18 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 07:25:18 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 07:25:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:25:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:18 INFO - 'HOME': '/Users/cltbld', 07:25:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:18 INFO - 'LOGNAME': 'cltbld', 07:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:18 INFO - 'MOZ_NO_REMOTE': '1', 07:25:18 INFO - 'NO_EM_RESTART': '1', 07:25:18 INFO - 'PAGER': '/bin/cat', 07:25:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:18 INFO - 'PWD': '/builds/slave/test', 07:25:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:18 INFO - 'SHELL': '/bin/bash', 07:25:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:25:18 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:25:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:18 INFO - 'USER': 'cltbld', 07:25:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:18 INFO - 'XPC_FLAGS': '0x0', 07:25:18 INFO - 'XPC_SERVICE_NAME': '0', 07:25:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:18 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)) 07:25:18 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)) 07:25:18 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)) 07:25:18 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)) 07:25:18 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)) 07:25:18 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)) 07:25:18 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)) 07:25:18 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)) 07:25:18 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)) 07:25:18 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)) 07:25:18 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)) 07:25:18 INFO - Cleaning up... 07:25:18 INFO - Return code: 0 07:25:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:18 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 0x10ee9ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef31618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4a14c51e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, '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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:25:18 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 07:25:18 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 07:25:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:25:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:18 INFO - 'HOME': '/Users/cltbld', 07:25:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:18 INFO - 'LOGNAME': 'cltbld', 07:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:18 INFO - 'MOZ_NO_REMOTE': '1', 07:25:18 INFO - 'NO_EM_RESTART': '1', 07:25:18 INFO - 'PAGER': '/bin/cat', 07:25:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:18 INFO - 'PWD': '/builds/slave/test', 07:25:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:18 INFO - 'SHELL': '/bin/bash', 07:25:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:25:18 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:25:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:18 INFO - 'USER': 'cltbld', 07:25:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:18 INFO - 'XPC_FLAGS': '0x0', 07:25:18 INFO - 'XPC_SERVICE_NAME': '0', 07:25:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:25:18 INFO - Cleaning up... 07:25:18 INFO - Return code: 0 07:25:18 INFO - Running post-action listener: _resource_record_post_action 07:25:18 INFO - Running post-action listener: _start_resource_monitoring 07:25:18 INFO - Starting resource monitoring. 07:25:18 INFO - ##### 07:25:18 INFO - ##### Finished create-virtualenv step (success) 07:25:18 INFO - ##### 07:25:18 INFO - ##### 07:25:18 INFO - ##### Running install step. 07:25:18 INFO - ##### 07:25:18 INFO - Running pre-action listener: _resource_record_pre_action 07:25:18 INFO - Running main action method: install 07:25:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:19 INFO - Reading from file tmpfile_stdout 07:25:19 INFO - Detecting whether we're running mozinstall >=1.0... 07:25:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:25:19 INFO - Reading from file tmpfile_stdout 07:25:19 INFO - Output received: 07:25:19 INFO - Usage: mozinstall [options] installer 07:25:19 INFO - Options: 07:25:19 INFO - -h, --help show this help message and exit 07:25:19 INFO - -d DEST, --destination=DEST 07:25:19 INFO - Directory to install application into. [default: 07:25:19 INFO - "/builds/slave/test"] 07:25:19 INFO - --app=APP Application being installed. [default: firefox] 07:25:19 INFO - mkdir: /builds/slave/test/build/application 07:25:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:25:53 INFO - Reading from file tmpfile_stdout 07:25:53 INFO - Output received: 07:25:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:25:53 INFO - Running post-action listener: _resource_record_post_action 07:25:53 INFO - ##### 07:25:53 INFO - ##### Finished install step (success) 07:25:53 INFO - ##### 07:25:53 INFO - ##### 07:25:53 INFO - ##### Running run-tests step. 07:25:53 INFO - ##### 07:25:53 INFO - Running pre-action listener: _resource_record_pre_action 07:25:53 INFO - Running main action method: run_tests 07:25:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:25:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:25:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:25:53 INFO - 2016-07-14 07:25:53.701 screenresolution[1631:7544] starting screenresolution argv=screenresolution get 07:25:53 INFO - 2016-07-14 07:25:53.704 screenresolution[1631:7544] Display 0: 1600x1200x32@60 07:25:53 INFO - 2016-07-14 07:25:53.710 screenresolution[1632:7546] starting screenresolution argv=screenresolution list 07:25:53 INFO - Available Modes on Display 0 07:25:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:25:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:25:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:25:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:25:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:25:53 INFO - Intel Iris: 07:25:53 INFO - Chipset Model: Intel Iris 07:25:53 INFO - Type: GPU 07:25:53 INFO - Bus: Built-In 07:25:53 INFO - VRAM (Dynamic, Max): 1536 MB 07:25:53 INFO - Vendor: Intel (0x8086) 07:25:53 INFO - Device ID: 0x0a2e 07:25:53 INFO - Revision ID: 0x0009 07:25:53 INFO - Displays: 07:25:53 INFO - Display: 07:25:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:25:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:25:53 INFO - Display Serial Number: 1600x1200 60 07:25:53 INFO - Main Display: Yes 07:25:53 INFO - Mirror: Off 07:25:53 INFO - Online: Yes 07:25:53 INFO - Rotation: Supported 07:25:53 INFO - Return code: 0 07:25:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:25:53 INFO - Python 2.7.3 07:25:53 INFO - Return code: 0 07:25:53 INFO - grabbing minidump binary from tooltool 07:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:25:53 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 0x10ee00d30>, '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 0x10eb65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10edf8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:25:53 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 07:25:53 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 07:25:53 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 07:25:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:25:54 INFO - Return code: 0 07:25:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:25:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:25:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:25:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:25:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:25:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzRTP1dPWp/Render', 07:25:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:54 INFO - 'HOME': '/Users/cltbld', 07:25:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:54 INFO - 'LOGNAME': 'cltbld', 07:25:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:25:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:54 INFO - 'MOZ_NO_REMOTE': '1', 07:25:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:25:54 INFO - 'NO_EM_RESTART': '1', 07:25:54 INFO - 'PAGER': '/bin/cat', 07:25:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:54 INFO - 'PWD': '/builds/slave/test', 07:25:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:25:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:54 INFO - 'SHELL': '/bin/bash', 07:25:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtnMp9MX7F/Listeners', 07:25:54 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:25:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:54 INFO - 'USER': 'cltbld', 07:25:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:54 INFO - 'XPC_FLAGS': '0x0', 07:25:54 INFO - 'XPC_SERVICE_NAME': '0', 07:25:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:25:54 INFO - mozversion application_buildid: 20160714024642 07:25:54 INFO - mozversion application_changeset: 08f8a5aacd8308a73f6040fe522be7ba38497561 07:25:54 INFO - mozversion application_display_name: Nightly 07:25:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:25:54 INFO - mozversion application_name: Firefox 07:25:54 INFO - mozversion application_remotingname: firefox 07:25:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:25:54 INFO - mozversion application_vendor: Mozilla 07:25:54 INFO - mozversion application_version: 50.0a1 07:25:54 INFO - mozversion platform_buildid: 20160714024642 07:25:54 INFO - mozversion platform_changeset: 08f8a5aacd8308a73f6040fe522be7ba38497561 07:25:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:25:54 INFO - mozversion platform_version: 50.0a1 07:25:54 INFO - using testdate: 1468506354 07:25:54 INFO - actual date: 1468506354 07:25:54 INFO - starting webserver on 'localhost:49265' 07:25:54 INFO - SUITE-START | Running 2 tests 07:25:54 INFO - TEST-START | dromaeo_css 07:25:54 INFO - operating with platform_type : mac_ 07:25:54 INFO - Initialising browser for dromaeo_css test... 07:25:54 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpwlZuwz/profile http://localhost:49265/getInfo.html) 07:25:56 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 07:25:56 INFO - PROCESS | 1642 | colorDepth:24 07:25:56 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 07:25:56 INFO - PROCESS | 1642 | __metrics 07:25:56 INFO - PROCESS | 1642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:25:56 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:25:57 INFO - PROCESS | 1642 | ************************* 07:25:57 INFO - PROCESS | 1642 | A coding exception was thrown in a Promise resolution callback. 07:25:57 INFO - PROCESS | 1642 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 07:25:57 INFO - PROCESS | 1642 | 07:25:57 ERROR - PROCESS | 1642 | Full message: TypeError: win is null 07:25:57 INFO - PROCESS | 1642 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:855:17 07:25:57 INFO - PROCESS | 1642 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 07:25:57 INFO - PROCESS | 1642 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 07:25:57 INFO - PROCESS | 1642 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 07:25:57 INFO - PROCESS | 1642 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 07:25:57 INFO - PROCESS | 1642 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 07:25:57 INFO - PROCESS | 1642 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 07:25:57 INFO - PROCESS | 1642 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 07:25:57 INFO - PROCESS | 1642 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 07:25:57 INFO - PROCESS | 1642 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 07:25:57 INFO - PROCESS | 1642 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 07:25:57 INFO - PROCESS | 1642 | Barrier/this.client.addBlocker/promise resource://gre/modules/Promise-backend.js:937:23 07:44:33 INFO - PROCESS | 1655 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 07:44:33 INFO - PROCESS | 1655 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 07:44:33 INFO - PROCESS | 1655 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 07:44:33 INFO - PROCESS | 1655 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 07:44:33 INFO - PROCESS | 1655 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 07:44:33 INFO - PROCESS | 1655 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 07:44:33 INFO - PROCESS | 1655 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 07:44:33 INFO - PROCESS | 1655 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 07:44:33 INFO - PROCESS | 1655 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 07:44:33 INFO - PROCESS | 1655 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 07:44:33 INFO - PROCESS | 1655 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 07:44:33 INFO - PROCESS | 1655 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 07:44:33 INFO - PROCESS | 1655 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 07:44:33 INFO - PROCESS | 1655 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 07:44:33 INFO - PROCESS | 1655 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 07:44:33 INFO - PROCESS | 1655 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1515:5 07:44:33 INFO - PROCESS | 1655 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1647:21 07:44:33 INFO - PROCESS | 1655 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2011:23 07:44:33 INFO - PROCESS | 1655 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3726:34 07:44:33 INFO - PROCESS | 1655 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2715:25 07:44:33 INFO - PROCESS | 1655 | callProvider@resource://gre/modules/AddonManager.jsm:227:12 07:44:33 INFO - PROCESS | 1655 | _startProvider@resource://gre/modules/AddonManager.jsm:779:5 07:44:33 INFO - PROCESS | 1655 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:963:9 07:44:33 INFO - PROCESS | 1655 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:2985:5 07:44:33 INFO - PROCESS | 1655 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 07:44:33 INFO - PROCESS | 1655 | 07:44:33 INFO - PROCESS | 1655 | ************************* 07:44:33 INFO - TEST-INFO | 1655: exit 0 07:44:33 INFO - Browser initialized. 07:44:33 INFO - Running cycle 1/1 for kraken test... 07:44:33 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmprE9qWA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:44:45 INFO - PROCESS | 1656 | RSS: Main: 264663040 07:44:45 INFO - PROCESS | 1656 | 07:44:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 07:44:49 INFO - PROCESS | 1656 | RSS: Main: 311504896 07:44:49 INFO - PROCESS | 1656 | 07:44:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 07:44:52 INFO - PROCESS | 1656 | RSS: Main: 321642496 07:44:52 INFO - PROCESS | 1656 | 07:44:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 07:44:56 INFO - PROCESS | 1656 | RSS: Main: 406425600 07:44:56 INFO - PROCESS | 1656 | 07:44:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 07:44:59 INFO - PROCESS | 1656 | RSS: Main: 306237440 07:44:59 INFO - PROCESS | 1656 | 07:45:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:45:01 INFO - PROCESS | 1656 | RSS: Main: 309809152 07:45:01 INFO - PROCESS | 1656 | 07:45:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 07:45:05 INFO - PROCESS | 1656 | RSS: Main: 345776128 07:45:05 INFO - PROCESS | 1656 | 07:45:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 07:45:08 INFO - PROCESS | 1656 | RSS: Main: 348753920 07:45:08 INFO - PROCESS | 1656 | 07:45:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 07:45:11 INFO - PROCESS | 1656 | RSS: Main: 345591808 07:45:11 INFO - PROCESS | 1656 | 07:45:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:45:14 INFO - PROCESS | 1656 | RSS: Main: 328916992 07:45:14 INFO - PROCESS | 1656 | 07:45:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:45:16 INFO - PROCESS | 1656 | RSS: Main: 337039360 07:45:16 INFO - PROCESS | 1656 | 07:45:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:45:19 INFO - PROCESS | 1656 | RSS: Main: 332877824 07:45:19 INFO - PROCESS | 1656 | 07:45:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:45:23 INFO - PROCESS | 1656 | RSS: Main: 341774336 07:45:23 INFO - PROCESS | 1656 | 07:45:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:45:26 INFO - PROCESS | 1656 | RSS: Main: 344272896 07:45:26 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 07:45:28 INFO - PROCESS | 1656 | RSS: Main: 387670016 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | __start_tp_report 07:45:28 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 07:45:28 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 07:45:28 INFO - PROCESS | 1656 | |i|pagename|runs| 07:45:28 INFO - PROCESS | 1656 | |0;ai-astar;102;112;112;113;112;112;113;112;111;112 07:45:28 INFO - PROCESS | 1656 | |1;audio-beat-detection;142;108;106;155;110;108;128;105;159;137 07:45:28 INFO - PROCESS | 1656 | |2;audio-dft;190;183;183;183;185;184;190;183;173;183 07:45:28 INFO - PROCESS | 1656 | |3;audio-fft;82;74;58;107;58;60;62;66;96;56 07:45:28 INFO - PROCESS | 1656 | |4;audio-oscillator;84;93;92;70;70;68;99;71;67;100 07:45:28 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;87;90;86;89;85;89;87;88;85 07:45:28 INFO - PROCESS | 1656 | |6;imaging-darkroom;94;93;94;93;93;93;92;94;101;93 07:45:28 INFO - PROCESS | 1656 | |7;imaging-desaturate;86;87;87;84;84;83;86;86;86;90 07:45:28 INFO - PROCESS | 1656 | |8;json-parse-financial;55;56;56;53;53;54;54;54;53;56 07:45:28 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;66;69;78;78;75;71;71;72;71;71 07:45:28 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;84;84;84;93;101;101;102;84;84;98 07:45:28 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;163;141;137;135;135;140;142;152;232;146 07:45:28 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;166;177;167;166;165;165;168;166;166;167 07:45:28 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;69;67;66;65;67;67;67;68;83;72 07:45:28 INFO - PROCESS | 1656 | __end_tp_report 07:45:28 INFO - PROCESS | 1656 | __start_cc_report 07:45:28 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2090 07:45:28 INFO - PROCESS | 1656 | __end_cc_report 07:45:28 INFO - PROCESS | 1656 | __startTimestamp1468507528707__endTimestamp 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | ------- Summary: start ------- 07:45:28 INFO - PROCESS | 1656 | Number of tests: 14 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:3.25 (2.9%) stddev-sans-first:0.60 07:45:28 INFO - PROCESS | 1656 | Values: 102.0 112.0 112.0 113.0 112.0 112.0 113.0 112.0 111.0 112.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:125.80 Median:132.50 stddev:21.23 (16.0%) stddev-sans-first:21.69 07:45:28 INFO - PROCESS | 1656 | Values: 142.0 108.0 106.0 155.0 110.0 108.0 128.0 105.0 159.0 137.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:183.70 Median:183.50 stddev:4.69 (2.6%) stddev-sans-first:4.39 07:45:28 INFO - PROCESS | 1656 | Values: 190.0 183.0 183.0 183.0 185.0 184.0 190.0 183.0 173.0 183.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:71.90 Median:64.00 stddev:17.74 (27.7%) stddev-sans-first:18.44 07:45:28 INFO - PROCESS | 1656 | Values: 82.0 74.0 58.0 107.0 58.0 60.0 62.0 66.0 96.0 56.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:77.50 stddev:13.60 (17.5%) stddev-sans-first:14.39 07:45:28 INFO - PROCESS | 1656 | Values: 84.0 93.0 92.0 70.0 70.0 68.0 99.0 71.0 67.0 100.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:1.71 (1.9%) stddev-sans-first:1.80 07:45:28 INFO - PROCESS | 1656 | Values: 88.0 87.0 90.0 86.0 89.0 85.0 89.0 87.0 88.0 85.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:93.00 stddev:2.54 (2.7%) stddev-sans-first:2.69 07:45:28 INFO - PROCESS | 1656 | Values: 94.0 93.0 94.0 93.0 93.0 93.0 92.0 94.0 101.0 93.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:1.97 (2.3%) stddev-sans-first:2.09 07:45:28 INFO - PROCESS | 1656 | Values: 86.0 87.0 87.0 84.0 84.0 83.0 86.0 86.0 86.0 90.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.26 (2.3%) stddev-sans-first:1.32 07:45:28 INFO - PROCESS | 1656 | Values: 55.0 56.0 56.0 53.0 53.0 54.0 54.0 54.0 53.0 56.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:71.50 stddev:3.79 (5.3%) stddev-sans-first:3.30 07:45:28 INFO - PROCESS | 1656 | Values: 66.0 69.0 78.0 78.0 75.0 71.0 71.0 72.0 71.0 71.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:84.00 stddev:8.28 (9.9%) stddev-sans-first:8.32 07:45:28 INFO - PROCESS | 1656 | Values: 84.0 84.0 84.0 93.0 101.0 101.0 102.0 84.0 84.0 98.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:144.00 stddev:29.30 (20.3%) stddev-sans-first:30.82 07:45:28 INFO - PROCESS | 1656 | Values: 163.0 141.0 137.0 135.0 135.0 140.0 142.0 152.0 232.0 146.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:166.50 stddev:3.53 (2.1%) stddev-sans-first:3.71 07:45:28 INFO - PROCESS | 1656 | Values: 166.0 177.0 167.0 166.0 165.0 165.0 168.0 166.0 166.0 167.0 07:45:28 INFO - PROCESS | 1656 | 07:45:28 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:67.50 stddev:5.24 (7.8%) stddev-sans-first:5.56 07:45:28 INFO - PROCESS | 1656 | Values: 69.0 67.0 66.0 65.0 67.0 67.0 67.0 68.0 83.0 72.0 07:45:28 INFO - PROCESS | 1656 | -------- Summary: end -------- 07:45:28 INFO - PROCESS | 1656 | 07:45:29 INFO - TEST-INFO | 1656: exit 0 07:45:29 INFO - TEST-OK | kraken | took 59152ms 07:45:29 INFO - SUITE-END | took 1175s 07:45:29 INFO - Completed test suite (00:19:35) 07:45:29 INFO - javascript benchmark 07:45:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2726.345677037511, 3328.1014126185946, 3329.017939707787, 3342.331533693262, 3384.661533846616, 5399.19012148178, 5479.589030822684, 5482.369527504338, 5587.580931430144, 5620.606557540974, 5502.422245664207, 5541.750621222044, 5557.861053473665, 5566.805161819337, 5575.358833734122, 5024.849254522361, 5060.873478163057, 5116.820911268106, 5162.638615296931, 5175.482451754832, 5078.7968481260705, 5146.459621739719, 5151.253068305094, 5152.046871328797, 5157.30376399185, 5051.520105589963, 5101.209312556541, 5115.59075273979, 5119.10415677255, 5163.096458119833, 4149.698284831012, 5171.663841850305, 5195.714235717034, 5196.350456192976, 5258.763355649005, 4994.450610432849, 5034.119029169918, 5038.571721403685, 5122.436531981479, 5135.38375394955, 5478.671279723189, 5483.451654834525, 5540.2243685884005, 5568.693721845297, 5580.91628625571, 6087.282356173956, 6092.329843717188, 6093.268588407756, 6100.423737243143, 6104.528089495881, 5946.583739138218, 6037.034074548052, 6112.991356426184, 6187.721552530147, 6199.101130336121, 5671.489565939085, 5727.684977326204, 5747.39652336507, 5753.367129615781, 5753.539716822645, 6228.688565571703, 6295.937040629627, 6308.432241098231, 6309.652969086745, 6333.176687030748, 5805.82582522525, 5853.473187413068, 5905.675187864623, 5993.190010684287, 6038.323757355665, 6115.510759139258, 6130.049842274455, 6143.32423433422, 6148.815535533941, 6150.538709596798, 6047.879042419127, 6056.393273322157, 6080.726367313393, 6083.7566497340495, 6102.359252278536, 5862.12068189775, 5872.060470324727, 5920.378360272195, 6098.72555734993, 6103.908441373383, 4832.44426890912, 4870.537298956593, 4871.3667223261455, 4871.853844384604, 4876.195427754452, 4370.366296886998, 4421.159979603865, 4432.445944256968, 4442.089371678798, 4446.221911165533, 5628.690422026746, 5679.5172410345585, 5682.261306030272, 5688.374278829326, 5688.459596338341, 6125.326214116443, 6193.4425901669065, 6201.472874805692, 6210.50315974721, 6217.191765070512, 5459.3994660587305, 5613.214150019001, 5616.382197958222, 5631.155326701028, 5631.4087020863035, 5226.555742761908, 5240.423553409122, 5303.973480132575, 5306.708131052829, 5315.920261196008, 6308.589941653778, 6321.52588555851, 6323.30443651198, 6324.272708638381, 6334.429901308997, 5922.407759224043, 6117.327094019565, 6174.0, 6174.691265436621, 6176.783812566719, 5499.725013749377, 6021.548383871314, 6144.787876364416, 6273.811785646255, 6313.305536391085, 6066.3630318815885, 6068.939310606837, 6076.726547305293, 6083.329613704057, 6084.209052823105, 5926.851828704144, 6171.660558669317, 6197.03945888388, 6206.0, 6207.0, 4745.786439610157, 5759.424057594375, 5800.622959507618, 5819.447152520504, 5821.0, 5739.368669446441, 5778.219940308005, 5788.62373945709, 5791.420857914175, 5807.390224026416, 5663.32040155184, 5697.316322041382, 5708.143778433102, 5736.311642602915, 5762.222100016612, 5831.241938548132, 5864.32560255559, 5935.643861368331, 5937.881242375215, 5970.283565972111, 5776.306843178845, 5781.82654520365, 5789.8263052108505, 5796.884062318814, 5804.883902321845], "value": 5566.26268050281, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.4443578833823, 459.871236053905, 477.81604082428265, 514.3544851211728, 535.908895487767, 29052.85473572632, 41620.751377458684, 42094.789526052366, 42171.0, 45080.3237951431, 38535.42196867049, 43364.349534756926, 44266.557334426645, 44439.0, 44708.32937505932, 8593.527355995418, 9663.613455461773, 9844.901550984507, 9858.950705246463, 9863.210943124534, 8642.783930401727, 11870.287782733021, 12029.879701203006, 12032.0, 12387.876121238807, 12061.758764824699, 12124.090693198, 12125.757484850297, 12137.271763694162, 12191.329476878813, 8611.00973388062, 11786.941065294704, 11980.70048248792, 12106.818397723953, 12126.878731212708, 11629.302241865515, 12032.458539365749, 12090.395480225954, 12091.57679481214, 12113.636590902199, 41621.583784162074, 42045.57954420447, 42053.57946420527, 42110.78944605257, 42203.57796422027, 3588.5334906462253, 3598.208394153282, 3743.0, 3771.4342848572933, 3842.731008829383, 31972.360552789047, 32342.35315293681, 32527.674723252938, 32972.50541241883, 33012.50481242759, 3308.503724441329, 3319.7012268895915, 3328.284418849959, 3330.58367704037, 3461.9441070473495, 524709.3764531153, 525286.0, 527467.0, 528243.0, 531211.0, 33143.83428082844, 62074.689626551575, 62577.0, 62613.0, 62781.05828412577, 34132.0, 35950.46074308887, 35963.460548091796, 35997.46003809945, 36015.559377625126, 14681.559553213421, 14936.253187340806, 14944.551663450115, 14948.626284342761, 14977.925110374379, 11310.264832785842, 11480.483378247998, 11490.310581365145, 12609.054320925967, 12706.555270565486, 7439.330460258603, 7486.550806951492, 7615.781474963979, 7761.301482866569, 7769.184235655288, 3262.2496825730213, 3307.9007629771145, 3321.2693207494312, 3324.202191474029, 3346.9330613387597, 879.9208071273616, 910.4764760262875, 1039.9740006499746, 1048.4810019040624, 1069.2782371898936, 27225.45549089047, 29620.555691664642, 31034.91377801766, 31313.0, 31924.0, 1064.3347907557777, 1088.3571556013712, 1089.6622047165404, 1098.5880294889416, 1193.456977075446, 14417.855821441652, 16015.279312430735, 16026.599335016717, 16143.002210142895, 16331.856770025985, 622748.0, 668816.0, 681465.0, 681838.0, 681874.181258191, 59821.0, 60328.09507857385, 60412.09381859188, 60503.69748151231, 60523.0, 1281.3144967442374, 1411.6329754263966, 1417.1426287096158, 1447.0087989727072, 1447.5440236325524, 30816.0, 32029.67970320267, 32359.676403236608, 32359.838200808845, 32381.51427728633, 46337.0, 46794.596162115195, 47170.0, 47279.76360118315, 47291.52708472871, 449167.0, 449433.0, 449459.0, 450028.0, 450214.7489262533, 24124.311298208955, 26535.203943881716, 26953.730462695123, 27006.459870802082, 28121.43757124887, 48820.0, 48958.51041489682, 49290.50709493003, 50362.74818625883, 50404.49595504145, 31226.37547248997, 39550.0, 40100.35977097307, 40914.590854091075, 41077.79461102795, 39526.60473395229, 39866.402003969386, 40164.3975340376, 40396.0, 40529.0, 37792.0, 38497.0, 39013.82958511249, 39030.829075126654, 39038.0, 34290.314193716484, 34335.484967725, 35213.29573408569, 35619.0, 35961.640383595826, 25596.488070238862, 26138.817789153643, 26221.868890655423, 26363.953546329216, 26414.603780943686], "value": 19259.671858263835, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.50473850824375, 383.29665064606445, 389.851856294608, 394.69608401530803, 411.89290784396064, 15080.0, 15279.770803437943, 20832.270870519536, 20984.68522972155, 21080.94595270238, 21267.255646052392, 21314.254001109963, 21387.657518553562, 21636.3509094727, 21980.450488737788, 5476.17857321401, 5538.529225015878, 5978.637813843794, 6151.477124444427, 6164.691765411734, 6010.96994515029, 6030.246219222598, 6113.174721412619, 6116.571839971204, 6126.509879209652, 4877.561019508244, 5879.823605291826, 6057.515398768088, 6067.635941843503, 6108.992016317302, 5766.538676905838, 5980.983232850404, 6004.699765011754, 6025.578209525335, 6044.788432404866, 5152.613553983466, 5177.844664660066, 5197.610179236573, 5206.349206349207, 5208.114620514521, 26906.654667266557, 26972.73027269722, 27026.729732702617, 27364.0, 27447.86276068627, 2294.9557951132197, 2329.662198981155, 2400.5798985177526, 2782.986085069582, 3174.127264913689, 16101.43644972432, 16150.192490375552, 16188.75716864248, 16400.343986240536, 16419.917900410543, 1908.0841196225751, 1929.9421017369361, 1932.222355433005, 1944.5819148883058, 1956.5597740508272, 131213.3439332797, 133558.0, 135176.0, 135574.0, 136967.0, 9539.570719317648, 10567.418791966362, 10737.0, 10751.301165424222, 11080.833787493195, 13681.589552313446, 13702.177869327872, 13749.587512374645, 13770.380332884844, 13781.242031688153, 8497.957510212409, 8711.825763484694, 8722.30221582272, 8765.991910930197, 8798.912010879938, 8743.300535957236, 8766.824663506823, 8795.604197811113, 8923.170749996236, 8929.687460938965, 6532.281449040602, 6632.0, 6642.368974947372, 6666.2333831609985, 6671.199456065303, 1687.2829047654698, 1833.8532917366579, 1866.2814816295825, 1874.268251542872, 1903.067496926496, 894.4990805149138, 938.5119737736339, 992.3153024413276, 1041.8020576090519, 1053.7154968158554, 18297.176627051813, 20148.496287592927, 22864.885675571848, 23249.0, 23437.296881093593, 927.2442958988403, 997.6857289953641, 1013.9391636501833, 1046.1735229169024, 1062.4262898035129, 7540.622968851536, 11360.03439707634, 11870.598350919228, 11995.400229988465, 12178.451969661386, 625395.0, 631608.0, 632072.0, 637504.0, 646530.0, 141334.0, 143469.0, 148278.0, 153999.0, 156557.2172139132, 984.4634674102631, 1007.5264625625944, 1020.8566405625598, 1029.0841151375282, 1030.0986636692894, 15464.690706186038, 18033.73763836519, 19053.809461905203, 19309.4207173785, 19590.804091958897, 52995.73502132465, 60393.396066038775, 60880.08679869894, 61287.0, 62097.75804483976, 88527.55736221277, 89236.10763892537, 89505.0, 90165.0, 90503.54748226216, 6605.075289459383, 8053.217809485067, 8064.83870322602, 8302.750917472486, 8435.072142064491, 12329.383530823243, 12704.872951270369, 12954.417051232529, 13088.0, 13100.148490348098, 11109.388983605979, 11122.555097796321, 11253.21227514099, 11321.830172547254, 11381.317120972768, 11078.944605276922, 11127.0, 11223.4388280584, 11234.775304494027, 11287.0, 5870.735816888165, 11457.885421146015, 11471.942640286745, 11523.193376463902, 11560.13299002595, 8051.718189863561, 8071.192880712117, 8182.509049457051, 8193.83612327762, 8307.252347288773, 10880.945595271973, 11061.0, 11140.108791296514, 11274.943625281821, 11312.6040588579], "value": 10018.161207704172, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.5447502324234, 1565.7886185364973, 1597.496788511619, 1797.8651601129911, 1920.3470819921224, 7868.881966770496, 8354.916450835497, 8551.230389264965, 8785.780355491115, 8815.51514666693, 8626.698065567707, 8702.216800487955, 8774.341924355667, 8778.2099611035, 8785.38502304839, 2109.786872548283, 2284.4403121235237, 2344.78896899279, 2376.0, 2379.0483806477373, 2333.265021518225, 2361.5867223235864, 2382.6068698664785, 2385.7017872765905, 2385.904563817454, 2330.3242059802637, 2359.056377449017, 2362.3031205794246, 2368.7749663781915, 2369.07606033647, 2047.8668886522328, 2332.801711854486, 2338.240071976614, 2343.95312093758, 2356.5404746074523, 2331.2423462374795, 2332.498512819735, 2335.2760644200184, 2339.017612602711, 2340.2745149003863, 8736.039035706068, 8796.47221166732, 8824.294056475466, 8875.245604123656, 8882.644694212224, 5178.896422071575, 5376.946230537723, 5391.919121213185, 5444.0, 5456.0, 3504.3692135415613, 3536.5932917714536, 3550.8579656813695, 3598.7660802047785, 3604.0, 1081.9783604327947, 1092.83607458881, 1093.278436232091, 1094.7044298039484, 1100.0979197058416, 11714.824277635842, 11847.992920601759, 11851.881481185164, 11856.22934509254, 11866.228695134874, 4428.158649856517, 4484.8430304939175, 4626.306054091913, 4643.628509719214, 4651.86044418668, 9316.673916412892, 9328.953355233316, 9333.719988400358, 9335.57989890443, 9343.0, 5262.184361423985, 5269.262303277545, 5270.868228294246, 5276.657017293864, 5278.155495120839, 4178.561251068655, 4339.175556644227, 4498.752568608758, 4890.486498917562, 4935.136351138536, 3396.7941380809953, 3685.1708365617524, 3781.7352785304765, 3848.5189351331082, 3868.458415821787, 1015.304516406264, 1139.641013080877, 1151.6314779270554, 1153.8326942593278, 1155.6937411585936, 716.1907045039177, 821.3593397150232, 875.5053394831899, 936.7143021378545, 971.9125278724807, 5833.82498525045, 5966.910496342472, 6108.419700128481, 6121.387861213932, 6125.1731016313115, 926.5043201886958, 966.4346357380871, 977.9462129582942, 982.3958265666706, 994.7712026233862, 3986.9601303986587, 4032.4556184915245, 4042.0, 4090.6318431341324, 4110.815013324408, 10680.198985076151, 10890.23768336209, 10963.50664220112, 11023.889761102446, 11035.006849383593, 10983.505742241618, 10989.505472253768, 10995.0, 11006.229563930448, 11015.779684406267, 7281.0, 8109.310708589836, 8124.2688158064875, 8185.30424913889, 8368.456050356708, 6000.639961602318, 6074.78738244177, 6133.815985520263, 6178.351273116438, 6323.715432805627, 9885.209183265179, 10174.8473772892, 10407.375557466576, 10430.426326552113, 10440.582376704846, 9783.657571984946, 10182.134518566005, 10420.06219440254, 10451.320664156805, 10467.110295624958, 6975.825604359931, 7169.749058782918, 7458.291462311072, 7473.0, 7658.387329013554, 8427.705030323908, 8580.455518006818, 8591.226789588967, 8593.355498337525, 8596.011458682082, 7402.888956665546, 7535.773926782205, 7566.394688424803, 7680.270374314431, 7693.807654808675, 7407.962960185165, 7485.925140748523, 7503.137139229058, 7629.0, 7672.38620910337, 7155.248698886542, 7166.211716711054, 7225.313595208447, 7237.059182306267, 7245.130584329914, 4967.776450059684, 5080.542751152414, 5110.48895110486, 5184.637075404686, 5192.896142077213, 5099.209622508517, 5149.613778966517, 5261.289725887109, 5326.573874090141, 5334.839954801362], "value": 4594.4002894927735, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.6755205705923, 313.8226901800483, 314.7985289414775, 321.6526151756105, 324.8651809499058, 5335.279737235482, 5367.812126575571, 5400.729963501829, 5433.91849122263, 5481.040817856869, 5335.973320133394, 5341.225522299264, 5351.785928562863, 5361.356637203531, 5382.4886635769535, 3635.4365073413533, 3849.634284742945, 3862.9227415451815, 3893.0851249956236, 3992.141689536749, 3681.8711345102924, 3884.514435695538, 4002.4196491508715, 4033.5764744701823, 4056.1887622475474, 4025.235205310996, 4038.737482063671, 4042.070323825522, 4076.4700588923547, 4083.775392353419, 3721.218544105741, 3757.7975047984655, 3796.6772824309965, 3867.035239837721, 3964.068443915692, 3358.2779702363864, 3505.2814173094453, 3523.8942831714835, 3543.7342199334894, 3553.502509648651, 4271.7146326715365, 4453.710508816916, 5154.742262886878, 5163.967206558703, 5225.850312931149, 4159.83360665573, 4301.65586753059, 4572.085582883404, 4618.291232244092, 4669.322948172496, 5499.807506737285, 5505.394406615269, 5531.308586426697, 5536.695481748502, 5542.196381524656, 3832.5784163741964, 3847.1920896611746, 3860.324443222453, 3860.8069596520063, 3878.8836334909997, 4768.713877167381, 5078.695278283278, 5452.809151679672, 5796.82609521715, 5842.678652674102, 3226.9193270168435, 3367.7305815535187, 3592.084018575247, 3817.408301713239, 3863.459115723801, 3917.941230881539, 3929.941050884239, 3932.6657234134846, 3948.9605103948593, 3972.1857019310974, 3842.6733727633464, 4124.010237543028, 4526.524714904954, 4604.536572933532, 4654.97672511642, 5223.4515375884985, 5532.336119665666, 5556.666600004012, 5556.805511807067, 5592.664440133605, 1101.658485869383, 1229.428315833124, 1335.6927906581345, 1342.2013901728455, 1354.464986330394, 734.904991562568, 794.4200733464603, 814.4298990706411, 818.7134502924048, 829.655692887457, 4293.763842988666, 4954.529319714621, 5143.27994080829, 5175.197844334134, 5192.948070519246, 4995.600351971833, 5144.122395325754, 5604.971975140098, 5622.465865742748, 5711.685857277889, 4478.977605112019, 4963.429205641325, 5178.818741344035, 5215.940537678446, 5305.867353316198, 2970.8265235232393, 2983.209449495885, 2984.507556253194, 2997.415503976704, 2998.995336562276, 4910.631702622318, 5028.798848046037, 5809.9419005809405, 5859.736311865891, 5906.940930590639, 5337.119375303031, 5355.464453554613, 5435.972820135953, 5500.662218557177, 5909.793157239476, 4678.6491013174145, 5246.842594722164, 5810.6925941662785, 5837.650950042245, 5896.970515147397, 5631.7184140793615, 5674.716264186856, 5677.602567820378, 5702.258706368311, 5721.942780572141, 4750.5249475052215, 5115.974420127875, 5279.736013199402, 5605.747741351567, 5632.760792625616, 5122.129238029469, 5800.100984347433, 5846.444587764156, 5857.920438244362, 5895.260898035004, 3221.7905836120576, 3765.5690837481584, 3776.6978641708047, 3782.659560639556, 3791.658750712449, 4229.682773792039, 4373.781310934504, 4517.796699148481, 5132.66550696814, 5576.442355764391, 4671.813127474863, 5467.562594992471, 5494.752736126964, 5497.3952865184, 5505.697186654773, 4379.846705365297, 4640.466346370211, 4854.878628034327, 5089.6691715038405, 5141.871453213699, 4951.455339912679, 5087.491250874883, 5166.870828229325, 5171.095058364846, 5543.61194716366, 4602.056578401487, 4763.618910487084, 5453.4819192178265, 5560.888782224414, 5572.189038562402, 5520.770349345517, 5526.47498487643, 5561.608508341452, 5573.693446860461, 5576.86057848557], "value": 4004.4619923576597, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.8500299940011, 766.1763604125566, 790.960451977401, 862.1723145780051, 873.8820259264998, 14934.327955242014, 16301.347946082173, 16301.51095467138, 16336.754948675765, 16343.0, 14509.564713058598, 14660.973731838776, 14683.926580367082, 14706.55880323592, 14708.926455367735, 704.0600797934763, 730.3682314797688, 875.4572165257549, 901.8511945529012, 902.8013836955892, 895.4627223665781, 897.407710910799, 898.0749827677503, 899.5861903524386, 901.535709109809, 938.1790932933683, 953.6852838563258, 955.1403736637047, 955.9522023898812, 958.1807554540871, 741.218014994181, 944.6740874398322, 945.1729736480579, 945.9101385368414, 949.5679465843059, 565.7793460550389, 567.568647827654, 569.0780934885512, 682.5392859819602, 706.7526365771938, 14805.925970370186, 14849.183294918776, 14860.62848428787, 14882.0, 14892.180930048842, 11324.0, 14169.433222671183, 14173.503927362493, 14219.36012879423, 14393.856061439355, 11199.720006999809, 12622.368881555978, 12805.55180568685, 12849.100562960597, 12852.0, 4615.607673347769, 4662.860114196579, 4708.7174769513595, 4752.310914917352, 4853.684510506806, 104687.53124687547, 107116.0, 107313.0, 107315.0, 107407.46296268469, 10357.274990750575, 10719.410432426292, 10819.56721731122, 10952.233343665866, 11436.370999594936, 16710.58223544397, 20856.895715521325, 20986.0, 21113.838738869203, 21124.78875211259, 7973.0830954439825, 8034.758957231293, 8035.558044307502, 8198.38512111594, 8199.549024803573, 4323.200207961537, 4477.641788656965, 4579.564941330568, 4659.324397962277, 4672.462666793294, 3415.487676848443, 3523.0839981604454, 3527.6119626841028, 3530.982345088258, 3531.858725650945, 647.5337756815086, 649.0500674434705, 652.350910843704, 654.1561385812249, 654.3783405764543, 528.1364968276819, 593.9465448109604, 616.4174854762207, 630.2657404124144, 630.2940706408759, 8680.696175633822, 8724.65101395937, 8793.868091978626, 8795.604197811113, 9062.81874362509, 545.9972700136553, 562.2072877243149, 600.948919341852, 608.8477880529867, 626.117174783553, 3987.6211759882763, 4300.376445415397, 4310.913781724348, 4315.28797748368, 4413.867583972486, 149138.7629185563, 156047.0, 156832.21583892006, 156887.0, 160301.0, 81572.0, 126449.0, 129137.0, 132116.3394183042, 134449.32775336062, 117051.0, 118275.0, 119202.0, 119378.0, 119865.40067299783, 8334.87497687523, 8409.495430274203, 8456.154384561494, 8579.227869491775, 8682.609282582425, 54635.72682136723, 64270.678646608336, 64315.035274469985, 65896.34103659094, 65917.0, 93431.79556920091, 96947.51526242324, 99073.0, 100832.0, 102501.0, 11579.434002594619, 12676.873231267939, 13385.0, 13404.32978351098, 13489.392977315849, 15028.0, 15109.244537773287, 15293.923530382277, 15424.845751542342, 16124.516264512084, 13479.528216512375, 13851.584452466443, 13853.30733463343, 14129.364178612193, 14917.179555124572, 10254.641087562199, 13837.30813459303, 14610.634734131307, 14648.60751182588, 14670.853291466949, 13394.26331551735, 13679.0, 13811.0, 13818.447262109403, 14072.718545629235, 11117.388543629855, 11258.38078905668, 11361.54553817838, 11856.70358241051, 11884.702882428008, 11229.943850280697, 11558.0, 11750.94124529372, 11759.823602646138, 11792.882071179178], "value": 7885.665412672829, "unit": "score"}], "name": "dromaeo_css", "value": 7335.726248801533, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 112.0, 113.0, 112.0, 112.0, 113.0, 112.0, 111.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 106.0, 155.0, 110.0, 108.0, 128.0, 105.0, 159.0, 137.0], "value": 125.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 183.0, 183.0, 183.0, 185.0, 184.0, 190.0, 183.0, 173.0, 183.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 58.0, 107.0, 58.0, 60.0, 62.0, 66.0, 96.0, 56.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 93.0, 92.0, 70.0, 70.0, 68.0, 99.0, 71.0, 67.0, 100.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 90.0, 86.0, 89.0, 85.0, 89.0, 87.0, 88.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 93.0, 93.0, 92.0, 94.0, 101.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 84.0, 84.0, 83.0, 86.0, 86.0, 86.0, 90.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 53.0, 53.0, 54.0, 54.0, 54.0, 53.0, 56.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 78.0, 78.0, 75.0, 71.0, 71.0, 72.0, 71.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 93.0, 101.0, 101.0, 102.0, 84.0, 84.0, 98.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 141.0, 137.0, 135.0, 135.0, 140.0, 142.0, 152.0, 232.0, 146.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 177.0, 167.0, 166.0, 165.0, 165.0, 168.0, 166.0, 166.0, 167.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 66.0, 65.0, 67.0, 67.0, 67.0, 68.0, 83.0, 72.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1447.9999999999998, "alertThreshold": 2.0}]} 07:45:29 INFO - Return code: 0 07:45:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:45:30 ERROR - # TBPL SUCCESS # 07:45:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:45:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:45:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:45:30 INFO - 2016-07-14 07:45:30.050 screenresolution[1660:10366] starting screenresolution argv=screenresolution get 07:45:30 INFO - 2016-07-14 07:45:30.052 screenresolution[1660:10366] Display 0: 1600x1200x32@60 07:45:30 INFO - 2016-07-14 07:45:30.058 screenresolution[1661:10369] starting screenresolution argv=screenresolution list 07:45:30 INFO - Available Modes on Display 0 07:45:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:45:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:45:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:45:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:45:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:45:30 INFO - Intel Iris: 07:45:30 INFO - Chipset Model: Intel Iris 07:45:30 INFO - Type: GPU 07:45:30 INFO - Bus: Built-In 07:45:30 INFO - VRAM (Dynamic, Max): 1536 MB 07:45:30 INFO - Vendor: Intel (0x8086) 07:45:30 INFO - Device ID: 0x0a2e 07:45:30 INFO - Revision ID: 0x0009 07:45:30 INFO - Displays: 07:45:30 INFO - Display: 07:45:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:45:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:45:30 INFO - Display Serial Number: 1600x1200 60 07:45:30 INFO - Main Display: Yes 07:45:30 INFO - Mirror: Off 07:45:30 INFO - Online: Yes 07:45:30 INFO - Rotation: Supported 07:45:30 INFO - Return code: 0 07:45:30 INFO - Running post-action listener: _resource_record_post_action 07:45:30 INFO - ##### 07:45:30 INFO - ##### Finished run-tests step (success) 07:45:30 INFO - ##### 07:45:30 INFO - Running post-run listener: _resource_record_post_run 07:45:30 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 38176256; Write bytes: 706010112; Read time: 391; Write time: 1943 07:45:30 INFO - TinderboxPrint: CPU usage
53.3% 07:45:30 INFO - TinderboxPrint: I/O read bytes / time
38,176,256 / 391 07:45:30 INFO - TinderboxPrint: I/O write bytes / time
706,010,112 / 1,943 07:45:30 INFO - TinderboxPrint: CPU idle
2,260.2 (46.7%) 07:45:30 INFO - TinderboxPrint: CPU system
961.0 (19.8%) 07:45:30 INFO - TinderboxPrint: CPU user
1,620.4 (33.5%) 07:45:30 INFO - TinderboxPrint: Swap in / out
445,255,680 / 0 07:45:30 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392688128; Write bytes: 386603008; Read time: 29365; Write time: 750 07:45:30 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 37209600; Write bytes: 295427584; Read time: 376; Write time: 1139 07:45:30 INFO - Running post-run listener: _upload_blobber_files 07:45:30 INFO - Blob upload gear active. 07:45:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:45:30 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 07:45:30 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:45:30 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:45:31 INFO - (blobuploader) - INFO - Open directory for files ... 07:45:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:45:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:45:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:32 INFO - (blobuploader) - INFO - Done attempting. 07:45:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:45:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:45:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:33 INFO - (blobuploader) - INFO - Done attempting. 07:45:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:45:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:45:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:36 INFO - (blobuploader) - INFO - Done attempting. 07:45:36 INFO - (blobuploader) - INFO - Iteration through files over. 07:45:36 INFO - Return code: 0 07:45:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:45:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:45:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b59780e58111e32242a3ac7b548c4171d9ee2cbb8fc0c15ec74106d6f0dc7a11a8fb3ac80c366ebe5b88b28a7815d48e6cfe757acd50c85168c2665c9482e048", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4636669760a9f056c5cf5fae38646048a5331e2cf1f09f307e26b75c8a490873b202a300d8b7b186c7e751f3c612d8252a0c3f57fcfc147651fe1dba6ba22c5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f24440b4c1ee63892f1902a33e9fe57162fb23628c109f12b11f21ead033bcd41e594065ba16460155296276c9fe30b16d4e878d601658c452d1565e0dfa1c7"} 07:45:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:45:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:45:36 INFO - Contents: 07:45:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b59780e58111e32242a3ac7b548c4171d9ee2cbb8fc0c15ec74106d6f0dc7a11a8fb3ac80c366ebe5b88b28a7815d48e6cfe757acd50c85168c2665c9482e048", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4636669760a9f056c5cf5fae38646048a5331e2cf1f09f307e26b75c8a490873b202a300d8b7b186c7e751f3c612d8252a0c3f57fcfc147651fe1dba6ba22c5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f24440b4c1ee63892f1902a33e9fe57162fb23628c109f12b11f21ead033bcd41e594065ba16460155296276c9fe30b16d4e878d601658c452d1565e0dfa1c7"} 07:45:36 INFO - Running post-run listener: copy_logs_to_upload_dir 07:45:36 INFO - Copying logs to upload dir... 07:45:36 INFO - mkdir: /builds/slave/test/build/upload/logs 07:45:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.033673 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-14 07:45:36.493517) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 07:45:36.497439) ========= 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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b59780e58111e32242a3ac7b548c4171d9ee2cbb8fc0c15ec74106d6f0dc7a11a8fb3ac80c366ebe5b88b28a7815d48e6cfe757acd50c85168c2665c9482e048", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4636669760a9f056c5cf5fae38646048a5331e2cf1f09f307e26b75c8a490873b202a300d8b7b186c7e751f3c612d8252a0c3f57fcfc147651fe1dba6ba22c5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f24440b4c1ee63892f1902a33e9fe57162fb23628c109f12b11f21ead033bcd41e594065ba16460155296276c9fe30b16d4e878d601658c452d1565e0dfa1c7"} build_url:https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010811 build_url: 'https://queue.taskcluster.net/v1/task/cMNLoB7vRp6R0kANmmwtoQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b59780e58111e32242a3ac7b548c4171d9ee2cbb8fc0c15ec74106d6f0dc7a11a8fb3ac80c366ebe5b88b28a7815d48e6cfe757acd50c85168c2665c9482e048", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4636669760a9f056c5cf5fae38646048a5331e2cf1f09f307e26b75c8a490873b202a300d8b7b186c7e751f3c612d8252a0c3f57fcfc147651fe1dba6ba22c5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f24440b4c1ee63892f1902a33e9fe57162fb23628c109f12b11f21ead033bcd41e594065ba16460155296276c9fe30b16d4e878d601658c452d1565e0dfa1c7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 07:45:36.528572) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 07:45:36.528867) ========= 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.ZzRTP1dPWp/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.VtnMp9MX7F/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.005151 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 07:45:36.576426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 07:45:36.576720) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 07:45:36.577037) ========= ========= Total master_lag: 0.31 =========