builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0038 starttime: 1469782088.56 results: success (0) buildid: 20160728231125 builduid: 49a9ebfa87af487b8a3fb8a4b96ca51b revision: 4dc4d45c2115f8805f15e10c0bf0e776408af0d2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:08.557311) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:08.557776) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:08.558074) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:08.591639) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:08.591988) ========= 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.AkAJyzJgMr/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.MvljfTewh0/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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-29 01:48:08-- 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% 7.51M=0.002s 2016-07-29 01:48:08 (7.51 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118524 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:08.803224) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:08.803529) ========= 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.AkAJyzJgMr/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.MvljfTewh0/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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.020347 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:08.844863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-29 01:48:08.845250) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4dc4d45c2115f8805f15e10c0bf0e776408af0d2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 4dc4d45c2115f8805f15e10c0bf0e776408af0d2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AkAJyzJgMr/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.MvljfTewh0/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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-29 01:48:08,918 truncating revision to first 12 chars 2016-07-29 01:48:08,918 Setting DEBUG logging. 2016-07-29 01:48:08,918 attempt 1/10 2016-07-29 01:48:08,918 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4dc4d45c2115?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-29 01:48:10,250 unpacking tar archive at: autoland-4dc4d45c2115/testing/mozharness/ program finished with exit code 0 elapsedTime=1.590135 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-29 01:48:10.457572) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:10.457875) ========= script_repo_revision: 4dc4d45c2115f8805f15e10c0bf0e776408af0d2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:10.458271) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:10.458537) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-29 01:48:10.477099) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-29 01:48:10.477471) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AkAJyzJgMr/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.MvljfTewh0/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:10 INFO - MultiFileLogger online at 20160729 01:48:10 in /builds/slave/test 01:48:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 01:48:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:48:10 INFO - {'append_to_log': False, 01:48:10 INFO - 'base_work_dir': '/builds/slave/test', 01:48:10 INFO - 'blob_upload_branch': 'Autoland', 01:48:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:48:10 INFO - 'branch': 'Autoland', 01:48:10 INFO - 'buildbot_json_path': 'buildprops.json', 01:48:10 INFO - 'config_files': ('talos/mac_config.py',), 01:48:10 INFO - 'default_actions': ('clobber', 01:48:10 INFO - 'read-buildbot-config', 01:48:10 INFO - 'download-and-extract', 01:48:10 INFO - 'populate-webroot', 01:48:10 INFO - 'create-virtualenv', 01:48:10 INFO - 'install', 01:48:10 INFO - 'run-tests'), 01:48:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:48:10 INFO - 'download_minidump_stackwalk': True, 01:48:10 INFO - 'download_symbols': 'ondemand', 01:48:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:48:10 INFO - 'tooltool.py': '/tools/tooltool.py', 01:48:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:48:10 INFO - '/tools/misc-python/virtualenv.py')}, 01:48:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:48:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:48:10 INFO - 'installer_path': 'installer.exe', 01:48:10 INFO - 'log_level': 'info', 01:48:10 INFO - 'log_name': 'talos', 01:48:10 INFO - 'log_to_console': True, 01:48:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:48:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:48:10 INFO - 'opt_config_files': (), 01:48:10 INFO - 'pip_index': False, 01:48:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:48:10 INFO - 'cmd': ('bash', 01:48:10 INFO - '-c', 01:48:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:48:10 INFO - 'enabled': True, 01:48:10 INFO - 'halt_on_failure': False, 01:48:10 INFO - 'name': 'check_screen_resolution'},), 01:48:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:48:10 INFO - 'cmd': ('bash', 01:48:10 INFO - '-c', 01:48:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:48:10 INFO - 'enabled': True, 01:48:10 INFO - 'halt_on_failure': False, 01:48:10 INFO - 'name': 'check_screen_resolution'},), 01:48:10 INFO - 'run_cmd_checks_enabled': True, 01:48:10 INFO - 'sps_profile': False, 01:48:10 INFO - 'sps_profile_interval': 0, 01:48:10 INFO - 'suite': 'dromaeojs', 01:48:10 INFO - 'system_bits': '32', 01:48:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:48:10 INFO - 'title': 't-yosemite-r7-0038', 01:48:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:48:10 INFO - 'use_talos_json': True, 01:48:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:48:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:48:10 INFO - 'work_dir': 'build'} 01:48:10 INFO - [mozharness: 2016-07-29 08:48:10.679895Z] Running clobber step. 01:48:10 INFO - Running pre-action listener: _resource_record_pre_action 01:48:10 INFO - Running main action method: clobber 01:48:10 INFO - rmtree: /builds/slave/test/build 01:48:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:48:10 INFO - Running post-action listener: _resource_record_post_action 01:48:10 INFO - [mozharness: 2016-07-29 08:48:10.930536Z] Finished clobber step (success) 01:48:10 INFO - [mozharness: 2016-07-29 08:48:10.930721Z] Running read-buildbot-config step. 01:48:10 INFO - Running pre-action listener: _resource_record_pre_action 01:48:10 INFO - Running main action method: read_buildbot_config 01:48:10 INFO - Using buildbot properties: 01:48:10 INFO - { 01:48:10 INFO - "project": "", 01:48:10 INFO - "product": "firefox", 01:48:10 INFO - "script_repo_revision": "production", 01:48:10 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 01:48:10 INFO - "repository": "", 01:48:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 01:48:10 INFO - "buildid": "20160728231125", 01:48:10 INFO - "pgo_build": "False", 01:48:10 INFO - "basedir": "/builds/slave/test", 01:48:10 INFO - "buildnumber": 58, 01:48:10 INFO - "slavename": "t-yosemite-r7-0038", 01:48:10 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 01:48:10 INFO - "platform": "yosemite_r7", 01:48:10 INFO - "scheduler": "tests-autoland-macosx64-talos", 01:48:10 INFO - "branch": "autoland", 01:48:10 INFO - "repo_path": "integration/autoland", 01:48:10 INFO - "revision": "4dc4d45c2115f8805f15e10c0bf0e776408af0d2", 01:48:10 INFO - "stage_platform": "macosx64", 01:48:10 INFO - "builduid": "49a9ebfa87af487b8a3fb8a4b96ca51b", 01:48:10 INFO - "slavebuilddir": "test" 01:48:10 INFO - } 01:48:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 01:48:10 INFO - Running post-action listener: _resource_record_post_action 01:48:10 INFO - [mozharness: 2016-07-29 08:48:10.934259Z] Finished read-buildbot-config step (success) 01:48:10 INFO - [mozharness: 2016-07-29 08:48:10.934412Z] Running download-and-extract step. 01:48:10 INFO - Running pre-action listener: _resource_record_pre_action 01:48:10 INFO - Running main action method: download_and_extract 01:48:10 INFO - mkdir: /builds/slave/test/build/tests 01:48:10 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')]} 01:48:10 INFO - https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:48:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:48:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:48:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/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 01:48:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/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 01:48:12 INFO - Downloaded 1199 bytes. 01:48:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 01:48:12 INFO - Using the following test package requirements: 01:48:12 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:48:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:48:12 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 01:48:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:48:12 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 01:48:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:48:12 INFO - u'jsshell-mac.zip'], 01:48:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:48:12 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 01:48:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:48:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:48:12 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 01:48:12 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:48:12 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 01:48:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:48:12 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 01:48:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:48:12 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 01:48:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:48:12 INFO - https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:48:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:48:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:48:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/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 01:48:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/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 01:48:13 INFO - Downloaded 18818395 bytes. 01:48:13 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'] 01:48:13 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 01:48:16 INFO - Return code: 0 01:48:16 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 01:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:48:16 INFO - https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:48:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:48:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:48:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/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 01:48:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/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 01:48:17 INFO - Downloaded 18818395 bytes. 01:48:17 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'] 01:48:17 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 01:48:21 INFO - Return code: 0 01:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:48:21 INFO - https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:48:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:48:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:48:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/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 01:48:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/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 01:48:22 INFO - Downloaded 14413725 bytes. 01:48:22 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'] 01:48:22 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 01:48:23 INFO - Return code: 0 01:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:48:23 INFO - https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:48:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:48:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:48:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 01:48:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G5tX0RGdQJi56qPgxwiDrA/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 01:48:26 INFO - Downloaded 113112186 bytes. 01:48:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:48:26 INFO - mkdir: /builds/slave/test/properties 01:48:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:48:26 INFO - Writing to file /builds/slave/test/properties/build_url 01:48:26 INFO - Contents: 01:48:26 INFO - build_url:https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:48:27 INFO - Running post-action listener: _resource_record_post_action 01:48:27 INFO - Running post-action listener: set_extra_try_arguments 01:48:27 INFO - [mozharness: 2016-07-29 08:48:27.173764Z] Finished download-and-extract step (success) 01:48:27 INFO - [mozharness: 2016-07-29 08:48:27.173958Z] Running populate-webroot step. 01:48:27 INFO - Running pre-action listener: _resource_record_pre_action 01:48:27 INFO - Running main action method: populate_webroot 01:48:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:48:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:48:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:48:27 INFO - '--tppagecycles', 01:48:27 INFO - '7'], 01:48:27 INFO - 'tests': ['tsvgm']}}, 01:48:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:48:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:48:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:48:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:48:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:48:27 INFO - 'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:48:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:48:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:48:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:48:27 INFO - 'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['damp', 'tps']}, 01:48:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:48:27 INFO - 'tests': ['damp', 'tps']}, 01:48:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['dromaeo_dom']}, 01:48:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:48:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['basic_compositor_video']}, 01:48:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:48:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['a11yr', 01:48:27 INFO - 'ts_paint', 01:48:27 INFO - 'tpaint', 01:48:27 INFO - 'sessionrestore', 01:48:27 INFO - 'sessionrestore_no_auto_restore', 01:48:27 INFO - 'tabpaint']}, 01:48:27 INFO - 'other-e10s': {'tests': ['a11yr', 01:48:27 INFO - 'ts_paint', 01:48:27 INFO - 'tpaint', 01:48:27 INFO - 'sessionrestore', 01:48:27 INFO - 'sessionrestore_no_auto_restore', 01:48:27 INFO - 'tabpaint']}, 01:48:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:48:27 INFO - 'ts_paint', 01:48:27 INFO - 'tpaint', 01:48:27 INFO - 'sessionrestore', 01:48:27 INFO - 'sessionrestore_no_auto_restore', 01:48:27 INFO - 'tabpaint']}, 01:48:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:48:27 INFO - 'ts_paint', 01:48:27 INFO - 'tpaint', 01:48:27 INFO - 'sessionrestore', 01:48:27 INFO - 'sessionrestore_no_auto_restore', 01:48:27 INFO - 'tabpaint']}, 01:48:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['a11yr', 01:48:27 INFO - 'ts_paint', 01:48:27 INFO - 'tpaint', 01:48:27 INFO - 'sessionrestore', 01:48:27 INFO - 'sessionrestore_no_auto_restore', 01:48:27 INFO - 'tabpaint']}, 01:48:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['a11yr', 01:48:27 INFO - 'ts_paint', 01:48:27 INFO - 'tpaint', 01:48:27 INFO - 'sessionrestore', 01:48:27 INFO - 'sessionrestore_no_auto_restore', 01:48:27 INFO - 'tabpaint']}, 01:48:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['tsvgx', 01:48:27 INFO - 'tsvgr_opacity', 01:48:27 INFO - 'tart', 01:48:27 INFO - 'tscrollx', 01:48:27 INFO - 'cart']}, 01:48:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:48:27 INFO - 'tsvgr_opacity', 01:48:27 INFO - 'tart', 01:48:27 INFO - 'tscrollx', 01:48:27 INFO - 'cart']}, 01:48:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:48:27 INFO - 'talos_options': ['--disable-e10s'], 01:48:27 INFO - 'tests': ['tp5o']}, 01:48:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:48:27 INFO - 'tests': ['tp5o']}, 01:48:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:48:27 INFO - 'talos_options': ['--disable-e10s', 01:48:27 INFO - '--xperf_path', 01:48:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:48:27 INFO - 'tests': ['tp5n']}, 01:48:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:48:27 INFO - 'talos_options': ['--xperf_path', 01:48:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:48:27 INFO - 'tests': ['tp5n']}}, 01:48:27 INFO - 'talos.zip': {'path': '', 01:48:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:48:27 INFO - Running post-action listener: _resource_record_post_action 01:48:27 INFO - [mozharness: 2016-07-29 08:48:27.193098Z] Finished populate-webroot step (success) 01:48:27 INFO - [mozharness: 2016-07-29 08:48:27.193237Z] Running create-virtualenv step. 01:48:27 INFO - Running pre-action listener: _resource_record_pre_action 01:48:27 INFO - Running main action method: create_virtualenv 01:48:27 INFO - Creating virtualenv /builds/slave/test/build/venv 01:48:27 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 01:48:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:48:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:48:27 INFO - Using real prefix '/tools/python27' 01:48:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:48:28 INFO - Installing distribute.............................................................................................................................................................................................done. 01:48:30 INFO - Installing pip.................done. 01:48:30 INFO - Return code: 0 01:48:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:48: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')]} 01:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48: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')]} 01:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48: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', 'pip>=1.5'] in /builds/slave/test/build 01:48: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 pip>=1.5 01:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:30 INFO - 'HOME': '/Users/cltbld', 01:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:30 INFO - 'LOGNAME': 'cltbld', 01:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:30 INFO - 'MOZ_NO_REMOTE': '1', 01:48:30 INFO - 'NO_EM_RESTART': '1', 01:48:30 INFO - 'PAGER': '/bin/cat', 01:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:30 INFO - 'PWD': '/builds/slave/test', 01:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:30 INFO - 'SHELL': '/bin/bash', 01:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:30 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:30 INFO - 'USER': 'cltbld', 01:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:30 INFO - 'XPC_FLAGS': '0x0', 01:48:30 INFO - 'XPC_SERVICE_NAME': '0', 01:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:31 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 01:48:31 INFO - Cleaning up... 01:48:31 INFO - Return code: 0 01:48:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:48:31 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')]} 01:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:31 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')]} 01:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:31 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 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:31 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 01:48:31 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 01:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:31 INFO - 'HOME': '/Users/cltbld', 01:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:31 INFO - 'LOGNAME': 'cltbld', 01:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:31 INFO - 'MOZ_NO_REMOTE': '1', 01:48:31 INFO - 'NO_EM_RESTART': '1', 01:48:31 INFO - 'PAGER': '/bin/cat', 01:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:31 INFO - 'PWD': '/builds/slave/test', 01:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:31 INFO - 'SHELL': '/bin/bash', 01:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:31 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:31 INFO - 'USER': 'cltbld', 01:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:31 INFO - 'XPC_FLAGS': '0x0', 01:48:31 INFO - 'XPC_SERVICE_NAME': '0', 01:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:31 INFO - Downloading/unpacking psutil>=3.1.1 01:48:31 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 01:48:31 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 01:48:31 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 01:48:31 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 01:48:31 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 01:48:31 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 01:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:48:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:48:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:48:34 INFO - Installing collected packages: psutil 01:48:34 INFO - Running setup.py install for psutil 01:48:34 INFO - building 'psutil._psutil_osx' extension 01:48:34 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 01:48:34 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 01:48:34 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 01:48:35 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 01:48:35 INFO - building 'psutil._psutil_posix' extension 01:48:35 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 01:48:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:48:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:48:35 INFO - ^ 01:48:35 INFO - 1 warning generated. 01:48:35 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 01:48:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:48:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:48:35 INFO - Successfully installed psutil 01:48:35 INFO - Cleaning up... 01:48:35 INFO - Return code: 0 01:48:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:48:35 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')]} 01:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:35 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')]} 01:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:35 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 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:35 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 01:48:35 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 01:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:35 INFO - 'HOME': '/Users/cltbld', 01:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:35 INFO - 'LOGNAME': 'cltbld', 01:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:35 INFO - 'MOZ_NO_REMOTE': '1', 01:48:35 INFO - 'NO_EM_RESTART': '1', 01:48:35 INFO - 'PAGER': '/bin/cat', 01:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:35 INFO - 'PWD': '/builds/slave/test', 01:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:35 INFO - 'SHELL': '/bin/bash', 01:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:35 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:35 INFO - 'USER': 'cltbld', 01:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:35 INFO - 'XPC_FLAGS': '0x0', 01:48:35 INFO - 'XPC_SERVICE_NAME': '0', 01:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:48:35 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 01:48:35 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 01:48:35 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 01:48:35 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 01:48:35 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 01:48:35 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 01:48:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:48:38 INFO - Installing collected packages: mozsystemmonitor 01:48:38 INFO - Running setup.py install for mozsystemmonitor 01:48:38 INFO - Successfully installed mozsystemmonitor 01:48:38 INFO - Cleaning up... 01:48:38 INFO - Return code: 0 01:48:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:38 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 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:38 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 01:48:38 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 01:48:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:38 INFO - 'HOME': '/Users/cltbld', 01:48:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:38 INFO - 'LOGNAME': 'cltbld', 01:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:38 INFO - 'MOZ_NO_REMOTE': '1', 01:48:38 INFO - 'NO_EM_RESTART': '1', 01:48:38 INFO - 'PAGER': '/bin/cat', 01:48:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:38 INFO - 'PWD': '/builds/slave/test', 01:48:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:38 INFO - 'SHELL': '/bin/bash', 01:48:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:38 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:38 INFO - 'USER': 'cltbld', 01:48:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:38 INFO - 'XPC_FLAGS': '0x0', 01:48:38 INFO - 'XPC_SERVICE_NAME': '0', 01:48:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:38 INFO - Downloading/unpacking jsonschema==2.5.1 01:48:38 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 01:48:38 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 01:48:38 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 01:48:38 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 01:48:38 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 01:48:38 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 01:48:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:48:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:48:41 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 01:48:41 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 01:48:41 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 01:48:41 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 01:48:41 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 01:48:41 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 01:48:41 INFO - Downloading functools32-3.2.3-2.tar.gz 01:48:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:48:41 INFO - warning: no files found matching '*.txt' 01:48:41 INFO - no previously-included directories found matching 'build' 01:48:41 INFO - no previously-included directories found matching 'dist' 01:48:41 INFO - no previously-included directories found matching '.git*' 01:48:41 INFO - Installing collected packages: jsonschema, functools32 01:48:41 INFO - Running setup.py install for functools32 01:48:41 INFO - warning: no files found matching '*.txt' 01:48:41 INFO - no previously-included directories found matching 'build' 01:48:41 INFO - no previously-included directories found matching 'dist' 01:48:41 INFO - no previously-included directories found matching '.git*' 01:48:41 INFO - Successfully installed jsonschema functools32 01:48:41 INFO - Cleaning up... 01:48:41 INFO - Return code: 0 01:48:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:48: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')]} 01:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48: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')]} 01:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:48:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:41 INFO - 'HOME': '/Users/cltbld', 01:48:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:41 INFO - 'LOGNAME': 'cltbld', 01:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:41 INFO - 'MOZ_NO_REMOTE': '1', 01:48:41 INFO - 'NO_EM_RESTART': '1', 01:48:41 INFO - 'PAGER': '/bin/cat', 01:48:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:41 INFO - 'PWD': '/builds/slave/test', 01:48:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:41 INFO - 'SHELL': '/bin/bash', 01:48:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:41 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:41 INFO - 'USER': 'cltbld', 01:48:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:41 INFO - 'XPC_FLAGS': '0x0', 01:48:41 INFO - 'XPC_SERVICE_NAME': '0', 01:48:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:48:42 INFO - Cleaning up... 01:48:42 INFO - Return code: 0 01:48:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:42 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 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:42 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 01:48:42 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 01:48:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:42 INFO - 'HOME': '/Users/cltbld', 01:48:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:42 INFO - 'LOGNAME': 'cltbld', 01:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:42 INFO - 'MOZ_NO_REMOTE': '1', 01:48:42 INFO - 'NO_EM_RESTART': '1', 01:48:42 INFO - 'PAGER': '/bin/cat', 01:48:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:42 INFO - 'PWD': '/builds/slave/test', 01:48:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:42 INFO - 'SHELL': '/bin/bash', 01:48:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:42 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:42 INFO - 'USER': 'cltbld', 01:48:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:42 INFO - 'XPC_FLAGS': '0x0', 01:48:42 INFO - 'XPC_SERVICE_NAME': '0', 01:48:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:42 INFO - Downloading/unpacking blobuploader==1.2.4 01:48:42 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 01:48:42 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 01:48:42 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 01:48:42 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 01:48:42 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 01:48:42 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 01:48:44 INFO - Downloading blobuploader-1.2.4.tar.gz 01:48:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:48:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:48:44 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 01:48:44 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 01:48:44 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 01:48:44 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 01:48:44 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 01:48:44 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 01:48:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:48:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:48:45 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 01:48:45 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 01:48:45 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 01:48:45 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 01:48:45 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 01:48:45 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 01:48:45 INFO - Downloading docopt-0.6.1.tar.gz 01:48:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:48:46 INFO - Installing collected packages: blobuploader, requests, docopt 01:48:46 INFO - Running setup.py install for blobuploader 01:48:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:48:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:48:46 INFO - Running setup.py install for requests 01:48:46 INFO - Running setup.py install for docopt 01:48:46 INFO - Successfully installed blobuploader requests docopt 01:48:46 INFO - Cleaning up... 01:48:46 INFO - Return code: 0 01:48:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:48: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')]} 01:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48: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')]} 01:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:46 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 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:46 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 01:48:46 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 01:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:46 INFO - 'HOME': '/Users/cltbld', 01:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:46 INFO - 'LOGNAME': 'cltbld', 01:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:46 INFO - 'MOZ_NO_REMOTE': '1', 01:48:46 INFO - 'NO_EM_RESTART': '1', 01:48:46 INFO - 'PAGER': '/bin/cat', 01:48:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:46 INFO - 'PWD': '/builds/slave/test', 01:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:46 INFO - 'SHELL': '/bin/bash', 01:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:46 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:46 INFO - 'USER': 'cltbld', 01:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:46 INFO - 'XPC_FLAGS': '0x0', 01:48:46 INFO - 'XPC_SERVICE_NAME': '0', 01:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:48:47 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-7w4_TC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:48:47 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-EZBqa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:48:47 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-5BAl_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:48:47 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-vQn3n7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:48:47 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-AeVjTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:48:47 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-BOnvpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:48:47 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-fbFi46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:48:47 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-edLcoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:48:48 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-vnf3yE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:48:48 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-2ezk0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:48:48 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-8B9mHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:48:48 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-MgM0xO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:48:48 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-CsunNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:48:48 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-XgFYdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:48:48 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-myllup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:48:48 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-2YFpqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:48:48 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-5xUrJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:48:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:48:48 INFO - Running setup.py install for manifestparser 01:48:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:48:49 INFO - Running setup.py install for mozcrash 01:48:49 INFO - Running setup.py install for mozdebug 01:48:49 INFO - Running setup.py install for mozdevice 01:48:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:48:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:48:49 INFO - Running setup.py install for mozfile 01:48:49 INFO - Running setup.py install for mozhttpd 01:48:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:48:49 INFO - Running setup.py install for mozinfo 01:48:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Running setup.py install for mozInstall 01:48:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Running setup.py install for mozleak 01:48:50 INFO - Running setup.py install for mozlog 01:48:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Running setup.py install for moznetwork 01:48:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Running setup.py install for mozprocess 01:48:50 INFO - Running setup.py install for mozprofile 01:48:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:48:50 INFO - Running setup.py install for mozrunner 01:48:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:48:51 INFO - Running setup.py install for mozscreenshot 01:48:51 INFO - Running setup.py install for moztest 01:48:51 INFO - Running setup.py install for mozversion 01:48:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:48:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:48:51 INFO - Cleaning up... 01:48:51 INFO - Return code: 0 01:48:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:48: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')]} 01:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48: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')]} 01:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:51 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 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:51 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 01:48:51 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 01:48:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:51 INFO - 'HOME': '/Users/cltbld', 01:48:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:51 INFO - 'LOGNAME': 'cltbld', 01:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:51 INFO - 'MOZ_NO_REMOTE': '1', 01:48:51 INFO - 'NO_EM_RESTART': '1', 01:48:51 INFO - 'PAGER': '/bin/cat', 01:48:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:51 INFO - 'PWD': '/builds/slave/test', 01:48:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:51 INFO - 'SHELL': '/bin/bash', 01:48:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:51 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:51 INFO - 'USER': 'cltbld', 01:48:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:51 INFO - 'XPC_FLAGS': '0x0', 01:48:51 INFO - 'XPC_SERVICE_NAME': '0', 01:48:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:48:51 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-adMccZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:48:51 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)) 01:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:48:51 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-aM_chC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:48:52 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)) 01:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:48:52 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-2faDRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:48:52 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)) 01:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:48:52 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-rINVJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:48:52 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)) 01:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:48:52 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-8RYEh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:48:52 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)) 01:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:48:52 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-rr7EeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:48:52 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)) 01:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:48:52 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-LGS7sK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:48:52 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)) 01:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:48:52 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-Vu5zdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:48:52 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)) 01:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:48:52 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-O_hMe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:48:52 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)) 01:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:48:52 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-PquNTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:48:52 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-TA7uqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:48:53 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)) 01:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:48:53 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-Q3f3FE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:48:53 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)) 01:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:48:53 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-TwPj_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:48:53 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)) 01:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:48:53 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-bUIRr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:48:53 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)) 01:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:48:53 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-vR78rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:48:53 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)) 01:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:48:53 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-tC9no5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:48:53 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)) 01:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:48:53 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-4C8HSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:48:53 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)) 01:48:53 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)) 01:48:53 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)) 01:48:53 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)) 01:48:53 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)) 01:48:53 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)) 01:48:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:48:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:48:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:48:56 INFO - Downloading blessings-1.6.tar.gz 01:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:48:56 INFO - Installing collected packages: blessings 01:48:56 INFO - Running setup.py install for blessings 01:48:56 INFO - Successfully installed blessings 01:48:56 INFO - Cleaning up... 01:48:56 INFO - Return code: 0 01:48:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:48:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:48:56 INFO - Reading from file tmpfile_stdout 01:48:56 INFO - Current package versions: 01:48:56 INFO - blessings == 1.6 01:48:56 INFO - blobuploader == 1.2.4 01:48:56 INFO - docopt == 0.6.1 01:48:56 INFO - functools32 == 3.2.3-2 01:48:56 INFO - jsonschema == 2.5.1 01:48:56 INFO - manifestparser == 1.1 01:48:56 INFO - mozInstall == 1.12 01:48:56 INFO - mozcrash == 0.17 01:48:56 INFO - mozdebug == 0.1 01:48:56 INFO - mozdevice == 0.48 01:48:56 INFO - mozfile == 1.2 01:48:56 INFO - mozhttpd == 0.7 01:48:56 INFO - mozinfo == 0.9 01:48:56 INFO - mozleak == 0.1 01:48:56 INFO - mozlog == 3.3 01:48:56 INFO - moznetwork == 0.27 01:48:56 INFO - mozprocess == 0.23 01:48:56 INFO - mozprofile == 0.28 01:48:56 INFO - mozrunner == 6.12 01:48:56 INFO - mozscreenshot == 0.1 01:48:56 INFO - mozsystemmonitor == 0.3 01:48:56 INFO - moztest == 0.7 01:48:56 INFO - mozversion == 1.4 01:48:56 INFO - psutil == 3.1.1 01:48:56 INFO - requests == 1.2.3 01:48:56 INFO - wsgiref == 0.1.2 01:48:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:56 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 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:56 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 01:48:56 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 01:48:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:56 INFO - 'HOME': '/Users/cltbld', 01:48:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:56 INFO - 'LOGNAME': 'cltbld', 01:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:56 INFO - 'MOZ_NO_REMOTE': '1', 01:48:56 INFO - 'NO_EM_RESTART': '1', 01:48:56 INFO - 'PAGER': '/bin/cat', 01:48:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:56 INFO - 'PWD': '/builds/slave/test', 01:48:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:56 INFO - 'SHELL': '/bin/bash', 01:48:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:56 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:56 INFO - 'USER': 'cltbld', 01:48:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:56 INFO - 'XPC_FLAGS': '0x0', 01:48:56 INFO - 'XPC_SERVICE_NAME': '0', 01:48:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:56 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)) 01:48:56 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)) 01:48:56 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)) 01:48:56 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)) 01:48:56 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)) 01:48:56 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)) 01:48:56 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)) 01:48:56 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)) 01:48:56 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)) 01:48:56 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)) 01:48:56 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)) 01:48:56 INFO - Cleaning up... 01:48:56 INFO - Return code: 0 01:48:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:48:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103715a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037aa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc883d37c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, '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.AkAJyzJgMr/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.MvljfTewh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 01:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:48:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:48:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:57 INFO - 'HOME': '/Users/cltbld', 01:48:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:57 INFO - 'LOGNAME': 'cltbld', 01:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:57 INFO - 'MOZ_NO_REMOTE': '1', 01:48:57 INFO - 'NO_EM_RESTART': '1', 01:48:57 INFO - 'PAGER': '/bin/cat', 01:48:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:57 INFO - 'PWD': '/builds/slave/test', 01:48:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:57 INFO - 'SHELL': '/bin/bash', 01:48:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:48:57 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:48:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:57 INFO - 'USER': 'cltbld', 01:48:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:57 INFO - 'XPC_FLAGS': '0x0', 01:48:57 INFO - 'XPC_SERVICE_NAME': '0', 01:48:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:48:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:48:57 INFO - Cleaning up... 01:48:57 INFO - Return code: 0 01:48:57 INFO - Running post-action listener: _resource_record_post_action 01:48:57 INFO - Running post-action listener: _start_resource_monitoring 01:48:57 INFO - Starting resource monitoring. 01:48:57 INFO - [mozharness: 2016-07-29 08:48:57.294443Z] Finished create-virtualenv step (success) 01:48:57 INFO - [mozharness: 2016-07-29 08:48:57.295497Z] Running install step. 01:48:57 INFO - Running pre-action listener: _resource_record_pre_action 01:48:57 INFO - Running main action method: install 01:48:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:48:57 INFO - Reading from file tmpfile_stdout 01:48:57 INFO - Detecting whether we're running mozinstall >=1.0... 01:48:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:48:57 INFO - Reading from file tmpfile_stdout 01:48:57 INFO - Output received: 01:48:57 INFO - Usage: mozinstall [options] installer 01:48:57 INFO - Options: 01:48:57 INFO - -h, --help show this help message and exit 01:48:57 INFO - -d DEST, --destination=DEST 01:48:57 INFO - Directory to install application into. [default: 01:48:57 INFO - "/builds/slave/test"] 01:48:57 INFO - --app=APP Application being installed. [default: firefox] 01:48:57 INFO - mkdir: /builds/slave/test/build/application 01:48:57 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'] 01:48:57 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 01:49:32 INFO - Reading from file tmpfile_stdout 01:49:32 INFO - Output received: 01:49:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:49:32 INFO - Running post-action listener: _resource_record_post_action 01:49:32 INFO - [mozharness: 2016-07-29 08:49:32.738839Z] Finished install step (success) 01:49:32 INFO - [mozharness: 2016-07-29 08:49:32.738989Z] Running run-tests step. 01:49:32 INFO - Running pre-action listener: _resource_record_pre_action 01:49:32 INFO - Running main action method: run_tests 01:49:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:49:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:49:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:49:32 INFO - 2016-07-29 01:49:32.752 screenresolution[2473:21920] starting screenresolution argv=screenresolution get 01:49:32 INFO - 2016-07-29 01:49:32.755 screenresolution[2473:21920] Display 0: 1600x1200x32@60 01:49:32 INFO - 2016-07-29 01:49:32.760 screenresolution[2474:21923] starting screenresolution argv=screenresolution list 01:49:32 INFO - Available Modes on Display 0 01:49:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:49:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:49:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:49:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:49:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:49:32 INFO - Intel Iris: 01:49:32 INFO - Chipset Model: Intel Iris 01:49:32 INFO - Type: GPU 01:49:32 INFO - Bus: Built-In 01:49:32 INFO - VRAM (Dynamic, Max): 1536 MB 01:49:32 INFO - Vendor: Intel (0x8086) 01:49:32 INFO - Device ID: 0x0a2e 01:49:32 INFO - Revision ID: 0x0009 01:49:32 INFO - Displays: 01:49:32 INFO - Display: 01:49:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:49:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:49:32 INFO - Display Serial Number: 1600x1200 60 01:49:32 INFO - Main Display: Yes 01:49:32 INFO - Mirror: Off 01:49:32 INFO - Online: Yes 01:49:32 INFO - Rotation: Supported 01:49:32 INFO - Return code: 0 01:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:49:33 INFO - Python 2.7.3 01:49:33 INFO - Return code: 0 01:49:33 INFO - grabbing minidump binary from tooltool 01:49: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')]} 01:49:33 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 0x103679d30>, '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 0x1033dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103671b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:49:33 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 01:49:33 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 01:49:33 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 01:49:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:49:33 INFO - Return code: 0 01:49:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:49:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:49:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:49:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:49:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:49:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--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 01:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0038 --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 01:49:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AkAJyzJgMr/Render', 01:49:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:49:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:49:33 INFO - 'HOME': '/Users/cltbld', 01:49:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:49:33 INFO - 'LOGNAME': 'cltbld', 01:49:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:49:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:49:33 INFO - 'MOZ_NO_REMOTE': '1', 01:49:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:49:33 INFO - 'NO_EM_RESTART': '1', 01:49:33 INFO - 'PAGER': '/bin/cat', 01:49:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:49:33 INFO - 'PWD': '/builds/slave/test', 01:49:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:49:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:49:33 INFO - 'SHELL': '/bin/bash', 01:49:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvljfTewh0/Listeners', 01:49:33 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 01:49:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:49:33 INFO - 'USER': 'cltbld', 01:49:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:49:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:49:33 INFO - 'XPC_FLAGS': '0x0', 01:49:33 INFO - 'XPC_SERVICE_NAME': '0', 01:49:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:49:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--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 01:49:33 INFO - mozversion application_buildid: 20160728231125 01:49:33 INFO - mozversion application_changeset: 4dc4d45c2115f8805f15e10c0bf0e776408af0d2 01:49:33 INFO - mozversion application_display_name: Nightly 01:49:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:49:33 INFO - mozversion application_name: Firefox 01:49:33 INFO - mozversion application_remotingname: firefox 01:49:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 01:49:33 INFO - mozversion application_vendor: Mozilla 01:49:33 INFO - mozversion application_version: 50.0a1 01:49:33 INFO - mozversion platform_buildid: 20160728231125 01:49:33 INFO - mozversion platform_changeset: 4dc4d45c2115f8805f15e10c0bf0e776408af0d2 01:49:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 01:49:33 INFO - mozversion platform_version: 50.0a1 01:49:33 INFO - using testdate: 1469782173 01:49:33 INFO - actual date: 1469782173 01:49:33 INFO - starting webserver on 'localhost:54166' 01:49:33 INFO - SUITE-START | Running 2 tests 01:49:33 INFO - TEST-START | dromaeo_css 01:49:33 INFO - operating with platform_type : mac_ 01:49:33 INFO - Initialising browser for dromaeo_css test... 01:49:33 INFO - TEST-INFO | started process 2484 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpDdo2JQ/profile http://localhost:54166/getInfo.html) 01:49:35 INFO - PROCESS | 2484 | JavaScript error: , line 0: uncaught exception: undefined 01:49:35 INFO - PROCESS | 2484 | __metrics Screen width/height:1600/1200 01:49:35 INFO - PROCESS | 2484 | colorDepth:24 01:49:35 INFO - PROCESS | 2484 | Browser inner width/height: 1024/689 01:49:35 INFO - PROCESS | 2484 | __metrics 01:49:35 INFO - PROCESS | 2484 | 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" 01:49:35 INFO - PROCESS | 2484 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:49:36 INFO - TEST-INFO | 2484: exit 0 01:49:36 INFO - Browser initialized. 01:49:36 INFO - Running cycle 1/1 for dromaeo_css test... 01:49:36 INFO - TEST-INFO | started process 2486 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpDdo2JQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:49:48 INFO - PROCESS | 2486 | RSS: Main: 245518336 01:49:48 INFO - PROCESS | 2486 | 01:50:39 INFO - PROCESS | 2486 | 1469782239716 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:52:51 INFO - PROCESS | 2486 | Cycle 1(1): loaded http://localhost:54166/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54166/tests/dromaeo/cssquery-ext.html) 01:52:53 INFO - PROCESS | 2486 | RSS: Main: 399519744 01:52:53 INFO - PROCESS | 2486 | 01:55:56 INFO - PROCESS | 2486 | Cycle 1(1): loaded http://localhost:54166/tests/dromaeo/cssquery-ext.html (next: http://localhost:54166/tests/dromaeo/cssquery-jquery.html) 01:55:57 INFO - PROCESS | 2486 | RSS: Main: 390045696 01:55:57 INFO - PROCESS | 2486 | 01:58:59 INFO - PROCESS | 2486 | Cycle 1(1): loaded http://localhost:54166/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54166/tests/dromaeo/cssquery-mootools.html) 01:59:00 INFO - PROCESS | 2486 | RSS: Main: 354988032 01:59:00 INFO - PROCESS | 2486 | 02:02:02 INFO - PROCESS | 2486 | Cycle 1(1): loaded http://localhost:54166/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54166/tests/dromaeo/cssquery-prototype.html) 02:02:03 INFO - PROCESS | 2486 | RSS: Main: 359833600 02:02:03 INFO - PROCESS | 2486 | 02:05:06 INFO - PROCESS | 2486 | Cycle 1(1): loaded http://localhost:54166/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54166/tests/dromaeo/cssquery-yui.html) 02:05:07 INFO - PROCESS | 2486 | RSS: Main: 354627584 02:05:07 INFO - PROCESS | 2486 | 02:08:10 INFO - PROCESS | 2486 | Cycle 1(1): loaded http://localhost:54166/tests/dromaeo/cssquery-yui.html (next: http://localhost:54166/tests/dromaeo/cssquery-dojo.html) 02:08:11 INFO - PROCESS | 2486 | RSS: Main: 454299648 02:08:11 INFO - PROCESS | 2486 | 02:08:11 INFO - PROCESS | 2486 | __start_tp_report 02:08:11 INFO - PROCESS | 2486 | _x_x_mozilla_page_load 02:08:11 INFO - PROCESS | 2486 | _x_x_mozilla_page_load_details 02:08:11 INFO - PROCESS | 2486 | |i|pagename|runs| 02:08:11 INFO - PROCESS | 2486 | |0;dojo.html;3295.57157569516;3320.1865542941973;3330.317284956584;3344.5819272590925;3371.32573485303;5636.436356364362;5688.573356998221;5695.174199741035;5744.3106827180745;5783.942160578398;5748.913766293498;5779.017567013607;5796;5807.8257652270395;5811.215485909401;5180.870478238036;5222.895542089156;5240.004399164147;5243.475652434745;5293.258943747878;5267.3942496613;5297.867553311159;5301.231321458386;5302.416734159239;5307.071262529062;5187.974060129694;5209.322788037569;5225.16397376418;5246.816361427351;5293.947060529384;5282;5308.946910530923;5319.9734001330135;5324.467553244684;5362.839114826562;4145.7719825409595;5096.51583099605;5098.745062746848;5223.138182199932;5235.424103348629;5647.745851436654;5651.660900345952;5699.173619825102;5719.885602287973;5734.369219385864;6267.6866156691985;6268.059791031336;6281.623102613812;6285.842853928642;6312.242530896322;6110.358412366682;6213.56505044647;6296.96851515744;6331.4934805215935;6352.6823658816875;5870.119482077723;5894.086416605433;5898.2332296802;5908.379620139909;5909.320428150818;6294.339094395114;6298;6313.747450102036;6314.431701146918;6317.210348706411;6033.185519954838;6097.75608975636;6132.7546898124465;6159.168512250789;6184.226971628546;6299.590526615755;6308.621482711052;6309.936900631027;6309.968450157811;6316.968415157895;6184.196054512884;6261.342559031236;6268.717907694165;6287.528435367366;6292.968535157386;6121.357257487989;6138.296437970319;6158.846028849314;6264.404881536247;6292.779752708633;4981.9252711209365;4982.177940639826;5001.049800537959;5002;5007.198848184273;4464.665150113755;4506.414166158378;4532.365468834366;4551.06703125865;4563.817447302071;5692;5702.515286200636;5712.542996560265;5762.884742305214;5764.193012978187;6256.287663728292;6266.749330026839;6318.147050148171;6338.619682819046;6346.2067241594805;5545.584081193846;5580.86047848807;5602.327720673464;5694.373618901917;5759.971200143972;5400.378956419983;5408.567314614821;5432.619716619799;5450.018996580574;5486.643368181135;6293.937060629427;6349.619022858551;6360.363963603788;6364;6371.171747672773;6327.873442531216;6330.240371155491;6330.683465826783;6347.555671103163;6350.650714210578;6217.036359364306;6243.219597550306;6341.524385671001;6348.555601107878;6382.425581697669;6138.357744607813;6241.3134555197985;6249.593776404519;6251.34360892118;6258.311585725475;6274.372562743872;6306.684665766602;6306.873862522632;6313.116163737172;6316.968415157895;5999.010163323177;6002.729877155626;6171.567990240819;6184.165137706352;6186.752529898754;6087.208662873975;6131.448169664752;6134.6625935573975;6154.353792851686;6171.938280617316;5889.263842019747;6028.2766068072115;6060.454559089703;6072.666003369857;6083.300420451527;5893.204417403767;6123.941167985503;6165.383461653978;6185.41238582334;6187.288461826947;5797.333306669787;5857.736401861841;5895.263092113486;5908.645481271137;5915.112733090071 02:08:11 INFO - PROCESS | 2486 | |1;ext.html;502.3344069108432;510.4997102839217;511.00864323212954;523.3954782226527;550.1225545255317;30815.537766933514;42607;44083;44124.55875441244;46654.300185497246;45157.322640160426;45617.859553511094;45669.54330456703;45685.314720279224;45748.77125614367;8729.214370706635;9760.17038551724;9950.104490595844;9950.30347875649;9970.800583988315;8672.176143266379;8855.601497932576;8874;8890;11895.52417903283;11916.821247681291;12202.511899524008;12224.02207823372;12256.387180640932;12269.938650306736;12034.69913252167;12125.333106679129;12139.332336721476;12140.635780926586;12144.392780360946;11908.16642835002;11958.581449649308;11994.520219191221;11994.760104797855;11997;42608.93477663052;42847.57152428467;42924.356134658;42999.57000429987;43008.78495607533;3476.2352282498064;3532.7880327180196;3612.747107702462;3913.902152446183;4022.45696830927;26099.52054554814;31510.054698359087;31520.211994700087;31750.04749857508;33909.49135762965;2854.343500994762;3233.66046565112;3350.480675495302;3404.7276217902754;3409.488576713488;491050;493864;496098;498647;500090;65431.67284163549;65663;65834.67082664651;65908.34091659117;65915;69125.65437172883;69138.9629155563;69192.6540367305;69267;69353.3064669357;17238.224279907434;17450;17458.65082698339;17472.30110795554;17500.737488937677;13183;13266.867331326755;13274.93362533181;13280.601581952556;13290.003249756306;16373.263203155648;16727.247273872705;17792.042807922586;18488.907555462138;19129.617407651767;6587.176602924635;6685.732570697215;6749.527533072638;6767.2894346093935;6791.966040169767;746.2500187311717;930.7021753039098;960.8126415349079;980.152168374351;993.0417147452598;30630.846845765627;31136;31303.747850086198;32043.35913281721;32046;1046.4878250927336;1105.2926127278547;1107.44074242507;1148.018444230171;1178.8467499225217;15344.386224551115;16346.918265408596;16393.016419014897;16413.753793693104;16811.243494042796;687477.6878346829;690031;690322;692479;694291;54105.1884221737;54566.72716636391;61873.690631547455;62309.065364019574;63587;1428.6642638979831;1433.5555977646964;1442.9278536073364;1450.2241300904177;1455.8980871338904;28335.866565337157;31355.953200957534;31967.840160799977;33387.33225335431;33936.91646740171;54189;54746.726266368416;54804.45195547993;54819.725901370235;54857.72571137119;626902;686474;699408;700080.4947678385;884756;24858.378540536632;27124;27448.451030979668;27492.725072749017;27495;43848.780756096014;47691.523084768705;48557.75721121372;49285;49478.01043979028;39470.80264598659;40016.79991600024;40720.79639601902;40837.61845021777;41706.79146604247;39535.86033815586;39648.60351396449;40087;41381.37927931023;41452.58547414608;38648.613513864504;39261.87750286179;39278.80360598179;39424.802875985435;39499.2100158001;29139.688714007498;29534.409311814074;35759;36251.33673805905;36980.63019369772;24172.03311867576;26208.606870896572;26620;26643;26704 02:08:11 INFO - PROCESS | 2486 | |2;jquery.html;338.62920102487766;376.1536542778748;377.5205489028933;385.67795890431495;403.98384064637395;15643.296051677673;16104.194790260484;21156.682649760227;21189.364319070413;21257.893710531444;21213.939303034826;21549.922503874823;21813.890930545323;22143.44641383966;22185.334439966788;5792.218050563183;5912.556558258127;6009.639421634694;6065.090236464522;6076.088586711984;4152.356384760367;5862.149988251701;5926.674032928187;5997.790077347294;6341.397567231105;5609.831705048855;6620.371064748865;6672.833179170511;6676.499262555303;6704.664766761667;6366.904496432558;6576.671166441706;6634.800955971328;6639.070530125786;6659.6004239745525;5581;5760.53915686744;5760.9630266552;5761.0494268445655;5764.250647415851;26132;26367.736322636913;26370.472590548274;26680.332991675168;26691.599626005627;1882.7458293130526;2337.22871452422;2692.7845772338164;3112.6264848218357;3215.7266632336277;14898.627534311621;15918.522444326689;16513.504594862174;16585.751213731804;16649.4172703956;1877.5401360006667;1881.2192939929912;1938.1472152252963;1988.443235893952;1999.2203040814095;124680;125192;126073.36963315216;127834;128973;8673.956630216808;10381.273310868166;11123.27698699582;11186.608468703556;11375.943120284346;13925.442982280798;13937.512187073606;13947.372368243454;14005.229712365714;14011.439542418393;7351.338379545867;8589.52757598338;8844.982826974852;8898.377113602115;9005.774855628531;8970.910290897138;8984;8986.955065224764;9177.541122943827;9184;6565;6697.497687673444;6703;6708.127943367331;6710.932890671031;1695.8660964730677;1728.179114920428;1874.990625046866;1912.7895931447529;1950.8731932424348;906.9093090690878;927.3554879358916;1010.838265877456;1074.425182527344;1106.3527836215742;19989;22324.888375558017;22512.32463026112;22626.981785819677;22788;972.8005758819567;977.2426369563503;990.736810566519;1014.7868947521104;1023.4831410137981;11705;11883.762324753456;12036.45835937385;12085.818712719316;12236.571719989757;599846;607409;609576;612655;616117;118834.4058279703;119210;119381;142345;145432;972.8832540095234;994.9701508954598;1009.0313299232672;1009.4094954451581;1033.4161198922434;19120.220068895;20349;20480;20544.69182962227;20566;57878;58622;59654.70172649109;59978.700106499185;60851.69574152278;82907.5854620723;83321;83876;84048;85066.57466712628;8293.419460637939;8321.916780832114;8402.789930251793;8468.661253550035;8544.658213671384;11458.71353216143;12162;13198.60404187876;13235.205887646773;13489.662758431117;11233.494492747683;11471.197016208785;11531;11540.365279909685;11679.474423650787;9169.541522923693;10251.179905607683;11158.832617510581;11216.66350009501;11481.885181148416;8147.266745992889;8179.10029896699;8374.87437688423;11601.883981160081;11827.763444731228;10007.299489035697;11084.057855082086;12064.638060857837;13145.868541314465;13399.866001339862;11560.248583842023;11848.466818993338;12099.81850272229;12262.019038476721;12271.202371846159 02:08:11 INFO - PROCESS | 2486 | |3;mootools.html;1527.9923600381996;1600.2638786158366;1828.4149072296862;1903.9048047597616;1975.7233987241789;7916.722914697987;8372.45579037363;9105.408148470346;9105.68130115446;9108;7914.960425197881;8137.308328792045;8139.186081391857;8319.95840020799;8947.284217262619;2137.7862213778653;2179.6512557990723;2299.3446867642724;2392.043182726906;2396.988015059931;2400;2435.3059378077164;2436.8659723715186;2438.890249938748;2439.865807380593;2342.824288178385;2407.5064611754547;2412.1557454890735;2419.2621250518673;2420.044082587377;2364.893579788905;2370.7510711375317;2383.690120284352;2388.176079252655;2396.8681722505257;2348.6829278047417;2357.646353047057;2361.2916125162383;2392.031227352925;2395.6765836612008;8768.430052046597;8777.166169213915;8786.560671966376;8789;8801.867971980426;4523;5232;5448.673079615196;5454.072807622713;5458.099413596752;3451.119964409085;3585.7848529088337;3618.656227658368;3637.508936293593;3645.38028535147;866.8266346730679;1062.2351906627216;1074.3017038924684;1086.7337502311984;1087.472575800736;11461.484233209525;11743.412829358584;11749;11762.705932351673;11763.7059073523;4481.462224533077;4636.8145274188655;4797.33747769991;4814.638902082288;4839.516048395203;10169.440680762482;10189.54147063384;10203.38779673222;10205.132563732017;10208.183345332356;5326.97336513315;5382.946170538323;5409.377921538995;5412.512873841373;5413.891722165535;4781.880452988634;4869.634777391639;4936.3829521309835;4955;4983.775730092082;2867.2688464441853;3100.7209351158053;3375.0887260439545;3572.7499075064497;3915.432262322004;990.7968866382519;1171.8769529199483;1180.2387460088196;1186.6262127429832;1192.8151136573845;843.6498852976069;921.7004473545991;922.2345453273766;932.2495391210006;990.3315262197988;5330.626856120034;6000.159977603139;6036.15493830864;6087.208662873798;6107.297660769068;961.4567769210374;983.4001259231862;1014.665160497049;1017.954192061359;1039.986013636698;3938.3501722215515;4006.7395619284653;4095.569965153616;4122.690798190148;4155.688323375759;8947.463152210888;10345.120664743581;10594;10705.19711021661;10872.891271087188;10790.244682872124;10824.5128969195;10830;10855.402952837512;10856.34861908288;7980.321672657773;8109.675612975533;8174.877376839352;8208.58957052145;8227.506349619041;5919.644821310735;5960.850978725566;6091.42131497507;6092.969535152296;6093.634381937098;9741.220702343653;10071.992800719869;10155.949220253851;10159.644412445825;10192.337498062601;10330.948345258226;10340.53467593975;10347.793044139225;10355.844662330222;10419.479026048819;7101.360877521048;7164.104486939133;7204.279572042754;7211.495195336276;7211.891821622785;6875.3812156906115;7625.580593067212;7921.128675845537;8414;8499.022612399653;7509;7529.962350188433;7556.395488361028;7579.052618422697;7701.422393320411;6081.483073938764;7132.322429369202;7201;7281.672324745293;7400.851982960418;6990.650467476504;7070.823229419306;7072.5402848814665;7073;7075.469339799432;4890.315355850113;4913.508649135201;5059.089363914531;5093.923591146062;5236.214567814858;4731.432228132646;5047.167217409086;5098.643094983316;5144.382674079135;5261.57907367402 02:08:11 INFO - PROCESS | 2486 | |4;prototype.html;265.19381081512205;297.88084766093573;301.36562535862015;313.3497991667289;318.90114064639965;4553.294239392895;4962.627802914778;5029.823956161535;5190.506901844328;5392.541633961118;4238.770756480624;4954.058728841517;5042.369703787027;5089.134847075997;5105.821296254632;3026.95570028341;3457.291255292659;3650.2843663478257;4028.415879697442;4039.676825853939;3720.7209459290525;4009.2582872168596;4010.7794071326066;4018.7588744675413;4028.4158796974566;3915.0033482923773;3927.5483319418204;3951.577432124433;3959.9010024749323;4011.8194681239415;3946.131850992777;3954.703397245218;3979.8408063677416;3979.900502487461;3999.300122478555;2998.745106665936;3325.5676762021026;3425.5889293284717;3441.741869359783;3446.9310613787584;4734.621230301567;5047.318611987372;5090.363704536933;5094.821681241138;5136.048301645388;4524.5927866492175;4528.479224889147;4546.931796023062;4547.407548037047;4563.657725670555;4573.336866154422;5482.095454250044;5520.779168833282;5542.7782888684405;5622.297212848394;3296.489044198153;3547.3614749345106;3685.68671662909;3789.393697008493;3825.5026846510054;5688.914666280009;5706.28671416073;5716.570857285678;5730.484256416901;5761.596688231825;3647.5258216431694;3776.527934008249;3796.8481260749813;3860.092878173627;3914.8825535233987;3109.8600562974266;3466.4626982817704;3540.8406621702084;3801.163743976321;3962.6829853611644;3821.6751576115785;4502.4371953505815;4509.6843220974215;4513.1650644630845;4520.186366454004;4751.358566593535;5487.6158668892795;5493.945060549423;5494.527462641827;5516.7517461714315;1230.308326051679;1277.7891647878182;1278.974420511603;1307.300594182127;1322.1670347681093;830.1034882327073;864.9221570058725;874.4403581707712;890.4345740454858;904.5115637555647;4436.822527098944;5015.84952451427;5069.340985671839;5139.614528910347;5167.534921857051;4885.706857588556;4984.626153038536;5237.5548078413;5507.862303442366;5547.559074007424;4729.976350118227;5037.697738135724;5067.797288108508;5068.923966140511;5083.339165908408;2229.921952731647;2444.987775061113;2475.306914064065;2482.6400171975324;2484.4844644766677;4046.4537287466405;4390.956090439118;4769.236922092448;4860.927086093711;4902.240152776325;3843.7501562398356;4220.430241917364;4587.495375508692;4694.638554819065;4838.088954862809;4061;4628.814847406066;4724.724324432453;4809.470958194595;4840.596227094174;4648.34923110758;4653.674242803107;4703.048234982581;4706.576408123286;4720.339152518582;4079.5716449772344;4189.287821070487;4560.495036637968;4596.011857450664;4613.370378884004;4313.61177494027;4722.788503817437;4813.590844778093;4813.807447702193;4877.80488780459;2895.0156946638244;3105.4410206163107;3259.6088469382876;3293.7035666790102;3324.25204329024;4566.013784003969;4683.196969166827;4688;4724.551167639069;5357.892842143213;5176.378834539879;5211.400688920823;5355.277037599874;5411.269478620493;5515.007298686275;5454.400015998316;5469.206964990134;5486.6982315973;5488.545535433193;5490;4970.95609921906;5065.620078494054;5303.337082864642;5351.170568561879;5394;4420.447444069491;4803.327534145153;5297.761600728054;5438.728063596725;5494.423085575957;4302.655787537052;4698;4701.341812146371;5206.240692433016;5358.526405238435 02:08:11 INFO - PROCESS | 2486 | |5;yui.html;740.3189066059226;752.2778132992327;795.9681612735487;844.9408541402103;859.501489136301;15361.923190384046;16475.423360182365;16496;16498.91750541247;16517.421890233843;15010;15184.54446366608;15189.01271417357;15194.6961060779;15231;704.1233664088204;731.8243621530847;884.3323290915382;909.3316412436853;910.0762725833284;878.2227728460326;896.3098414221046;897.9685711000117;898.2544488074915;899.280575539569;945.1493655709847;945.7919257763281;947.2280091725222;949.4398305000049;950.2303134461074;745.9515131516488;807.8990126234221;931.1712575807537;944.2304521814699;945.9101385368414;704.3414407528977;705.830600655844;706.3783870194196;707.8195060259652;709.2198581560294;15248.39006439741;15294.235288235655;15295.158766267961;15310.157941313224;15312.004719693185;13808.792868106986;13948.2328471934;13977.440902363893;14064.929675351661;14257.429702811874;12674.873251267461;12824;12835.614931551974;12919;13290.401931913088;4492.505824359318;4519.615832654195;4528.43394575678;4551.840685575989;4567.177907976563;115448;116042;118376.40811795885;118889;120509.39745301305;11056.557737690402;11110.277831940897;11124.666260012213;11139.164562657834;11548.480318385693;21913.561728765337;21949.68301901891;21952.341429757133;21957.890210548845;21974.780252197594;7878;7930.405219608552;7997.880031799527;8131.918680813116;8154.8776768348525;4361.476622805284;4380.802863871133;4387.3418987151545;4393.560643935581;4559.6808223424705;3449.5170676105367;3519.1378112362636;3525.8060806655367;3569.803660798681;3570.767900086486;649.1334069017914;649.1658219188415;658.7924803686823;663.8008597420754;667.7729571945562;577.4254616656453;583.743153012673;623.6632218601904;646.4731244036095;646.5926466326184;6779.9322006780285;7546.622668866535;8217.465864718773;10435.16518678504;10646.787064258671;611.9975361138107;627.3099590450468;685.9245482996866;694.198201077756;705.1186017478152;4185.204811085884;4734.431868175772;4753.548412900769;5047.740198286408;5249.186376111708;164291;166200;166452;167493;171690;123635;138943.30528347497;140511;142817.5718242825;143903;67962.66018669974;68815;123955.38022310012;127530;127629.36185319014;10086.644104131945;10212.540435680263;10241.334313269912;10295.485225738534;10306.536205870603;49562.25656615226;51738.96522069462;58786.11820822606;65803.67098164478;66439.66780166069;67361;70622.64688676524;77963;92658.53670731603;93037;13439.664008399868;13778.862211377758;13950.651233718832;14145.575632731034;14382.280885955452;15414.614634633775;15558.611034724223;15583.376664933729;15647;16123.516294511184;14326.140431574138;14496.492622758153;14514.927425363228;14611.269436528344;14640.853591463949;11873.584424545099;14483;14589;14894.180820055;14911.25443727831;13745.177589168092;14083.94370422202;14190.929045354707;14193;14560.927195363955;11652.475638596114;11692.532298707956;11756.35340056271;11832.290062596272;11908.345041022827;9008.549572521217;10481.370305425906;10789;11008.559657613927;11836.230645008047 02:08:11 INFO - PROCESS | 2486 | __end_tp_report 02:08:11 INFO - PROCESS | 2486 | __start_cc_report 02:08:11 INFO - PROCESS | 2486 | _x_x_mozilla_cycle_collect,1538 02:08:11 INFO - PROCESS | 2486 | __end_cc_report 02:08:11 INFO - PROCESS | 2486 | __startTimestamp1469783291069__endTimestamp 02:08:11 INFO - PROCESS | 2486 | 02:08:11 INFO - PROCESS | 2486 | ------- Summary: start ------- 02:08:11 INFO - PROCESS | 2486 | Number of tests: 6 02:08:11 INFO - PROCESS | 2486 | 02:08:11 INFO - PROCESS | 2486 | [#0] dojo.html Cycles:165 Average:5775.77 Median:5999.01 stddev:652.06 (10.9%) stddev-sans-first:624.35 02:08:11 INFO - PROCESS | 2486 | Values: 3295.6 3320.2 3330.3 3344.6 3371.3 5636.4 5688.6 5695.2 5744.3 5783.9 5748.9 5779.0 5796.0 5807.8 5811.2 5180.9 5222.9 5240.0 5243.5 5293.3 5267.4 5297.9 5301.2 5302.4 5307.1 5188.0 5209.3 5225.2 5246.8 5293.9 5282.0 5308.9 5320.0 5324.5 5362.8 4145.8 5096.5 5098.7 5223.1 5235.4 5647.7 5651.7 5699.2 5719.9 5734.4 6267.7 6268.1 6281.6 6285.8 6312.2 6110.4 6213.6 6297.0 6331.5 6352.7 5870.1 5894.1 5898.2 5908.4 5909.3 6294.3 6298.0 6313.7 6314.4 6317.2 6033.2 6097.8 6132.8 6159.2 6184.2 6299.6 6308.6 6309.9 6310.0 6317.0 6184.2 6261.3 6268.7 6287.5 6293.0 6121.4 6138.3 6158.8 6264.4 6292.8 4981.9 4982.2 5001.0 5002.0 5007.2 4464.7 4506.4 4532.4 4551.1 4563.8 5692.0 5702.5 5712.5 5762.9 5764.2 6256.3 6266.7 6318.1 6338.6 6346.2 5545.6 5580.9 5602.3 5694.4 5760.0 5400.4 5408.6 5432.6 5450.0 5486.6 6293.9 6349.6 6360.4 6364.0 6371.2 6327.9 6330.2 6330.7 6347.6 6350.7 6217.0 6243.2 6341.5 6348.6 6382.4 6138.4 6241.3 6249.6 6251.3 6258.3 6274.4 6306.7 6306.9 6313.1 6317.0 5999.0 6002.7 6171.6 6184.2 6186.8 6087.2 6131.4 6134.7 6154.4 6171.9 5889.3 6028.3 6060.5 6072.7 6083.3 5893.2 6123.9 6165.4 6185.4 6187.3 5797.3 5857.7 5895.3 5908.6 5915.1 02:08:11 INFO - PROCESS | 2486 | 02:08:11 INFO - PROCESS | 2486 | [#1] ext.html Cycles:180 Average:76993.88 Median:39485.01 stddev:172954.57 (438.0%) stddev-sans-first:173344.40 02:08:11 INFO - PROCESS | 2486 | Values: 502.3 510.5 511.0 523.4 550.1 30815.5 42607.0 44083.0 44124.6 46654.3 45157.3 45617.9 45669.5 45685.3 45748.8 8729.2 9760.2 9950.1 9950.3 9970.8 8672.2 8855.6 8874.0 8890.0 11895.5 11916.8 12202.5 12224.0 12256.4 12269.9 12034.7 12125.3 12139.3 12140.6 12144.4 11908.2 11958.6 11994.5 11994.8 11997.0 42608.9 42847.6 42924.4 42999.6 43008.8 3476.2 3532.8 3612.7 3913.9 4022.5 26099.5 31510.1 31520.2 31750.0 33909.5 2854.3 3233.7 3350.5 3404.7 3409.5 491050.0 493864.0 496098.0 498647.0 500090.0 65431.7 65663.0 65834.7 65908.3 65915.0 69125.7 69139.0 69192.7 69267.0 69353.3 17238.2 17450.0 17458.7 17472.3 17500.7 13183.0 13266.9 13274.9 13280.6 13290.0 16373.3 16727.2 17792.0 18488.9 19129.6 6587.2 6685.7 6749.5 6767.3 6792.0 746.3 930.7 960.8 980.2 993.0 30630.8 31136.0 31303.7 32043.4 32046.0 1046.5 1105.3 1107.4 1148.0 1178.8 15344.4 16346.9 16393.0 16413.8 16811.2 687477.7 690031.0 690322.0 692479.0 694291.0 54105.2 54566.7 61873.7 62309.1 63587.0 1428.7 1433.6 1442.9 1450.2 1455.9 28335.9 31356.0 31967.8 33387.3 33936.9 54189.0 54746.7 54804.5 54819.7 54857.7 626902.0 686474.0 699408.0 700080.5 884756.0 24858.4 27124.0 27448.5 27492.7 27495.0 43848.8 47691.5 48557.8 49285.0 49478.0 39470.8 40016.8 40720.8 40837.6 41706.8 39535.9 39648.6 40087.0 41381.4 41452.6 38648.6 39261.9 39278.8 39424.8 39499.2 29139.7 29534.4 35759.0 36251.3 36980.6 24172.0 26208.6 26620.0 26643.0 26704.0 02:08:11 INFO - PROCESS | 2486 | 02:08:11 INFO - PROCESS | 2486 | [#2] jquery.html Cycles:180 Average:36428.48 Median:22418.61 stddev:101810.41 (454.1%) stddev-sans-first:102059.95 02:08:11 INFO - PROCESS | 2486 | Values: 338.6 376.2 377.5 385.7 404.0 15643.3 16104.2 21156.7 21189.4 21257.9 21213.9 21549.9 21813.9 22143.4 22185.3 5792.2 5912.6 6009.6 6065.1 6076.1 4152.4 5862.1 5926.7 5997.8 6341.4 5609.8 6620.4 6672.8 6676.5 6704.7 6366.9 6576.7 6634.8 6639.1 6659.6 5581.0 5760.5 5761.0 5761.0 5764.3 26132.0 26367.7 26370.5 26680.3 26691.6 1882.7 2337.2 2692.8 3112.6 3215.7 14898.6 15918.5 16513.5 16585.8 16649.4 1877.5 1881.2 1938.1 1988.4 1999.2 124680.0 125192.0 126073.4 127834.0 128973.0 8674.0 10381.3 11123.3 11186.6 11375.9 13925.4 13937.5 13947.4 14005.2 14011.4 7351.3 8589.5 8845.0 8898.4 9005.8 8970.9 8984.0 8987.0 9177.5 9184.0 6565.0 6697.5 6703.0 6708.1 6710.9 1695.9 1728.2 1875.0 1912.8 1950.9 906.9 927.4 1010.8 1074.4 1106.4 19989.0 22324.9 22512.3 22627.0 22788.0 972.8 977.2 990.7 1014.8 1023.5 11705.0 11883.8 12036.5 12085.8 12236.6 599846.0 607409.0 609576.0 612655.0 616117.0 118834.4 119210.0 119381.0 142345.0 145432.0 972.9 995.0 1009.0 1009.4 1033.4 19120.2 20349.0 20480.0 20544.7 20566.0 57878.0 58622.0 59654.7 59978.7 60851.7 82907.6 83321.0 83876.0 84048.0 85066.6 8293.4 8321.9 8402.8 8468.7 8544.7 11458.7 12162.0 13198.6 13235.2 13489.7 11233.5 11471.2 11531.0 11540.4 11679.5 9169.5 10251.2 11158.8 11216.7 11481.9 8147.3 8179.1 8374.9 11601.9 11827.8 10007.3 11084.1 12064.6 13145.9 13399.9 11560.2 11848.5 12099.8 12262.0 12271.2 02:08:11 INFO - PROCESS | 2486 | 02:08:11 INFO - PROCESS | 2486 | [#3] mootools.html Cycles:180 Average:5697.18 Median:4827.08 stddev:3194.63 (66.2%) stddev-sans-first:3188.23 02:08:11 INFO - PROCESS | 2486 | Values: 1528.0 1600.3 1828.4 1903.9 1975.7 7916.7 8372.5 9105.4 9105.7 9108.0 7915.0 8137.3 8139.2 8320.0 8947.3 2137.8 2179.7 2299.3 2392.0 2397.0 2400.0 2435.3 2436.9 2438.9 2439.9 2342.8 2407.5 2412.2 2419.3 2420.0 2364.9 2370.8 2383.7 2388.2 2396.9 2348.7 2357.6 2361.3 2392.0 2395.7 8768.4 8777.2 8786.6 8789.0 8801.9 4523.0 5232.0 5448.7 5454.1 5458.1 3451.1 3585.8 3618.7 3637.5 3645.4 866.8 1062.2 1074.3 1086.7 1087.5 11461.5 11743.4 11749.0 11762.7 11763.7 4481.5 4636.8 4797.3 4814.6 4839.5 10169.4 10189.5 10203.4 10205.1 10208.2 5327.0 5382.9 5409.4 5412.5 5413.9 4781.9 4869.6 4936.4 4955.0 4983.8 2867.3 3100.7 3375.1 3572.7 3915.4 990.8 1171.9 1180.2 1186.6 1192.8 843.6 921.7 922.2 932.2 990.3 5330.6 6000.2 6036.2 6087.2 6107.3 961.5 983.4 1014.7 1018.0 1040.0 3938.4 4006.7 4095.6 4122.7 4155.7 8947.5 10345.1 10594.0 10705.2 10872.9 10790.2 10824.5 10830.0 10855.4 10856.3 7980.3 8109.7 8174.9 8208.6 8227.5 5919.6 5960.9 6091.4 6093.0 6093.6 9741.2 10072.0 10155.9 10159.6 10192.3 10330.9 10340.5 10347.8 10355.8 10419.5 7101.4 7164.1 7204.3 7211.5 7211.9 6875.4 7625.6 7921.1 8414.0 8499.0 7509.0 7530.0 7556.4 7579.1 7701.4 6081.5 7132.3 7201.0 7281.7 7400.9 6990.7 7070.8 7072.5 7073.0 7075.5 4890.3 4913.5 5059.1 5093.9 5236.2 4731.4 5047.2 5098.6 5144.4 5261.6 02:08:11 INFO - PROCESS | 2486 | 02:08:11 INFO - PROCESS | 2486 | [#4] prototype.html Cycles:180 Average:4210.97 Median:4651.01 stddev:1280.32 (27.5%) stddev-sans-first:1249.18 02:08:11 INFO - PROCESS | 2486 | Values: 265.2 297.9 301.4 313.3 318.9 4553.3 4962.6 5029.8 5190.5 5392.5 4238.8 4954.1 5042.4 5089.1 5105.8 3027.0 3457.3 3650.3 4028.4 4039.7 3720.7 4009.3 4010.8 4018.8 4028.4 3915.0 3927.5 3951.6 3959.9 4011.8 3946.1 3954.7 3979.8 3979.9 3999.3 2998.7 3325.6 3425.6 3441.7 3446.9 4734.6 5047.3 5090.4 5094.8 5136.0 4524.6 4528.5 4546.9 4547.4 4563.7 4573.3 5482.1 5520.8 5542.8 5622.3 3296.5 3547.4 3685.7 3789.4 3825.5 5688.9 5706.3 5716.6 5730.5 5761.6 3647.5 3776.5 3796.8 3860.1 3914.9 3109.9 3466.5 3540.8 3801.2 3962.7 3821.7 4502.4 4509.7 4513.2 4520.2 4751.4 5487.6 5493.9 5494.5 5516.8 1230.3 1277.8 1279.0 1307.3 1322.2 830.1 864.9 874.4 890.4 904.5 4436.8 5015.8 5069.3 5139.6 5167.5 4885.7 4984.6 5237.6 5507.9 5547.6 4730.0 5037.7 5067.8 5068.9 5083.3 2229.9 2445.0 2475.3 2482.6 2484.5 4046.5 4391.0 4769.2 4860.9 4902.2 3843.8 4220.4 4587.5 4694.6 4838.1 4061.0 4628.8 4724.7 4809.5 4840.6 4648.3 4653.7 4703.0 4706.6 4720.3 4079.6 4189.3 4560.5 4596.0 4613.4 4313.6 4722.8 4813.6 4813.8 4877.8 2895.0 3105.4 3259.6 3293.7 3324.3 4566.0 4683.2 4688.0 4724.6 5357.9 5176.4 5211.4 5355.3 5411.3 5515.0 5454.4 5469.2 5486.7 5488.5 5490.0 4971.0 5065.6 5303.3 5351.2 5394.0 4420.4 4803.3 5297.8 5438.7 5494.4 4302.7 4698.0 4701.3 5206.2 5358.5 02:08:11 INFO - PROCESS | 2486 | 02:08:11 INFO - PROCESS | 2486 | [#5] yui.html Cycles:180 Average:25495.93 Median:21951.01 stddev:41740.07 (190.2%) stddev-sans-first:41815.77 02:08:11 INFO - PROCESS | 2486 | Values: 740.3 752.3 796.0 844.9 859.5 15361.9 16475.4 16496.0 16498.9 16517.4 15010.0 15184.5 15189.0 15194.7 15231.0 704.1 731.8 884.3 909.3 910.1 878.2 896.3 898.0 898.3 899.3 945.1 945.8 947.2 949.4 950.2 746.0 807.9 931.2 944.2 945.9 704.3 705.8 706.4 707.8 709.2 15248.4 15294.2 15295.2 15310.2 15312.0 13808.8 13948.2 13977.4 14064.9 14257.4 12674.9 12824.0 12835.6 12919.0 13290.4 4492.5 4519.6 4528.4 4551.8 4567.2 115448.0 116042.0 118376.4 118889.0 120509.4 11056.6 11110.3 11124.7 11139.2 11548.5 21913.6 21949.7 21952.3 21957.9 21974.8 7878.0 7930.4 7997.9 8131.9 8154.9 4361.5 4380.8 4387.3 4393.6 4559.7 3449.5 3519.1 3525.8 3569.8 3570.8 649.1 649.2 658.8 663.8 667.8 577.4 583.7 623.7 646.5 646.6 6779.9 7546.6 8217.5 10435.2 10646.8 612.0 627.3 685.9 694.2 705.1 4185.2 4734.4 4753.5 5047.7 5249.2 164291.0 166200.0 166452.0 167493.0 171690.0 123635.0 138943.3 140511.0 142817.6 143903.0 67962.7 68815.0 123955.4 127530.0 127629.4 10086.6 10212.5 10241.3 10295.5 10306.5 49562.3 51739.0 58786.1 65803.7 66439.7 67361.0 70622.6 77963.0 92658.5 93037.0 13439.7 13778.9 13950.7 14145.6 14382.3 15414.6 15558.6 15583.4 15647.0 16123.5 14326.1 14496.5 14514.9 14611.3 14640.9 11873.6 14483.0 14589.0 14894.2 14911.3 13745.2 14083.9 14190.9 14193.0 14560.9 11652.5 11692.5 11756.4 11832.3 11908.3 9008.5 10481.4 10789.0 11008.6 11836.2 02:08:11 INFO - PROCESS | 2486 | -------- Summary: end -------- 02:08:11 INFO - PROCESS | 2486 | 02:08:11 INFO - TEST-INFO | 2486: exit 0 02:08:11 INFO - TEST-OK | dromaeo_css | took 1118387ms 02:08:11 INFO - TEST-START | kraken 02:08:11 INFO - operating with platform_type : mac_ 02:08:11 INFO - Initialising browser for kraken test... 02:08:11 INFO - TEST-INFO | started process 2489 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpzH6G6z/profile http://localhost:54166/getInfo.html) 02:08:14 INFO - PROCESS | 2489 | JavaScript error: , line 0: uncaught exception: undefined 02:08:14 INFO - PROCESS | 2489 | __metrics Screen width/height:1600/1200 02:08:14 INFO - PROCESS | 2489 | colorDepth:24 02:08:14 INFO - PROCESS | 2489 | Browser inner width/height: 1024/689 02:08:14 INFO - PROCESS | 2489 | __metrics 02:08:14 INFO - PROCESS | 2489 | 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" 02:08:14 INFO - PROCESS | 2489 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:08:14 INFO - TEST-INFO | 2489: exit 0 02:08:14 INFO - Browser initialized. 02:08:14 INFO - Running cycle 1/1 for kraken test... 02:08:14 INFO - TEST-INFO | started process 2490 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpzH6G6z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:08:26 INFO - PROCESS | 2490 | RSS: Main: 246005760 02:08:26 INFO - PROCESS | 2490 | 02:08:29 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54166/tests/kraken/driver.html?testName=audio-beat-detection) 02:08:30 INFO - PROCESS | 2490 | RSS: Main: 324620288 02:08:30 INFO - PROCESS | 2490 | 02:08:33 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54166/tests/kraken/driver.html?testName=audio-dft) 02:08:33 INFO - PROCESS | 2490 | RSS: Main: 331665408 02:08:33 INFO - PROCESS | 2490 | 02:08:37 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54166/tests/kraken/driver.html?testName=audio-fft) 02:08:37 INFO - PROCESS | 2490 | RSS: Main: 424210432 02:08:37 INFO - PROCESS | 2490 | 02:08:39 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54166/tests/kraken/driver.html?testName=audio-oscillator) 02:08:40 INFO - PROCESS | 2490 | RSS: Main: 327364608 02:08:40 INFO - PROCESS | 2490 | 02:08:42 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54166/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:08:43 INFO - PROCESS | 2490 | RSS: Main: 331268096 02:08:43 INFO - PROCESS | 2490 | 02:08:45 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54166/tests/kraken/driver.html?testName=imaging-darkroom) 02:08:46 INFO - PROCESS | 2490 | RSS: Main: 369786880 02:08:46 INFO - PROCESS | 2490 | 02:08:49 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54166/tests/kraken/driver.html?testName=imaging-desaturate) 02:08:50 INFO - PROCESS | 2490 | RSS: Main: 370561024 02:08:50 INFO - PROCESS | 2490 | 02:08:53 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54166/tests/kraken/driver.html?testName=json-parse-financial) 02:08:53 INFO - PROCESS | 2490 | RSS: Main: 371675136 02:08:53 INFO - PROCESS | 2490 | 02:08:55 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54166/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:08:55 INFO - PROCESS | 2490 | RSS: Main: 345362432 02:08:55 INFO - PROCESS | 2490 | 02:08:57 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54166/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:08:58 INFO - PROCESS | 2490 | RSS: Main: 351023104 02:08:58 INFO - PROCESS | 2490 | 02:09:00 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54166/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:09:01 INFO - PROCESS | 2490 | RSS: Main: 349065216 02:09:01 INFO - PROCESS | 2490 | 02:09:04 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:09:05 INFO - PROCESS | 2490 | RSS: Main: 362340352 02:09:05 INFO - PROCESS | 2490 | 02:09:07 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:09:08 INFO - PROCESS | 2490 | RSS: Main: 364781568 02:09:08 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | Cycle 1(1): loaded http://localhost:54166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54166/tests/kraken/driver.html?testName=ai-astar) 02:09:10 INFO - PROCESS | 2490 | RSS: Main: 408158208 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | __start_tp_report 02:09:10 INFO - PROCESS | 2490 | _x_x_mozilla_page_load 02:09:10 INFO - PROCESS | 2490 | _x_x_mozilla_page_load_details 02:09:10 INFO - PROCESS | 2490 | |i|pagename|runs| 02:09:10 INFO - PROCESS | 2490 | |0;ai-astar;101;113;112;111;113;113;111;113;111;111 02:09:10 INFO - PROCESS | 2490 | |1;audio-beat-detection;142;114;107;163;105;111;130;107;142;110 02:09:10 INFO - PROCESS | 2490 | |2;audio-dft;185;188;183;186;186;182;184;185;184;183 02:09:10 INFO - PROCESS | 2490 | |3;audio-fft;80;74;80;133;57;57;60;57;96;56 02:09:10 INFO - PROCESS | 2490 | |4;audio-oscillator;88;69;93;70;81;69;96;70;67;99 02:09:10 INFO - PROCESS | 2490 | |5;imaging-gaussian-blur;93;118;88;85;88;85;89;86;87;85 02:09:10 INFO - PROCESS | 2490 | |6;imaging-darkroom;94;95;109;123;93;122;152;96;96;93 02:09:10 INFO - PROCESS | 2490 | |7;imaging-desaturate;84;85;87;81;87;85;86;85;85;86 02:09:10 INFO - PROCESS | 2490 | |8;json-parse-financial;54;52;51;76;82;83;53;54;53;52 02:09:10 INFO - PROCESS | 2490 | |9;json-stringify-tinderbox;67;67;78;75;75;70;71;69;70;96 02:09:10 INFO - PROCESS | 2490 | |10;stanford-crypto-aes;81;85;81;84;84;81;84;75;84;86 02:09:10 INFO - PROCESS | 2490 | |11;stanford-crypto-ccm;138;136;141;152;123;144;127;140;248;152 02:09:10 INFO - PROCESS | 2490 | |12;stanford-crypto-pbkdf2;210;194;161;163;162;168;162;163;162;162 02:09:10 INFO - PROCESS | 2490 | |13;stanford-crypto-sha256-iterative;66;67;67;67;67;66;65;67;64;65 02:09:10 INFO - PROCESS | 2490 | __end_tp_report 02:09:10 INFO - PROCESS | 2490 | __start_cc_report 02:09:10 INFO - PROCESS | 2490 | _x_x_mozilla_cycle_collect,2070 02:09:10 INFO - PROCESS | 2490 | __end_cc_report 02:09:10 INFO - PROCESS | 2490 | __startTimestamp1469783350448__endTimestamp 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | ------- Summary: start ------- 02:09:10 INFO - PROCESS | 2490 | Number of tests: 14 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.50 stddev:3.60 (3.2%) stddev-sans-first:1.00 02:09:10 INFO - PROCESS | 2490 | Values: 101.0 113.0 112.0 111.0 113.0 113.0 111.0 113.0 111.0 111.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:122.00 stddev:20.00 (16.4%) stddev-sans-first:20.01 02:09:10 INFO - PROCESS | 2490 | Values: 142.0 114.0 107.0 163.0 105.0 111.0 130.0 107.0 142.0 110.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:1.78 (1.0%) stddev-sans-first:1.88 02:09:10 INFO - PROCESS | 2490 | Values: 185.0 188.0 183.0 186.0 186.0 182.0 184.0 185.0 184.0 183.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#3] audio-fft Cycles:10 Average:75.00 Median:67.00 stddev:24.53 (36.6%) stddev-sans-first:25.95 02:09:10 INFO - PROCESS | 2490 | Values: 80.0 74.0 80.0 133.0 57.0 57.0 60.0 57.0 96.0 56.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:84.50 stddev:12.74 (15.1%) stddev-sans-first:13.20 02:09:10 INFO - PROCESS | 2490 | Values: 88.0 69.0 93.0 70.0 81.0 69.0 96.0 70.0 67.0 99.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:87.50 stddev:10.00 (11.4%) stddev-sans-first:10.56 02:09:10 INFO - PROCESS | 2490 | Values: 93.0 118.0 88.0 85.0 88.0 85.0 89.0 86.0 87.0 85.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:93.50 stddev:19.60 (21.0%) stddev-sans-first:20.19 02:09:10 INFO - PROCESS | 2490 | Values: 94.0 95.0 109.0 123.0 93.0 122.0 152.0 96.0 96.0 93.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.73 (2.0%) stddev-sans-first:1.79 02:09:10 INFO - PROCESS | 2490 | Values: 84.0 85.0 87.0 81.0 87.0 85.0 86.0 85.0 85.0 86.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#8] json-parse-financial Cycles:10 Average:61.00 Median:54.00 stddev:13.49 (25.0%) stddev-sans-first:14.07 02:09:10 INFO - PROCESS | 2490 | Values: 54.0 52.0 51.0 76.0 82.0 83.0 53.0 54.0 53.0 52.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:73.00 stddev:8.60 (11.8%) stddev-sans-first:8.76 02:09:10 INFO - PROCESS | 2490 | Values: 67.0 67.0 78.0 75.0 75.0 70.0 71.0 69.0 70.0 96.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:84.00 stddev:3.17 (3.8%) stddev-sans-first:3.32 02:09:10 INFO - PROCESS | 2490 | Values: 81.0 85.0 81.0 84.0 84.0 81.0 84.0 75.0 84.0 86.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:142.50 stddev:35.63 (25.0%) stddev-sans-first:37.52 02:09:10 INFO - PROCESS | 2490 | Values: 138.0 136.0 141.0 152.0 123.0 144.0 127.0 140.0 248.0 152.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:163.00 stddev:17.03 (10.4%) stddev-sans-first:10.57 02:09:10 INFO - PROCESS | 2490 | Values: 210.0 194.0 161.0 163.0 162.0 168.0 162.0 163.0 162.0 162.0 02:09:10 INFO - PROCESS | 2490 | 02:09:10 INFO - PROCESS | 2490 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:67.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 02:09:10 INFO - PROCESS | 2490 | Values: 66.0 67.0 67.0 67.0 67.0 66.0 65.0 67.0 64.0 65.0 02:09:10 INFO - PROCESS | 2490 | -------- Summary: end -------- 02:09:10 INFO - PROCESS | 2490 | 02:09:11 INFO - TEST-INFO | 2490: exit 0 02:09:11 INFO - TEST-OK | kraken | took 59813ms 02:09:11 INFO - SUITE-END | took 1178s 02:09:11 INFO - Completed test suite (00:19:38) 02:09:11 INFO - javascript benchmark 02:09:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.57157569516, 3320.1865542941973, 3330.317284956584, 3344.5819272590925, 3371.32573485303, 5636.436356364362, 5688.573356998221, 5695.174199741035, 5744.3106827180745, 5783.942160578398, 5748.913766293498, 5779.017567013607, 5796.0, 5807.8257652270395, 5811.215485909401, 5180.870478238036, 5222.895542089156, 5240.004399164147, 5243.475652434745, 5293.258943747878, 5267.3942496613, 5297.867553311159, 5301.231321458386, 5302.416734159239, 5307.071262529062, 5187.974060129694, 5209.322788037569, 5225.16397376418, 5246.816361427351, 5293.947060529384, 5282.0, 5308.946910530923, 5319.9734001330135, 5324.467553244684, 5362.839114826562, 4145.7719825409595, 5096.51583099605, 5098.745062746848, 5223.138182199932, 5235.424103348629, 5647.745851436654, 5651.660900345952, 5699.173619825102, 5719.885602287973, 5734.369219385864, 6267.6866156691985, 6268.059791031336, 6281.623102613812, 6285.842853928642, 6312.242530896322, 6110.358412366682, 6213.56505044647, 6296.96851515744, 6331.4934805215935, 6352.6823658816875, 5870.119482077723, 5894.086416605433, 5898.2332296802, 5908.379620139909, 5909.320428150818, 6294.339094395114, 6298.0, 6313.747450102036, 6314.431701146918, 6317.210348706411, 6033.185519954838, 6097.75608975636, 6132.7546898124465, 6159.168512250789, 6184.226971628546, 6299.590526615755, 6308.621482711052, 6309.936900631027, 6309.968450157811, 6316.968415157895, 6184.196054512884, 6261.342559031236, 6268.717907694165, 6287.528435367366, 6292.968535157386, 6121.357257487989, 6138.296437970319, 6158.846028849314, 6264.404881536247, 6292.779752708633, 4981.9252711209365, 4982.177940639826, 5001.049800537959, 5002.0, 5007.198848184273, 4464.665150113755, 4506.414166158378, 4532.365468834366, 4551.06703125865, 4563.817447302071, 5692.0, 5702.515286200636, 5712.542996560265, 5762.884742305214, 5764.193012978187, 6256.287663728292, 6266.749330026839, 6318.147050148171, 6338.619682819046, 6346.2067241594805, 5545.584081193846, 5580.86047848807, 5602.327720673464, 5694.373618901917, 5759.971200143972, 5400.378956419983, 5408.567314614821, 5432.619716619799, 5450.018996580574, 5486.643368181135, 6293.937060629427, 6349.619022858551, 6360.363963603788, 6364.0, 6371.171747672773, 6327.873442531216, 6330.240371155491, 6330.683465826783, 6347.555671103163, 6350.650714210578, 6217.036359364306, 6243.219597550306, 6341.524385671001, 6348.555601107878, 6382.425581697669, 6138.357744607813, 6241.3134555197985, 6249.593776404519, 6251.34360892118, 6258.311585725475, 6274.372562743872, 6306.684665766602, 6306.873862522632, 6313.116163737172, 6316.968415157895, 5999.010163323177, 6002.729877155626, 6171.567990240819, 6184.165137706352, 6186.752529898754, 6087.208662873975, 6131.448169664752, 6134.6625935573975, 6154.353792851686, 6171.938280617316, 5889.263842019747, 6028.2766068072115, 6060.454559089703, 6072.666003369857, 6083.300420451527, 5893.204417403767, 6123.941167985503, 6165.383461653978, 6185.41238582334, 6187.288461826947, 5797.333306669787, 5857.736401861841, 5895.263092113486, 5908.645481271137, 5915.112733090071], "value": 5732.709077117917, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.3344069108432, 510.4997102839217, 511.00864323212954, 523.3954782226527, 550.1225545255317, 30815.537766933514, 42607.0, 44083.0, 44124.55875441244, 46654.300185497246, 45157.322640160426, 45617.859553511094, 45669.54330456703, 45685.314720279224, 45748.77125614367, 8729.214370706635, 9760.17038551724, 9950.104490595844, 9950.30347875649, 9970.800583988315, 8672.176143266379, 8855.601497932576, 8874.0, 8890.0, 11895.52417903283, 11916.821247681291, 12202.511899524008, 12224.02207823372, 12256.387180640932, 12269.938650306736, 12034.69913252167, 12125.333106679129, 12139.332336721476, 12140.635780926586, 12144.392780360946, 11908.16642835002, 11958.581449649308, 11994.520219191221, 11994.760104797855, 11997.0, 42608.93477663052, 42847.57152428467, 42924.356134658, 42999.57000429987, 43008.78495607533, 3476.2352282498064, 3532.7880327180196, 3612.747107702462, 3913.902152446183, 4022.45696830927, 26099.52054554814, 31510.054698359087, 31520.211994700087, 31750.04749857508, 33909.49135762965, 2854.343500994762, 3233.66046565112, 3350.480675495302, 3404.7276217902754, 3409.488576713488, 491050.0, 493864.0, 496098.0, 498647.0, 500090.0, 65431.67284163549, 65663.0, 65834.67082664651, 65908.34091659117, 65915.0, 69125.65437172883, 69138.9629155563, 69192.6540367305, 69267.0, 69353.3064669357, 17238.224279907434, 17450.0, 17458.65082698339, 17472.30110795554, 17500.737488937677, 13183.0, 13266.867331326755, 13274.93362533181, 13280.601581952556, 13290.003249756306, 16373.263203155648, 16727.247273872705, 17792.042807922586, 18488.907555462138, 19129.617407651767, 6587.176602924635, 6685.732570697215, 6749.527533072638, 6767.2894346093935, 6791.966040169767, 746.2500187311717, 930.7021753039098, 960.8126415349079, 980.152168374351, 993.0417147452598, 30630.846845765627, 31136.0, 31303.747850086198, 32043.35913281721, 32046.0, 1046.4878250927336, 1105.2926127278547, 1107.44074242507, 1148.018444230171, 1178.8467499225217, 15344.386224551115, 16346.918265408596, 16393.016419014897, 16413.753793693104, 16811.243494042796, 687477.6878346829, 690031.0, 690322.0, 692479.0, 694291.0, 54105.1884221737, 54566.72716636391, 61873.690631547455, 62309.065364019574, 63587.0, 1428.6642638979831, 1433.5555977646964, 1442.9278536073364, 1450.2241300904177, 1455.8980871338904, 28335.866565337157, 31355.953200957534, 31967.840160799977, 33387.33225335431, 33936.91646740171, 54189.0, 54746.726266368416, 54804.45195547993, 54819.725901370235, 54857.72571137119, 626902.0, 686474.0, 699408.0, 700080.4947678385, 884756.0, 24858.378540536632, 27124.0, 27448.451030979668, 27492.725072749017, 27495.0, 43848.780756096014, 47691.523084768705, 48557.75721121372, 49285.0, 49478.01043979028, 39470.80264598659, 40016.79991600024, 40720.79639601902, 40837.61845021777, 41706.79146604247, 39535.86033815586, 39648.60351396449, 40087.0, 41381.37927931023, 41452.58547414608, 38648.613513864504, 39261.87750286179, 39278.80360598179, 39424.802875985435, 39499.2100158001, 29139.688714007498, 29534.409311814074, 35759.0, 36251.33673805905, 36980.63019369772, 24172.03311867576, 26208.606870896572, 26620.0, 26643.0, 26704.0], "value": 21065.9064131484, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.62920102487766, 376.1536542778748, 377.5205489028933, 385.67795890431495, 403.98384064637395, 15643.296051677673, 16104.194790260484, 21156.682649760227, 21189.364319070413, 21257.893710531444, 21213.939303034826, 21549.922503874823, 21813.890930545323, 22143.44641383966, 22185.334439966788, 5792.218050563183, 5912.556558258127, 6009.639421634694, 6065.090236464522, 6076.088586711984, 4152.356384760367, 5862.149988251701, 5926.674032928187, 5997.790077347294, 6341.397567231105, 5609.831705048855, 6620.371064748865, 6672.833179170511, 6676.499262555303, 6704.664766761667, 6366.904496432558, 6576.671166441706, 6634.800955971328, 6639.070530125786, 6659.6004239745525, 5581.0, 5760.53915686744, 5760.9630266552, 5761.0494268445655, 5764.250647415851, 26132.0, 26367.736322636913, 26370.472590548274, 26680.332991675168, 26691.599626005627, 1882.7458293130526, 2337.22871452422, 2692.7845772338164, 3112.6264848218357, 3215.7266632336277, 14898.627534311621, 15918.522444326689, 16513.504594862174, 16585.751213731804, 16649.4172703956, 1877.5401360006667, 1881.2192939929912, 1938.1472152252963, 1988.443235893952, 1999.2203040814095, 124680.0, 125192.0, 126073.36963315216, 127834.0, 128973.0, 8673.956630216808, 10381.273310868166, 11123.27698699582, 11186.608468703556, 11375.943120284346, 13925.442982280798, 13937.512187073606, 13947.372368243454, 14005.229712365714, 14011.439542418393, 7351.338379545867, 8589.52757598338, 8844.982826974852, 8898.377113602115, 9005.774855628531, 8970.910290897138, 8984.0, 8986.955065224764, 9177.541122943827, 9184.0, 6565.0, 6697.497687673444, 6703.0, 6708.127943367331, 6710.932890671031, 1695.8660964730677, 1728.179114920428, 1874.990625046866, 1912.7895931447529, 1950.8731932424348, 906.9093090690878, 927.3554879358916, 1010.838265877456, 1074.425182527344, 1106.3527836215742, 19989.0, 22324.888375558017, 22512.32463026112, 22626.981785819677, 22788.0, 972.8005758819567, 977.2426369563503, 990.736810566519, 1014.7868947521104, 1023.4831410137981, 11705.0, 11883.762324753456, 12036.45835937385, 12085.818712719316, 12236.571719989757, 599846.0, 607409.0, 609576.0, 612655.0, 616117.0, 118834.4058279703, 119210.0, 119381.0, 142345.0, 145432.0, 972.8832540095234, 994.9701508954598, 1009.0313299232672, 1009.4094954451581, 1033.4161198922434, 19120.220068895, 20349.0, 20480.0, 20544.69182962227, 20566.0, 57878.0, 58622.0, 59654.70172649109, 59978.700106499185, 60851.69574152278, 82907.5854620723, 83321.0, 83876.0, 84048.0, 85066.57466712628, 8293.419460637939, 8321.916780832114, 8402.789930251793, 8468.661253550035, 8544.658213671384, 11458.71353216143, 12162.0, 13198.60404187876, 13235.205887646773, 13489.662758431117, 11233.494492747683, 11471.197016208785, 11531.0, 11540.365279909685, 11679.474423650787, 9169.541522923693, 10251.179905607683, 11158.832617510581, 11216.66350009501, 11481.885181148416, 8147.266745992889, 8179.10029896699, 8374.87437688423, 11601.883981160081, 11827.763444731228, 10007.299489035697, 11084.057855082086, 12064.638060857837, 13145.868541314465, 13399.866001339862, 11560.248583842023, 11848.466818993338, 12099.81850272229, 12262.019038476721, 12271.202371846159], "value": 10178.415014165057, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1527.9923600381996, 1600.2638786158366, 1828.4149072296862, 1903.9048047597616, 1975.7233987241789, 7916.722914697987, 8372.45579037363, 9105.408148470346, 9105.68130115446, 9108.0, 7914.960425197881, 8137.308328792045, 8139.186081391857, 8319.95840020799, 8947.284217262619, 2137.7862213778653, 2179.6512557990723, 2299.3446867642724, 2392.043182726906, 2396.988015059931, 2400.0, 2435.3059378077164, 2436.8659723715186, 2438.890249938748, 2439.865807380593, 2342.824288178385, 2407.5064611754547, 2412.1557454890735, 2419.2621250518673, 2420.044082587377, 2364.893579788905, 2370.7510711375317, 2383.690120284352, 2388.176079252655, 2396.8681722505257, 2348.6829278047417, 2357.646353047057, 2361.2916125162383, 2392.031227352925, 2395.6765836612008, 8768.430052046597, 8777.166169213915, 8786.560671966376, 8789.0, 8801.867971980426, 4523.0, 5232.0, 5448.673079615196, 5454.072807622713, 5458.099413596752, 3451.119964409085, 3585.7848529088337, 3618.656227658368, 3637.508936293593, 3645.38028535147, 866.8266346730679, 1062.2351906627216, 1074.3017038924684, 1086.7337502311984, 1087.472575800736, 11461.484233209525, 11743.412829358584, 11749.0, 11762.705932351673, 11763.7059073523, 4481.462224533077, 4636.8145274188655, 4797.33747769991, 4814.638902082288, 4839.516048395203, 10169.440680762482, 10189.54147063384, 10203.38779673222, 10205.132563732017, 10208.183345332356, 5326.97336513315, 5382.946170538323, 5409.377921538995, 5412.512873841373, 5413.891722165535, 4781.880452988634, 4869.634777391639, 4936.3829521309835, 4955.0, 4983.775730092082, 2867.2688464441853, 3100.7209351158053, 3375.0887260439545, 3572.7499075064497, 3915.432262322004, 990.7968866382519, 1171.8769529199483, 1180.2387460088196, 1186.6262127429832, 1192.8151136573845, 843.6498852976069, 921.7004473545991, 922.2345453273766, 932.2495391210006, 990.3315262197988, 5330.626856120034, 6000.159977603139, 6036.15493830864, 6087.208662873798, 6107.297660769068, 961.4567769210374, 983.4001259231862, 1014.665160497049, 1017.954192061359, 1039.986013636698, 3938.3501722215515, 4006.7395619284653, 4095.569965153616, 4122.690798190148, 4155.688323375759, 8947.463152210888, 10345.120664743581, 10594.0, 10705.19711021661, 10872.891271087188, 10790.244682872124, 10824.5128969195, 10830.0, 10855.402952837512, 10856.34861908288, 7980.321672657773, 8109.675612975533, 8174.877376839352, 8208.58957052145, 8227.506349619041, 5919.644821310735, 5960.850978725566, 6091.42131497507, 6092.969535152296, 6093.634381937098, 9741.220702343653, 10071.992800719869, 10155.949220253851, 10159.644412445825, 10192.337498062601, 10330.948345258226, 10340.53467593975, 10347.793044139225, 10355.844662330222, 10419.479026048819, 7101.360877521048, 7164.104486939133, 7204.279572042754, 7211.495195336276, 7211.891821622785, 6875.3812156906115, 7625.580593067212, 7921.128675845537, 8414.0, 8499.022612399653, 7509.0, 7529.962350188433, 7556.395488361028, 7579.052618422697, 7701.422393320411, 6081.483073938764, 7132.322429369202, 7201.0, 7281.672324745293, 7400.851982960418, 6990.650467476504, 7070.823229419306, 7072.5402848814665, 7073.0, 7075.469339799432, 4890.315355850113, 4913.508649135201, 5059.089363914531, 5093.923591146062, 5236.214567814858, 4731.432228132646, 5047.167217409086, 5098.643094983316, 5144.382674079135, 5261.57907367402], "value": 4585.098359125374, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.19381081512205, 297.88084766093573, 301.36562535862015, 313.3497991667289, 318.90114064639965, 4553.294239392895, 4962.627802914778, 5029.823956161535, 5190.506901844328, 5392.541633961118, 4238.770756480624, 4954.058728841517, 5042.369703787027, 5089.134847075997, 5105.821296254632, 3026.95570028341, 3457.291255292659, 3650.2843663478257, 4028.415879697442, 4039.676825853939, 3720.7209459290525, 4009.2582872168596, 4010.7794071326066, 4018.7588744675413, 4028.4158796974566, 3915.0033482923773, 3927.5483319418204, 3951.577432124433, 3959.9010024749323, 4011.8194681239415, 3946.131850992777, 3954.703397245218, 3979.8408063677416, 3979.900502487461, 3999.300122478555, 2998.745106665936, 3325.5676762021026, 3425.5889293284717, 3441.741869359783, 3446.9310613787584, 4734.621230301567, 5047.318611987372, 5090.363704536933, 5094.821681241138, 5136.048301645388, 4524.5927866492175, 4528.479224889147, 4546.931796023062, 4547.407548037047, 4563.657725670555, 4573.336866154422, 5482.095454250044, 5520.779168833282, 5542.7782888684405, 5622.297212848394, 3296.489044198153, 3547.3614749345106, 3685.68671662909, 3789.393697008493, 3825.5026846510054, 5688.914666280009, 5706.28671416073, 5716.570857285678, 5730.484256416901, 5761.596688231825, 3647.5258216431694, 3776.527934008249, 3796.8481260749813, 3860.092878173627, 3914.8825535233987, 3109.8600562974266, 3466.4626982817704, 3540.8406621702084, 3801.163743976321, 3962.6829853611644, 3821.6751576115785, 4502.4371953505815, 4509.6843220974215, 4513.1650644630845, 4520.186366454004, 4751.358566593535, 5487.6158668892795, 5493.945060549423, 5494.527462641827, 5516.7517461714315, 1230.308326051679, 1277.7891647878182, 1278.974420511603, 1307.300594182127, 1322.1670347681093, 830.1034882327073, 864.9221570058725, 874.4403581707712, 890.4345740454858, 904.5115637555647, 4436.822527098944, 5015.84952451427, 5069.340985671839, 5139.614528910347, 5167.534921857051, 4885.706857588556, 4984.626153038536, 5237.5548078413, 5507.862303442366, 5547.559074007424, 4729.976350118227, 5037.697738135724, 5067.797288108508, 5068.923966140511, 5083.339165908408, 2229.921952731647, 2444.987775061113, 2475.306914064065, 2482.6400171975324, 2484.4844644766677, 4046.4537287466405, 4390.956090439118, 4769.236922092448, 4860.927086093711, 4902.240152776325, 3843.7501562398356, 4220.430241917364, 4587.495375508692, 4694.638554819065, 4838.088954862809, 4061.0, 4628.814847406066, 4724.724324432453, 4809.470958194595, 4840.596227094174, 4648.34923110758, 4653.674242803107, 4703.048234982581, 4706.576408123286, 4720.339152518582, 4079.5716449772344, 4189.287821070487, 4560.495036637968, 4596.011857450664, 4613.370378884004, 4313.61177494027, 4722.788503817437, 4813.590844778093, 4813.807447702193, 4877.80488780459, 2895.0156946638244, 3105.4410206163107, 3259.6088469382876, 3293.7035666790102, 3324.25204329024, 4566.013784003969, 4683.196969166827, 4688.0, 4724.551167639069, 5357.892842143213, 5176.378834539879, 5211.400688920823, 5355.277037599874, 5411.269478620493, 5515.007298686275, 5454.400015998316, 5469.206964990134, 5486.6982315973, 5488.545535433193, 5490.0, 4970.95609921906, 5065.620078494054, 5303.337082864642, 5351.170568561879, 5394.0, 4420.447444069491, 4803.327534145153, 5297.761600728054, 5438.728063596725, 5494.423085575957, 4302.655787537052, 4698.0, 4701.341812146371, 5206.240692433016, 5358.526405238435], "value": 3811.1031375939388, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.3189066059226, 752.2778132992327, 795.9681612735487, 844.9408541402103, 859.501489136301, 15361.923190384046, 16475.423360182365, 16496.0, 16498.91750541247, 16517.421890233843, 15010.0, 15184.54446366608, 15189.01271417357, 15194.6961060779, 15231.0, 704.1233664088204, 731.8243621530847, 884.3323290915382, 909.3316412436853, 910.0762725833284, 878.2227728460326, 896.3098414221046, 897.9685711000117, 898.2544488074915, 899.280575539569, 945.1493655709847, 945.7919257763281, 947.2280091725222, 949.4398305000049, 950.2303134461074, 745.9515131516488, 807.8990126234221, 931.1712575807537, 944.2304521814699, 945.9101385368414, 704.3414407528977, 705.830600655844, 706.3783870194196, 707.8195060259652, 709.2198581560294, 15248.39006439741, 15294.235288235655, 15295.158766267961, 15310.157941313224, 15312.004719693185, 13808.792868106986, 13948.2328471934, 13977.440902363893, 14064.929675351661, 14257.429702811874, 12674.873251267461, 12824.0, 12835.614931551974, 12919.0, 13290.401931913088, 4492.505824359318, 4519.615832654195, 4528.43394575678, 4551.840685575989, 4567.177907976563, 115448.0, 116042.0, 118376.40811795885, 118889.0, 120509.39745301305, 11056.557737690402, 11110.277831940897, 11124.666260012213, 11139.164562657834, 11548.480318385693, 21913.561728765337, 21949.68301901891, 21952.341429757133, 21957.890210548845, 21974.780252197594, 7878.0, 7930.405219608552, 7997.880031799527, 8131.918680813116, 8154.8776768348525, 4361.476622805284, 4380.802863871133, 4387.3418987151545, 4393.560643935581, 4559.6808223424705, 3449.5170676105367, 3519.1378112362636, 3525.8060806655367, 3569.803660798681, 3570.767900086486, 649.1334069017914, 649.1658219188415, 658.7924803686823, 663.8008597420754, 667.7729571945562, 577.4254616656453, 583.743153012673, 623.6632218601904, 646.4731244036095, 646.5926466326184, 6779.9322006780285, 7546.622668866535, 8217.465864718773, 10435.16518678504, 10646.787064258671, 611.9975361138107, 627.3099590450468, 685.9245482996866, 694.198201077756, 705.1186017478152, 4185.204811085884, 4734.431868175772, 4753.548412900769, 5047.740198286408, 5249.186376111708, 164291.0, 166200.0, 166452.0, 167493.0, 171690.0, 123635.0, 138943.30528347497, 140511.0, 142817.5718242825, 143903.0, 67962.66018669974, 68815.0, 123955.38022310012, 127530.0, 127629.36185319014, 10086.644104131945, 10212.540435680263, 10241.334313269912, 10295.485225738534, 10306.536205870603, 49562.25656615226, 51738.96522069462, 58786.11820822606, 65803.67098164478, 66439.66780166069, 67361.0, 70622.64688676524, 77963.0, 92658.53670731603, 93037.0, 13439.664008399868, 13778.862211377758, 13950.651233718832, 14145.575632731034, 14382.280885955452, 15414.614634633775, 15558.611034724223, 15583.376664933729, 15647.0, 16123.516294511184, 14326.140431574138, 14496.492622758153, 14514.927425363228, 14611.269436528344, 14640.853591463949, 11873.584424545099, 14483.0, 14589.0, 14894.180820055, 14911.25443727831, 13745.177589168092, 14083.94370422202, 14190.929045354707, 14193.0, 14560.927195363955, 11652.475638596114, 11692.532298707956, 11756.35340056271, 11832.290062596272, 11908.345041022827, 9008.549572521217, 10481.370305425906, 10789.0, 11008.559657613927, 11836.230645008047], "value": 8046.589869125517, "unit": "score"}], "name": "dromaeo_css", "value": 7463.5823531689575, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 112.0, 111.0, 113.0, 113.0, 111.0, 113.0, 111.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 107.0, 163.0, 105.0, 111.0, 130.0, 107.0, 142.0, 110.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 183.0, 186.0, 186.0, 182.0, 184.0, 185.0, 184.0, 183.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 80.0, 133.0, 57.0, 57.0, 60.0, 57.0, 96.0, 56.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 93.0, 70.0, 81.0, 69.0, 96.0, 70.0, 67.0, 99.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 118.0, 88.0, 85.0, 88.0, 85.0, 89.0, 86.0, 87.0, 85.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 109.0, 123.0, 93.0, 122.0, 152.0, 96.0, 96.0, 93.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 87.0, 81.0, 87.0, 85.0, 86.0, 85.0, 85.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 51.0, 76.0, 82.0, 83.0, 53.0, 54.0, 53.0, 52.0], "value": 61.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 78.0, 75.0, 75.0, 70.0, 71.0, 69.0, 70.0, 96.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 81.0, 84.0, 84.0, 81.0, 84.0, 75.0, 84.0, 86.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 141.0, 152.0, 123.0, 144.0, 127.0, 140.0, 248.0, 152.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 194.0, 161.0, 163.0, 162.0, 168.0, 162.0, 163.0, 162.0, 162.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 67.0, 66.0, 65.0, 67.0, 64.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1460.8, "alertThreshold": 2.0}]} 02:09:11 INFO - Return code: 0 02:09:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:09:11 INFO - # TBPL SUCCESS # 02:09:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:09:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:09:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:09:11 INFO - 2016-07-29 02:09:11.739 screenresolution[2494:23876] starting screenresolution argv=screenresolution get 02:09:11 INFO - 2016-07-29 02:09:11.742 screenresolution[2494:23876] Display 0: 1600x1200x32@60 02:09:11 INFO - 2016-07-29 02:09:11.749 screenresolution[2495:23879] starting screenresolution argv=screenresolution list 02:09:11 INFO - Available Modes on Display 0 02:09:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:09:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:09:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:09:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:09:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:09:11 INFO - Intel Iris: 02:09:11 INFO - Chipset Model: Intel Iris 02:09:11 INFO - Type: GPU 02:09:11 INFO - Bus: Built-In 02:09:11 INFO - VRAM (Dynamic, Max): 1536 MB 02:09:11 INFO - Vendor: Intel (0x8086) 02:09:11 INFO - Device ID: 0x0a2e 02:09:11 INFO - Revision ID: 0x0009 02:09:11 INFO - Displays: 02:09:11 INFO - Display: 02:09:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:09:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:09:11 INFO - Display Serial Number: 1600x1200 60 02:09:11 INFO - Main Display: Yes 02:09:11 INFO - Mirror: Off 02:09:11 INFO - Online: Yes 02:09:11 INFO - Rotation: Supported 02:09:11 INFO - Return code: 0 02:09:11 INFO - Running post-action listener: _resource_record_post_action 02:09:11 INFO - [mozharness: 2016-07-29 09:09:11.996111Z] Finished run-tests step (success) 02:09:11 INFO - Running post-run listener: _resource_record_post_run 02:09:12 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 491520; Write bytes: 709527040; Read time: 6; Write time: 1939 02:09:12 INFO - TinderboxPrint: CPU usage
53.6% 02:09:12 INFO - TinderboxPrint: I/O read bytes / time
491,520 / 6 02:09:12 INFO - TinderboxPrint: I/O write bytes / time
709,527,040 / 1,939 02:09:12 INFO - TinderboxPrint: CPU idle
2,255.9 (46.4%) 02:09:12 INFO - TinderboxPrint: CPU system
969.0 (19.9%) 02:09:12 INFO - TinderboxPrint: CPU user
1,632.9 (33.6%) 02:09:12 INFO - TinderboxPrint: Swap in / out
409,198,592 / 0 02:09:12 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 402741760; Write bytes: 403886080; Read time: 30547; Write time: 808 02:09:12 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 450560; Write bytes: 292992512; Read time: 6; Write time: 1095 02:09:12 INFO - Running post-run listener: _upload_blobber_files 02:09:12 INFO - Blob upload gear active. 02:09:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:09:12 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 02:09:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:09:12 INFO - (blobuploader) - INFO - Open directory for files ... 02:09:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:09:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:09:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:09:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:09:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:09:13 INFO - (blobuploader) - INFO - Done attempting. 02:09:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:09:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:09:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:09:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:09:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:09:15 INFO - (blobuploader) - INFO - Done attempting. 02:09:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:09:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:09:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:09:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:09:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:09:17 INFO - (blobuploader) - INFO - Done attempting. 02:09:17 INFO - (blobuploader) - INFO - Iteration through files over. 02:09:17 INFO - Return code: 0 02:09:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:09:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:09:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5d9556a4a531b733f38380f903dc0afd4376c4fe501f2bcc5a17492fb30752c3f2561e5ea2bfdff2343ed14305f4175bcb90252b0340bc2077f5e10f55207643", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9de79904bd9d0093b399b3cc81d42941657e35c202eaa66212f67b877ec74c5d0e56ecd16d1ea3a2b49ef111171b7a59014f15d0b49bdb3ce6568cb4404f0c9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2bd617aeed01db2d1b88fa73edff67598f2fd6270bf8e9e644ed80bb1d381ceb1f885beb4a65218e0f3a56b893835672a70f2dd06aab42347cee09dd9db94f23"} 02:09:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:09:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:09:17 INFO - Contents: 02:09:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5d9556a4a531b733f38380f903dc0afd4376c4fe501f2bcc5a17492fb30752c3f2561e5ea2bfdff2343ed14305f4175bcb90252b0340bc2077f5e10f55207643", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9de79904bd9d0093b399b3cc81d42941657e35c202eaa66212f67b877ec74c5d0e56ecd16d1ea3a2b49ef111171b7a59014f15d0b49bdb3ce6568cb4404f0c9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2bd617aeed01db2d1b88fa73edff67598f2fd6270bf8e9e644ed80bb1d381ceb1f885beb4a65218e0f3a56b893835672a70f2dd06aab42347cee09dd9db94f23"} 02:09:17 INFO - Running post-run listener: copy_logs_to_upload_dir 02:09:17 INFO - Copying logs to upload dir... 02:09:17 INFO - mkdir: /builds/slave/test/build/upload/logs 02:09:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.471410 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-29 02:09:17.996606) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-29 02:09:18.000521) ========= 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.AkAJyzJgMr/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.MvljfTewh0/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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/Autoland/sha512/5d9556a4a531b733f38380f903dc0afd4376c4fe501f2bcc5a17492fb30752c3f2561e5ea2bfdff2343ed14305f4175bcb90252b0340bc2077f5e10f55207643", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9de79904bd9d0093b399b3cc81d42941657e35c202eaa66212f67b877ec74c5d0e56ecd16d1ea3a2b49ef111171b7a59014f15d0b49bdb3ce6568cb4404f0c9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2bd617aeed01db2d1b88fa73edff67598f2fd6270bf8e9e644ed80bb1d381ceb1f885beb4a65218e0f3a56b893835672a70f2dd06aab42347cee09dd9db94f23"} build_url:https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011027 build_url: 'https://queue.taskcluster.net/v1/task/G5tX0RGdQJi56qPgxwiDrA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5d9556a4a531b733f38380f903dc0afd4376c4fe501f2bcc5a17492fb30752c3f2561e5ea2bfdff2343ed14305f4175bcb90252b0340bc2077f5e10f55207643", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9de79904bd9d0093b399b3cc81d42941657e35c202eaa66212f67b877ec74c5d0e56ecd16d1ea3a2b49ef111171b7a59014f15d0b49bdb3ce6568cb4404f0c9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2bd617aeed01db2d1b88fa73edff67598f2fd6270bf8e9e644ed80bb1d381ceb1f885beb4a65218e0f3a56b893835672a70f2dd06aab42347cee09dd9db94f23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-29 02:09:18.032843) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 02:09:18.033183) ========= 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.AkAJyzJgMr/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.MvljfTewh0/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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.005500 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 02:09:18.081920) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-29 02:09:18.082237) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-29 02:09:18.082552) ========= ========= Total master_lag: 0.25 =========