builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0009 starttime: 1474121881.52 results: success (0) buildid: 20160917071757 builduid: 774ed64aa8384705bf402b3d89fbda68 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.517585) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.518033) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.518331) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.578398) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:01.578657) ========= 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.mkw7tSj3SO/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.tjRyis2B9U/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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-09-17 07:18:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.7M=0.001s 2016-09-17 07:18:02 (10.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.634079 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.237154) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.237464) ========= 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.mkw7tSj3SO/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.tjRyis2B9U/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.029017 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:02.286629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-17 07:18:02.286993) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b9499912b32324acd84792bf02b533b40e98e496 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mkw7tSj3SO/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.tjRyis2B9U/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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-09-17 07:18:02,363 truncating revision to first 12 chars 2016-09-17 07:18:02,363 Setting DEBUG logging. 2016-09-17 07:18:02,363 attempt 1/10 2016-09-17 07:18:02,363 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b9499912b323?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 07:18:04,400 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=2.298566 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-17 07:18:04.607653) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.608025) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.608458) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.608751) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.627135) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:18:04.627465) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mkw7tSj3SO/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.tjRyis2B9U/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:18:04 INFO - MultiFileLogger online at 20160917 07:18:04 in /builds/slave/test 07:18:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:18:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:04 INFO - {'append_to_log': False, 07:18:04 INFO - 'base_work_dir': '/builds/slave/test', 07:18:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:04 INFO - 'branch': 'Mozilla-Aurora', 07:18:04 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:04 INFO - 'config_files': ('talos/mac_config.py',), 07:18:04 INFO - 'default_actions': ('clobber', 07:18:04 INFO - 'read-buildbot-config', 07:18:04 INFO - 'download-and-extract', 07:18:04 INFO - 'populate-webroot', 07:18:04 INFO - 'create-virtualenv', 07:18:04 INFO - 'install', 07:18:04 INFO - 'run-tests'), 07:18:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:04 INFO - 'download_minidump_stackwalk': True, 07:18:04 INFO - 'download_symbols': 'ondemand', 07:18:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:04 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:04 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:04 INFO - 'installer_path': 'installer.exe', 07:18:04 INFO - 'log_level': 'info', 07:18:04 INFO - 'log_name': 'talos', 07:18:04 INFO - 'log_to_console': True, 07:18:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:04 INFO - 'opt_config_files': (), 07:18:04 INFO - 'pip_index': False, 07:18:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:04 INFO - 'cmd': ('bash', 07:18:04 INFO - '-c', 07:18:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:04 INFO - 'enabled': True, 07:18:04 INFO - 'halt_on_failure': False, 07:18:04 INFO - 'name': 'check_screen_resolution'},), 07:18:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:04 INFO - 'cmd': ('bash', 07:18:04 INFO - '-c', 07:18:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:04 INFO - 'enabled': True, 07:18:04 INFO - 'halt_on_failure': False, 07:18:04 INFO - 'name': 'check_screen_resolution'},), 07:18:04 INFO - 'run_cmd_checks_enabled': True, 07:18:04 INFO - 'sps_profile': False, 07:18:04 INFO - 'sps_profile_interval': 0, 07:18:04 INFO - 'suite': 'dromaeojs', 07:18:04 INFO - 'system_bits': '32', 07:18:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:04 INFO - 'title': 't-yosemite-r7-0009', 07:18:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:04 INFO - 'use_talos_json': True, 07:18:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:04 INFO - 'work_dir': 'build'} 07:18:04 INFO - [mozharness: 2016-09-17 14:18:04.820915Z] Running clobber step. 07:18:04 INFO - Running pre-action listener: _resource_record_pre_action 07:18:04 INFO - Running main action method: clobber 07:18:04 INFO - rmtree: /builds/slave/test/build 07:18:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:07 INFO - Running post-action listener: _resource_record_post_action 07:18:07 INFO - [mozharness: 2016-09-17 14:18:07.394399Z] Finished clobber step (success) 07:18:07 INFO - [mozharness: 2016-09-17 14:18:07.394527Z] Running read-buildbot-config step. 07:18:07 INFO - Running pre-action listener: _resource_record_pre_action 07:18:07 INFO - Running main action method: read_buildbot_config 07:18:07 INFO - Using buildbot properties: 07:18:07 INFO - { 07:18:07 INFO - "project": "", 07:18:07 INFO - "product": "firefox", 07:18:07 INFO - "script_repo_revision": "production", 07:18:07 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:18:07 INFO - "repository": "", 07:18:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:18:07 INFO - "buildid": "20160917071757", 07:18:07 INFO - "stage_platform": "macosx64", 07:18:07 INFO - "basedir": "/builds/slave/test", 07:18:07 INFO - "buildnumber": 43, 07:18:07 INFO - "platform": "yosemite_r7", 07:18:07 INFO - "mozci_request": { 07:18:07 INFO - "priority": -1, 07:18:07 INFO - "type": "trigger_all_talos_jobs", 07:18:07 INFO - "times": 6 07:18:07 INFO - }, 07:18:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:18:07 INFO - "slavebuilddir": "test", 07:18:07 INFO - "branch": "mozilla-aurora", 07:18:07 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:18:07 INFO - "slavename": "t-yosemite-r7-0009", 07:18:07 INFO - "builduid": "774ed64aa8384705bf402b3d89fbda68", 07:18:07 INFO - "repo_path": "releases/mozilla-aurora" 07:18:07 INFO - } 07:18:07 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg. 07:18:07 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json. 07:18:07 INFO - Running post-action listener: _resource_record_post_action 07:18:07 INFO - [mozharness: 2016-09-17 14:18:07.398424Z] Finished read-buildbot-config step (success) 07:18:07 INFO - [mozharness: 2016-09-17 14:18:07.398544Z] Running download-and-extract step. 07:18:07 INFO - Running pre-action listener: _resource_record_pre_action 07:18:07 INFO - Running main action method: download_and_extract 07:18:07 INFO - mkdir: /builds/slave/test/build/tests 07:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:07 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json 07:18:07 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json'}, attempt #1 07:18:07 INFO - Downloaded 1199 bytes. 07:18:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:07 INFO - Using the following test package requirements: 07:18:07 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:07 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 07:18:07 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 07:18:07 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'jsshell-mac.zip'], 07:18:07 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 07:18:07 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:07 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 07:18:07 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 07:18:07 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 07:18:07 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:07 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 07:18:07 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:07 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:07 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:07 INFO - Downloaded 16309409 bytes. 07:18:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:10 INFO - Return code: 0 07:18:10 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip', u'firefox-50.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:18: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')]} 07:18:10 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:10 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip 07:18:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:11 INFO - Downloaded 16309409 bytes. 07:18:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:14 INFO - Return code: 0 07:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:14 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip 07:18:14 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip 07:18:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:18:15 INFO - Downloaded 14413740 bytes. 07:18:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:18:15 INFO - Return code: 0 07:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:15 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:15 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg 07:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg'}, attempt #1 07:18:17 INFO - Downloaded 115349660 bytes. 07:18:17 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:17 INFO - mkdir: /builds/slave/test/properties 07:18:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:17 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:17 INFO - Contents: 07:18:17 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:18 INFO - Running post-action listener: _resource_record_post_action 07:18:18 INFO - Running post-action listener: set_extra_try_arguments 07:18:18 INFO - [mozharness: 2016-09-17 14:18:18.109999Z] Finished download-and-extract step (success) 07:18:18 INFO - [mozharness: 2016-09-17 14:18:18.110147Z] Running populate-webroot step. 07:18:18 INFO - Running pre-action listener: _resource_record_pre_action 07:18:18 INFO - Running main action method: populate_webroot 07:18:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:18 INFO - '--tppagecycles', 07:18:18 INFO - '7'], 07:18:18 INFO - 'tests': ['tsvgm']}}, 07:18:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:18 INFO - 'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:18 INFO - 'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['damp', 'tps']}, 07:18:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:18 INFO - 'tests': ['damp', 'tps']}, 07:18:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['dromaeo_dom']}, 07:18:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['basic_compositor_video']}, 07:18:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:18:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['a11yr', 07:18:18 INFO - 'ts_paint', 07:18:18 INFO - 'tpaint', 07:18:18 INFO - 'sessionrestore', 07:18:18 INFO - 'sessionrestore_no_auto_restore', 07:18:18 INFO - 'tabpaint']}, 07:18:18 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:18 INFO - 'ts_paint', 07:18:18 INFO - 'tpaint', 07:18:18 INFO - 'sessionrestore', 07:18:18 INFO - 'sessionrestore_no_auto_restore', 07:18:18 INFO - 'tabpaint']}, 07:18:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:18 INFO - 'ts_paint', 07:18:18 INFO - 'tpaint', 07:18:18 INFO - 'sessionrestore', 07:18:18 INFO - 'sessionrestore_no_auto_restore', 07:18:18 INFO - 'tabpaint']}, 07:18:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:18 INFO - 'ts_paint', 07:18:18 INFO - 'tpaint', 07:18:18 INFO - 'sessionrestore', 07:18:18 INFO - 'sessionrestore_no_auto_restore', 07:18:18 INFO - 'tabpaint']}, 07:18:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['a11yr', 07:18:18 INFO - 'ts_paint', 07:18:18 INFO - 'tpaint', 07:18:18 INFO - 'sessionrestore', 07:18:18 INFO - 'sessionrestore_no_auto_restore', 07:18:18 INFO - 'tabpaint']}, 07:18:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['a11yr', 07:18:18 INFO - 'ts_paint', 07:18:18 INFO - 'tpaint', 07:18:18 INFO - 'sessionrestore', 07:18:18 INFO - 'sessionrestore_no_auto_restore', 07:18:18 INFO - 'tabpaint']}, 07:18:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['tsvgx', 07:18:18 INFO - 'tsvgr_opacity', 07:18:18 INFO - 'tart', 07:18:18 INFO - 'tscrollx', 07:18:18 INFO - 'cart']}, 07:18:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:18 INFO - 'tsvgr_opacity', 07:18:18 INFO - 'tart', 07:18:18 INFO - 'tscrollx', 07:18:18 INFO - 'cart']}, 07:18:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:18 INFO - 'talos_options': ['--disable-e10s'], 07:18:18 INFO - 'tests': ['tp5o']}, 07:18:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:18 INFO - 'tests': ['tp5o']}, 07:18:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:18 INFO - 'talos_options': ['--disable-e10s', 07:18:18 INFO - '--xperf_path', 07:18:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:18 INFO - 'tests': ['tp5n']}, 07:18:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:18 INFO - 'talos_options': ['--xperf_path', 07:18:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:18 INFO - 'tests': ['tp5n']}}, 07:18:18 INFO - 'talos.zip': {'path': '', 07:18:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:18 INFO - Running post-action listener: _resource_record_post_action 07:18:18 INFO - [mozharness: 2016-09-17 14:18:18.129270Z] Finished populate-webroot step (success) 07:18:18 INFO - [mozharness: 2016-09-17 14:18:18.129376Z] Running create-virtualenv step. 07:18:18 INFO - Running pre-action listener: _resource_record_pre_action 07:18:18 INFO - Running main action method: create_virtualenv 07:18:18 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:18 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:18:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:18 INFO - Using real prefix '/tools/python27' 07:18:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:18 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:21 INFO - Installing pip.................done. 07:18:21 INFO - Return code: 0 07:18:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:18:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:21 INFO - 'HOME': '/Users/cltbld', 07:18:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:21 INFO - 'LOGNAME': 'cltbld', 07:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:21 INFO - 'MOZ_NO_REMOTE': '1', 07:18:21 INFO - 'NO_EM_RESTART': '1', 07:18:21 INFO - 'PAGER': '/bin/cat', 07:18:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:21 INFO - 'PWD': '/builds/slave/test', 07:18:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:21 INFO - 'SHELL': '/bin/bash', 07:18:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:21 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:21 INFO - 'USER': 'cltbld', 07:18:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:21 INFO - 'XPC_FLAGS': '0x0', 07:18:21 INFO - 'XPC_SERVICE_NAME': '0', 07:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:18:22 INFO - Cleaning up... 07:18:22 INFO - Return code: 0 07:18:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:22 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 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:22 INFO - 'HOME': '/Users/cltbld', 07:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:22 INFO - 'LOGNAME': 'cltbld', 07:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:22 INFO - 'MOZ_NO_REMOTE': '1', 07:18:22 INFO - 'NO_EM_RESTART': '1', 07:18:22 INFO - 'PAGER': '/bin/cat', 07:18:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:22 INFO - 'PWD': '/builds/slave/test', 07:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:22 INFO - 'SHELL': '/bin/bash', 07:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:22 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:22 INFO - 'USER': 'cltbld', 07:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:22 INFO - 'XPC_FLAGS': '0x0', 07:18:22 INFO - 'XPC_SERVICE_NAME': '0', 07:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:22 INFO - Downloading/unpacking psutil>=3.1.1 07:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:25 INFO - Installing collected packages: psutil 07:18:25 INFO - Running setup.py install for psutil 07:18:25 INFO - building 'psutil._psutil_osx' extension 07:18:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:18:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:18:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:18:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:18:26 INFO - building 'psutil._psutil_posix' extension 07:18:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:18:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:26 INFO - ^ 07:18:26 INFO - 1 warning generated. 07:18:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:18:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:26 INFO - Successfully installed psutil 07:18:26 INFO - Cleaning up... 07:18:26 INFO - Return code: 0 07:18:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:26 INFO - 'HOME': '/Users/cltbld', 07:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:26 INFO - 'LOGNAME': 'cltbld', 07:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:26 INFO - 'MOZ_NO_REMOTE': '1', 07:18:26 INFO - 'NO_EM_RESTART': '1', 07:18:26 INFO - 'PAGER': '/bin/cat', 07:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:26 INFO - 'PWD': '/builds/slave/test', 07:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:26 INFO - 'SHELL': '/bin/bash', 07:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:26 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:26 INFO - 'USER': 'cltbld', 07:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:26 INFO - 'XPC_FLAGS': '0x0', 07:18:26 INFO - 'XPC_SERVICE_NAME': '0', 07:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:18:29 INFO - Installing collected packages: mozsystemmonitor 07:18:29 INFO - Running setup.py install for mozsystemmonitor 07:18:29 INFO - Successfully installed mozsystemmonitor 07:18:29 INFO - Cleaning up... 07:18:29 INFO - Return code: 0 07:18:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 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 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:29 INFO - 'HOME': '/Users/cltbld', 07:18:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:29 INFO - 'LOGNAME': 'cltbld', 07:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:29 INFO - 'MOZ_NO_REMOTE': '1', 07:18:29 INFO - 'NO_EM_RESTART': '1', 07:18:29 INFO - 'PAGER': '/bin/cat', 07:18:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:29 INFO - 'PWD': '/builds/slave/test', 07:18:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:29 INFO - 'SHELL': '/bin/bash', 07:18:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:29 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:29 INFO - 'USER': 'cltbld', 07:18:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:29 INFO - 'XPC_FLAGS': '0x0', 07:18:29 INFO - 'XPC_SERVICE_NAME': '0', 07:18:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:29 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:18:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:32 INFO - Downloading functools32-3.2.3-2.tar.gz 07:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:32 INFO - warning: no files found matching '*.txt' 07:18:32 INFO - no previously-included directories found matching 'build' 07:18:32 INFO - no previously-included directories found matching 'dist' 07:18:32 INFO - no previously-included directories found matching '.git*' 07:18:32 INFO - Installing collected packages: jsonschema, functools32 07:18:32 INFO - Running setup.py install for functools32 07:18:33 INFO - warning: no files found matching '*.txt' 07:18:33 INFO - no previously-included directories found matching 'build' 07:18:33 INFO - no previously-included directories found matching 'dist' 07:18:33 INFO - no previously-included directories found matching '.git*' 07:18:33 INFO - Successfully installed jsonschema functools32 07:18:33 INFO - Cleaning up... 07:18:33 INFO - Return code: 0 07:18:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:33 INFO - 'HOME': '/Users/cltbld', 07:18:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:33 INFO - 'LOGNAME': 'cltbld', 07:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:33 INFO - 'MOZ_NO_REMOTE': '1', 07:18:33 INFO - 'NO_EM_RESTART': '1', 07:18:33 INFO - 'PAGER': '/bin/cat', 07:18:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:33 INFO - 'PWD': '/builds/slave/test', 07:18:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:33 INFO - 'SHELL': '/bin/bash', 07:18:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:33 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:33 INFO - 'USER': 'cltbld', 07:18:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:33 INFO - 'XPC_FLAGS': '0x0', 07:18:33 INFO - 'XPC_SERVICE_NAME': '0', 07:18:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:18:33 INFO - Cleaning up... 07:18:33 INFO - Return code: 0 07:18:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:33 INFO - 'HOME': '/Users/cltbld', 07:18:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:33 INFO - 'LOGNAME': 'cltbld', 07:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:33 INFO - 'MOZ_NO_REMOTE': '1', 07:18:33 INFO - 'NO_EM_RESTART': '1', 07:18:33 INFO - 'PAGER': '/bin/cat', 07:18:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:33 INFO - 'PWD': '/builds/slave/test', 07:18:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:33 INFO - 'SHELL': '/bin/bash', 07:18:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:33 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:33 INFO - 'USER': 'cltbld', 07:18:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:33 INFO - 'XPC_FLAGS': '0x0', 07:18:33 INFO - 'XPC_SERVICE_NAME': '0', 07:18:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:33 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:37 INFO - Downloading docopt-0.6.1.tar.gz 07:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:37 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:37 INFO - Running setup.py install for blobuploader 07:18:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:37 INFO - Running setup.py install for requests 07:18:38 INFO - Running setup.py install for docopt 07:18:38 INFO - Successfully installed blobuploader requests docopt 07:18:38 INFO - Cleaning up... 07:18:38 INFO - Return code: 0 07:18:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:38 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 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:38 INFO - 'HOME': '/Users/cltbld', 07:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:38 INFO - 'LOGNAME': 'cltbld', 07:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:38 INFO - 'MOZ_NO_REMOTE': '1', 07:18:38 INFO - 'NO_EM_RESTART': '1', 07:18:38 INFO - 'PAGER': '/bin/cat', 07:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:38 INFO - 'PWD': '/builds/slave/test', 07:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:38 INFO - 'SHELL': '/bin/bash', 07:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:38 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:38 INFO - 'USER': 'cltbld', 07:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:38 INFO - 'XPC_FLAGS': '0x0', 07:18:38 INFO - 'XPC_SERVICE_NAME': '0', 07:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-U3gCQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-EtkaUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-p0fwFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-A3b3GE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Ob93i1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-QzeZKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-9IONTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-seanE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-DnVwVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Nq7Xw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-pDFJeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-srl2UC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-hRY2dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-VOS1wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:40 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-PkA5yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:40 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-TzJdAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:40 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-SneKRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:40 INFO - Running setup.py install for manifestparser 07:18:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozcrash 07:18:40 INFO - Running setup.py install for mozdebug 07:18:40 INFO - Running setup.py install for mozdevice 07:18:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:40 INFO - Running setup.py install for mozfile 07:18:41 INFO - Running setup.py install for mozhttpd 07:18:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozinfo 07:18:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozInstall 07:18:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozleak 07:18:41 INFO - Running setup.py install for mozlog 07:18:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for moznetwork 07:18:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:41 INFO - Running setup.py install for mozprocess 07:18:42 INFO - Running setup.py install for mozprofile 07:18:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Running setup.py install for mozrunner 07:18:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Running setup.py install for mozscreenshot 07:18:42 INFO - Running setup.py install for moztest 07:18:42 INFO - Running setup.py install for mozversion 07:18:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:42 INFO - Cleaning up... 07:18:42 INFO - Return code: 0 07:18:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:42 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 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:42 INFO - 'HOME': '/Users/cltbld', 07:18:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:42 INFO - 'LOGNAME': 'cltbld', 07:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:42 INFO - 'MOZ_NO_REMOTE': '1', 07:18:42 INFO - 'NO_EM_RESTART': '1', 07:18:42 INFO - 'PAGER': '/bin/cat', 07:18:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:42 INFO - 'PWD': '/builds/slave/test', 07:18:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:42 INFO - 'SHELL': '/bin/bash', 07:18:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:42 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:42 INFO - 'USER': 'cltbld', 07:18:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:42 INFO - 'XPC_FLAGS': '0x0', 07:18:42 INFO - 'XPC_SERVICE_NAME': '0', 07:18:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-aPvSTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-VettKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Vo691F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-oduvEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-igmE6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-KYesin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-AvlD9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-bEFaHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-ERUzOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-v8guMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:44 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)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-VyFWzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-KJlDMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Gf6of3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-FAYYaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-ki8NeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-PhZFgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Ahojg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18:47 INFO - Downloading blessings-1.6.tar.gz 07:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:47 INFO - Installing collected packages: blessings 07:18:47 INFO - Running setup.py install for blessings 07:18:47 INFO - Successfully installed blessings 07:18:47 INFO - Cleaning up... 07:18:47 INFO - Return code: 0 07:18:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:47 INFO - Reading from file tmpfile_stdout 07:18:47 INFO - Current package versions: 07:18:47 INFO - blessings == 1.6 07:18:47 INFO - blobuploader == 1.2.4 07:18:47 INFO - docopt == 0.6.1 07:18:47 INFO - functools32 == 3.2.3-2 07:18:47 INFO - jsonschema == 2.5.1 07:18:47 INFO - manifestparser == 1.1 07:18:47 INFO - mozInstall == 1.12 07:18:47 INFO - mozcrash == 0.17 07:18:47 INFO - mozdebug == 0.1 07:18:47 INFO - mozdevice == 0.48 07:18:47 INFO - mozfile == 1.2 07:18:47 INFO - mozhttpd == 0.7 07:18:47 INFO - mozinfo == 0.9 07:18:47 INFO - mozleak == 0.1 07:18:47 INFO - mozlog == 3.3 07:18:47 INFO - moznetwork == 0.27 07:18:47 INFO - mozprocess == 0.23 07:18:47 INFO - mozprofile == 0.28 07:18:47 INFO - mozrunner == 6.12 07:18:47 INFO - mozscreenshot == 0.1 07:18:47 INFO - mozsystemmonitor == 0.3 07:18:47 INFO - moztest == 0.7 07:18:47 INFO - mozversion == 1.4 07:18:47 INFO - psutil == 3.1.1 07:18:47 INFO - requests == 1.2.3 07:18:47 INFO - wsgiref == 0.1.2 07:18:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:48 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 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:48 INFO - 'HOME': '/Users/cltbld', 07:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:48 INFO - 'LOGNAME': 'cltbld', 07:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:48 INFO - 'MOZ_NO_REMOTE': '1', 07:18:48 INFO - 'NO_EM_RESTART': '1', 07:18:48 INFO - 'PAGER': '/bin/cat', 07:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:48 INFO - 'PWD': '/builds/slave/test', 07:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:48 INFO - 'SHELL': '/bin/bash', 07:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:48 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:48 INFO - 'USER': 'cltbld', 07:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:48 INFO - 'XPC_FLAGS': '0x0', 07:18:48 INFO - 'XPC_SERVICE_NAME': '0', 07:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:18:48 INFO - Cleaning up... 07:18:48 INFO - Return code: 0 07:18:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:48 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 0x1057d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb822ce3a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, '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.mkw7tSj3SO/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.tjRyis2B9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:48 INFO - 'HOME': '/Users/cltbld', 07:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:48 INFO - 'LOGNAME': 'cltbld', 07:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:48 INFO - 'MOZ_NO_REMOTE': '1', 07:18:48 INFO - 'NO_EM_RESTART': '1', 07:18:48 INFO - 'PAGER': '/bin/cat', 07:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:48 INFO - 'PWD': '/builds/slave/test', 07:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:48 INFO - 'SHELL': '/bin/bash', 07:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:18:48 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:48 INFO - 'USER': 'cltbld', 07:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:48 INFO - 'XPC_FLAGS': '0x0', 07:18:48 INFO - 'XPC_SERVICE_NAME': '0', 07:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:18:48 INFO - Cleaning up... 07:18:48 INFO - Return code: 0 07:18:48 INFO - Running post-action listener: _resource_record_post_action 07:18:48 INFO - Running post-action listener: _start_resource_monitoring 07:18:48 INFO - Starting resource monitoring. 07:18:48 INFO - [mozharness: 2016-09-17 14:18:48.556949Z] Finished create-virtualenv step (success) 07:18:48 INFO - [mozharness: 2016-09-17 14:18:48.557868Z] Running install step. 07:18:48 INFO - Running pre-action listener: _resource_record_pre_action 07:18:48 INFO - Running main action method: install 07:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:48 INFO - Reading from file tmpfile_stdout 07:18:48 INFO - Detecting whether we're running mozinstall >=1.0... 07:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:48 INFO - Reading from file tmpfile_stdout 07:18:48 INFO - Output received: 07:18:48 INFO - Usage: mozinstall [options] installer 07:18:48 INFO - Options: 07:18:48 INFO - -h, --help show this help message and exit 07:18:48 INFO - -d DEST, --destination=DEST 07:18:48 INFO - Directory to install application into. [default: 07:18:48 INFO - "/builds/slave/test"] 07:18:48 INFO - --app=APP Application being installed. [default: firefox] 07:18:48 INFO - mkdir: /builds/slave/test/build/application 07:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:19:21 INFO - Reading from file tmpfile_stdout 07:19:21 INFO - Output received: 07:19:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:19:21 INFO - Running post-action listener: _resource_record_post_action 07:19:21 INFO - [mozharness: 2016-09-17 14:19:21.202861Z] Finished install step (success) 07:19:21 INFO - [mozharness: 2016-09-17 14:19:21.203019Z] Running run-tests step. 07:19:21 INFO - Running pre-action listener: _resource_record_pre_action 07:19:21 INFO - Running main action method: run_tests 07:19:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:21 INFO - 2016-09-17 07:19:21.217 screenresolution[1761:15126] starting screenresolution argv=screenresolution get 07:19:21 INFO - 2016-09-17 07:19:21.220 screenresolution[1761:15126] Display 0: 1600x1200x32@60 07:19:21 INFO - 2016-09-17 07:19:21.225 screenresolution[1762:15128] starting screenresolution argv=screenresolution list 07:19:21 INFO - Available Modes on Display 0 07:19:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:21 INFO - Intel Iris: 07:19:21 INFO - Chipset Model: Intel Iris 07:19:21 INFO - Type: GPU 07:19:21 INFO - Bus: Built-In 07:19:21 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:21 INFO - Vendor: Intel (0x8086) 07:19:21 INFO - Device ID: 0x0a2e 07:19:21 INFO - Revision ID: 0x0009 07:19:21 INFO - Displays: 07:19:21 INFO - Display: 07:19:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:21 INFO - Display Serial Number: 1600x1200 60 07:19:21 INFO - Main Display: Yes 07:19:21 INFO - Mirror: Off 07:19:21 INFO - Online: Yes 07:19:21 INFO - Rotation: Supported 07:19:21 INFO - Return code: 0 07:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:21 INFO - Python 2.7.3 07:19:21 INFO - Return code: 0 07:19:21 INFO - grabbing minidump binary from tooltool 07:19: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')]} 07:19:21 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 0x10573cd30>, '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 0x1054a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105734b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:19:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:19:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:19:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:21 INFO - Return code: 0 07:19:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mkw7tSj3SO/Render', 07:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:21 INFO - 'HOME': '/Users/cltbld', 07:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:21 INFO - 'LOGNAME': 'cltbld', 07:19:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:21 INFO - 'MOZ_NO_REMOTE': '1', 07:19:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:21 INFO - 'NO_EM_RESTART': '1', 07:19:21 INFO - 'PAGER': '/bin/cat', 07:19:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:21 INFO - 'PWD': '/builds/slave/test', 07:19:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:21 INFO - 'SHELL': '/bin/bash', 07:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tjRyis2B9U/Listeners', 07:19:21 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 07:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:21 INFO - 'USER': 'cltbld', 07:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:21 INFO - 'XPC_FLAGS': '0x0', 07:19:21 INFO - 'XPC_SERVICE_NAME': '0', 07:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:19:21 INFO - mozversion application_buildid: 20160916162818 07:19:21 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:19:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:21 INFO - mozversion application_name: Firefox 07:19:21 INFO - mozversion application_remotingname: firefox-dev 07:19:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:21 INFO - mozversion application_vendor: Mozilla 07:19:21 INFO - mozversion application_version: 50.0a2 07:19:21 INFO - mozversion platform_buildid: 20160916162818 07:19:21 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:21 INFO - mozversion platform_version: 50.0a2 07:19:21 INFO - using testdate: 1474121961 07:19:21 INFO - actual date: 1474121961 07:19:21 INFO - starting webserver on 'localhost:49268' 07:19:21 INFO - SUITE-START | Running 2 tests 07:19:21 INFO - TEST-START | dromaeo_css 07:19:21 INFO - operating with platform_type : mac_ 07:19:21 INFO - Initialising browser for dromaeo_css test... 07:19:21 INFO - TEST-INFO | started process 1772 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpYObdp5/profile http://localhost:49268/getInfo.html) 07:19:24 INFO - PROCESS | 1772 | __metrics Screen width/height:1600/1200 07:19:24 INFO - PROCESS | 1772 | colorDepth:24 07:19:24 INFO - PROCESS | 1772 | Browser inner width/height: 1024/689 07:19:24 INFO - PROCESS | 1772 | __metrics 07:19:24 INFO - PROCESS | 1772 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:19:24 INFO - PROCESS | 1772 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:25 INFO - TEST-INFO | 1772: exit 0 07:19:25 INFO - Browser initialized. 07:19:25 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:25 INFO - TEST-INFO | started process 1775 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpYObdp5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:37 INFO - PROCESS | 1775 | RSS: Main: 215351296 07:19:37 INFO - PROCESS | 1775 | 07:20:28 INFO - PROCESS | 1775 | 1474122028876 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] 07:22:39 INFO - PROCESS | 1775 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 07:22:40 INFO - PROCESS | 1775 | RSS: Main: 337223680 07:22:40 INFO - PROCESS | 1775 | 07:25:43 INFO - PROCESS | 1775 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 07:25:44 INFO - PROCESS | 1775 | RSS: Main: 361455616 07:25:44 INFO - PROCESS | 1775 | 07:28:46 INFO - PROCESS | 1775 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 07:28:47 INFO - PROCESS | 1775 | RSS: Main: 332300288 07:28:47 INFO - PROCESS | 1775 | 07:31:49 INFO - PROCESS | 1775 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 07:31:50 INFO - PROCESS | 1775 | RSS: Main: 332922880 07:31:50 INFO - PROCESS | 1775 | 07:34:53 INFO - PROCESS | 1775 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 07:34:54 INFO - PROCESS | 1775 | RSS: Main: 316268544 07:34:54 INFO - PROCESS | 1775 | 07:37:57 INFO - PROCESS | 1775 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 07:37:57 INFO - PROCESS | 1775 | RSS: Main: 398610432 07:37:57 INFO - PROCESS | 1775 | 07:37:57 INFO - PROCESS | 1775 | __start_tp_report 07:37:57 INFO - PROCESS | 1775 | _x_x_mozilla_page_load 07:37:57 INFO - PROCESS | 1775 | _x_x_mozilla_page_load_details 07:37:57 INFO - PROCESS | 1775 | |i|pagename|runs| 07:37:57 INFO - PROCESS | 1775 | |0;dojo.html;3334.933301333973;3345.41455245332;3361.9663803361973;3383.695467407933;3421.692047715705;5805.535557155428;5853.5902486825935;5864.266966629171;5864.354920958702;5874.118882167677;5825.737841797119;5880.705964701759;5903.527717782577;5917.497012753921;5921.052631578948;5221.530062294392;5272.736363181835;5299;5304.496072873071;5358.91961620576;5266.683998960075;5361.5978801589845;5363.54409875161;5367.570594352443;5377.11277639189;5254.975279820436;5293.57651387888;5294.92057619136;5303.992241474127;5360.517553420192;5365.061114305001;5400.540954018913;5408.107662235727;5410.269613602152;5437.102878025121;5139.974300128475;5223.451537588537;5234.371875374979;5306.575473962073;5314.5748340132695;5702.287214098238;5707.1153971134545;5749.252597162384;5753.654780713171;5782.190493330937;6366;6366.93633063668;6369.872602547969;6375.776847810351;6384.106225128486;6208.41020103094;6235.87528249437;6288;6312.905306420407;6319.526035547352;5965.403459654043;6021;6024.126501657235;6029.487493563009;6037.45662890342;6341.460975817107;6369.649669268235;6397.61614303134;6398.29618741938;6414.454771344488;6129.664444256008;6178.320384757673;6260.436560709467;6263.060540918898;6315.778947736899;6321;6325.841853953688;6329.651869147149;6338.936610633927;6361.9045714314325;6247.562670613013;6313.747450102036;6321.051842223611;6352.047192921099;6364;6232.906506402408;6268.435840774261;6279.654618995999;6332.8416789579705;6346.143270658495;5013.5989120870245;5054.342935418372;5084.211947148198;5089.745512724349;5098.031374038921;4411.625011874026;4574.5425457453985;4579.5878370946775;4599.471060827981;4626.37543931565;5333.7866485340155;5883.058710606282;5925.496332811759;5931.911021334683;5964.075568286922;6240.719167637467;6318.71565779541;6325.746970121236;6334.619922804646;6355.713992870332;5854.970725146347;5874.5887787854435;5938.673372964528;5974.40255974399;6011.12838638395;5563.972180139073;5565.749541270652;5569.192467092249;5571.913476872052;5574.9442505574425;6399.648019358887;6408.61548307103;6412.775552855627;6421.678916054272;6442.033694945702;6236.719347629277;6256.0303153011355;6261.436470717657;6330.525210609315;6339.90490142639;6416.807495775134;6419.711113000019;6427.485801135805;6441.581297215666;6467.417932386108;5294.629375943647;6294.087357333253;6322.968385158045;6333.588316759396;6369.33122022181;5760.971195143998;6221.564490485623;6232.719527621177;6280.214973128359;6320.5891617044745;6291.1192433058495;6308.337624549356;6313.116163737172;6316.305206427382;6326.114343991753;6270.7491700331475;6278.968605156945;6291.339409361951;6303.212098487689;6306.936930630635;5149.2276158576515;5536.557075434036;5924;6027.909581356195;6101.450869421773;6235.095911092775;6239.1577137085915;6266.436020758154;6271.466925311217;6318.494520438446;6108.633481991094;6125.816225513242;6142.416470435302;6152.815415537541;6158.291796443466 07:37:57 INFO - PROCESS | 1775 | |1;ext.html;483.43196743825996;514.5317760837637;532.4435964417183;538.3889285660774;556.3240662594947;31102.53346199806;32158.67841321589;33594;35732.64267357325;45753.79723419362;44242.89392765174;44581.108377832505;44627;44627;44628;9815.411075335485;9965.701028969106;9969.75075623108;9995.70012899614;9995.850062249072;8694.130586941319;8864.73405797827;9046.914370275575;9192.172704456598;9742.415455072683;8930.419522731034;10389.584416623326;11934.880651193464;12173.878261217407;12323.69190770229;12034.81947770784;12080.15438919276;12082.395880206042;12099.637010889688;12103.697407564794;8759.255463285628;12024.939875300655;12082.818757718642;12101.515939362414;12103.152779305452;41236.96907577304;41347.173056538704;41558.79220603908;43350.78324608388;43640.563594363965;3584.2831433713363;3632.237230181665;3707.277080969212;3881.340172170738;3926.88219353417;31490;31722.682773172204;32127.839360803046;33365.83219587327;34175.6582434175;3243.6269828969735;3254.8209848458328;3264.689854463822;3283.8522266498067;3370.3427831572853;523258;525745;526550;528495;532992.3350383262;54620.449670771275;64524.70950581056;65498.34501655018;65580;69295.65352173308;70090.2990970094;70228.2977170232;70238.9464158038;70291.64854175696;70344.2965570348;17570.91214543919;17739.73390399145;17751.201195946214;17775.644487110185;17807.46577602674;13492.865071349357;13568.253746043865;13571.1857288563;13853.584392468241;13873.236971966639;17332;17673.29306827738;19698.606027879363;19755.60488790245;19854;6729.528932974743;7949.483283586548;7949.7217597383815;7997.160298168725;8016.71941482057;880.2210044111008;886.3529623374973;902.9142231487998;1019.7501612105082;1048.5962904281907;30287.242818929262;31138.68861311403;31319;31864.522032169534;32001.359972800878;1006.6376104602338;1128.6501184632791;1201.9218750781172;1205.8251553524865;1221;16029.599260018593;16543.503694889172;16554.751678724828;16656.708689331004;16848.494545163663;750114;757762;757906;759501;761724;52485.21272180921;52858.73570632199;62324.688376557824;62495.687521562104;63058;1415.6673181802269;1433.8709516143388;1453.556665217119;1454.6872422429228;1457.4680241711699;27607.58588621132;30633.387332252783;32413.18967025843;32550.674493255712;33143.50284745779;53161;53829.46170538245;53845.730771345894;53918.46081539134;54071;640421;926645;945506;949423;950109;44684.32973505465;45052.774736126106;45244;45653;45737.542624573325;47950.760246198544;48676.75661621669;48830.75584622054;49384.75307623439;49527.75236123797;39298.6070139295;40141.79574612766;41852.581474184866;41897.50588658763;42118.57881421147;40733.98165045779;40817.59182408138;40933.79533102315;42326.788366057976;42382.36426453667;39314.01714957035;39466;40300.39549406702;40392;40432.59567404288;31665.525017124302;35020.12449688778;35736.642633573334;35738.10654733548;36031.63968360283;25835.870820645778;25843;25971.61042584325;26411.207663770118;26497 07:37:57 INFO - PROCESS | 1775 | |2;jquery.html;360.68079749421753;372.72604635592853;380.43315459964646;387.99315775562417;408.98364065437386;12267.693307667301;15920;20807.6475029123;21078.57842843142;21078.99999999998;20480.078396472156;20801.687974680353;20950.790492095075;21023.579528409424;21037.368878933656;5660.5471562274915;5773.5381169506545;6026.728797204137;6033.095035744652;6054.212952316193;5835.270591176103;6016.939830601704;6019.337872833985;6031.909521357183;6040.879182416349;5784.681842498682;5960.910586341187;6008.48927841134;6028.517718582525;6041.6375017499095;5997.430244126801;5999.370066143036;6005.789797357072;6019.939800602004;6020.157177995085;5580.162975553659;5738.741756620962;5821.15593238978;5823.79616713413;5824.3302020267365;13224.801627975588;13451.865481345258;13456.259905705183;27580.724192758018;28705.70824312911;2230.2528652901296;2353.5292941411785;2468.3705655057847;2943.1023537820956;2993.760499160062;15684.843151568566;16925.06912119833;17591.38430154951;18137.637247255112;18164.636707265912;1886.9716954245698;1972.5364539333389;1982.9603407931904;2067.7518697756213;2072.7045596502185;126434.10348844774;146146.26926865405;149210.28553715;155445;158763;21468.463288417603;21712.565748684938;21980.01089950924;22117.742848348233;22218.66671999921;35977.82011089928;36727;36752.448713269325;36863;36893.262134757155;11321.037711794424;11325.943370283096;11359.716007099889;11488.483018264198;11705.531778728755;7671.347935425551;9429;9431;9457.479838608942;9638.518074096268;8307.335413166933;9763.414195148314;9898.851517227247;10037.397756134655;10043.246756493292;3234.8059116453087;3366;3688.4651725499652;3734.2531493701367;3794.924101517954;828.2876726015621;904.7240591619619;907.8774365460579;931.9068093190626;978.7357413498315;17108.914455427643;21500;21503.03236354368;21568.56862862734;21573;956.4596003258137;970.9611615535441;980.4558470049191;1009.3338396658318;1041.8489319048695;10454.63408780689;10784.406857622906;11152.776944461228;11334.546618135348;11364.261323014143;621969;632224;639321;643467;646616;96151;118182;128546;136164;138332.61667383398;901.7330650436147;952.9761755956157;969.2778879734502;983.6360546597805;983.9508024598885;16876.324947001984;18423.26306947707;18961.525961851064;18987.43037708871;19036.809631903503;44777.77611111924;46154.307685385415;49237.507624923295;50742.98514029773;52301.69245768886;74381.6280918592;74669;75567;76462.4707505858;77031;7096.929030709627;7884;7907.209279072047;8114.5942702865805;8166.959165204374;10618.309809862334;12680.23918564889;12887;12887.484500620245;12890.871091289342;10813.837792433276;10909.563617455213;11072.39101849406;11208.383538905438;11213;10674.039336459757;10799.352038877692;10886;11064.557417703201;11387.886121138683;10710.946445267724;10908.781824363627;11027.669169924915;11243.212975091665;11329.376884271443;7252.89120663201;7267.854642907218;7351.448641351813;7396.815079623052;7425.146108197627;8857.247133993682;9452.385594936308;10643.627473038407;11315.886841131483;11487 07:37:57 INFO - PROCESS | 1775 | |3;mootools.html;1552.9611759706008;1603.0862408427197;1823.425620929408;1916.2047750183674;1959.951001224969;8647.394682372236;9212.309076819249;9217.861732074025;9222.123898229658;9224.538773061346;8031.39764517662;8264;8704.65181392745;8897.866532002008;8984.95507522463;2366.574016677002;2403.9639405408934;2406.5186962607463;2410.554047501218;2411.626197939322;2372.2764556810166;2394.1500767227644;2406.3863714752715;2410.7468715784853;2410.987945060281;2379.274321331993;2393.246127469841;2404.0984630763464;2405.36257891658;2409.662647229389;2370.0756704885107;2382.2614989353356;2388.1163969331283;2389.940251493718;2401.927942161738;2010.668239740441;2319.9652005219937;2352.1179557665873;2375.09746296408;2389.796867266267;9032.593533290952;9160.8625870612;9168.81662366749;9172.312076594282;9172.724818255463;5323.361196656388;5490.69801160936;5490.80782172626;5497.092979658351;5507.889842203133;3562.625924277965;3650.233450975298;3671.94492082619;3718.3864662330684;3723.311187430334;1084.6258040975852;1087.8259478483405;1090.334895713614;1092.431935393599;1105.0772604874858;12079;12128.090393220455;12174.5738899139;12212.633620991295;12237.632871013884;4423.756693381831;4470.038941627566;4524.524924882906;4684.273937539687;4700.788464519036;8007.559584222924;9420.152186303265;9423.01058388873;9425.76435589102;9434.292428067922;5272.077386457355;5301.3373328333955;5314.867128321745;5325.440828713007;5333.226682131147;3920.2355540669296;4084.7549147051272;4588.747618880993;4803.6549766065555;4916.237983112624;3804.1821008482757;3825.713071519647;3879.8642047528765;3914.412838074312;3926.5288165420334;1168.8889555492385;1176.311857563318;1177.6231605886035;1190.8035174196161;1192.6839387562302;936.0218571592702;953.2231231546267;958.5159494455255;966.7003228999033;1016.1007508355159;5069.594432445396;5413.675179489243;5423.249468479768;6136.478399336017;6255.405736455121;363.88719496955497;443.9289713645801;445.3831443450844;448.6007453366506;452.0529490716943;4092.4270602115726;4125.958740412618;4129.132882094734;4174.582541745801;4255.063885945087;9639.469829159323;10820.296680715728;11166.385848778395;11226.326420414802;11279.548818047351;10829.079528240189;11155.219134660496;11186.049185819296;11211.327320360804;11219.214654974237;8551.486910785248;8594.52730099838;8626.137386261324;8652.264557512683;8668.479891206547;5999.340072592098;6007.639541627516;6080;6123.2652081750475;6182;10056.044675755791;10227.642032528825;10289.485525723834;10363.119134873621;10420.68737937863;10664.626738064131;10688.091512221548;10690;10695.197860160364;10700.732481688021;6054.757809687563;6516.120323756232;6988.650567471708;7325.120985481776;7420;8464.280536154496;8684.131586841266;8686.04453510126;8688.478691278544;8698.304135669005;7756.767296981099;7862.4103192261955;7906.683732650629;7959.761207163794;8059.234372734581;7701.1143718473095;7721.382289416945;7774.339181169664;7815.92184078152;7892.526448413114;7201.495895287279;7342.19235884063;7346.37555807741;7361.153467351323;7361.889571656537;4394.340848872695;5077.644564880423;5127.615428842926;5162.380514338303;5232.319798426332;4762.166620841408;4858.663867436483;4860.538248866352;4891.682040667345;4953.529414705597 07:37:57 INFO - PROCESS | 1775 | |4;prototype.html;294.82752589735003;297.8614944051016;311.16297160637896;311.58247947750027;312.69043646789686;5436.782528698852;5472.34331880174;5512.228288039672;5535.972320138404;5581.525570326522;4989.600831933437;5027.773750181242;5184.481551844814;5339.5461385782255;5364.827244965785;4090.611391917763;4135.979320103395;4142.606452387018;4149.9792501037455;4152.771597562133;4108.712390132692;4116.259073366793;4141.6065473779945;4154.4183814266025;4200.222958752641;3986.4817573715377;4055.513338399396;4117.21772863155;4125.66994640428;4133.462649855514;3743.550773907135;3910.7262491625597;4086.836526538965;4092.161106973093;4139;3595.388783906716;3595.7123430125525;3603.4234522476277;3615.439606860941;3624.5469316335457;4546.635749410289;5183.844484665428;5217.947820521784;5274.868128296824;5298.178782288752;4283.571642835723;4356.564343565649;4368.584984426474;4544;4753.239481682914;5004.399472063339;5651.3893540340805;5688.829335119953;5707.74315155819;5785.363610002896;3404.5433730862105;3940.54683711374;4015.9799201003807;4041.717079804415;4042.413849991764;4934;5915.822525324204;5926.703664816742;5943.4056594340655;6047.69761511927;3577.463380492895;4008.0781420272915;4012.117334186474;4015.638592526687;4027.2750904836826;3486.267883744442;3933.6066393361007;4122.2579935611875;4123.113530590937;4137.668986481074;4043.7978101094827;4149.315362965137;4252.340887162495;4769.308450274691;4792.7363994980615;4791.736454494967;5010.273510341053;5388.733647592812;5668;5674.971625141848;1216.9291023898913;1321.6123070775648;1332.700142467937;1407.451803016694;1416.0512456654067;909.1999040844015;941.4257303045136;966.7915106117354;967.5259123029806;982.543117450389;4687.898343889184;5154.716490593053;5262.710550919736;5326.44072372403;5331.893362132736;5811.680357580373;5856.853578660568;5867.325257595346;5870.55970802183;5909.5863289569315;4610.469795973506;5026.472220416804;5253.973730131325;5311.203319502106;5422;3042.9239269018453;3052.847357632154;3059.29636183679;3061.755059595227;3085.1207405889213;5095.821646242363;5889.1755154278435;5995.46040856325;6044.758209671564;6046.699959508726;5036.6726162799305;5897.705114744245;5915.822525324161;5949.345571987078;5963.910541341883;4766.976165119152;5736.483716465538;5795.159701843209;5968.880622387528;6049.274087109575;5275.446078161738;5438.918416223681;5745.339285982165;5768.884622307614;5802.564807639528;4916;5741;5750.367459579359;5753.453421924911;5753.539716822728;5256.712105534169;5512.39363670004;5661.405552417102;5921.970390148194;6001.439625697263;3813.3517302058162;3852;3863.34323165057;3884.2425726982965;3916.6083391660604;5776.797812076557;5790.363060063474;5805.970970145291;5815.883682326415;5823.330317013598;4626.560476754703;5388.407275199804;5633.436656334334;5652.886942261214;5673.461021202979;4680.578747912568;5359.490848369399;5569.721513924369;5570.468121266522;5593.664380137205;4851.58761505276;5242.554382877498;5402.35171779389;5435.782568697366;5461.508464238238;5145.742712864267;5491.320815054003;5575.721213939368;5608.831735047955;5679.659220446786;5479.4520547944885;5488.807891723771;5491.0116179088145;5519.227308176939;5528.253685752372 07:37:57 INFO - PROCESS | 1775 | |5;yui.html;743.5835931878145;750.313463181189;780.1379475679374;792.2196636313234;876.1413814461825;15429.6142596435;16562.17189140541;16608.584785380343;16645.83354166458;16664.58338541537;15084.849151508482;15157.545273641808;15272.16003119828;15617.921910390432;15860.206989650529;877.2587163846555;924.5284904698585;927.772695689557;929.2287401456808;929.5491686532026;719.7013239505599;739.6560599321315;902.1339514066472;915.057490784493;920.1442658327752;954.0364232125534;965.9758506037335;966.3622009473748;968.1818863060703;969.6266937229183;955.9713208603753;957.1194501059042;958.8322043642335;963.1524258652411;963.629002833907;553.4493179286595;703.2229386527864;719.3957076056138;724.0731863215041;726.0778810910115;15748.527544173694;15800.130992795392;15810.60473488172;15811.762823557656;15843.841561584468;13646.795298070538;13919.930400348036;14012.859871401257;14019.789703154462;14219.431222751182;12537.247765134121;12787.5524356648;12900.485679451516;13182.209067455984;13586.048976571645;3970.940435893464;4555.612772914306;4678.415198100238;4747.145513807513;4779.61763058958;102000.48999755028;104475.4776226114;106583.46708266487;107820.46089769501;113326.86673133245;9330.58012389444;9711.720698254365;9712.757181070388;9862;10108.989101089832;18828.152733126768;18913.338033168773;18937.14782834776;18962;19022.61954760897;6770.052192693027;7409.110906691231;7471.439642026761;7532.962335188289;7591.278828511283;4462.286034234572;4504.436945381827;4579.5878370946775;4610.953890461053;4680.297955306732;3514.9297014059575;3598.2443686825536;3631.5460567429072;3641.308151451216;3641.5266015418356;668.2749217099405;674.5851301449574;675.4765057080801;678.0778141727247;678.4335080208082;571.6248985465398;616.9722362493699;621.0683974038942;656.2551504042946;663.1114306828872;9067.81864362709;9352.485613291334;9472.857907131398;9474.526273686288;9564;640.5324113397246;649.143131066987;657.0407205480053;659.82184810101;670.6881300195432;4203.852865149705;4601.631869450436;4610.792514336863;4640.164770341371;4667.810006710923;98270.01729982608;150945;165824;170635;171797;120780;140988.29505852406;143710.40723981985;149316;151193;121892.39053804852;124603;125685;126069.10896336562;130393.34803325732;8752.256058234867;8877.289816814768;8941.776455588402;8975.865362019706;9107.954460227656;64514.03228951663;67366.97899063036;67660.32339676737;70413;70469.6476517614;62257.688711556155;67169.66415167892;100939.49530252302;102156;104527.4773626127;11501.126153998664;11517.224554013912;11535.96176344133;11600.709982250512;11648.431692435024;11435.142364322875;11726.296422214695;12100.213486123374;12146.817797733218;12271.631851044483;11153.888461115284;13824.516141935386;14106.717865642424;14172.929135354258;14372.568822935329;10557.336315070195;12829.358532073546;13907;14058.578242652737;14640.780388294397;13966.441342346192;14312;14511.492097776527;15066.09603423798;15173;10761.730956726144;11280.548778048786;11682.88317116818;11706.122040847142;11855.288682679067;10545.31455455393;11185.384803835866;11373.431328433711;11417.885821142014;11452.312861228353 07:37:57 INFO - PROCESS | 1775 | __end_tp_report 07:37:57 INFO - PROCESS | 1775 | __start_cc_report 07:37:57 INFO - PROCESS | 1775 | _x_x_mozilla_cycle_collect,1216 07:37:57 INFO - PROCESS | 1775 | __end_cc_report 07:37:57 INFO - PROCESS | 1775 | __startTimestamp1474123077381__endTimestamp 07:37:57 INFO - PROCESS | 1775 | 07:37:57 INFO - PROCESS | 1775 | ------- Summary: start ------- 07:37:57 INFO - PROCESS | 1775 | Number of tests: 6 07:37:57 INFO - PROCESS | 1775 | 07:37:57 INFO - PROCESS | 1775 | [#0] dojo.html Cycles:165 Average:5851.06 Median:6108.63 stddev:650.47 (10.6%) stddev-sans-first:621.79 07:37:57 INFO - PROCESS | 1775 | Values: 3334.9 3345.4 3362.0 3383.7 3421.7 5805.5 5853.6 5864.3 5864.4 5874.1 5825.7 5880.7 5903.5 5917.5 5921.1 5221.5 5272.7 5299.0 5304.5 5358.9 5266.7 5361.6 5363.5 5367.6 5377.1 5255.0 5293.6 5294.9 5304.0 5360.5 5365.1 5400.5 5408.1 5410.3 5437.1 5140.0 5223.5 5234.4 5306.6 5314.6 5702.3 5707.1 5749.3 5753.7 5782.2 6366.0 6366.9 6369.9 6375.8 6384.1 6208.4 6235.9 6288.0 6312.9 6319.5 5965.4 6021.0 6024.1 6029.5 6037.5 6341.5 6369.6 6397.6 6398.3 6414.5 6129.7 6178.3 6260.4 6263.1 6315.8 6321.0 6325.8 6329.7 6338.9 6361.9 6247.6 6313.7 6321.1 6352.0 6364.0 6232.9 6268.4 6279.7 6332.8 6346.1 5013.6 5054.3 5084.2 5089.7 5098.0 4411.6 4574.5 4579.6 4599.5 4626.4 5333.8 5883.1 5925.5 5931.9 5964.1 6240.7 6318.7 6325.7 6334.6 6355.7 5855.0 5874.6 5938.7 5974.4 6011.1 5564.0 5565.7 5569.2 5571.9 5574.9 6399.6 6408.6 6412.8 6421.7 6442.0 6236.7 6256.0 6261.4 6330.5 6339.9 6416.8 6419.7 6427.5 6441.6 6467.4 5294.6 6294.1 6323.0 6333.6 6369.3 5761.0 6221.6 6232.7 6280.2 6320.6 6291.1 6308.3 6313.1 6316.3 6326.1 6270.7 6279.0 6291.3 6303.2 6306.9 5149.2 5536.6 5924.0 6027.9 6101.5 6235.1 6239.2 6266.4 6271.5 6318.5 6108.6 6125.8 6142.4 6152.8 6158.3 07:37:57 INFO - PROCESS | 1775 | 07:37:57 INFO - PROCESS | 1775 | [#1] ext.html Cycles:180 Average:84674.61 Median:40583.29 stddev:199422.85 (491.4%) stddev-sans-first:199882.10 07:37:57 INFO - PROCESS | 1775 | Values: 483.4 514.5 532.4 538.4 556.3 31102.5 32158.7 33594.0 35732.6 45753.8 44242.9 44581.1 44627.0 44627.0 44628.0 9815.4 9965.7 9969.8 9995.7 9995.9 8694.1 8864.7 9046.9 9192.2 9742.4 8930.4 10389.6 11934.9 12173.9 12323.7 12034.8 12080.2 12082.4 12099.6 12103.7 8759.3 12024.9 12082.8 12101.5 12103.2 41237.0 41347.2 41558.8 43350.8 43640.6 3584.3 3632.2 3707.3 3881.3 3926.9 31490.0 31722.7 32127.8 33365.8 34175.7 3243.6 3254.8 3264.7 3283.9 3370.3 523258.0 525745.0 526550.0 528495.0 532992.3 54620.4 64524.7 65498.3 65580.0 69295.7 70090.3 70228.3 70238.9 70291.6 70344.3 17570.9 17739.7 17751.2 17775.6 17807.5 13492.9 13568.3 13571.2 13853.6 13873.2 17332.0 17673.3 19698.6 19755.6 19854.0 6729.5 7949.5 7949.7 7997.2 8016.7 880.2 886.4 902.9 1019.8 1048.6 30287.2 31138.7 31319.0 31864.5 32001.4 1006.6 1128.7 1201.9 1205.8 1221.0 16029.6 16543.5 16554.8 16656.7 16848.5 750114.0 757762.0 757906.0 759501.0 761724.0 52485.2 52858.7 62324.7 62495.7 63058.0 1415.7 1433.9 1453.6 1454.7 1457.5 27607.6 30633.4 32413.2 32550.7 33143.5 53161.0 53829.5 53845.7 53918.5 54071.0 640421.0 926645.0 945506.0 949423.0 950109.0 44684.3 45052.8 45244.0 45653.0 45737.5 47950.8 48676.8 48830.8 49384.8 49527.8 39298.6 40141.8 41852.6 41897.5 42118.6 40734.0 40817.6 40933.8 42326.8 42382.4 39314.0 39466.0 40300.4 40392.0 40432.6 31665.5 35020.1 35736.6 35738.1 36031.6 25835.9 25843.0 25971.6 26411.2 26497.0 07:37:57 INFO - PROCESS | 1775 | 07:37:57 INFO - PROCESS | 1775 | [#2] jquery.html Cycles:180 Average:37727.05 Median:3114.28 stddev:106374.02 (3415.7%) stddev-sans-first:106635.42 07:37:57 INFO - PROCESS | 1775 | Values: 360.7 372.7 380.4 388.0 409.0 12267.7 15920.0 20807.6 21078.6 21079.0 20480.1 20801.7 20950.8 21023.6 21037.4 5660.5 5773.5 6026.7 6033.1 6054.2 5835.3 6016.9 6019.3 6031.9 6040.9 5784.7 5960.9 6008.5 6028.5 6041.6 5997.4 5999.4 6005.8 6019.9 6020.2 5580.2 5738.7 5821.2 5823.8 5824.3 13224.8 13451.9 13456.3 27580.7 28705.7 2230.3 2353.5 2468.4 2943.1 2993.8 15684.8 16925.1 17591.4 18137.6 18164.6 1887.0 1972.5 1983.0 2067.8 2072.7 126434.1 146146.3 149210.3 155445.0 158763.0 21468.5 21712.6 21980.0 22117.7 22218.7 35977.8 36727.0 36752.4 36863.0 36893.3 11321.0 11325.9 11359.7 11488.5 11705.5 7671.3 9429.0 9431.0 9457.5 9638.5 8307.3 9763.4 9898.9 10037.4 10043.2 3234.8 3366.0 3688.5 3734.3 3794.9 828.3 904.7 907.9 931.9 978.7 17108.9 21500.0 21503.0 21568.6 21573.0 956.5 971.0 980.5 1009.3 1041.8 10454.6 10784.4 11152.8 11334.5 11364.3 621969.0 632224.0 639321.0 643467.0 646616.0 96151.0 118182.0 128546.0 136164.0 138332.6 901.7 953.0 969.3 983.6 984.0 16876.3 18423.3 18961.5 18987.4 19036.8 44777.8 46154.3 49237.5 50743.0 52301.7 74381.6 74669.0 75567.0 76462.5 77031.0 7096.9 7884.0 7907.2 8114.6 8167.0 10618.3 12680.2 12887.0 12887.5 12890.9 10813.8 10909.6 11072.4 11208.4 11213.0 10674.0 10799.4 10886.0 11064.6 11387.9 10710.9 10908.8 11027.7 11243.2 11329.4 7252.9 7267.9 7351.4 7396.8 7425.1 8857.2 9452.4 10643.6 11315.9 11487.0 07:37:57 INFO - PROCESS | 1775 | 07:37:57 INFO - PROCESS | 1775 | [#3] mootools.html Cycles:180 Average:5793.77 Median:4556.64 stddev:3323.54 (72.9%) stddev-sans-first:3317.58 07:37:57 INFO - PROCESS | 1775 | Values: 1553.0 1603.1 1823.4 1916.2 1960.0 8647.4 9212.3 9217.9 9222.1 9224.5 8031.4 8264.0 8704.7 8897.9 8985.0 2366.6 2404.0 2406.5 2410.6 2411.6 2372.3 2394.2 2406.4 2410.7 2411.0 2379.3 2393.2 2404.1 2405.4 2409.7 2370.1 2382.3 2388.1 2389.9 2401.9 2010.7 2320.0 2352.1 2375.1 2389.8 9032.6 9160.9 9168.8 9172.3 9172.7 5323.4 5490.7 5490.8 5497.1 5507.9 3562.6 3650.2 3671.9 3718.4 3723.3 1084.6 1087.8 1090.3 1092.4 1105.1 12079.0 12128.1 12174.6 12212.6 12237.6 4423.8 4470.0 4524.5 4684.3 4700.8 8007.6 9420.2 9423.0 9425.8 9434.3 5272.1 5301.3 5314.9 5325.4 5333.2 3920.2 4084.8 4588.7 4803.7 4916.2 3804.2 3825.7 3879.9 3914.4 3926.5 1168.9 1176.3 1177.6 1190.8 1192.7 936.0 953.2 958.5 966.7 1016.1 5069.6 5413.7 5423.2 6136.5 6255.4 363.9 443.9 445.4 448.6 452.1 4092.4 4126.0 4129.1 4174.6 4255.1 9639.5 10820.3 11166.4 11226.3 11279.5 10829.1 11155.2 11186.0 11211.3 11219.2 8551.5 8594.5 8626.1 8652.3 8668.5 5999.3 6007.6 6080.0 6123.3 6182.0 10056.0 10227.6 10289.5 10363.1 10420.7 10664.6 10688.1 10690.0 10695.2 10700.7 6054.8 6516.1 6988.7 7325.1 7420.0 8464.3 8684.1 8686.0 8688.5 8698.3 7756.8 7862.4 7906.7 7959.8 8059.2 7701.1 7721.4 7774.3 7815.9 7892.5 7201.5 7342.2 7346.4 7361.2 7361.9 4394.3 5077.6 5127.6 5162.4 5232.3 4762.2 4858.7 4860.5 4891.7 4953.5 07:37:57 INFO - PROCESS | 1775 | 07:37:57 INFO - PROCESS | 1775 | [#4] prototype.html Cycles:180 Average:4600.37 Median:5201.21 stddev:1387.40 (26.7%) stddev-sans-first:1353.13 07:37:57 INFO - PROCESS | 1775 | Values: 294.8 297.9 311.2 311.6 312.7 5436.8 5472.3 5512.2 5536.0 5581.5 4989.6 5027.8 5184.5 5339.5 5364.8 4090.6 4136.0 4142.6 4150.0 4152.8 4108.7 4116.3 4141.6 4154.4 4200.2 3986.5 4055.5 4117.2 4125.7 4133.5 3743.6 3910.7 4086.8 4092.2 4139.0 3595.4 3595.7 3603.4 3615.4 3624.5 4546.6 5183.8 5217.9 5274.9 5298.2 4283.6 4356.6 4368.6 4544.0 4753.2 5004.4 5651.4 5688.8 5707.7 5785.4 3404.5 3940.5 4016.0 4041.7 4042.4 4934.0 5915.8 5926.7 5943.4 6047.7 3577.5 4008.1 4012.1 4015.6 4027.3 3486.3 3933.6 4122.3 4123.1 4137.7 4043.8 4149.3 4252.3 4769.3 4792.7 4791.7 5010.3 5388.7 5668.0 5675.0 1216.9 1321.6 1332.7 1407.5 1416.1 909.2 941.4 966.8 967.5 982.5 4687.9 5154.7 5262.7 5326.4 5331.9 5811.7 5856.9 5867.3 5870.6 5909.6 4610.5 5026.5 5254.0 5311.2 5422.0 3042.9 3052.8 3059.3 3061.8 3085.1 5095.8 5889.2 5995.5 6044.8 6046.7 5036.7 5897.7 5915.8 5949.3 5963.9 4767.0 5736.5 5795.2 5968.9 6049.3 5275.4 5438.9 5745.3 5768.9 5802.6 4916.0 5741.0 5750.4 5753.5 5753.5 5256.7 5512.4 5661.4 5922.0 6001.4 3813.4 3852.0 3863.3 3884.2 3916.6 5776.8 5790.4 5806.0 5815.9 5823.3 4626.6 5388.4 5633.4 5652.9 5673.5 4680.6 5359.5 5569.7 5570.5 5593.7 4851.6 5242.6 5402.4 5435.8 5461.5 5145.7 5491.3 5575.7 5608.8 5679.7 5479.5 5488.8 5491.0 5519.2 5528.3 07:37:57 INFO - PROCESS | 1775 | 07:37:57 INFO - PROCESS | 1775 | [#5] yui.html Cycles:180 Average:25597.45 Median:3636.43 stddev:41961.91 (1153.9%) stddev-sans-first:42038.13 07:37:57 INFO - PROCESS | 1775 | Values: 743.6 750.3 780.1 792.2 876.1 15429.6 16562.2 16608.6 16645.8 16664.6 15084.8 15157.5 15272.2 15617.9 15860.2 877.3 924.5 927.8 929.2 929.5 719.7 739.7 902.1 915.1 920.1 954.0 966.0 966.4 968.2 969.6 956.0 957.1 958.8 963.2 963.6 553.4 703.2 719.4 724.1 726.1 15748.5 15800.1 15810.6 15811.8 15843.8 13646.8 13919.9 14012.9 14019.8 14219.4 12537.2 12787.6 12900.5 13182.2 13586.0 3970.9 4555.6 4678.4 4747.1 4779.6 102000.5 104475.5 106583.5 107820.5 113326.9 9330.6 9711.7 9712.8 9862.0 10109.0 18828.2 18913.3 18937.1 18962.0 19022.6 6770.1 7409.1 7471.4 7533.0 7591.3 4462.3 4504.4 4579.6 4611.0 4680.3 3514.9 3598.2 3631.5 3641.3 3641.5 668.3 674.6 675.5 678.1 678.4 571.6 617.0 621.1 656.3 663.1 9067.8 9352.5 9472.9 9474.5 9564.0 640.5 649.1 657.0 659.8 670.7 4203.9 4601.6 4610.8 4640.2 4667.8 98270.0 150945.0 165824.0 170635.0 171797.0 120780.0 140988.3 143710.4 149316.0 151193.0 121892.4 124603.0 125685.0 126069.1 130393.3 8752.3 8877.3 8941.8 8975.9 9108.0 64514.0 67367.0 67660.3 70413.0 70469.6 62257.7 67169.7 100939.5 102156.0 104527.5 11501.1 11517.2 11536.0 11600.7 11648.4 11435.1 11726.3 12100.2 12146.8 12271.6 11153.9 13824.5 14106.7 14172.9 14372.6 10557.3 12829.4 13907.0 14058.6 14640.8 13966.4 14312.0 14511.5 15066.1 15173.0 10761.7 11280.5 11682.9 11706.1 11855.3 10545.3 11185.4 11373.4 11417.9 11452.3 07:37:57 INFO - PROCESS | 1775 | -------- Summary: end -------- 07:37:57 INFO - PROCESS | 1775 | 07:37:58 INFO - TEST-INFO | 1775: exit 0 07:37:58 INFO - TEST-OK | dromaeo_css | took 1116281ms 07:37:58 INFO - TEST-START | kraken 07:37:58 INFO - operating with platform_type : mac_ 07:37:58 INFO - Initialising browser for kraken test... 07:37:58 INFO - TEST-INFO | started process 1782 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpFL8CIR/profile http://localhost:49268/getInfo.html) 07:38:00 INFO - PROCESS | 1782 | __metrics Screen width/height:1600/1200 07:38:00 INFO - PROCESS | 1782 | colorDepth:24 07:38:00 INFO - PROCESS | 1782 | Browser inner width/height: 1024/689 07:38:00 INFO - PROCESS | 1782 | __metrics 07:38:00 INFO - PROCESS | 1782 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:38:00 INFO - PROCESS | 1782 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:38:01 INFO - TEST-INFO | 1782: exit 0 07:38:01 INFO - Browser initialized. 07:38:01 INFO - Running cycle 1/1 for kraken test... 07:38:01 INFO - TEST-INFO | started process 1783 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpFL8CIR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:12 INFO - PROCESS | 1783 | RSS: Main: 215597056 07:38:12 INFO - PROCESS | 1783 | 07:38:15 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:16 INFO - PROCESS | 1783 | RSS: Main: 279244800 07:38:16 INFO - PROCESS | 1783 | 07:38:19 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 07:38:19 INFO - PROCESS | 1783 | RSS: Main: 283086848 07:38:19 INFO - PROCESS | 1783 | 07:38:22 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 07:38:23 INFO - PROCESS | 1783 | RSS: Main: 378707968 07:38:23 INFO - PROCESS | 1783 | 07:38:25 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 07:38:26 INFO - PROCESS | 1783 | RSS: Main: 284917760 07:38:26 INFO - PROCESS | 1783 | 07:38:28 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:28 INFO - PROCESS | 1783 | RSS: Main: 285036544 07:38:28 INFO - PROCESS | 1783 | 07:38:31 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:32 INFO - PROCESS | 1783 | RSS: Main: 323252224 07:38:32 INFO - PROCESS | 1783 | 07:38:35 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:35 INFO - PROCESS | 1783 | RSS: Main: 324947968 07:38:35 INFO - PROCESS | 1783 | 07:38:38 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 07:38:39 INFO - PROCESS | 1783 | RSS: Main: 322486272 07:38:39 INFO - PROCESS | 1783 | 07:38:40 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:41 INFO - PROCESS | 1783 | RSS: Main: 296972288 07:38:41 INFO - PROCESS | 1783 | 07:38:43 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:43 INFO - PROCESS | 1783 | RSS: Main: 303722496 07:38:43 INFO - PROCESS | 1783 | 07:38:45 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:46 INFO - PROCESS | 1783 | RSS: Main: 315981824 07:38:46 INFO - PROCESS | 1783 | 07:38:49 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:50 INFO - PROCESS | 1783 | RSS: Main: 317403136 07:38:50 INFO - PROCESS | 1783 | 07:38:52 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:53 INFO - PROCESS | 1783 | RSS: Main: 318988288 07:38:53 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 07:38:55 INFO - PROCESS | 1783 | RSS: Main: 362561536 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | __start_tp_report 07:38:55 INFO - PROCESS | 1783 | _x_x_mozilla_page_load 07:38:55 INFO - PROCESS | 1783 | _x_x_mozilla_page_load_details 07:38:55 INFO - PROCESS | 1783 | |i|pagename|runs| 07:38:55 INFO - PROCESS | 1783 | |0;ai-astar;114;115;127;115;113;113;112;114;115;115 07:38:55 INFO - PROCESS | 1783 | |1;audio-beat-detection;144;107;129;155;129;153;108;135;144;107 07:38:55 INFO - PROCESS | 1783 | |2;audio-dft;184;184;184;183;185;184;180;197;254;201 07:38:55 INFO - PROCESS | 1783 | |3;audio-fft;71;81;78;160;57;57;57;63;137;57 07:38:55 INFO - PROCESS | 1783 | |4;audio-oscillator;83;82;136;69;76;90;123;70;69;120 07:38:55 INFO - PROCESS | 1783 | |5;imaging-gaussian-blur;86;84;85;83;86;83;86;83;86;84 07:38:55 INFO - PROCESS | 1783 | |6;imaging-darkroom;93;92;92;93;92;92;92;92;92;93 07:38:55 INFO - PROCESS | 1783 | |7;imaging-desaturate;85;84;84;85;85;83;86;85;86;83 07:38:55 INFO - PROCESS | 1783 | |8;json-parse-financial;56;55;56;56;55;56;55;56;55;56 07:38:55 INFO - PROCESS | 1783 | |9;json-stringify-tinderbox;67;66;68;77;75;71;69;71;70;72 07:38:55 INFO - PROCESS | 1783 | |10;stanford-crypto-aes;82;83;84;83;86;87;82;85;87;111 07:38:55 INFO - PROCESS | 1783 | |11;stanford-crypto-ccm;135;133;136;140;143;146;135;135;146;137 07:38:55 INFO - PROCESS | 1783 | |12;stanford-crypto-pbkdf2;159;158;163;161;160;162;161;163;158;213 07:38:55 INFO - PROCESS | 1783 | |13;stanford-crypto-sha256-iterative;69;65;66;67;64;66;65;65;65;65 07:38:55 INFO - PROCESS | 1783 | __end_tp_report 07:38:55 INFO - PROCESS | 1783 | __start_cc_report 07:38:55 INFO - PROCESS | 1783 | _x_x_mozilla_cycle_collect,2119 07:38:55 INFO - PROCESS | 1783 | __end_cc_report 07:38:55 INFO - PROCESS | 1783 | __startTimestamp1474123135477__endTimestamp 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | ------- Summary: start ------- 07:38:55 INFO - PROCESS | 1783 | Number of tests: 14 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#0] ai-astar Cycles:10 Average:115.30 Median:115.00 stddev:4.24 (3.7%) stddev-sans-first:4.48 07:38:55 INFO - PROCESS | 1783 | Values: 114.0 115.0 127.0 115.0 113.0 113.0 112.0 114.0 115.0 115.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:139.50 stddev:18.57 (13.3%) stddev-sans-first:19.10 07:38:55 INFO - PROCESS | 1783 | Values: 144.0 107.0 129.0 155.0 129.0 153.0 108.0 135.0 144.0 107.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#2] audio-dft Cycles:10 Average:193.60 Median:184.50 stddev:22.25 (12.1%) stddev-sans-first:23.32 07:38:55 INFO - PROCESS | 1783 | Values: 184.0 184.0 184.0 183.0 185.0 184.0 180.0 197.0 254.0 201.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#3] audio-fft Cycles:10 Average:81.80 Median:60.00 stddev:36.68 (61.1%) stddev-sans-first:38.69 07:38:55 INFO - PROCESS | 1783 | Values: 71.0 81.0 78.0 160.0 57.0 57.0 57.0 63.0 137.0 57.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:73.00 stddev:25.09 (34.4%) stddev-sans-first:26.40 07:38:55 INFO - PROCESS | 1783 | Values: 83.0 82.0 136.0 69.0 76.0 90.0 123.0 70.0 69.0 120.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#5] imaging-gaussian-blur Cycles:10 Average:84.60 Median:85.50 stddev:1.35 (1.6%) stddev-sans-first:1.33 07:38:55 INFO - PROCESS | 1783 | Values: 86.0 84.0 85.0 83.0 86.0 83.0 86.0 83.0 86.0 84.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#6] imaging-darkroom Cycles:10 Average:92.30 Median:92.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 07:38:55 INFO - PROCESS | 1783 | Values: 93.0 92.0 92.0 93.0 92.0 92.0 92.0 92.0 92.0 93.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.07 (1.3%) stddev-sans-first:1.13 07:38:55 INFO - PROCESS | 1783 | Values: 85.0 84.0 84.0 85.0 85.0 83.0 86.0 85.0 86.0 83.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 07:38:55 INFO - PROCESS | 1783 | Values: 56.0 55.0 56.0 56.0 55.0 56.0 55.0 56.0 55.0 56.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:3.44 (4.8%) stddev-sans-first:3.39 07:38:55 INFO - PROCESS | 1783 | Values: 67.0 66.0 68.0 77.0 75.0 71.0 69.0 71.0 70.0 72.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:84.50 stddev:8.64 (10.2%) stddev-sans-first:8.97 07:38:55 INFO - PROCESS | 1783 | Values: 82.0 83.0 84.0 83.0 86.0 87.0 82.0 85.0 87.0 111.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#11] stanford-crypto-ccm Cycles:10 Average:138.60 Median:138.50 stddev:4.84 (3.5%) stddev-sans-first:4.95 07:38:55 INFO - PROCESS | 1783 | Values: 135.0 133.0 136.0 140.0 143.0 146.0 135.0 135.0 146.0 137.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.80 Median:161.50 stddev:16.69 (10.3%) stddev-sans-first:17.52 07:38:55 INFO - PROCESS | 1783 | Values: 159.0 158.0 163.0 161.0 160.0 162.0 161.0 163.0 158.0 213.0 07:38:55 INFO - PROCESS | 1783 | 07:38:55 INFO - PROCESS | 1783 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:65.50 stddev:1.42 (2.2%) stddev-sans-first:0.87 07:38:55 INFO - PROCESS | 1783 | Values: 69.0 65.0 66.0 67.0 64.0 66.0 65.0 65.0 65.0 65.0 07:38:55 INFO - PROCESS | 1783 | -------- Summary: end -------- 07:38:55 INFO - PROCESS | 1783 | 07:38:56 INFO - TEST-INFO | 1783: exit 0 07:38:56 INFO - TEST-OK | kraken | took 58247ms 07:38:56 INFO - SUITE-END | took 1174s 07:38:56 INFO - Completed test suite (00:19:34) 07:38:56 INFO - javascript benchmark 07:38:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.933301333973, 3345.41455245332, 3361.9663803361973, 3383.695467407933, 3421.692047715705, 5805.535557155428, 5853.5902486825935, 5864.266966629171, 5864.354920958702, 5874.118882167677, 5825.737841797119, 5880.705964701759, 5903.527717782577, 5917.497012753921, 5921.052631578948, 5221.530062294392, 5272.736363181835, 5299.0, 5304.496072873071, 5358.91961620576, 5266.683998960075, 5361.5978801589845, 5363.54409875161, 5367.570594352443, 5377.11277639189, 5254.975279820436, 5293.57651387888, 5294.92057619136, 5303.992241474127, 5360.517553420192, 5365.061114305001, 5400.540954018913, 5408.107662235727, 5410.269613602152, 5437.102878025121, 5139.974300128475, 5223.451537588537, 5234.371875374979, 5306.575473962073, 5314.5748340132695, 5702.287214098238, 5707.1153971134545, 5749.252597162384, 5753.654780713171, 5782.190493330937, 6366.0, 6366.93633063668, 6369.872602547969, 6375.776847810351, 6384.106225128486, 6208.41020103094, 6235.87528249437, 6288.0, 6312.905306420407, 6319.526035547352, 5965.403459654043, 6021.0, 6024.126501657235, 6029.487493563009, 6037.45662890342, 6341.460975817107, 6369.649669268235, 6397.61614303134, 6398.29618741938, 6414.454771344488, 6129.664444256008, 6178.320384757673, 6260.436560709467, 6263.060540918898, 6315.778947736899, 6321.0, 6325.841853953688, 6329.651869147149, 6338.936610633927, 6361.9045714314325, 6247.562670613013, 6313.747450102036, 6321.051842223611, 6352.047192921099, 6364.0, 6232.906506402408, 6268.435840774261, 6279.654618995999, 6332.8416789579705, 6346.143270658495, 5013.5989120870245, 5054.342935418372, 5084.211947148198, 5089.745512724349, 5098.031374038921, 4411.625011874026, 4574.5425457453985, 4579.5878370946775, 4599.471060827981, 4626.37543931565, 5333.7866485340155, 5883.058710606282, 5925.496332811759, 5931.911021334683, 5964.075568286922, 6240.719167637467, 6318.71565779541, 6325.746970121236, 6334.619922804646, 6355.713992870332, 5854.970725146347, 5874.5887787854435, 5938.673372964528, 5974.40255974399, 6011.12838638395, 5563.972180139073, 5565.749541270652, 5569.192467092249, 5571.913476872052, 5574.9442505574425, 6399.648019358887, 6408.61548307103, 6412.775552855627, 6421.678916054272, 6442.033694945702, 6236.719347629277, 6256.0303153011355, 6261.436470717657, 6330.525210609315, 6339.90490142639, 6416.807495775134, 6419.711113000019, 6427.485801135805, 6441.581297215666, 6467.417932386108, 5294.629375943647, 6294.087357333253, 6322.968385158045, 6333.588316759396, 6369.33122022181, 5760.971195143998, 6221.564490485623, 6232.719527621177, 6280.214973128359, 6320.5891617044745, 6291.1192433058495, 6308.337624549356, 6313.116163737172, 6316.305206427382, 6326.114343991753, 6270.7491700331475, 6278.968605156945, 6291.339409361951, 6303.212098487689, 6306.936930630635, 5149.2276158576515, 5536.557075434036, 5924.0, 6027.909581356195, 6101.450869421773, 6235.095911092775, 6239.1577137085915, 6266.436020758154, 6271.466925311217, 6318.494520438446, 6108.633481991094, 6125.816225513242, 6142.416470435302, 6152.815415537541, 6158.291796443466], "value": 5808.988227846478, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.43196743825996, 514.5317760837637, 532.4435964417183, 538.3889285660774, 556.3240662594947, 31102.53346199806, 32158.67841321589, 33594.0, 35732.64267357325, 45753.79723419362, 44242.89392765174, 44581.108377832505, 44627.0, 44627.0, 44628.0, 9815.411075335485, 9965.701028969106, 9969.75075623108, 9995.70012899614, 9995.850062249072, 8694.130586941319, 8864.73405797827, 9046.914370275575, 9192.172704456598, 9742.415455072683, 8930.419522731034, 10389.584416623326, 11934.880651193464, 12173.878261217407, 12323.69190770229, 12034.81947770784, 12080.15438919276, 12082.395880206042, 12099.637010889688, 12103.697407564794, 8759.255463285628, 12024.939875300655, 12082.818757718642, 12101.515939362414, 12103.152779305452, 41236.96907577304, 41347.173056538704, 41558.79220603908, 43350.78324608388, 43640.563594363965, 3584.2831433713363, 3632.237230181665, 3707.277080969212, 3881.340172170738, 3926.88219353417, 31490.0, 31722.682773172204, 32127.839360803046, 33365.83219587327, 34175.6582434175, 3243.6269828969735, 3254.8209848458328, 3264.689854463822, 3283.8522266498067, 3370.3427831572853, 523258.0, 525745.0, 526550.0, 528495.0, 532992.3350383262, 54620.449670771275, 64524.70950581056, 65498.34501655018, 65580.0, 69295.65352173308, 70090.2990970094, 70228.2977170232, 70238.9464158038, 70291.64854175696, 70344.2965570348, 17570.91214543919, 17739.73390399145, 17751.201195946214, 17775.644487110185, 17807.46577602674, 13492.865071349357, 13568.253746043865, 13571.1857288563, 13853.584392468241, 13873.236971966639, 17332.0, 17673.29306827738, 19698.606027879363, 19755.60488790245, 19854.0, 6729.528932974743, 7949.483283586548, 7949.7217597383815, 7997.160298168725, 8016.71941482057, 880.2210044111008, 886.3529623374973, 902.9142231487998, 1019.7501612105082, 1048.5962904281907, 30287.242818929262, 31138.68861311403, 31319.0, 31864.522032169534, 32001.359972800878, 1006.6376104602338, 1128.6501184632791, 1201.9218750781172, 1205.8251553524865, 1221.0, 16029.599260018593, 16543.503694889172, 16554.751678724828, 16656.708689331004, 16848.494545163663, 750114.0, 757762.0, 757906.0, 759501.0, 761724.0, 52485.21272180921, 52858.73570632199, 62324.688376557824, 62495.687521562104, 63058.0, 1415.6673181802269, 1433.8709516143388, 1453.556665217119, 1454.6872422429228, 1457.4680241711699, 27607.58588621132, 30633.387332252783, 32413.18967025843, 32550.674493255712, 33143.50284745779, 53161.0, 53829.46170538245, 53845.730771345894, 53918.46081539134, 54071.0, 640421.0, 926645.0, 945506.0, 949423.0, 950109.0, 44684.32973505465, 45052.774736126106, 45244.0, 45653.0, 45737.542624573325, 47950.760246198544, 48676.75661621669, 48830.75584622054, 49384.75307623439, 49527.75236123797, 39298.6070139295, 40141.79574612766, 41852.581474184866, 41897.50588658763, 42118.57881421147, 40733.98165045779, 40817.59182408138, 40933.79533102315, 42326.788366057976, 42382.36426453667, 39314.01714957035, 39466.0, 40300.39549406702, 40392.0, 40432.59567404288, 31665.525017124302, 35020.12449688778, 35736.642633573334, 35738.10654733548, 36031.63968360283, 25835.870820645778, 25843.0, 25971.61042584325, 26411.207663770118, 26497.0], "value": 21662.104429660983, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.68079749421753, 372.72604635592853, 380.43315459964646, 387.99315775562417, 408.98364065437386, 12267.693307667301, 15920.0, 20807.6475029123, 21078.57842843142, 21078.99999999998, 20480.078396472156, 20801.687974680353, 20950.790492095075, 21023.579528409424, 21037.368878933656, 5660.5471562274915, 5773.5381169506545, 6026.728797204137, 6033.095035744652, 6054.212952316193, 5835.270591176103, 6016.939830601704, 6019.337872833985, 6031.909521357183, 6040.879182416349, 5784.681842498682, 5960.910586341187, 6008.48927841134, 6028.517718582525, 6041.6375017499095, 5997.430244126801, 5999.370066143036, 6005.789797357072, 6019.939800602004, 6020.157177995085, 5580.162975553659, 5738.741756620962, 5821.15593238978, 5823.79616713413, 5824.3302020267365, 13224.801627975588, 13451.865481345258, 13456.259905705183, 27580.724192758018, 28705.70824312911, 2230.2528652901296, 2353.5292941411785, 2468.3705655057847, 2943.1023537820956, 2993.760499160062, 15684.843151568566, 16925.06912119833, 17591.38430154951, 18137.637247255112, 18164.636707265912, 1886.9716954245698, 1972.5364539333389, 1982.9603407931904, 2067.7518697756213, 2072.7045596502185, 126434.10348844774, 146146.26926865405, 149210.28553715, 155445.0, 158763.0, 21468.463288417603, 21712.565748684938, 21980.01089950924, 22117.742848348233, 22218.66671999921, 35977.82011089928, 36727.0, 36752.448713269325, 36863.0, 36893.262134757155, 11321.037711794424, 11325.943370283096, 11359.716007099889, 11488.483018264198, 11705.531778728755, 7671.347935425551, 9429.0, 9431.0, 9457.479838608942, 9638.518074096268, 8307.335413166933, 9763.414195148314, 9898.851517227247, 10037.397756134655, 10043.246756493292, 3234.8059116453087, 3366.0, 3688.4651725499652, 3734.2531493701367, 3794.924101517954, 828.2876726015621, 904.7240591619619, 907.8774365460579, 931.9068093190626, 978.7357413498315, 17108.914455427643, 21500.0, 21503.03236354368, 21568.56862862734, 21573.0, 956.4596003258137, 970.9611615535441, 980.4558470049191, 1009.3338396658318, 1041.8489319048695, 10454.63408780689, 10784.406857622906, 11152.776944461228, 11334.546618135348, 11364.261323014143, 621969.0, 632224.0, 639321.0, 643467.0, 646616.0, 96151.0, 118182.0, 128546.0, 136164.0, 138332.61667383398, 901.7330650436147, 952.9761755956157, 969.2778879734502, 983.6360546597805, 983.9508024598885, 16876.324947001984, 18423.26306947707, 18961.525961851064, 18987.43037708871, 19036.809631903503, 44777.77611111924, 46154.307685385415, 49237.507624923295, 50742.98514029773, 52301.69245768886, 74381.6280918592, 74669.0, 75567.0, 76462.4707505858, 77031.0, 7096.929030709627, 7884.0, 7907.209279072047, 8114.5942702865805, 8166.959165204374, 10618.309809862334, 12680.23918564889, 12887.0, 12887.484500620245, 12890.871091289342, 10813.837792433276, 10909.563617455213, 11072.39101849406, 11208.383538905438, 11213.0, 10674.039336459757, 10799.352038877692, 10886.0, 11064.557417703201, 11387.886121138683, 10710.946445267724, 10908.781824363627, 11027.669169924915, 11243.212975091665, 11329.376884271443, 7252.89120663201, 7267.854642907218, 7351.448641351813, 7396.815079623052, 7425.146108197627, 8857.247133993682, 9452.385594936308, 10643.627473038407, 11315.886841131483, 11487.0], "value": 10597.718921563424, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1552.9611759706008, 1603.0862408427197, 1823.425620929408, 1916.2047750183674, 1959.951001224969, 8647.394682372236, 9212.309076819249, 9217.861732074025, 9222.123898229658, 9224.538773061346, 8031.39764517662, 8264.0, 8704.65181392745, 8897.866532002008, 8984.95507522463, 2366.574016677002, 2403.9639405408934, 2406.5186962607463, 2410.554047501218, 2411.626197939322, 2372.2764556810166, 2394.1500767227644, 2406.3863714752715, 2410.7468715784853, 2410.987945060281, 2379.274321331993, 2393.246127469841, 2404.0984630763464, 2405.36257891658, 2409.662647229389, 2370.0756704885107, 2382.2614989353356, 2388.1163969331283, 2389.940251493718, 2401.927942161738, 2010.668239740441, 2319.9652005219937, 2352.1179557665873, 2375.09746296408, 2389.796867266267, 9032.593533290952, 9160.8625870612, 9168.81662366749, 9172.312076594282, 9172.724818255463, 5323.361196656388, 5490.69801160936, 5490.80782172626, 5497.092979658351, 5507.889842203133, 3562.625924277965, 3650.233450975298, 3671.94492082619, 3718.3864662330684, 3723.311187430334, 1084.6258040975852, 1087.8259478483405, 1090.334895713614, 1092.431935393599, 1105.0772604874858, 12079.0, 12128.090393220455, 12174.5738899139, 12212.633620991295, 12237.632871013884, 4423.756693381831, 4470.038941627566, 4524.524924882906, 4684.273937539687, 4700.788464519036, 8007.559584222924, 9420.152186303265, 9423.01058388873, 9425.76435589102, 9434.292428067922, 5272.077386457355, 5301.3373328333955, 5314.867128321745, 5325.440828713007, 5333.226682131147, 3920.2355540669296, 4084.7549147051272, 4588.747618880993, 4803.6549766065555, 4916.237983112624, 3804.1821008482757, 3825.713071519647, 3879.8642047528765, 3914.412838074312, 3926.5288165420334, 1168.8889555492385, 1176.311857563318, 1177.6231605886035, 1190.8035174196161, 1192.6839387562302, 936.0218571592702, 953.2231231546267, 958.5159494455255, 966.7003228999033, 1016.1007508355159, 5069.594432445396, 5413.675179489243, 5423.249468479768, 6136.478399336017, 6255.405736455121, 363.88719496955497, 443.9289713645801, 445.3831443450844, 448.6007453366506, 452.0529490716943, 4092.4270602115726, 4125.958740412618, 4129.132882094734, 4174.582541745801, 4255.063885945087, 9639.469829159323, 10820.296680715728, 11166.385848778395, 11226.326420414802, 11279.548818047351, 10829.079528240189, 11155.219134660496, 11186.049185819296, 11211.327320360804, 11219.214654974237, 8551.486910785248, 8594.52730099838, 8626.137386261324, 8652.264557512683, 8668.479891206547, 5999.340072592098, 6007.639541627516, 6080.0, 6123.2652081750475, 6182.0, 10056.044675755791, 10227.642032528825, 10289.485525723834, 10363.119134873621, 10420.68737937863, 10664.626738064131, 10688.091512221548, 10690.0, 10695.197860160364, 10700.732481688021, 6054.757809687563, 6516.120323756232, 6988.650567471708, 7325.120985481776, 7420.0, 8464.280536154496, 8684.131586841266, 8686.04453510126, 8688.478691278544, 8698.304135669005, 7756.767296981099, 7862.4103192261955, 7906.683732650629, 7959.761207163794, 8059.234372734581, 7701.1143718473095, 7721.382289416945, 7774.339181169664, 7815.92184078152, 7892.526448413114, 7201.495895287279, 7342.19235884063, 7346.37555807741, 7361.153467351323, 7361.889571656537, 4394.340848872695, 5077.644564880423, 5127.615428842926, 5162.380514338303, 5232.319798426332, 4762.166620841408, 4858.663867436483, 4860.538248866352, 4891.682040667345, 4953.529414705597], "value": 4554.756866393835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.82752589735003, 297.8614944051016, 311.16297160637896, 311.58247947750027, 312.69043646789686, 5436.782528698852, 5472.34331880174, 5512.228288039672, 5535.972320138404, 5581.525570326522, 4989.600831933437, 5027.773750181242, 5184.481551844814, 5339.5461385782255, 5364.827244965785, 4090.611391917763, 4135.979320103395, 4142.606452387018, 4149.9792501037455, 4152.771597562133, 4108.712390132692, 4116.259073366793, 4141.6065473779945, 4154.4183814266025, 4200.222958752641, 3986.4817573715377, 4055.513338399396, 4117.21772863155, 4125.66994640428, 4133.462649855514, 3743.550773907135, 3910.7262491625597, 4086.836526538965, 4092.161106973093, 4139.0, 3595.388783906716, 3595.7123430125525, 3603.4234522476277, 3615.439606860941, 3624.5469316335457, 4546.635749410289, 5183.844484665428, 5217.947820521784, 5274.868128296824, 5298.178782288752, 4283.571642835723, 4356.564343565649, 4368.584984426474, 4544.0, 4753.239481682914, 5004.399472063339, 5651.3893540340805, 5688.829335119953, 5707.74315155819, 5785.363610002896, 3404.5433730862105, 3940.54683711374, 4015.9799201003807, 4041.717079804415, 4042.413849991764, 4934.0, 5915.822525324204, 5926.703664816742, 5943.4056594340655, 6047.69761511927, 3577.463380492895, 4008.0781420272915, 4012.117334186474, 4015.638592526687, 4027.2750904836826, 3486.267883744442, 3933.6066393361007, 4122.2579935611875, 4123.113530590937, 4137.668986481074, 4043.7978101094827, 4149.315362965137, 4252.340887162495, 4769.308450274691, 4792.7363994980615, 4791.736454494967, 5010.273510341053, 5388.733647592812, 5668.0, 5674.971625141848, 1216.9291023898913, 1321.6123070775648, 1332.700142467937, 1407.451803016694, 1416.0512456654067, 909.1999040844015, 941.4257303045136, 966.7915106117354, 967.5259123029806, 982.543117450389, 4687.898343889184, 5154.716490593053, 5262.710550919736, 5326.44072372403, 5331.893362132736, 5811.680357580373, 5856.853578660568, 5867.325257595346, 5870.55970802183, 5909.5863289569315, 4610.469795973506, 5026.472220416804, 5253.973730131325, 5311.203319502106, 5422.0, 3042.9239269018453, 3052.847357632154, 3059.29636183679, 3061.755059595227, 3085.1207405889213, 5095.821646242363, 5889.1755154278435, 5995.46040856325, 6044.758209671564, 6046.699959508726, 5036.6726162799305, 5897.705114744245, 5915.822525324161, 5949.345571987078, 5963.910541341883, 4766.976165119152, 5736.483716465538, 5795.159701843209, 5968.880622387528, 6049.274087109575, 5275.446078161738, 5438.918416223681, 5745.339285982165, 5768.884622307614, 5802.564807639528, 4916.0, 5741.0, 5750.367459579359, 5753.453421924911, 5753.539716822728, 5256.712105534169, 5512.39363670004, 5661.405552417102, 5921.970390148194, 6001.439625697263, 3813.3517302058162, 3852.0, 3863.34323165057, 3884.2425726982965, 3916.6083391660604, 5776.797812076557, 5790.363060063474, 5805.970970145291, 5815.883682326415, 5823.330317013598, 4626.560476754703, 5388.407275199804, 5633.436656334334, 5652.886942261214, 5673.461021202979, 4680.578747912568, 5359.490848369399, 5569.721513924369, 5570.468121266522, 5593.664380137205, 4851.58761505276, 5242.554382877498, 5402.35171779389, 5435.782568697366, 5461.508464238238, 5145.742712864267, 5491.320815054003, 5575.721213939368, 5608.831735047955, 5679.659220446786, 5479.4520547944885, 5488.807891723771, 5491.0116179088145, 5519.227308176939, 5528.253685752372], "value": 4158.583138891843, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.5835931878145, 750.313463181189, 780.1379475679374, 792.2196636313234, 876.1413814461825, 15429.6142596435, 16562.17189140541, 16608.584785380343, 16645.83354166458, 16664.58338541537, 15084.849151508482, 15157.545273641808, 15272.16003119828, 15617.921910390432, 15860.206989650529, 877.2587163846555, 924.5284904698585, 927.772695689557, 929.2287401456808, 929.5491686532026, 719.7013239505599, 739.6560599321315, 902.1339514066472, 915.057490784493, 920.1442658327752, 954.0364232125534, 965.9758506037335, 966.3622009473748, 968.1818863060703, 969.6266937229183, 955.9713208603753, 957.1194501059042, 958.8322043642335, 963.1524258652411, 963.629002833907, 553.4493179286595, 703.2229386527864, 719.3957076056138, 724.0731863215041, 726.0778810910115, 15748.527544173694, 15800.130992795392, 15810.60473488172, 15811.762823557656, 15843.841561584468, 13646.795298070538, 13919.930400348036, 14012.859871401257, 14019.789703154462, 14219.431222751182, 12537.247765134121, 12787.5524356648, 12900.485679451516, 13182.209067455984, 13586.048976571645, 3970.940435893464, 4555.612772914306, 4678.415198100238, 4747.145513807513, 4779.61763058958, 102000.48999755028, 104475.4776226114, 106583.46708266487, 107820.46089769501, 113326.86673133245, 9330.58012389444, 9711.720698254365, 9712.757181070388, 9862.0, 10108.989101089832, 18828.152733126768, 18913.338033168773, 18937.14782834776, 18962.0, 19022.61954760897, 6770.052192693027, 7409.110906691231, 7471.439642026761, 7532.962335188289, 7591.278828511283, 4462.286034234572, 4504.436945381827, 4579.5878370946775, 4610.953890461053, 4680.297955306732, 3514.9297014059575, 3598.2443686825536, 3631.5460567429072, 3641.308151451216, 3641.5266015418356, 668.2749217099405, 674.5851301449574, 675.4765057080801, 678.0778141727247, 678.4335080208082, 571.6248985465398, 616.9722362493699, 621.0683974038942, 656.2551504042946, 663.1114306828872, 9067.81864362709, 9352.485613291334, 9472.857907131398, 9474.526273686288, 9564.0, 640.5324113397246, 649.143131066987, 657.0407205480053, 659.82184810101, 670.6881300195432, 4203.852865149705, 4601.631869450436, 4610.792514336863, 4640.164770341371, 4667.810006710923, 98270.01729982608, 150945.0, 165824.0, 170635.0, 171797.0, 120780.0, 140988.29505852406, 143710.40723981985, 149316.0, 151193.0, 121892.39053804852, 124603.0, 125685.0, 126069.10896336562, 130393.34803325732, 8752.256058234867, 8877.289816814768, 8941.776455588402, 8975.865362019706, 9107.954460227656, 64514.03228951663, 67366.97899063036, 67660.32339676737, 70413.0, 70469.6476517614, 62257.688711556155, 67169.66415167892, 100939.49530252302, 102156.0, 104527.4773626127, 11501.126153998664, 11517.224554013912, 11535.96176344133, 11600.709982250512, 11648.431692435024, 11435.142364322875, 11726.296422214695, 12100.213486123374, 12146.817797733218, 12271.631851044483, 11153.888461115284, 13824.516141935386, 14106.717865642424, 14172.929135354258, 14372.568822935329, 10557.336315070195, 12829.358532073546, 13907.0, 14058.578242652737, 14640.780388294397, 13966.441342346192, 14312.0, 14511.492097776527, 15066.09603423798, 15173.0, 10761.730956726144, 11280.548778048786, 11682.88317116818, 11706.122040847142, 11855.288682679067, 10545.31455455393, 11185.384803835866, 11373.431328433711, 11417.885821142014, 11452.312861228353], "value": 7931.482198806937, "unit": "score"}], "name": "dromaeo_css", "value": 7649.5876611359545, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 127.0, 115.0, 113.0, 113.0, 112.0, 114.0, 115.0, 115.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 129.0, 155.0, 129.0, 153.0, 108.0, 135.0, 144.0, 107.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 183.0, 185.0, 184.0, 180.0, 197.0, 254.0, 201.0], "value": 193.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 81.0, 78.0, 160.0, 57.0, 57.0, 57.0, 63.0, 137.0, 57.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 136.0, 69.0, 76.0, 90.0, 123.0, 70.0, 69.0, 120.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 83.0, 86.0, 83.0, 86.0, 83.0, 86.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 92.0, 93.0, 92.0, 92.0, 92.0, 92.0, 92.0, 93.0], "value": 92.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 85.0, 85.0, 83.0, 86.0, 85.0, 86.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 56.0, 55.0, 56.0, 55.0, 56.0, 55.0, 56.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 77.0, 75.0, 71.0, 69.0, 71.0, 70.0, 72.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 84.0, 83.0, 86.0, 87.0, 82.0, 85.0, 87.0, 111.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 133.0, 136.0, 140.0, 143.0, 146.0, 135.0, 135.0, 146.0, 137.0], "value": 138.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 163.0, 161.0, 160.0, 162.0, 161.0, 163.0, 158.0, 213.0], "value": 165.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 66.0, 67.0, 64.0, 66.0, 65.0, 65.0, 65.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1458.3999999999999, "alertThreshold": 2.0}]} 07:38:56 INFO - Return code: 0 07:38:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:38:56 INFO - # TBPL SUCCESS # 07:38:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:56 INFO - 2016-09-17 07:38:56.804 screenresolution[1787:17438] starting screenresolution argv=screenresolution get 07:38:56 INFO - 2016-09-17 07:38:56.807 screenresolution[1787:17438] Display 0: 1600x1200x32@60 07:38:56 INFO - 2016-09-17 07:38:56.812 screenresolution[1788:17441] starting screenresolution argv=screenresolution list 07:38:56 INFO - Available Modes on Display 0 07:38:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:57 INFO - Intel Iris: 07:38:57 INFO - Chipset Model: Intel Iris 07:38:57 INFO - Type: GPU 07:38:57 INFO - Bus: Built-In 07:38:57 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:57 INFO - Vendor: Intel (0x8086) 07:38:57 INFO - Device ID: 0x0a2e 07:38:57 INFO - Revision ID: 0x0009 07:38:57 INFO - Displays: 07:38:57 INFO - Display: 07:38:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:57 INFO - Display Serial Number: 1600x1200 60 07:38:57 INFO - Main Display: Yes 07:38:57 INFO - Mirror: Off 07:38:57 INFO - Online: Yes 07:38:57 INFO - Rotation: Supported 07:38:57 INFO - Return code: 0 07:38:57 INFO - Running post-action listener: _resource_record_post_action 07:38:57 INFO - [mozharness: 2016-09-17 14:38:57.047898Z] Finished run-tests step (success) 07:38:57 INFO - Running post-run listener: _resource_record_post_run 07:38:57 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 32992256; Write bytes: 665300480; Read time: 308; Write time: 2135 07:38:57 INFO - TinderboxPrint: CPU usage
53.4% 07:38:57 INFO - TinderboxPrint: I/O read bytes / time
32,992,256 / 308 07:38:57 INFO - TinderboxPrint: I/O write bytes / time
665,300,480 / 2,135 07:38:57 INFO - TinderboxPrint: CPU idle
2,252.7 (46.6%) 07:38:57 INFO - TinderboxPrint: CPU system
964.7 (20.0%) 07:38:57 INFO - TinderboxPrint: CPU user
1,616.3 (33.4%) 07:38:57 INFO - TinderboxPrint: Swap in / out
393,003,008 / 0 07:38:57 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 357720576; Write bytes: 356955648; Read time: 27224; Write time: 771 07:38:57 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 31226880; Write bytes: 297531392; Read time: 292; Write time: 1266 07:38:57 INFO - Running post-run listener: _upload_blobber_files 07:38:57 INFO - Blob upload gear active. 07:38:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:38:57 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:38:57 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:38:57 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:38:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:38:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:59 INFO - (blobuploader) - INFO - Done attempting. 07:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:39:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:00 INFO - (blobuploader) - INFO - Done attempting. 07:39:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:39:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:39:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:02 INFO - (blobuploader) - INFO - Done attempting. 07:39:02 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:02 INFO - Return code: 0 07:39:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c2132c96a67089b76c63ccf54d44458106d391aec1ffeb5074d131f8feac594e37dc3717b0c97710107e0e97e417e30c00bc7f694013750ffd06472ab65568b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0be70bf31ae0d2fbe9fbc48302c63a005eeaab3aa0aa1c1efba76f5b237f785d896db9e905aab963fdb44b1b05e7e2a17b6e1a73c124560a54825576a98bc8f"} 07:39:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:02 INFO - Contents: 07:39:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c2132c96a67089b76c63ccf54d44458106d391aec1ffeb5074d131f8feac594e37dc3717b0c97710107e0e97e417e30c00bc7f694013750ffd06472ab65568b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0be70bf31ae0d2fbe9fbc48302c63a005eeaab3aa0aa1c1efba76f5b237f785d896db9e905aab963fdb44b1b05e7e2a17b6e1a73c124560a54825576a98bc8f"} 07:39:02 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:02 INFO - Copying logs to upload dir... 07:39:02 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.281632 ========= master_lag: 0.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:39:03.210893) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:03.216950) ========= 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.mkw7tSj3SO/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.tjRyis2B9U/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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/Mozilla-Aurora/sha512/1c2132c96a67089b76c63ccf54d44458106d391aec1ffeb5074d131f8feac594e37dc3717b0c97710107e0e97e417e30c00bc7f694013750ffd06472ab65568b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0be70bf31ae0d2fbe9fbc48302c63a005eeaab3aa0aa1c1efba76f5b237f785d896db9e905aab963fdb44b1b05e7e2a17b6e1a73c124560a54825576a98bc8f"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011196 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c2132c96a67089b76c63ccf54d44458106d391aec1ffeb5074d131f8feac594e37dc3717b0c97710107e0e97e417e30c00bc7f694013750ffd06472ab65568b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88c63e866d31cee6dc527905f4009fb6e1f64df795150a724321960d6401c924a22e3def1607c960b433c73ffa02802e02b308f079ec61c53e5a058b405d552f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0be70bf31ae0d2fbe9fbc48302c63a005eeaab3aa0aa1c1efba76f5b237f785d896db9e905aab963fdb44b1b05e7e2a17b6e1a73c124560a54825576a98bc8f"}' ========= master_lag: 0.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:03.334188) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:03.334630) ========= 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.mkw7tSj3SO/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.tjRyis2B9U/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.005297 ========= master_lag: 0.72 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:04.060996) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:04.061272) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:04.072351) ========= ========= Total master_lag: 1.20 =========