builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0272 starttime: 1468279679.36 results: success (0) buildid: 20160711141026 builduid: 57834fe1578344e1a73354074eac1cf8 revision: aa3e75261064c860d29583974df3fef7fd90a15a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 16:27:59.362274) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 16:27:59.362740) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 16:27:59.363012) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 16:27:59.387183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:27:59.387458) ========= 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.fSj7clwVso/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.zFotWls4Bk/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-11 16:27:59-- 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% 152M=0s 2016-07-11 16:27:59 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.344782 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:27:59.754324) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:27:59.754636) ========= 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.fSj7clwVso/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.zFotWls4Bk/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.025712 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:27:59.803137) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:27:59.803457) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev aa3e75261064c860d29583974df3fef7fd90a15a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev aa3e75261064c860d29583974df3fef7fd90a15a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fSj7clwVso/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.zFotWls4Bk/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-11 16:27:59,885 truncating revision to first 12 chars 2016-07-11 16:27:59,885 Setting DEBUG logging. 2016-07-11 16:27:59,885 attempt 1/10 2016-07-11 16:27:59,885 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/aa3e75261064?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 16:28:00,573 unpacking tar archive at: fx-team-aa3e75261064/testing/mozharness/ program finished with exit code 0 elapsedTime=0.964830 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:28:00.790437) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 16:28:00.790788) ========= script_repo_revision: aa3e75261064c860d29583974df3fef7fd90a15a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 16:28:00.791171) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 16:28:00.791440) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 16:28:00.809779) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-11 16:28:00.810125) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fSj7clwVso/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.zFotWls4Bk/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28:01 INFO - MultiFileLogger online at 20160711 16:28:01 in /builds/slave/test 16:28:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:28:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:01 INFO - {'append_to_log': False, 16:28:01 INFO - 'base_work_dir': '/builds/slave/test', 16:28:01 INFO - 'blob_upload_branch': 'Fx-Team', 16:28:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:01 INFO - 'branch': 'Fx-Team', 16:28:01 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:01 INFO - 'config_files': ('talos/mac_config.py',), 16:28:01 INFO - 'default_actions': ('clobber', 16:28:01 INFO - 'read-buildbot-config', 16:28:01 INFO - 'download-and-extract', 16:28:01 INFO - 'populate-webroot', 16:28:01 INFO - 'create-virtualenv', 16:28:01 INFO - 'install', 16:28:01 INFO - 'run-tests'), 16:28:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:01 INFO - 'download_minidump_stackwalk': True, 16:28:01 INFO - 'download_symbols': 'ondemand', 16:28:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:01 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:01 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:01 INFO - 'installer_path': 'installer.exe', 16:28:01 INFO - 'log_level': 'info', 16:28:01 INFO - 'log_name': 'talos', 16:28:01 INFO - 'log_to_console': True, 16:28:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:28:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:28:01 INFO - 'opt_config_files': (), 16:28:01 INFO - 'pip_index': False, 16:28:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:01 INFO - 'cmd': ('bash', 16:28:01 INFO - '-c', 16:28:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:01 INFO - 'enabled': True, 16:28:01 INFO - 'halt_on_failure': False, 16:28:01 INFO - 'name': 'check_screen_resolution'},), 16:28:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:01 INFO - 'cmd': ('bash', 16:28:01 INFO - '-c', 16:28:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:01 INFO - 'enabled': True, 16:28:01 INFO - 'halt_on_failure': False, 16:28:01 INFO - 'name': 'check_screen_resolution'},), 16:28:01 INFO - 'run_cmd_checks_enabled': True, 16:28:01 INFO - 'sps_profile': False, 16:28:01 INFO - 'sps_profile_interval': 0, 16:28:01 INFO - 'suite': 'dromaeojs', 16:28:01 INFO - 'system_bits': '32', 16:28:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:01 INFO - 'title': 't-yosemite-r7-0272', 16:28:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:01 INFO - 'use_talos_json': True, 16:28:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:01 INFO - 'work_dir': 'build'} 16:28:01 INFO - ##### 16:28:01 INFO - ##### Running clobber step. 16:28:01 INFO - ##### 16:28:01 INFO - Running pre-action listener: _resource_record_pre_action 16:28:01 INFO - Running main action method: clobber 16:28:01 INFO - rmtree: /builds/slave/test/build 16:28:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:03 INFO - Running post-action listener: _resource_record_post_action 16:28:03 INFO - ##### 16:28:03 INFO - ##### Finished clobber step (success) 16:28:03 INFO - ##### 16:28:03 INFO - ##### 16:28:03 INFO - ##### Running read-buildbot-config step. 16:28:03 INFO - ##### 16:28:03 INFO - Running pre-action listener: _resource_record_pre_action 16:28:03 INFO - Running main action method: read_buildbot_config 16:28:03 INFO - Using buildbot properties: 16:28:03 INFO - { 16:28:03 INFO - "project": "", 16:28:03 INFO - "product": "firefox", 16:28:03 INFO - "script_repo_revision": "production", 16:28:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:28:03 INFO - "repository": "", 16:28:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:28:03 INFO - "buildid": "20160711141026", 16:28:03 INFO - "pgo_build": "False", 16:28:03 INFO - "basedir": "/builds/slave/test", 16:28:03 INFO - "buildnumber": 94, 16:28:03 INFO - "slavename": "t-yosemite-r7-0272", 16:28:03 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:28:03 INFO - "platform": "yosemite_r7", 16:28:03 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:28:03 INFO - "branch": "fx-team", 16:28:03 INFO - "repo_path": "integration/fx-team", 16:28:03 INFO - "revision": "aa3e75261064c860d29583974df3fef7fd90a15a", 16:28:03 INFO - "stage_platform": "macosx64", 16:28:03 INFO - "builduid": "57834fe1578344e1a73354074eac1cf8", 16:28:03 INFO - "slavebuilddir": "test" 16:28:03 INFO - } 16:28:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:28:03 INFO - Running post-action listener: _resource_record_post_action 16:28:03 INFO - ##### 16:28:03 INFO - ##### Finished read-buildbot-config step (success) 16:28:03 INFO - ##### 16:28:03 INFO - ##### 16:28:03 INFO - ##### Running download-and-extract step. 16:28:03 INFO - ##### 16:28:03 INFO - Running pre-action listener: _resource_record_pre_action 16:28:03 INFO - Running main action method: download_and_extract 16:28:03 INFO - mkdir: /builds/slave/test/build/tests 16:28: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')]} 16:28:03 INFO - https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:28:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:28:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:28:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/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 16:28:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/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 16:28:04 INFO - Downloaded 1199 bytes. 16:28:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:28:04 INFO - Using the following test package requirements: 16:28:04 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:28:04 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:28:04 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:28:04 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:28:04 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:28:04 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:28:04 INFO - u'jsshell-mac.zip'], 16:28:04 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:28:04 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:28:04 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:28:04 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:28:04 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:28:04 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:28:04 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:28:04 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:28:04 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:28:04 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:28:04 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:28:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:04 INFO - https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:28:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:28:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:28:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/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 16:28:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/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 16:28:05 INFO - Downloaded 18772189 bytes. 16:28:05 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'] 16:28:05 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 16:28:08 INFO - Return code: 0 16:28:08 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 16:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:08 INFO - https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:28:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:28:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:28:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/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 16:28:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/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 16:28:10 INFO - Downloaded 18772189 bytes. 16:28:10 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'] 16:28:10 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 16:28:13 INFO - Return code: 0 16:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:28:14 INFO - https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:28:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:28:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:28:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/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 16:28:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/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 16:28:15 INFO - Downloaded 14403333 bytes. 16:28:15 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'] 16:28:15 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 16:28:15 INFO - Return code: 0 16:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:28:15 INFO - https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:28:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:28:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:28:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:28:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EAG2ghZXS021-cg8Skmwhg/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 16:28:21 INFO - Downloaded 112436457 bytes. 16:28:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:28:21 INFO - mkdir: /builds/slave/test/properties 16:28:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:28:21 INFO - Writing to file /builds/slave/test/properties/build_url 16:28:21 INFO - Contents: 16:28:21 INFO - build_url:https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:28:22 INFO - Running post-action listener: _resource_record_post_action 16:28:22 INFO - Running post-action listener: set_extra_try_arguments 16:28:22 INFO - ##### 16:28:22 INFO - ##### Finished download-and-extract step (success) 16:28:22 INFO - ##### 16:28:22 INFO - ##### 16:28:22 INFO - ##### Running populate-webroot step. 16:28:22 INFO - ##### 16:28:22 INFO - Running pre-action listener: _resource_record_pre_action 16:28:22 INFO - Running main action method: populate_webroot 16:28:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:28:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:28:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:28:22 INFO - '--tppagecycles', 16:28:22 INFO - '7'], 16:28:22 INFO - 'tests': ['tsvgm']}}, 16:28:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:28:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:28:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:28:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:28:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:22 INFO - 'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:22 INFO - 'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['damp', 'tps']}, 16:28:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:22 INFO - 'tests': ['damp', 'tps']}, 16:28:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['dromaeo_dom']}, 16:28:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:28:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['basic_compositor_video']}, 16:28:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:28:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['a11yr', 16:28:22 INFO - 'ts_paint', 16:28:22 INFO - 'tpaint', 16:28:22 INFO - 'sessionrestore', 16:28:22 INFO - 'sessionrestore_no_auto_restore', 16:28:22 INFO - 'tabpaint']}, 16:28:22 INFO - 'other-e10s': {'tests': ['a11yr', 16:28:22 INFO - 'ts_paint', 16:28:22 INFO - 'tpaint', 16:28:22 INFO - 'sessionrestore', 16:28:22 INFO - 'sessionrestore_no_auto_restore', 16:28:22 INFO - 'tabpaint']}, 16:28:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:28:22 INFO - 'ts_paint', 16:28:22 INFO - 'tpaint', 16:28:22 INFO - 'sessionrestore', 16:28:22 INFO - 'sessionrestore_no_auto_restore', 16:28:22 INFO - 'tabpaint']}, 16:28:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:28:22 INFO - 'ts_paint', 16:28:22 INFO - 'tpaint', 16:28:22 INFO - 'sessionrestore', 16:28:22 INFO - 'sessionrestore_no_auto_restore', 16:28:22 INFO - 'tabpaint']}, 16:28:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['a11yr', 16:28:22 INFO - 'ts_paint', 16:28:22 INFO - 'tpaint', 16:28:22 INFO - 'sessionrestore', 16:28:22 INFO - 'sessionrestore_no_auto_restore', 16:28:22 INFO - 'tabpaint']}, 16:28:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['a11yr', 16:28:22 INFO - 'ts_paint', 16:28:22 INFO - 'tpaint', 16:28:22 INFO - 'sessionrestore', 16:28:22 INFO - 'sessionrestore_no_auto_restore', 16:28:22 INFO - 'tabpaint']}, 16:28:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['tsvgx', 16:28:22 INFO - 'tsvgr_opacity', 16:28:22 INFO - 'tart', 16:28:22 INFO - 'tscrollx', 16:28:22 INFO - 'cart']}, 16:28:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:28:22 INFO - 'tsvgr_opacity', 16:28:22 INFO - 'tart', 16:28:22 INFO - 'tscrollx', 16:28:22 INFO - 'cart']}, 16:28:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:22 INFO - 'talos_options': ['--disable-e10s'], 16:28:22 INFO - 'tests': ['tp5o']}, 16:28:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:22 INFO - 'tests': ['tp5o']}, 16:28:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:22 INFO - 'talos_options': ['--disable-e10s', 16:28:22 INFO - '--xperf_path', 16:28:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:22 INFO - 'tests': ['tp5n']}, 16:28:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:22 INFO - 'talos_options': ['--xperf_path', 16:28:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:22 INFO - 'tests': ['tp5n']}}, 16:28:22 INFO - 'talos.zip': {'path': '', 16:28:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:28:22 INFO - Running post-action listener: _resource_record_post_action 16:28:22 INFO - ##### 16:28:22 INFO - ##### Finished populate-webroot step (success) 16:28:22 INFO - ##### 16:28:22 INFO - ##### 16:28:22 INFO - ##### Running create-virtualenv step. 16:28:22 INFO - ##### 16:28:22 INFO - Running pre-action listener: _resource_record_pre_action 16:28:22 INFO - Running main action method: create_virtualenv 16:28:22 INFO - Creating virtualenv /builds/slave/test/build/venv 16:28:22 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 16:28:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:28:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:28:22 INFO - Using real prefix '/tools/python27' 16:28:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:28:22 INFO - Installing distribute.............................................................................................................................................................................................done. 16:28:25 INFO - Installing pip.................done. 16:28:25 INFO - Return code: 0 16:28:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ade8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb102c82930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, '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.fSj7clwVso/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.zFotWls4Bk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:28:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:28:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:25 INFO - 'HOME': '/Users/cltbld', 16:28:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:25 INFO - 'LOGNAME': 'cltbld', 16:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:25 INFO - 'MOZ_NO_REMOTE': '1', 16:28:25 INFO - 'NO_EM_RESTART': '1', 16:28:25 INFO - 'PAGER': '/bin/cat', 16:28:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:25 INFO - 'PWD': '/builds/slave/test', 16:28:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:25 INFO - 'SHELL': '/bin/bash', 16:28:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:28:25 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:28:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:25 INFO - 'USER': 'cltbld', 16:28:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:25 INFO - 'XPC_FLAGS': '0x0', 16:28:25 INFO - 'XPC_SERVICE_NAME': '0', 16:28:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:26 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 16:28:26 INFO - Cleaning up... 16:28:26 INFO - Return code: 0 16:28:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ad53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ade8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb102c82930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, '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.fSj7clwVso/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.zFotWls4Bk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:26 INFO - 'HOME': '/Users/cltbld', 16:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:26 INFO - 'LOGNAME': 'cltbld', 16:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:26 INFO - 'MOZ_NO_REMOTE': '1', 16:28:26 INFO - 'NO_EM_RESTART': '1', 16:28:26 INFO - 'PAGER': '/bin/cat', 16:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:26 INFO - 'PWD': '/builds/slave/test', 16:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:26 INFO - 'SHELL': '/bin/bash', 16:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:28:26 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:26 INFO - 'USER': 'cltbld', 16:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:26 INFO - 'XPC_FLAGS': '0x0', 16:28:26 INFO - 'XPC_SERVICE_NAME': '0', 16:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:26 INFO - Downloading/unpacking psutil>=3.1.1 16:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:28:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:28:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:28:29 INFO - Installing collected packages: psutil 16:28:29 INFO - Running setup.py install for psutil 16:28:29 INFO - building 'psutil._psutil_osx' extension 16:28:29 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 16:28:29 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 16:28:30 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 16:28:30 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 16:28:30 INFO - building 'psutil._psutil_posix' extension 16:28:30 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 16:28:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:28:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:28:30 INFO - ^ 16:28:30 INFO - 1 warning generated. 16:28:30 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 16:28:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:28:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:28:30 INFO - Successfully installed psutil 16:28:30 INFO - Cleaning up... 16:28:30 INFO - Return code: 0 16:28:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ade8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb102c82930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, '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.fSj7clwVso/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.zFotWls4Bk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:28:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:28:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:28:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:30 INFO - 'HOME': '/Users/cltbld', 16:28:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:30 INFO - 'LOGNAME': 'cltbld', 16:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:30 INFO - 'MOZ_NO_REMOTE': '1', 16:28:30 INFO - 'NO_EM_RESTART': '1', 16:28:30 INFO - 'PAGER': '/bin/cat', 16:28:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:30 INFO - 'PWD': '/builds/slave/test', 16:28:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:30 INFO - 'SHELL': '/bin/bash', 16:28:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:28:30 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:28:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:30 INFO - 'USER': 'cltbld', 16:28:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:30 INFO - 'XPC_FLAGS': '0x0', 16:28:30 INFO - 'XPC_SERVICE_NAME': '0', 16:28:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:28:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:28:33 INFO - Installing collected packages: mozsystemmonitor 16:28:33 INFO - Running setup.py install for mozsystemmonitor 16:28:33 INFO - Successfully installed mozsystemmonitor 16:28:33 INFO - Cleaning up... 16:28:33 INFO - Return code: 0 16:28:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:28:33 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')]} 16:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:33 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')]} 16:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:33 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 0x10ad53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ade8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb102c82930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, '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.fSj7clwVso/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.zFotWls4Bk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28:33 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 16:28:33 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 16:28:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:28:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:33 INFO - 'HOME': '/Users/cltbld', 16:28:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:33 INFO - 'LOGNAME': 'cltbld', 16:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:33 INFO - 'MOZ_NO_REMOTE': '1', 16:28:33 INFO - 'NO_EM_RESTART': '1', 16:28:33 INFO - 'PAGER': '/bin/cat', 16:28:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:33 INFO - 'PWD': '/builds/slave/test', 16:28:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:33 INFO - 'SHELL': '/bin/bash', 16:28:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:28:33 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:28:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:33 INFO - 'USER': 'cltbld', 16:28:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:33 INFO - 'XPC_FLAGS': '0x0', 16:28:33 INFO - 'XPC_SERVICE_NAME': '0', 16:28:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:33 INFO - Downloading/unpacking jsonschema==2.5.1 16:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:28:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:28:36 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 16:28:36 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 16:28:36 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 16:28:36 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 16:28:36 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 16:28:36 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 16:28:36 INFO - Downloading functools32-3.2.3-2.tar.gz 16:28:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:28:36 INFO - warning: no files found matching '*.txt' 16:28:36 INFO - no previously-included directories found matching 'build' 16:28:36 INFO - no previously-included directories found matching 'dist' 16:28:36 INFO - no previously-included directories found matching '.git*' 16:28:36 INFO - Installing collected packages: jsonschema, functools32 16:28:36 INFO - Running setup.py install for functools32 16:28:36 INFO - warning: no files found matching '*.txt' 16:28:36 INFO - no previously-included directories found matching 'build' 16:28:36 INFO - no previously-included directories found matching 'dist' 16:28:36 INFO - no previously-included directories found matching '.git*' 16:28:36 INFO - Successfully installed jsonschema functools32 16:28:36 INFO - Cleaning up... 16:28:36 INFO - Return code: 0 16:28:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:28:36 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')]} 16:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:36 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')]} 16:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:36 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 0x10ad53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ade8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb102c82930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, '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.fSj7clwVso/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.zFotWls4Bk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28:36 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 16:28:36 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 16:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:36 INFO - 'HOME': '/Users/cltbld', 16:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:36 INFO - 'LOGNAME': 'cltbld', 16:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:36 INFO - 'MOZ_NO_REMOTE': '1', 16:28:36 INFO - 'NO_EM_RESTART': '1', 16:28:36 INFO - 'PAGER': '/bin/cat', 16:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:36 INFO - 'PWD': '/builds/slave/test', 16:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:36 INFO - 'SHELL': '/bin/bash', 16:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:28:36 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:36 INFO - 'USER': 'cltbld', 16:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:36 INFO - 'XPC_FLAGS': '0x0', 16:28:36 INFO - 'XPC_SERVICE_NAME': '0', 16:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:37 INFO - Downloading/unpacking blobuploader==1.2.4 16:28:37 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 16:28:37 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 16:28:37 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 16:28:37 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 16:28:37 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 16:28:37 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 16:28:39 INFO - Downloading blobuploader-1.2.4.tar.gz 16:28:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:28:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:28:39 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 16:28:39 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 16:28:39 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 16:28:39 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 16:28:39 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 16:28:39 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 16:28:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:28:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:28:40 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 16:28:40 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 16:28:40 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 16:28:40 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 16:28:40 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 16:28:40 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 16:28:40 INFO - Downloading docopt-0.6.1.tar.gz 16:28:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:28:41 INFO - Installing collected packages: blobuploader, requests, docopt 16:28:41 INFO - Running setup.py install for blobuploader 16:28:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:28:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:28:41 INFO - Running setup.py install for requests 16:28:41 INFO - Running setup.py install for docopt 16:28:41 INFO - Successfully installed blobuploader requests docopt 16:28:41 INFO - Cleaning up... 16:28:41 INFO - Return code: 0 16:28:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:41 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')]} 16:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:41 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')]} 16:28:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:41 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 0x10ad53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ade8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb102c82930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, '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.fSj7clwVso/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.zFotWls4Bk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28:41 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 16:28:41 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 16:28:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:28:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:41 INFO - 'HOME': '/Users/cltbld', 16:28:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:41 INFO - 'LOGNAME': 'cltbld', 16:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:41 INFO - 'MOZ_NO_REMOTE': '1', 16:28:41 INFO - 'NO_EM_RESTART': '1', 16:28:41 INFO - 'PAGER': '/bin/cat', 16:28:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:41 INFO - 'PWD': '/builds/slave/test', 16:28:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:41 INFO - 'SHELL': '/bin/bash', 16:28:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:28:41 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:28:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:41 INFO - 'USER': 'cltbld', 16:28:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:41 INFO - 'XPC_FLAGS': '0x0', 16:28:41 INFO - 'XPC_SERVICE_NAME': '0', 16:28:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:42 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-sD_M7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:42 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-bNwCF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:42 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-4tMdAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:42 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-A2rmlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:42 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-eKrkWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:42 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-GWHlPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:42 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-f40OsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:42 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-XQikJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:42 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Bl1AmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:43 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-QMzLVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:43 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-uQ1yzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:43 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-IEU3S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:43 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-4szyGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:43 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-bDsk29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:43 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-jwa22a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:43 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-EKMdUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:43 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-ILlRfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:28:43 INFO - Running setup.py install for manifestparser 16:28:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:28:44 INFO - Running setup.py install for mozcrash 16:28:44 INFO - Running setup.py install for mozdebug 16:28:44 INFO - Running setup.py install for mozdevice 16:28:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:28:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:28:44 INFO - Running setup.py install for mozfile 16:28:44 INFO - Running setup.py install for mozhttpd 16:28:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:28:44 INFO - Running setup.py install for mozinfo 16:28:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:28:44 INFO - Running setup.py install for mozInstall 16:28:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:28:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:28:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:28:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:28:45 INFO - Running setup.py install for mozleak 16:28:45 INFO - Running setup.py install for mozlog 16:28:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:28:45 INFO - Running setup.py install for moznetwork 16:28:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:28:45 INFO - Running setup.py install for mozprocess 16:28:45 INFO - Running setup.py install for mozprofile 16:28:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:28:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:28:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:28:45 INFO - Running setup.py install for mozrunner 16:28:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:28:46 INFO - Running setup.py install for mozscreenshot 16:28:46 INFO - Running setup.py install for moztest 16:28:46 INFO - Running setup.py install for mozversion 16:28:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:28:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:28:46 INFO - Cleaning up... 16:28:46 INFO - Return code: 0 16:28:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:46 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 0x10ad53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ade8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb102c82930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, '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.fSj7clwVso/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.zFotWls4Bk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28:46 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 16:28:46 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 16:28:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:28:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:46 INFO - 'HOME': '/Users/cltbld', 16:28:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:46 INFO - 'LOGNAME': 'cltbld', 16:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:46 INFO - 'MOZ_NO_REMOTE': '1', 16:28:46 INFO - 'NO_EM_RESTART': '1', 16:28:46 INFO - 'PAGER': '/bin/cat', 16:28:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:46 INFO - 'PWD': '/builds/slave/test', 16:28:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:46 INFO - 'SHELL': '/bin/bash', 16:28:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:28:46 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:28:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:46 INFO - 'USER': 'cltbld', 16:28:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:46 INFO - 'XPC_FLAGS': '0x0', 16:28:46 INFO - 'XPC_SERVICE_NAME': '0', 16:28:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:46 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-b9oGLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:46 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)) 16:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:46 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-sWYABy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:46 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)) 16:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:46 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-RXdf7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:47 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)) 16:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:47 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-qJDXy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:47 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)) 16:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:47 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-mDS6Ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:47 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)) 16:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:47 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-htdwhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:47 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)) 16:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:47 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-FdgT7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:47 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)) 16:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:47 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-UtgFOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:47 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)) 16:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:47 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-8HKU18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:47 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)) 16:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:47 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-qWzMpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:47 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)) 16:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:47 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-9esWtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:47 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)) 16:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:47 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-ipFWnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:48 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)) 16:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:48 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-t37Cyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:48 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)) 16:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:48 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-VxIMtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:48 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)) 16:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:48 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-qz2mwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:48 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)) 16:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:48 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-6eXVKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:48 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)) 16:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:48 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-mxWTkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:48 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)) 16:28:48 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)) 16:28:48 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)) 16:28:48 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)) 16:28:48 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)) 16:28:48 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)) 16:28:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28:48 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 16:28:48 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 16:28:48 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 16:28:48 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 16:28:48 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 16:28:48 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 16:28:51 INFO - Downloading blessings-1.6.tar.gz 16:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:28:51 INFO - Installing collected packages: blessings 16:28:51 INFO - Running setup.py install for blessings 16:28:51 INFO - Successfully installed blessings 16:28:51 INFO - Cleaning up... 16:28:51 INFO - Return code: 0 16:28:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:28:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:51 INFO - Reading from file tmpfile_stdout 16:28:51 INFO - Current package versions: 16:28:51 INFO - blessings == 1.6 16:28:51 INFO - blobuploader == 1.2.4 16:28:51 INFO - docopt == 0.6.1 16:28:51 INFO - functools32 == 3.2.3-2 16:28:51 INFO - jsonschema == 2.5.1 16:28:51 INFO - manifestparser == 1.1 16:28:51 INFO - mozInstall == 1.12 16:28:51 INFO - mozcrash == 0.17 16:28:51 INFO - mozdebug == 0.1 16:28:51 INFO - mozdevice == 0.48 16:28:51 INFO - mozfile == 1.2 16:28:51 INFO - mozhttpd == 0.7 16:28:51 INFO - mozinfo == 0.9 16:28:51 INFO - mozleak == 0.1 16:28:51 INFO - mozlog == 3.2 16:28:51 INFO - moznetwork == 0.27 16:28:51 INFO - mozprocess == 0.23 16:28:51 INFO - mozprofile == 0.28 16:28:51 INFO - mozrunner == 6.12 16:28:51 INFO - mozscreenshot == 0.1 16:28:51 INFO - mozsystemmonitor == 0.3 16:28:51 INFO - moztest == 0.7 16:28:51 INFO - mozversion == 1.4 16:28:51 INFO - psutil == 3.1.1 16:28:51 INFO - requests == 1.2.3 16:28:51 INFO - wsgiref == 0.1.2 16:28:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28: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')]} 16:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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')]} 16:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:51 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 0x10ad53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ade8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb102c82930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, '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.fSj7clwVso/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.zFotWls4Bk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28:51 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 16:28:51 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 16:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:51 INFO - 'HOME': '/Users/cltbld', 16:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:51 INFO - 'LOGNAME': 'cltbld', 16:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:51 INFO - 'MOZ_NO_REMOTE': '1', 16:28:51 INFO - 'NO_EM_RESTART': '1', 16:28:51 INFO - 'PAGER': '/bin/cat', 16:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:51 INFO - 'PWD': '/builds/slave/test', 16:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:51 INFO - 'SHELL': '/bin/bash', 16:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:28:51 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:51 INFO - 'USER': 'cltbld', 16:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:51 INFO - 'XPC_FLAGS': '0x0', 16:28:51 INFO - 'XPC_SERVICE_NAME': '0', 16:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:51 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)) 16:28:51 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)) 16:28:51 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)) 16:28:51 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)) 16:28:51 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)) 16:28:51 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)) 16:28:51 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)) 16:28:51 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)) 16:28:51 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)) 16:28:51 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)) 16:28:51 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)) 16:28:51 INFO - Cleaning up... 16:28:51 INFO - Return code: 0 16:28:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:28: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')]} 16:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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')]} 16:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ade8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb102c82930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, '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.fSj7clwVso/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.zFotWls4Bk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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 16:28: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', 'jsonschema'] in /builds/slave/test/build 16:28: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 jsonschema 16:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:51 INFO - 'HOME': '/Users/cltbld', 16:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:51 INFO - 'LOGNAME': 'cltbld', 16:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:51 INFO - 'MOZ_NO_REMOTE': '1', 16:28:51 INFO - 'NO_EM_RESTART': '1', 16:28:51 INFO - 'PAGER': '/bin/cat', 16:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:51 INFO - 'PWD': '/builds/slave/test', 16:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:51 INFO - 'SHELL': '/bin/bash', 16:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:28:51 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:51 INFO - 'USER': 'cltbld', 16:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:51 INFO - 'XPC_FLAGS': '0x0', 16:28:51 INFO - 'XPC_SERVICE_NAME': '0', 16:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:28:52 INFO - Cleaning up... 16:28:52 INFO - Return code: 0 16:28:52 INFO - Running post-action listener: _resource_record_post_action 16:28:52 INFO - Running post-action listener: _start_resource_monitoring 16:28:52 INFO - Starting resource monitoring. 16:28:52 INFO - ##### 16:28:52 INFO - ##### Finished create-virtualenv step (success) 16:28:52 INFO - ##### 16:28:52 INFO - ##### 16:28:52 INFO - ##### Running install step. 16:28:52 INFO - ##### 16:28:52 INFO - Running pre-action listener: _resource_record_pre_action 16:28:52 INFO - Running main action method: install 16:28:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:52 INFO - Reading from file tmpfile_stdout 16:28:52 INFO - Detecting whether we're running mozinstall >=1.0... 16:28:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:28:52 INFO - Reading from file tmpfile_stdout 16:28:52 INFO - Output received: 16:28:52 INFO - Usage: mozinstall [options] installer 16:28:52 INFO - Options: 16:28:52 INFO - -h, --help show this help message and exit 16:28:52 INFO - -d DEST, --destination=DEST 16:28:52 INFO - Directory to install application into. [default: 16:28:52 INFO - "/builds/slave/test"] 16:28:52 INFO - --app=APP Application being installed. [default: firefox] 16:28:52 INFO - mkdir: /builds/slave/test/build/application 16:28:52 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'] 16:28:52 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 16:29:27 INFO - Reading from file tmpfile_stdout 16:29:27 INFO - Output received: 16:29:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:29:27 INFO - Running post-action listener: _resource_record_post_action 16:29:27 INFO - ##### 16:29:27 INFO - ##### Finished install step (success) 16:29:27 INFO - ##### 16:29:27 INFO - ##### 16:29:27 INFO - ##### Running run-tests step. 16:29:27 INFO - ##### 16:29:27 INFO - Running pre-action listener: _resource_record_pre_action 16:29:27 INFO - Running main action method: run_tests 16:29:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:29:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:29:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:29:27 INFO - 2016-07-11 16:29:27.496 screenresolution[1635:7377] starting screenresolution argv=screenresolution get 16:29:27 INFO - 2016-07-11 16:29:27.499 screenresolution[1635:7377] Display 0: 1600x1200x32@60 16:29:27 INFO - 2016-07-11 16:29:27.505 screenresolution[1636:7379] starting screenresolution argv=screenresolution list 16:29:27 INFO - Available Modes on Display 0 16:29:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:29:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:29:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:29:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:29:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:29:27 INFO - Intel Iris: 16:29:27 INFO - Chipset Model: Intel Iris 16:29:27 INFO - Type: GPU 16:29:27 INFO - Bus: Built-In 16:29:27 INFO - VRAM (Dynamic, Max): 1536 MB 16:29:27 INFO - Vendor: Intel (0x8086) 16:29:27 INFO - Device ID: 0x0a2e 16:29:27 INFO - Revision ID: 0x0009 16:29:27 INFO - Displays: 16:29:27 INFO - Display: 16:29:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:29:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:29:27 INFO - Display Serial Number: 1600x1200 60 16:29:27 INFO - Main Display: Yes 16:29:27 INFO - Mirror: Off 16:29:27 INFO - Online: Yes 16:29:27 INFO - Rotation: Supported 16:29:27 INFO - Return code: 0 16:29:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:29:27 INFO - Python 2.7.3 16:29:27 INFO - Return code: 0 16:29:27 INFO - grabbing minidump binary from tooltool 16:29: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')]} 16:29:27 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 0x10acb7d30>, '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 0x10aa1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acafb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:29:27 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 16:29:27 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 16:29:27 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 16:29:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:29:27 INFO - Return code: 0 16:29:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:29:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:29:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:29:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:29:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:29:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--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 16:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0272 --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 16:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fSj7clwVso/Render', 16:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:27 INFO - 'HOME': '/Users/cltbld', 16:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:27 INFO - 'LOGNAME': 'cltbld', 16:29:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:29:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:27 INFO - 'MOZ_NO_REMOTE': '1', 16:29:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:29:27 INFO - 'NO_EM_RESTART': '1', 16:29:27 INFO - 'PAGER': '/bin/cat', 16:29:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:27 INFO - 'PWD': '/builds/slave/test', 16:29:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:27 INFO - 'SHELL': '/bin/bash', 16:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zFotWls4Bk/Listeners', 16:29:27 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 16:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:27 INFO - 'USER': 'cltbld', 16:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:27 INFO - 'XPC_FLAGS': '0x0', 16:29:27 INFO - 'XPC_SERVICE_NAME': '0', 16:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--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 16:29:28 INFO - mozversion application_buildid: 20160711141026 16:29:28 INFO - mozversion application_changeset: aa3e75261064c860d29583974df3fef7fd90a15a 16:29:28 INFO - mozversion application_display_name: Nightly 16:29:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:29:28 INFO - mozversion application_name: Firefox 16:29:28 INFO - mozversion application_remotingname: firefox 16:29:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:29:28 INFO - mozversion application_vendor: Mozilla 16:29:28 INFO - mozversion application_version: 50.0a1 16:29:28 INFO - mozversion platform_buildid: 20160711141026 16:29:28 INFO - mozversion platform_changeset: aa3e75261064c860d29583974df3fef7fd90a15a 16:29:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:29:28 INFO - mozversion platform_version: 50.0a1 16:29:28 INFO - using testdate: 1468279768 16:29:28 INFO - actual date: 1468279768 16:29:28 INFO - starting webserver on 'localhost:49275' 16:29:28 INFO - SUITE-START | Running 2 tests 16:29:28 INFO - TEST-START | dromaeo_css 16:29:28 INFO - operating with platform_type : mac_ 16:29:28 INFO - Initialising browser for dromaeo_css test... 16:29:28 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpX3sg15/profile http://localhost:49275/getInfo.html) 16:29:30 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 16:29:30 INFO - PROCESS | 1646 | colorDepth:24 16:29:30 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 16:29:30 INFO - PROCESS | 1646 | __metrics 16:29:30 INFO - PROCESS | 1646 | 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" 16:29:30 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:29:31 INFO - PROCESS | 1646 | ************************* 16:29:31 INFO - PROCESS | 1646 | A coding exception was thrown in a Promise resolution callback. 16:29:31 INFO - PROCESS | 1646 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 16:29:31 INFO - PROCESS | 1646 | 16:29:31 ERROR - PROCESS | 1646 | Full message: TypeError: win is null 16:29:31 INFO - PROCESS | 1646 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 16:29:31 INFO - PROCESS | 1646 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 16:29:31 INFO - PROCESS | 1646 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 16:29:31 INFO - PROCESS | 1646 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 16:29:31 INFO - PROCESS | 1646 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 16:29:31 INFO - PROCESS | 1646 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 16:29:31 INFO - PROCESS | 1646 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 16:29:31 INFO - PROCESS | 1646 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 16:29:31 INFO - PROCESS | 1646 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 16:29:31 INFO - PROCESS | 1646 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 16:29:31 INFO - PROCESS | 1646 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 16:29:31 INFO - PROCESS | 1646 | Barrier/this.client.addBlocker/promise resource://gre/modules/Promise-backend.js:937:23 16:48:07 INFO - PROCESS | 1659 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 16:48:07 INFO - PROCESS | 1659 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 16:48:07 INFO - PROCESS | 1659 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 16:48:07 INFO - PROCESS | 1659 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 16:48:07 INFO - PROCESS | 1659 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 16:48:07 INFO - PROCESS | 1659 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 16:48:07 INFO - PROCESS | 1659 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 16:48:07 INFO - PROCESS | 1659 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 16:48:07 INFO - PROCESS | 1659 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 16:48:07 INFO - PROCESS | 1659 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 16:48:07 INFO - PROCESS | 1659 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 16:48:07 INFO - PROCESS | 1659 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 16:48:07 INFO - PROCESS | 1659 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 16:48:07 INFO - PROCESS | 1659 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 16:48:07 INFO - PROCESS | 1659 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 16:48:07 INFO - PROCESS | 1659 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1515:5 16:48:07 INFO - PROCESS | 1659 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1647:21 16:48:07 INFO - PROCESS | 1659 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2011:23 16:48:07 INFO - PROCESS | 1659 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3726:34 16:48:07 INFO - PROCESS | 1659 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2715:25 16:48:07 INFO - PROCESS | 1659 | callProvider@resource://gre/modules/AddonManager.jsm:227:12 16:48:07 INFO - PROCESS | 1659 | _startProvider@resource://gre/modules/AddonManager.jsm:779:5 16:48:07 INFO - PROCESS | 1659 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:963:9 16:48:07 INFO - PROCESS | 1659 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:2985:5 16:48:07 INFO - PROCESS | 1659 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 16:48:07 INFO - PROCESS | 1659 | 16:48:07 INFO - PROCESS | 1659 | ************************* 16:48:08 INFO - TEST-INFO | 1659: exit 0 16:48:08 INFO - Browser initialized. 16:48:08 INFO - Running cycle 1/1 for kraken test... 16:48:08 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmpP0LIQ1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:20 INFO - PROCESS | 1660 | RSS: Main: 267505664 16:48:20 INFO - PROCESS | 1660 | 16:48:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 16:48:23 INFO - PROCESS | 1660 | RSS: Main: 315166720 16:48:23 INFO - PROCESS | 1660 | 16:48:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 16:48:27 INFO - PROCESS | 1660 | RSS: Main: 324509696 16:48:27 INFO - PROCESS | 1660 | 16:48:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 16:48:31 INFO - PROCESS | 1660 | RSS: Main: 409305088 16:48:31 INFO - PROCESS | 1660 | 16:48:33 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 16:48:33 INFO - PROCESS | 1660 | RSS: Main: 310251520 16:48:33 INFO - PROCESS | 1660 | 16:48:35 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:48:36 INFO - PROCESS | 1660 | RSS: Main: 314249216 16:48:36 INFO - PROCESS | 1660 | 16:48:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 16:48:40 INFO - PROCESS | 1660 | RSS: Main: 350519296 16:48:40 INFO - PROCESS | 1660 | 16:48:42 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 16:48:43 INFO - PROCESS | 1660 | RSS: Main: 351625216 16:48:43 INFO - PROCESS | 1660 | 16:48:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 16:48:47 INFO - PROCESS | 1660 | RSS: Main: 351641600 16:48:47 INFO - PROCESS | 1660 | 16:48:48 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:48:49 INFO - PROCESS | 1660 | RSS: Main: 331964416 16:48:49 INFO - PROCESS | 1660 | 16:48:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:48:52 INFO - PROCESS | 1660 | RSS: Main: 338956288 16:48:52 INFO - PROCESS | 1660 | 16:48:54 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:48:54 INFO - PROCESS | 1660 | RSS: Main: 336269312 16:48:54 INFO - PROCESS | 1660 | 16:48:57 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:48:58 INFO - PROCESS | 1660 | RSS: Main: 345014272 16:48:58 INFO - PROCESS | 1660 | 16:49:01 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:01 INFO - PROCESS | 1660 | RSS: Main: 346419200 16:49:01 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 16:49:03 INFO - PROCESS | 1660 | RSS: Main: 389963776 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | __start_tp_report 16:49:03 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 16:49:03 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 16:49:03 INFO - PROCESS | 1660 | |i|pagename|runs| 16:49:03 INFO - PROCESS | 1660 | |0;ai-astar;94;113;112;111;112;111;111;110;111;113 16:49:03 INFO - PROCESS | 1660 | |1;audio-beat-detection;143;107;132;193;132;107;130;106;138;108 16:49:03 INFO - PROCESS | 1660 | |2;audio-dft;183;183;185;182;181;179;182;227;185;183 16:49:03 INFO - PROCESS | 1660 | |3;audio-fft;78;74;57;109;59;59;62;64;98;58 16:49:03 INFO - PROCESS | 1660 | |4;audio-oscillator;82;89;90;70;70;68;96;71;67;101 16:49:03 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;117;130;94;91;95;90;93;92;94;87 16:49:03 INFO - PROCESS | 1660 | |6;imaging-darkroom;96;97;96;96;96;95;96;95;96;95 16:49:03 INFO - PROCESS | 1660 | |7;imaging-desaturate;97;96;91;88;88;90;94;139;142;85 16:49:03 INFO - PROCESS | 1660 | |8;json-parse-financial;56;56;54;55;53;54;54;54;53;55 16:49:03 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;67;66;78;112;103;85;69;72;72;70 16:49:03 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;89;83;81;82;82;84;83;91;102;101 16:49:03 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;143;146;145;149;135;153;145;143;186;140 16:49:03 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;163;162;163;161;162;164;170;173;162;161 16:49:03 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;66;66;68;66;66;67;64;68;68;62 16:49:03 INFO - PROCESS | 1660 | __end_tp_report 16:49:03 INFO - PROCESS | 1660 | __start_cc_report 16:49:03 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1995 16:49:03 INFO - PROCESS | 1660 | __end_cc_report 16:49:03 INFO - PROCESS | 1660 | __startTimestamp1468280943861__endTimestamp 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | ------- Summary: start ------- 16:49:03 INFO - PROCESS | 1660 | Number of tests: 14 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:109.80 Median:112.00 stddev:5.63 (5.0%) stddev-sans-first:1.01 16:49:03 INFO - PROCESS | 1660 | Values: 94.0 113.0 112.0 111.0 112.0 111.0 111.0 110.0 111.0 113.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:132.00 stddev:26.51 (20.1%) stddev-sans-first:27.67 16:49:03 INFO - PROCESS | 1660 | Values: 143.0 107.0 132.0 193.0 132.0 107.0 130.0 106.0 138.0 108.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:187.00 Median:183.00 stddev:14.17 (7.7%) stddev-sans-first:14.95 16:49:03 INFO - PROCESS | 1660 | Values: 183.0 183.0 185.0 182.0 181.0 179.0 182.0 227.0 185.0 183.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:71.80 Median:63.00 stddev:18.28 (29.0%) stddev-sans-first:19.25 16:49:03 INFO - PROCESS | 1660 | Values: 78.0 74.0 57.0 109.0 59.0 59.0 62.0 64.0 98.0 58.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:76.50 stddev:12.80 (16.7%) stddev-sans-first:13.56 16:49:03 INFO - PROCESS | 1660 | Values: 82.0 89.0 90.0 70.0 70.0 68.0 96.0 71.0 67.0 101.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:92.50 stddev:13.82 (14.9%) stddev-sans-first:12.90 16:49:03 INFO - PROCESS | 1660 | Values: 117.0 130.0 94.0 91.0 95.0 90.0 93.0 92.0 94.0 87.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 16:49:03 INFO - PROCESS | 1660 | Values: 96.0 97.0 96.0 96.0 96.0 95.0 96.0 95.0 96.0 95.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:101.00 Median:90.50 stddev:21.16 (23.4%) stddev-sans-first:22.39 16:49:03 INFO - PROCESS | 1660 | Values: 97.0 96.0 91.0 88.0 88.0 90.0 94.0 139.0 142.0 85.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.07 (2.0%) stddev-sans-first:0.97 16:49:03 INFO - PROCESS | 1660 | Values: 56.0 56.0 54.0 55.0 53.0 54.0 54.0 54.0 53.0 55.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:79.40 Median:71.00 stddev:15.96 (22.5%) stddev-sans-first:16.28 16:49:03 INFO - PROCESS | 1660 | Values: 67.0 66.0 78.0 112.0 103.0 85.0 69.0 72.0 72.0 70.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:83.00 stddev:7.90 (9.5%) stddev-sans-first:8.37 16:49:03 INFO - PROCESS | 1660 | Values: 89.0 83.0 81.0 82.0 82.0 84.0 83.0 91.0 102.0 101.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:145.50 stddev:14.03 (9.6%) stddev-sans-first:14.74 16:49:03 INFO - PROCESS | 1660 | Values: 143.0 146.0 145.0 149.0 135.0 153.0 145.0 143.0 186.0 140.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:163.00 stddev:4.07 (2.5%) stddev-sans-first:4.29 16:49:03 INFO - PROCESS | 1660 | Values: 163.0 162.0 163.0 161.0 162.0 164.0 170.0 173.0 162.0 161.0 16:49:03 INFO - PROCESS | 1660 | 16:49:03 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.91 (2.9%) stddev-sans-first:2.03 16:49:03 INFO - PROCESS | 1660 | Values: 66.0 66.0 68.0 66.0 66.0 67.0 64.0 68.0 68.0 62.0 16:49:03 INFO - PROCESS | 1660 | -------- Summary: end -------- 16:49:03 INFO - PROCESS | 1660 | 16:49:04 INFO - TEST-INFO | 1660: exit 0 16:49:04 INFO - TEST-OK | kraken | took 59684ms 16:49:04 INFO - SUITE-END | took 1176s 16:49:05 INFO - Completed test suite (00:19:36) 16:49:05 INFO - javascript benchmark 16:49:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.666733326668, 3360.7479439042063, 3368.27582069855, 3395.270016946356, 3433.7939723616587, 5553.333599967999, 5647.124695672167, 5664.433556644333, 5790.9420905790985, 5803.361630220677, 5673.26247587814, 5714.1428785682165, 5714.428557144294, 5716.399778023299, 5732.770689172428, 5106.77019534121, 5157.071727089123, 5166.147585648364, 5182.404023537304, 5244.239585260135, 5224.45143259958, 5226.268322434862, 5240.60695447841, 5244.501772331641, 5250.317458730379, 5152.330197074394, 5161.741912904358, 5165.509276618715, 5182.637215394924, 5231.921521177185, 5262.763175657085, 5278.419373868871, 5289.576833853283, 5294.311739473844, 5326.627136100475, 5071.467495912912, 5077.959018401219, 5111.386633603955, 5198.740062996872, 5214.843554693365, 5654.830355089354, 5665.12190610456, 5718.370979192286, 5745.827625171251, 5784.334801497839, 6262.2172228471445, 6276.843078923018, 6286.528510361695, 6286.811395658137, 6300.905486417708, 6122.724477398483, 6195.101710251988, 6300.873982520324, 6324.0, 6343.619382836999, 5837.09525023622, 5863.120531920161, 5881.735321910524, 5886.6173698709445, 5908.379620139909, 6288.056791481315, 6300.0, 6300.1494798202575, 6303.905441418382, 6309.179806625109, 5094.719790411489, 5987.43119403656, 6090.908636370458, 6104.76233045773, 6150.261968563712, 5286.286351342596, 6318.8420289492215, 6323.272823625342, 6332.366763323723, 6345.524085693592, 6189.412005859436, 6194.876102477925, 6197.318294987548, 6199.56603037783, 6206.10011548332, 5901.852453688693, 6056.545759068087, 6157.107219453154, 6206.286277078104, 6211.9689401553605, 4984.77568509418, 5026.145555255615, 5035.874103147451, 5044.54599086084, 5068.265101560253, 4486.798094085774, 4501.032278060233, 4510.300903359919, 4535.092981403666, 4554.681172317906, 5613.073842815971, 5658.74535645897, 5662.745176467069, 5718.085106382959, 5720.68536230503, 6186.0, 6243.656598887014, 6317.273513545909, 6320.55756097078, 6322.747090116344, 5775.95394376435, 5781.682007489629, 5783.652980821164, 5783.71081445926, 5846.590738648339, 5346.171343441695, 5467.72661366938, 5468.48049435303, 5484.067708489566, 5488.4785945335125, 6192.8761424771255, 6216.098665693539, 6221.595596286227, 6238.812835614939, 6287.717052732546, 6303.905441418474, 6320.178376810985, 6328.841778955379, 6329.810105696836, 6339.175907132228, 6210.192674952227, 6218.751249950133, 6292.339304373158, 6322.525810564134, 6332.050192471166, 5385.434529374359, 6123.343628574205, 6168.629882207082, 6244.843878903063, 6248.062790581449, 5162.019216348882, 6270.905936410866, 6287.2140982377205, 6307.653079080695, 6321.1782468278825, 5996.640201587918, 6086.482648974709, 6127.3566275540425, 6136.2636483622255, 6181.598196117238, 6153.046277826944, 6155.045967874987, 6167.352427994996, 6173.536984726074, 6180.320164781961, 6027.728752206073, 6051.697415129138, 6065.969670151798, 6096.9390306096375, 6110.205673262448, 5914.497267732291, 6007.519398448201, 6094.0, 6101.343849114374, 6101.762223876893, 5897.67562784051, 5966.850828729316, 5973.850653733518, 5975.850603734942, 6056.545759068], "value": 5705.4180951079325, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.9615278854086, 542.3816848792377, 548.4488089470082, 549.4752511351658, 570.0281020859435, 23518.64722029171, 31043.0, 39974.80012599937, 41061.384079238764, 41931.790341048254, 42413.0, 42593.0, 42697.14605707884, 42736.572634273645, 42894.35658465118, 9618.374805637646, 9781.559829807677, 9783.853242201372, 9786.46174460408, 9808.01919808017, 8662.913370866305, 8860.645574177026, 11743.941280293586, 11901.166918315721, 11935.641930742091, 11838.526458941633, 11907.761844763143, 11921.986631136364, 11931.343776092312, 11942.462589183508, 8656.177663121993, 11726.882731172664, 11852.762944741144, 11927.821082683766, 11941.462634181482, 11591.884081159249, 11852.881471185265, 11869.703257418547, 11875.34685592292, 11878.109141814399, 40151.397729034084, 40496.78509644686, 40620.18759624821, 40656.59343406557, 40719.59280407217, 3599.406097993828, 3713.870014549505, 3820.6943444524372, 4083.1221287423346, 4425.09285596452, 31441.912551368176, 31486.842565787025, 32306.838465807756, 32421.351572968408, 32440.0, 3246.870125194989, 3364.158960259935, 3388.491726241059, 3421.9144521387166, 3452.7410444216785, 530621.0, 532951.0, 533917.9999999962, 535276.0, 536159.0, 56809.14786278209, 56915.0, 57355.71322143446, 57382.12251958547, 57433.42566574364, 59860.0, 60194.69902650546, 60261.39738602558, 60281.0, 60310.79378412495, 16120.838791612168, 16328.75506867398, 16331.346746130259, 16346.673066538602, 16365.181740912907, 11533.826992595117, 11798.115141364431, 12754.426050827735, 12813.679658008437, 12840.678983025313, 15243.771343429857, 15298.847011529964, 15319.0, 15349.846501535065, 15407.15260660652, 5928.67392293428, 6908.689108990107, 7041.683124259421, 7148.0, 7169.175544812309, 571.828451464559, 754.4190972950906, 896.9686060987965, 1119.9048080913215, 1139.5954436175205, 22402.215922442636, 29452.410951781272, 30093.247668808453, 30235.244118896764, 30837.69162308393, 1053.4100903494068, 1069.1340014588209, 1125.0268517732266, 1128.430142777892, 1202.8255902894032, 12808.167469114478, 15591.844081559266, 15628.0, 15902.966307190227, 16353.936994095346, 735467.0, 736176.0, 743769.0, 744014.0, 745477.0, 58966.11550826741, 59434.70282648559, 59476.70261648664, 59578.80842383128, 59639.40360596339, 1267.283984548727, 1284.3963337231487, 1400.3138462153472, 1432.6676191142337, 1435.1174027972722, 31207.687923120477, 31466.37067258688, 31480.212994675316, 31606.525902111945, 32294.031179064667, 48537.0, 49085.75457122691, 49558.0, 49621.0, 49693.503064968885, 519754.0, 521853.0, 524859.0, 527151.0, 531405.0, 35899.0, 36536.45195322019, 42349.78825105855, 43126.13747725091, 43257.3511397323, 47625.76187119042, 47815.76092119517, 47899.0, 48610.0, 48749.0, 37986.62013379831, 39632.2073558533, 39655.0, 40175.79912100421, 40337.39493907653, 38243.0, 39169.06569611553, 39411.211775764896, 39979.40030899481, 40126.79936600298, 37866.62133378631, 38560.6143938557, 38625.0, 38703.0, 39037.414438784006, 30875.0, 33813.830930845186, 35082.82458587691, 35176.82411588028, 35328.64671353254, 20894.955252237025, 24103.758962410153, 25308.987640494175, 25627.87186064058, 26067.478650427263], "value": 20565.30603205175, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.4994507798925, 374.7189607794156, 374.7863717680921, 379.73798079325263, 395.33978256311957, 15539.61150971225, 15714.685706285867, 20834.166633334688, 21102.15591376347, 21179.894100529476, 21126.89436552815, 21192.046357913892, 21559.461013474673, 21566.0, 21575.784242157573, 5511.751971161308, 5837.445442682949, 6097.725602347883, 6106.175666285061, 6108.14485971962, 5398.568114550826, 5996.130561068642, 6076.939230607703, 6087.847803804896, 6101.908471372911, 5756.222909907172, 6031.758729650809, 6038.879222415549, 6126.908096378558, 6128.4178003089855, 6031.577789554689, 6111.388861113897, 6122.234720659895, 6133.294671112834, 6134.325224225332, 5868.501177399926, 6034.275886893601, 6035.426634469763, 6036.0945858121195, 6040.184575082351, 13068.477260909553, 13264.005199609972, 13541.187528748307, 13610.319484025758, 13673.24797136157, 2234.229190929127, 2245.4161917901465, 2379.821513386503, 2411.7105947286436, 2786.5123603369534, 10047.698569042941, 10334.276600637959, 10523.684289471328, 10537.262391632588, 11473.541058357656, 1740.5387572293353, 1887.9528011799678, 1904.190718944443, 1941.6412344855532, 1985.3448362040492, 108148.0, 114861.425692871, 114892.42553787261, 115111.0, 116809.41595292054, 10906.236563440641, 11221.831672524919, 11511.25176863518, 11550.537978480934, 11691.707707307385, 14282.57152285433, 14386.84905207581, 14392.424303027761, 14406.351714172888, 14406.927965360106, 8349.499030058216, 8510.78723031929, 8537.530435825965, 8601.655933762704, 8640.179182977607, 8458.154184581492, 8470.280026197719, 8478.321734261244, 8593.52735599548, 8608.78478038054, 3746.269477451871, 4499.662525310615, 4616.699914505546, 4647.279671650832, 4660.533946605312, 1870.3173341730171, 1917.6452356314126, 1919.7826510380532, 1923.519120219945, 1924.769027716683, 865.1996902864825, 911.8449863523217, 969.8254314223508, 988.125508924607, 1028.4395004722444, 21568.89215553912, 21980.670289945665, 21990.45023874416, 22052.228172013904, 22072.668909966364, 895.7044175422226, 931.7856892914667, 949.2168960607527, 957.3011701457974, 958.2450454942042, 8875.511846848485, 11478.827817582744, 11574.88425115755, 11586.304821710724, 11601.883981160248, 608616.9569152214, 616356.0, 622772.5570900831, 630067.0, 630091.0, 67145.0, 84438.75638032451, 134611.32694336466, 137630.0, 137846.31076844552, 948.0566835998226, 953.909378609031, 967.7532229281488, 975.3367709957297, 980.6812785844486, 19163.61672766565, 19314.32399865998, 19330.903345483184, 19366.225350986366, 19369.806301937362, 43944.04503594564, 53343.46656533385, 55156.44843551513, 55198.724006379714, 56050.43949560452, 75332.49335013378, 78296.0, 78503.21496784959, 78666.0, 79097.0, 8031.076426211061, 8086.1509541499645, 8136.552489613128, 8168.305694016075, 8168.959155204186, 11710.355930423908, 12274.693132671397, 12609.621711348675, 12645.178063425848, 12938.288394138413, 10904.291221070605, 11301.09591232683, 11383.54465821358, 11439.59961401347, 11474.368909710047, 4275.5724427557, 7593.860920861915, 9738.610455581698, 10859.619913302997, 11087.667369978913, 9506.667266645634, 11321.660350189508, 11455.828162577734, 11459.197856150324, 11690.941545292559, 6995.930040699528, 7412.851742965218, 7476.775696729107, 7694.923050769645, 8260.83478330418, 8617.003782272483, 9987.400755954666, 9997.300188986992, 11367.886321137014, 11541.134414919083], "value": 9346.017453970659, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1539.3611651164763, 1572.661877696295, 1784.7322901564764, 1912.2446633579727, 1940.8641395102347, 7859.174786647405, 8093.109757926623, 8333.0, 8797.824043519135, 8837.558122093893, 6956.582605043701, 8562.18659227374, 8563.015253245878, 8661.0, 8669.69656062038, 2272.6363781794876, 2347.6830627865193, 2352.3766201956496, 2353.670486131943, 2356.835021548484, 2073.1603700501323, 2140.9937329455133, 2207.3377986603955, 2280.7149106361703, 2291.713535808024, 2301.470661747799, 2335.077644330497, 2344.894479748407, 2349.635806450003, 2354.5997180479358, 2320.791128798408, 2356.422676444265, 2357.5167090746527, 2361.9291421257217, 2363.196513185525, 2291.4156889992946, 2302.5740238055846, 2316.513532158249, 2318.084356679119, 2323.0011095229047, 8617.353698472576, 8691.08743581921, 8692.739217823475, 8697.695580654647, 8700.695475658385, 5150.716710580917, 5302.708351040691, 5308.575313974911, 5312.654677445954, 5318.22885681574, 3475.4439289713523, 3548.503209550665, 3582.158192824684, 3582.3551760683067, 3585.2470981093743, 1064.1433645915035, 1086.869575650919, 1110.7056629993058, 1115.9944200279028, 1117.983230251547, 11212.0, 11380.374079425628, 11404.828927566094, 11413.315201087878, 11453.541858325658, 4072.490938632671, 4599.6090332321455, 4629.86110416688, 4821.349117869043, 4854.17479028566, 9068.54657267134, 9101.362904596746, 9159.862602060974, 9167.954160229156, 9172.357934944623, 5216.60875434344, 5218.321618189611, 5222.451642577551, 5226.451222621646, 5230.293910322134, 4701.2478003519955, 4857.417109946829, 4870.366852309177, 4880.829170978998, 4883.926741098886, 3705.814709264526, 3766.0584853786554, 3769.1142581493327, 3806.71449641278, 3869.6323849234277, 1079.63292480557, 1104.7127746785734, 1127.7575321305958, 1132.8130858408435, 1151.7351009267916, 839.8278352937633, 911.5351170902887, 936.8688383626297, 937.7421209167534, 971.7813491964251, 5831.883362332815, 5833.4749872511675, 5858.648481091148, 5958.7914422995855, 6000.129981152796, 900.5497251374313, 935.2144198872979, 955.6177528988321, 958.9520523973773, 983.7245571240064, 3869.3035253654048, 4045.474088368552, 4069.084455997377, 4077.347624380085, 4100.91798164035, 10063.49682515886, 10189.796204075878, 10531.473426328806, 10542.894571054192, 10557.577696892193, 10373.170146388271, 10488.68533943983, 10489.842652360221, 10501.579936802442, 10519.94740026295, 8032.518048917083, 8065.1934806518875, 8132.390070744717, 8190.836183276301, 8226.465279756796, 4964.882901347168, 5129.846104616868, 5690.829275121753, 5820.621659592113, 5938.257717785277, 9472.763180920532, 9632.181264592587, 9856.704298871045, 9856.901430985885, 9861.802763944825, 9855.45794981283, 9948.403095813985, 9972.052654997764, 10077.395356278646, 10233.0, 7131.643417828984, 7164.319389657974, 7206.89189662145, 7230.060092187982, 7237.312455316736, 7566.962165189138, 8070.6368213431715, 8320.875186872081, 8335.374846886387, 8356.87464688018, 6467.935320646921, 7110.182329032227, 7139.964300178466, 7351.117865856131, 7585.582792946441, 7025.437964962893, 7186.1376634804155, 7373.963130184315, 7377.188509264084, 7404.0, 6958.478114141561, 7052.047973523714, 7099.325564071405, 7112.822179445555, 7124.821879453055, 4258.552851950501, 4823.469418363907, 4953.009398120318, 4996.276284681779, 5029.346184995927, 4963.9007219854675, 4978.5270399312, 5022.970291090244, 5028.64799464034, 5118.641695081308], "value": 4515.312712946531, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.65090669370176, 304.3729916372273, 306.4942844306894, 311.09936733157525, 312.503120039138, 4744.762761861902, 5114.079465696173, 5328.227407025984, 5333.759980800864, 5369.194620806871, 5078.64449488536, 5082.881766011481, 5302.0, 5340.67955922644, 5353.544948679357, 3526.612072672004, 3835.123657257064, 4015.518137823465, 4069.470968774055, 4121.4848143982, 3758.6053464386255, 4056.5943405659345, 4058.9594104059024, 4109.506859176903, 4148.937765933513, 3520.9789161143367, 4025.899352516196, 4035.8587449439274, 4061.939070913939, 4065.166640838621, 3636.4181730922924, 3850.152966347399, 4024.1146947671464, 4054.2702313583545, 4096.40602112692, 3526.717862570988, 3547.7693949893433, 3569.8572057117685, 3570.2505772171708, 3594.5506811648543, 4545.636189258316, 4941.925871111936, 5031.949680503185, 5272.604554658416, 5341.973290133525, 3781.7541859779303, 3952.6245006724316, 4706.046990498939, 4721.480637129913, 4724.385829842133, 5367.919481207785, 5497.972510137463, 5548.44515548446, 5549.585930120411, 5552.278203833516, 3423.3837909176336, 3948.6643635291034, 3949.012746813297, 3962.0293028207984, 3975.608537012052, 4679.0, 5821.0, 5847.678377689225, 5861.5603829712945, 5931.199288096095, 3970.1737200887915, 3983.6215559521797, 3992.680585553149, 4008.6993475489453, 4021.2962731521866, 4086.1623367210245, 4088.713790034669, 4097.7541347519245, 4099.651529620016, 4110.383442483591, 4252.893677658021, 4470.0, 4574.08518296342, 4677.415323084614, 4697.765111744399, 5562.666240025612, 5585.436077898195, 5591.776328946797, 5594.328680558359, 5616.66300022, 1099.4612639806596, 1223.3577371879835, 1284.7623189709934, 1378.9586312410643, 1397.0290647999543, 750.9324160825552, 768.5350363030398, 812.1837553259055, 857.3227150550995, 868.774118729135, 4793.616510679138, 4922.695485696293, 4956.0, 5126.128558145124, 5138.768755406016, 4936.432310284292, 5344.171653393731, 5518.834434966958, 5751.1085781703905, 5821.476067153976, 4607.940173759987, 4838.201696720002, 5157.664751791197, 5201.401838788588, 5214.556762675215, 2730.945381092367, 2990.985045074804, 3033.8331391773663, 3049.7102775236317, 3053.6944606822153, 5022.472640372781, 5819.53443724501, 5934.406559344031, 5971.164037034819, 6005.729742161613, 5625.803096891589, 5889.558283128783, 5926.496247818897, 5936.257967754031, 5953.702314884238, 4846.39420072491, 5234.895302093937, 5953.940460595339, 5955.67243801595, 5965.403459654087, 5407.351117865881, 5444.782208711607, 5479.821838304783, 5750.108733146369, 5862.648241105548, 4972.776225069808, 5642.492175704207, 5655.0, 5667.801626943037, 5685.403032681509, 5014.044522636248, 5802.245708057925, 5925.259342582177, 5968.164456976114, 5971.731272092679, 2646.603009548583, 3318.20363112856, 3343.6656334366367, 3354.496825476198, 3369.7809642373168, 4174.624283814472, 4176.62410383067, 4221.556736542741, 4561.589456948758, 4578.2903649934315, 4060.944154519787, 4687.39063921688, 4692.225782745809, 4705.364775755229, 4730.800177917285, 3897.9610203897596, 4344.9782751087305, 4429.80065897029, 4659.627229821538, 4697.812087516598, 3967.5320131550357, 4623.976880115712, 4639.953600463952, 4651.930221046755, 4697.600703940203, 5248.868778280574, 5450.454954504517, 5478.506934375925, 5493.917591236214, 5506.394296627448, 4328.567143285646, 5334.0, 5364.865878352916, 5372.731363431734, 5401.9196160767215], "value": 3980.1893878456717, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.3506749128259, 751.2825251884451, 763.7861398808332, 807.4186585658326, 830.1283652165225, 15520.534383968485, 16628.16859157042, 16648.58378540534, 16685.082320472367, 16712.916435417836, 14670.339834707436, 15038.849611503882, 15246.923765381185, 16268.186590670479, 16301.755473667905, 872.1365847810654, 892.7277180459957, 897.5826240798027, 899.3614533681094, 899.4333569851018, 847.4872702015284, 855.247382303572, 855.2901092093579, 880.7093659092484, 891.2468963725642, 899.8380291547519, 900.9819803603924, 901.5807649443008, 948.2271948362097, 952.4428209497453, 944.7118628818208, 946.5740416812462, 947.8862536495631, 949.6866034208729, 949.7008442340641, 698.7798843364375, 699.7271064284932, 700.1528150937321, 700.3976580141075, 701.291695387657, 15071.171085590287, 15134.016288941184, 15714.842851571566, 15725.820563457672, 15769.132697701622, 13567.728645427134, 14044.92977535116, 14356.99501034928, 14392.09153826322, 14416.423343066264, 11623.0, 11809.106768841688, 11880.643580692593, 11913.523459061627, 12443.128980971336, 3419.982900085484, 3808.923821523582, 3835.1562656215588, 3849.1147036181555, 3985.9835741885922, 80645.99999999942, 89479.55260223722, 108885.45557272242, 114733.0, 114948.0, 9294.767630809283, 9817.0, 11307.717307067225, 11495.482703278372, 11824.645260642195, 21282.467938301666, 22889.427764305692, 22893.313200604007, 22924.541509170056, 22934.426639333815, 8147.307478864244, 8153.453297689788, 8253.0, 8254.54599996994, 8332.708355207538, 4415.66882483815, 4506.00350766743, 4530.184566778012, 4568.040711450632, 4658.650601204923, 3599.226166374242, 3726.4037753959237, 3726.6646001859963, 3731.328360895065, 3733.085394078468, 685.9931400685929, 694.6179601219309, 696.084648686975, 696.4219697650938, 701.130598058404, 607.9787207447719, 670.2225418514499, 687.1376422589618, 704.3801454719813, 712.9555201629637, 8533.615987280464, 9129.908700913038, 9205.634081738717, 9300.20948219409, 9401.717948461557, 573.4552175433355, 654.5614438326332, 672.9461643068543, 686.9416099631587, 701.0346235424184, 4330.675199359997, 4457.7548234846745, 4462.352958821018, 4469.7318160910445, 4542.411494918977, 90775.87751607758, 108746.45626771974, 117890.0, 139583.0, 148706.0, 81153.59423202847, 122825.0, 124626.0, 130039.69960300466, 131279.34360328328, 56502.71748641231, 72051.63974180096, 95803.52098239603, 96519.0, 97864.51067744759, 8740.994785599738, 9485.430874147572, 9617.951910240405, 9822.797411647585, 9863.260255481011, 47406.28890566575, 49856.0, 57292.71353643205, 64572.0, 65197.0, 61463.0, 63144.0, 77967.6101619511, 91104.0, 91152.54423727839, 13038.804417933548, 13362.198268103944, 13642.2496762679, 13723.862761372258, 13957.092788968685, 14169.43322267139, 14844.960852740205, 14929.10425374481, 15115.546533604009, 15304.540863774104, 13705.931470342584, 13876.306184690928, 13897.235652038831, 13906.58280251594, 13934.930325348309, 10930.465606724816, 13324.0, 13496.0, 13746.93126534361, 13925.442982280596, 12069.155159138776, 13011.154274972096, 13344.065915386218, 13437.462501499831, 13947.511837085653, 9448.385854919408, 11056.613018544313, 11271.211015228855, 11386.94306528462, 11462.770744585228, 9116.0, 11009.724756881142, 11374.0, 11463.837763464755, 11648.592299269485], "value": 7772.017294721113, "unit": "score"}], "name": "dromaeo_css", "value": 7314.82377312814, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 113.0, 112.0, 111.0, 112.0, 111.0, 111.0, 110.0, 111.0, 113.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 132.0, 193.0, 132.0, 107.0, 130.0, 106.0, 138.0, 108.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 185.0, 182.0, 181.0, 179.0, 182.0, 227.0, 185.0, 183.0], "value": 187.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 57.0, 109.0, 59.0, 59.0, 62.0, 64.0, 98.0, 58.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 90.0, 70.0, 70.0, 68.0, 96.0, 71.0, 67.0, 101.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 94.0, 91.0, 95.0, 90.0, 93.0, 92.0, 94.0, 87.0], "value": 98.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 91.0, 88.0, 88.0, 90.0, 94.0, 139.0, 142.0, 85.0], "value": 101.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 55.0, 53.0, 54.0, 54.0, 54.0, 53.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 78.0, 112.0, 103.0, 85.0, 69.0, 72.0, 72.0, 70.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 81.0, 82.0, 82.0, 84.0, 83.0, 91.0, 102.0, 101.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 146.0, 145.0, 149.0, 135.0, 153.0, 145.0, 143.0, 186.0, 140.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 161.0, 162.0, 164.0, 170.0, 173.0, 162.0, 161.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 66.0, 66.0, 67.0, 64.0, 68.0, 68.0, 62.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1473.9999999999998, "alertThreshold": 2.0}]} 16:49:05 INFO - Return code: 0 16:49:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:49:05 ERROR - # TBPL SUCCESS # 16:49:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:49:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:49:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:49:05 INFO - 2016-07-11 16:49:05.188 screenresolution[1664:9625] starting screenresolution argv=screenresolution get 16:49:05 INFO - 2016-07-11 16:49:05.190 screenresolution[1664:9625] Display 0: 1600x1200x32@60 16:49:05 INFO - 2016-07-11 16:49:05.197 screenresolution[1665:9628] starting screenresolution argv=screenresolution list 16:49:05 INFO - Available Modes on Display 0 16:49:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:49:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:49:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:49:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:49:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:49:05 INFO - Intel Iris: 16:49:05 INFO - Chipset Model: Intel Iris 16:49:05 INFO - Type: GPU 16:49:05 INFO - Bus: Built-In 16:49:05 INFO - VRAM (Dynamic, Max): 1536 MB 16:49:05 INFO - Vendor: Intel (0x8086) 16:49:05 INFO - Device ID: 0x0a2e 16:49:05 INFO - Revision ID: 0x0009 16:49:05 INFO - Displays: 16:49:05 INFO - Display: 16:49:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:49:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:49:05 INFO - Display Serial Number: 1600x1200 60 16:49:05 INFO - Main Display: Yes 16:49:05 INFO - Mirror: Off 16:49:05 INFO - Online: Yes 16:49:05 INFO - Rotation: Supported 16:49:05 INFO - Return code: 0 16:49:05 INFO - Running post-action listener: _resource_record_post_action 16:49:05 INFO - ##### 16:49:05 INFO - ##### Finished run-tests step (success) 16:49:05 INFO - ##### 16:49:05 INFO - Running post-run listener: _resource_record_post_run 16:49:05 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37049856; Write bytes: 704913920; Read time: 238; Write time: 1913 16:49:05 INFO - TinderboxPrint: CPU usage
53.6% 16:49:05 INFO - TinderboxPrint: I/O read bytes / time
37,049,856 / 238 16:49:05 INFO - TinderboxPrint: I/O write bytes / time
704,913,920 / 1,913 16:49:05 INFO - TinderboxPrint: CPU idle
2,249.6 (46.4%) 16:49:05 INFO - TinderboxPrint: CPU system
971.6 (20.0%) 16:49:05 INFO - TinderboxPrint: CPU user
1,628.4 (33.6%) 16:49:05 INFO - TinderboxPrint: Swap in / out
443,396,096 / 0 16:49:05 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 404414976; Write bytes: 399214080; Read time: 30615; Write time: 786 16:49:05 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 34485760; Write bytes: 294009856; Read time: 216; Write time: 1092 16:49:05 INFO - Running post-run listener: _upload_blobber_files 16:49:05 INFO - Blob upload gear active. 16:49:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:49:05 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 16:49:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:49:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:49:06 INFO - (blobuploader) - INFO - Open directory for files ... 16:49:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:49:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:49:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:07 INFO - (blobuploader) - INFO - Done attempting. 16:49:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:49:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:49:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:08 INFO - (blobuploader) - INFO - Done attempting. 16:49:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:49:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:49:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:10 INFO - (blobuploader) - INFO - Done attempting. 16:49:10 INFO - (blobuploader) - INFO - Iteration through files over. 16:49:10 INFO - Return code: 0 16:49:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:49:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:49:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1e2d4ed144d732066abfb8ce0950cf8140804a66c23d4551c6d97643d036c57cad8e89f45b36ed39da6f15fa117d5d995d168e5837fb2675453366aec31ba24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d33ef724cb268b81eb5002c503a9fb1b5a8565ba42e6d369ac9eef30bd2c7cd4c104d80a89f1db4c317a07f4286d7e756ddc44efd81a563221606613a13d4e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a84152c18f0244a65006ee3e59d81906c683ef4f86410544b491e1e534e219aa379726298936dcc21f95ae3443ae4c41110d741714ea0878c429a77684b7da3"} 16:49:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:49:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:49:10 INFO - Contents: 16:49:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1e2d4ed144d732066abfb8ce0950cf8140804a66c23d4551c6d97643d036c57cad8e89f45b36ed39da6f15fa117d5d995d168e5837fb2675453366aec31ba24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d33ef724cb268b81eb5002c503a9fb1b5a8565ba42e6d369ac9eef30bd2c7cd4c104d80a89f1db4c317a07f4286d7e756ddc44efd81a563221606613a13d4e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a84152c18f0244a65006ee3e59d81906c683ef4f86410544b491e1e534e219aa379726298936dcc21f95ae3443ae4c41110d741714ea0878c429a77684b7da3"} 16:49:10 INFO - Running post-run listener: copy_logs_to_upload_dir 16:49:10 INFO - Copying logs to upload dir... 16:49:10 INFO - mkdir: /builds/slave/test/build/upload/logs 16:49:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.741562 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-11 16:49:10.587227) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 16:49:10.592046) ========= 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.fSj7clwVso/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.zFotWls4Bk/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1e2d4ed144d732066abfb8ce0950cf8140804a66c23d4551c6d97643d036c57cad8e89f45b36ed39da6f15fa117d5d995d168e5837fb2675453366aec31ba24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d33ef724cb268b81eb5002c503a9fb1b5a8565ba42e6d369ac9eef30bd2c7cd4c104d80a89f1db4c317a07f4286d7e756ddc44efd81a563221606613a13d4e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a84152c18f0244a65006ee3e59d81906c683ef4f86410544b491e1e534e219aa379726298936dcc21f95ae3443ae4c41110d741714ea0878c429a77684b7da3"} build_url:https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/EAG2ghZXS021-cg8Skmwhg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1e2d4ed144d732066abfb8ce0950cf8140804a66c23d4551c6d97643d036c57cad8e89f45b36ed39da6f15fa117d5d995d168e5837fb2675453366aec31ba24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d33ef724cb268b81eb5002c503a9fb1b5a8565ba42e6d369ac9eef30bd2c7cd4c104d80a89f1db4c317a07f4286d7e756ddc44efd81a563221606613a13d4e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a84152c18f0244a65006ee3e59d81906c683ef4f86410544b491e1e534e219aa379726298936dcc21f95ae3443ae4c41110d741714ea0878c429a77684b7da3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 16:49:10.624391) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:49:10.624701) ========= 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.fSj7clwVso/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.zFotWls4Bk/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.005124 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 16:49:10.672797) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 16:49:10.673120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 16:49:10.673453) ========= ========= Total master_lag: 0.17 =========