builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0030 starttime: 1474121886.84 results: success (0) buildid: 20160917071803 builduid: 7f4ae5dd177a4de6a2202e13b3536f9f revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.839996) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.840682) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.841034) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.868525) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.868838) ========= 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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.61M=0.001s 2016-09-17 07:18:07 (9.61 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.455594 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:07.349473) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:07.350269) ========= 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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.027131 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:07.400215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:07.400610) ========= 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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:07,472 truncating revision to first 12 chars 2016-09-17 07:18:07,472 Setting DEBUG logging. 2016-09-17 07:18:07,472 attempt 1/10 2016-09-17 07:18:07,473 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:08,318 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=1.093660 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:08.517103) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:08.517455) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:08.517932) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:08.518248) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:08.537139) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:18:08.537486) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:08 INFO - MultiFileLogger online at 20160917 07:18:08 in /builds/slave/test 07:18:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:08 INFO - {'append_to_log': False, 07:18:08 INFO - 'base_work_dir': '/builds/slave/test', 07:18:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:08 INFO - 'branch': 'Mozilla-Aurora', 07:18:08 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:08 INFO - 'config_files': ('talos/mac_config.py',), 07:18:08 INFO - 'default_actions': ('clobber', 07:18:08 INFO - 'read-buildbot-config', 07:18:08 INFO - 'download-and-extract', 07:18:08 INFO - 'populate-webroot', 07:18:08 INFO - 'create-virtualenv', 07:18:08 INFO - 'install', 07:18:08 INFO - 'run-tests'), 07:18:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:08 INFO - 'download_minidump_stackwalk': True, 07:18:08 INFO - 'download_symbols': 'ondemand', 07:18:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:08 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:08 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:08 INFO - 'installer_path': 'installer.exe', 07:18:08 INFO - 'log_level': 'info', 07:18:08 INFO - 'log_name': 'talos', 07:18:08 INFO - 'log_to_console': True, 07:18:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:08 INFO - 'opt_config_files': (), 07:18:08 INFO - 'pip_index': False, 07:18:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:08 INFO - 'cmd': ('bash', 07:18:08 INFO - '-c', 07:18:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:08 INFO - 'enabled': True, 07:18:08 INFO - 'halt_on_failure': False, 07:18:08 INFO - 'name': 'check_screen_resolution'},), 07:18:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:08 INFO - 'cmd': ('bash', 07:18:08 INFO - '-c', 07:18:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:08 INFO - 'enabled': True, 07:18:08 INFO - 'halt_on_failure': False, 07:18:08 INFO - 'name': 'check_screen_resolution'},), 07:18:08 INFO - 'run_cmd_checks_enabled': True, 07:18:08 INFO - 'sps_profile': False, 07:18:08 INFO - 'sps_profile_interval': 0, 07:18:08 INFO - 'suite': 'dromaeojs-e10s', 07:18:08 INFO - 'system_bits': '32', 07:18:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:08 INFO - 'title': 't-yosemite-r7-0030', 07:18:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:08 INFO - 'use_talos_json': True, 07:18:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:08 INFO - 'work_dir': 'build'} 07:18:08 INFO - [mozharness: 2016-09-17 14:18:08.730258Z] Running clobber step. 07:18:08 INFO - Running pre-action listener: _resource_record_pre_action 07:18:08 INFO - Running main action method: clobber 07:18:08 INFO - rmtree: /builds/slave/test/build 07:18:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:10 INFO - Running post-action listener: _resource_record_post_action 07:18:10 INFO - [mozharness: 2016-09-17 14:18:10.817169Z] Finished clobber step (success) 07:18:10 INFO - [mozharness: 2016-09-17 14:18:10.817317Z] Running read-buildbot-config step. 07:18:10 INFO - Running pre-action listener: _resource_record_pre_action 07:18:10 INFO - Running main action method: read_buildbot_config 07:18:10 INFO - Using buildbot properties: 07:18:10 INFO - { 07:18:10 INFO - "project": "", 07:18:10 INFO - "product": "firefox", 07:18:10 INFO - "script_repo_revision": "production", 07:18:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:18:10 INFO - "repository": "", 07:18:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:18:10 INFO - "buildid": "20160917071803", 07:18:10 INFO - "stage_platform": "macosx64", 07:18:10 INFO - "basedir": "/builds/slave/test", 07:18:10 INFO - "buildnumber": 121, 07:18:10 INFO - "platform": "yosemite_r7", 07:18:10 INFO - "mozci_request": { 07:18:10 INFO - "priority": -1, 07:18:10 INFO - "type": "trigger_all_talos_jobs", 07:18:10 INFO - "times": 6 07:18:10 INFO - }, 07:18:10 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:18:10 INFO - "slavebuilddir": "test", 07:18:10 INFO - "branch": "mozilla-aurora", 07:18:10 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:18:10 INFO - "slavename": "t-yosemite-r7-0030", 07:18:10 INFO - "builduid": "7f4ae5dd177a4de6a2202e13b3536f9f", 07:18:10 INFO - "repo_path": "releases/mozilla-aurora" 07:18:10 INFO - } 07:18:10 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:10 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:10 INFO - Running post-action listener: _resource_record_post_action 07:18:10 INFO - [mozharness: 2016-09-17 14:18:10.821040Z] Finished read-buildbot-config step (success) 07:18:10 INFO - [mozharness: 2016-09-17 14:18:10.821167Z] Running download-and-extract step. 07:18:10 INFO - Running pre-action listener: _resource_record_pre_action 07:18:10 INFO - Running main action method: download_and_extract 07:18:10 INFO - mkdir: /builds/slave/test/build/tests 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.test_packages.json 07:18:10 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:10 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:10 INFO - Downloaded 1199 bytes. 07:18:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:10 INFO - Using the following test package requirements: 07:18:10 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:10 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:10 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 07:18:10 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:10 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 07:18:10 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:10 INFO - u'jsshell-mac.zip'], 07:18:10 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:10 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 07:18:10 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:10 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:10 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 07:18:10 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:10 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 07:18:10 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:10 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 07:18:10 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:10 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 07:18:10 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 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 - 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: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.common.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.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.mac.common.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.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:14 INFO - Downloaded 16309409 bytes. 07:18:14 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:14 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:18 INFO - Return code: 0 07:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:18 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:18 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:18 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:18 INFO - Downloaded 14413740 bytes. 07:18:18 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:18 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:18 INFO - Return code: 0 07:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:18 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:18 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:18 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:21 INFO - Downloaded 115349660 bytes. 07:18:21 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:21 INFO - mkdir: /builds/slave/test/properties 07:18:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:21 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:21 INFO - Contents: 07:18:21 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:21 INFO - Running post-action listener: _resource_record_post_action 07:18:21 INFO - Running post-action listener: set_extra_try_arguments 07:18:21 INFO - [mozharness: 2016-09-17 14:18:21.446646Z] Finished download-and-extract step (success) 07:18:21 INFO - [mozharness: 2016-09-17 14:18:21.446783Z] Running populate-webroot step. 07:18:21 INFO - Running pre-action listener: _resource_record_pre_action 07:18:21 INFO - Running main action method: populate_webroot 07:18:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:21 INFO - '--tppagecycles', 07:18:21 INFO - '7'], 07:18:21 INFO - 'tests': ['tsvgm']}}, 07:18:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:21 INFO - 'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:21 INFO - 'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['damp', 'tps']}, 07:18:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:21 INFO - 'tests': ['damp', 'tps']}, 07:18:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['dromaeo_dom']}, 07:18:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['basic_compositor_video']}, 07:18:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:18:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['a11yr', 07:18:21 INFO - 'ts_paint', 07:18:21 INFO - 'tpaint', 07:18:21 INFO - 'sessionrestore', 07:18:21 INFO - 'sessionrestore_no_auto_restore', 07:18:21 INFO - 'tabpaint']}, 07:18:21 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:21 INFO - 'ts_paint', 07:18:21 INFO - 'tpaint', 07:18:21 INFO - 'sessionrestore', 07:18:21 INFO - 'sessionrestore_no_auto_restore', 07:18:21 INFO - 'tabpaint']}, 07:18:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:21 INFO - 'ts_paint', 07:18:21 INFO - 'tpaint', 07:18:21 INFO - 'sessionrestore', 07:18:21 INFO - 'sessionrestore_no_auto_restore', 07:18:21 INFO - 'tabpaint']}, 07:18:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:21 INFO - 'ts_paint', 07:18:21 INFO - 'tpaint', 07:18:21 INFO - 'sessionrestore', 07:18:21 INFO - 'sessionrestore_no_auto_restore', 07:18:21 INFO - 'tabpaint']}, 07:18:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['a11yr', 07:18:21 INFO - 'ts_paint', 07:18:21 INFO - 'tpaint', 07:18:21 INFO - 'sessionrestore', 07:18:21 INFO - 'sessionrestore_no_auto_restore', 07:18:21 INFO - 'tabpaint']}, 07:18:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['a11yr', 07:18:21 INFO - 'ts_paint', 07:18:21 INFO - 'tpaint', 07:18:21 INFO - 'sessionrestore', 07:18:21 INFO - 'sessionrestore_no_auto_restore', 07:18:21 INFO - 'tabpaint']}, 07:18:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['tsvgx', 07:18:21 INFO - 'tsvgr_opacity', 07:18:21 INFO - 'tart', 07:18:21 INFO - 'tscrollx', 07:18:21 INFO - 'cart']}, 07:18:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:21 INFO - 'tsvgr_opacity', 07:18:21 INFO - 'tart', 07:18:21 INFO - 'tscrollx', 07:18:21 INFO - 'cart']}, 07:18:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:21 INFO - 'talos_options': ['--disable-e10s'], 07:18:21 INFO - 'tests': ['tp5o']}, 07:18:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:21 INFO - 'tests': ['tp5o']}, 07:18:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:21 INFO - 'talos_options': ['--disable-e10s', 07:18:21 INFO - '--xperf_path', 07:18:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:21 INFO - 'tests': ['tp5n']}, 07:18:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:21 INFO - 'talos_options': ['--xperf_path', 07:18:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:21 INFO - 'tests': ['tp5n']}}, 07:18:21 INFO - 'talos.zip': {'path': '', 07:18:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:21 INFO - Running post-action listener: _resource_record_post_action 07:18:21 INFO - [mozharness: 2016-09-17 14:18:21.468024Z] Finished populate-webroot step (success) 07:18:21 INFO - [mozharness: 2016-09-17 14:18:21.468158Z] Running create-virtualenv step. 07:18:21 INFO - Running pre-action listener: _resource_record_pre_action 07:18:21 INFO - Running main action method: create_virtualenv 07:18:21 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:21 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:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:21 INFO - Using real prefix '/tools/python27' 07:18:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:22 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:25 INFO - Installing pip.................done. 07:18:25 INFO - Return code: 0 07:18:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/Render', 07:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:25 INFO - 'HOME': '/Users/cltbld', 07:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:25 INFO - 'LOGNAME': 'cltbld', 07:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:25 INFO - 'MOZ_NO_REMOTE': '1', 07:18:25 INFO - 'NO_EM_RESTART': '1', 07:18:25 INFO - 'PAGER': '/bin/cat', 07:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:25 INFO - 'PWD': '/builds/slave/test', 07:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:25 INFO - 'SHELL': '/bin/bash', 07:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZaDxzcvcQT/Listeners', 07:18:25 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:25 INFO - 'USER': 'cltbld', 07:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:25 INFO - 'XPC_FLAGS': '0x0', 07:18:25 INFO - 'XPC_SERVICE_NAME': '0', 07:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:25 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:25 INFO - Cleaning up... 07:18:25 INFO - Return code: 0 07:18:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/Render', 07:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:25 INFO - 'HOME': '/Users/cltbld', 07:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:25 INFO - 'LOGNAME': 'cltbld', 07:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:25 INFO - 'MOZ_NO_REMOTE': '1', 07:18:25 INFO - 'NO_EM_RESTART': '1', 07:18:25 INFO - 'PAGER': '/bin/cat', 07:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:25 INFO - 'PWD': '/builds/slave/test', 07:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:25 INFO - 'SHELL': '/bin/bash', 07:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZaDxzcvcQT/Listeners', 07:18:25 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:25 INFO - 'USER': 'cltbld', 07:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:25 INFO - 'XPC_FLAGS': '0x0', 07:18:25 INFO - 'XPC_SERVICE_NAME': '0', 07:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:25 INFO - Downloading/unpacking psutil>=3.1.1 07:18:25 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:25 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 - 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:25 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:25 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:25 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 - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:29 INFO - Installing collected packages: psutil 07:18:29 INFO - Running setup.py install for psutil 07:18:29 INFO - building 'psutil._psutil_osx' extension 07:18:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:18:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:18:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:18:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:18:30 INFO - building 'psutil._psutil_posix' extension 07:18:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:18:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:30 INFO - ^ 07:18:30 INFO - 1 warning generated. 07:18:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:18:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:30 INFO - Successfully installed psutil 07:18:30 INFO - Cleaning up... 07:18:30 INFO - Return code: 0 07:18:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/Render', 07:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:30 INFO - 'HOME': '/Users/cltbld', 07:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:30 INFO - 'LOGNAME': 'cltbld', 07:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:30 INFO - 'MOZ_NO_REMOTE': '1', 07:18:30 INFO - 'NO_EM_RESTART': '1', 07:18:30 INFO - 'PAGER': '/bin/cat', 07:18:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:30 INFO - 'PWD': '/builds/slave/test', 07:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:30 INFO - 'SHELL': '/bin/bash', 07:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZaDxzcvcQT/Listeners', 07:18:30 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:30 INFO - 'USER': 'cltbld', 07:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:30 INFO - 'XPC_FLAGS': '0x0', 07:18:30 INFO - 'XPC_SERVICE_NAME': '0', 07:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:18:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:33 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:33 INFO - Installing collected packages: mozsystemmonitor 07:18:33 INFO - Running setup.py install for mozsystemmonitor 07:18:33 INFO - Successfully installed mozsystemmonitor 07:18:33 INFO - Cleaning up... 07:18:33 INFO - Return code: 0 07:18:33 INFO - Installing jsonschema==2.5.1 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', '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 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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', 'jsonschema==2.5.1'] 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 jsonschema==2.5.1 07:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 07:18:33 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:34 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:34 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:34 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:34 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:34 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:34 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:34 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 jsonschema-2.5.1-py2.py3-none-any.whl 07:18:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 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 functools32-3.2.3-2.tar.gz 07:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:37 INFO - warning: no files found matching '*.txt' 07:18:37 INFO - no previously-included directories found matching 'build' 07:18:37 INFO - no previously-included directories found matching 'dist' 07:18:37 INFO - no previously-included directories found matching '.git*' 07:18:37 INFO - Installing collected packages: jsonschema, functools32 07:18:37 INFO - Running setup.py install for functools32 07:18:37 INFO - warning: no files found matching '*.txt' 07:18:37 INFO - no previously-included directories found matching 'build' 07:18:37 INFO - no previously-included directories found matching 'dist' 07:18:37 INFO - no previously-included directories found matching '.git*' 07:18:37 INFO - Successfully installed jsonschema functools32 07:18:37 INFO - Cleaning up... 07:18:37 INFO - Return code: 0 07:18:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 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 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:37 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:37 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:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/Render', 07:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:37 INFO - 'HOME': '/Users/cltbld', 07:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:37 INFO - 'LOGNAME': 'cltbld', 07:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:37 INFO - 'MOZ_NO_REMOTE': '1', 07:18:37 INFO - 'NO_EM_RESTART': '1', 07:18:37 INFO - 'PAGER': '/bin/cat', 07:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:37 INFO - 'PWD': '/builds/slave/test', 07:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:37 INFO - 'SHELL': '/bin/bash', 07:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZaDxzcvcQT/Listeners', 07:18:37 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:37 INFO - 'USER': 'cltbld', 07:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:37 INFO - 'XPC_FLAGS': '0x0', 07:18:37 INFO - 'XPC_SERVICE_NAME': '0', 07:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:18:37 INFO - Cleaning up... 07:18:37 INFO - Return code: 0 07:18:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 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 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:37 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:37 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:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/Render', 07:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:37 INFO - 'HOME': '/Users/cltbld', 07:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:37 INFO - 'LOGNAME': 'cltbld', 07:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:37 INFO - 'MOZ_NO_REMOTE': '1', 07:18:37 INFO - 'NO_EM_RESTART': '1', 07:18:37 INFO - 'PAGER': '/bin/cat', 07:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:37 INFO - 'PWD': '/builds/slave/test', 07:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:37 INFO - 'SHELL': '/bin/bash', 07:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZaDxzcvcQT/Listeners', 07:18:37 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:37 INFO - 'USER': 'cltbld', 07:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:37 INFO - 'XPC_FLAGS': '0x0', 07:18:37 INFO - 'XPC_SERVICE_NAME': '0', 07:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:37 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:40 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:41 INFO - Downloading docopt-0.6.1.tar.gz 07:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:41 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:41 INFO - Running setup.py install for blobuploader 07:18:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:41 INFO - Running setup.py install for requests 07:18:42 INFO - Running setup.py install for docopt 07:18:42 INFO - Successfully installed blobuploader requests docopt 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', '--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 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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', '--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:42 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:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 07:18:42 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:42 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Q_TOe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-FiBu56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-8Or9Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-25hj3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-xk51f1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-MTsMGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-QRWlGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-kJ5efp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-CKY79W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-o2OdVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:43 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-EpuDGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:44 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-rnUnHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:44 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Pcvo6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:44 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-T25boj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:44 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-EdMfC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:44 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Q1NG9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:44 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-DJKU3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:44 INFO - Running setup.py install for manifestparser 07:18:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Running setup.py install for mozcrash 07:18:45 INFO - Running setup.py install for mozdebug 07:18:45 INFO - Running setup.py install for mozdevice 07:18:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Running setup.py install for mozfile 07:18:45 INFO - Running setup.py install for mozhttpd 07:18:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Running setup.py install for mozinfo 07:18:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Running setup.py install for mozInstall 07:18:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Running setup.py install for mozleak 07:18:46 INFO - Running setup.py install for mozlog 07:18:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:46 INFO - Running setup.py install for moznetwork 07:18:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:46 INFO - Running setup.py install for mozprocess 07:18:46 INFO - Running setup.py install for mozprofile 07:18:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:46 INFO - Running setup.py install for mozrunner 07:18:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:46 INFO - Running setup.py install for mozscreenshot 07:18:47 INFO - Running setup.py install for moztest 07:18:47 INFO - Running setup.py install for mozversion 07:18:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:47 INFO - Cleaning up... 07:18:47 INFO - Return code: 0 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:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:47 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 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:47 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:47 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:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/Render', 07:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:47 INFO - 'HOME': '/Users/cltbld', 07:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:47 INFO - 'LOGNAME': 'cltbld', 07:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:47 INFO - 'MOZ_NO_REMOTE': '1', 07:18:47 INFO - 'NO_EM_RESTART': '1', 07:18:47 INFO - 'PAGER': '/bin/cat', 07:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:47 INFO - 'PWD': '/builds/slave/test', 07:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:47 INFO - 'SHELL': '/bin/bash', 07:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZaDxzcvcQT/Listeners', 07:18:47 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:47 INFO - 'USER': 'cltbld', 07:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:47 INFO - 'XPC_FLAGS': '0x0', 07:18:47 INFO - 'XPC_SERVICE_NAME': '0', 07:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:47 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-4PxWe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:47 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-iXSo8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:47 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Ejrcz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:47 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Cdi_ZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:48 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:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:48 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Cyk6vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:48 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:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:48 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-2S8f2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:48 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:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:48 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-8nS07F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:48 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:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:48 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-PCGapH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:48 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:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:48 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Wc3RDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:48 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:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:48 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-sAiey9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:48 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:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:48 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-S7gIRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:48 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:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:48 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-9bWXe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:48 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-vTZWPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:49 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:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:49 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Qx_aYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:49 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:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:49 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-nG6xv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:49 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:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:49 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-jrmkXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:49 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:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:49 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-BM97K_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:49 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:49 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:49 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:49 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:49 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:49 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:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:49 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:49 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:49 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:49 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:49 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:49 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:51 INFO - Downloading blessings-1.6.tar.gz 07:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:51 INFO - Installing collected packages: blessings 07:18:51 INFO - Running setup.py install for blessings 07:18:52 INFO - Successfully installed blessings 07:18:52 INFO - Cleaning up... 07:18:52 INFO - Return code: 0 07:18:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:52 INFO - Reading from file tmpfile_stdout 07:18:52 INFO - Current package versions: 07:18:52 INFO - blessings == 1.6 07:18:52 INFO - blobuploader == 1.2.4 07:18:52 INFO - docopt == 0.6.1 07:18:52 INFO - functools32 == 3.2.3-2 07:18:52 INFO - jsonschema == 2.5.1 07:18:52 INFO - manifestparser == 1.1 07:18:52 INFO - mozInstall == 1.12 07:18:52 INFO - mozcrash == 0.17 07:18:52 INFO - mozdebug == 0.1 07:18:52 INFO - mozdevice == 0.48 07:18:52 INFO - mozfile == 1.2 07:18:52 INFO - mozhttpd == 0.7 07:18:52 INFO - mozinfo == 0.9 07:18:52 INFO - mozleak == 0.1 07:18:52 INFO - mozlog == 3.3 07:18:52 INFO - moznetwork == 0.27 07:18:52 INFO - mozprocess == 0.23 07:18:52 INFO - mozprofile == 0.28 07:18:52 INFO - mozrunner == 6.12 07:18:52 INFO - mozscreenshot == 0.1 07:18:52 INFO - mozsystemmonitor == 0.3 07:18:52 INFO - moztest == 0.7 07:18:52 INFO - mozversion == 1.4 07:18:52 INFO - psutil == 3.1.1 07:18:52 INFO - requests == 1.2.3 07:18:52 INFO - wsgiref == 0.1.2 07:18:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:52 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 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:52 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:52 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:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/Render', 07:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:52 INFO - 'HOME': '/Users/cltbld', 07:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:52 INFO - 'LOGNAME': 'cltbld', 07:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:52 INFO - 'MOZ_NO_REMOTE': '1', 07:18:52 INFO - 'NO_EM_RESTART': '1', 07:18:52 INFO - 'PAGER': '/bin/cat', 07:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:52 INFO - 'PWD': '/builds/slave/test', 07:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:52 INFO - 'SHELL': '/bin/bash', 07:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZaDxzcvcQT/Listeners', 07:18:52 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:52 INFO - 'USER': 'cltbld', 07:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:52 INFO - 'XPC_FLAGS': '0x0', 07:18:52 INFO - 'XPC_SERVICE_NAME': '0', 07:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:52 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:52 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:52 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:52 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:52 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:52 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:52 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:52 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:52 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:52 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:52 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:52 INFO - Cleaning up... 07:18:52 INFO - Return code: 0 07:18:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c69ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c731618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1684f4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, '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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/Render', 07:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:52 INFO - 'HOME': '/Users/cltbld', 07:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:52 INFO - 'LOGNAME': 'cltbld', 07:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:52 INFO - 'MOZ_NO_REMOTE': '1', 07:18:52 INFO - 'NO_EM_RESTART': '1', 07:18:52 INFO - 'PAGER': '/bin/cat', 07:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:52 INFO - 'PWD': '/builds/slave/test', 07:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:52 INFO - 'SHELL': '/bin/bash', 07:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZaDxzcvcQT/Listeners', 07:18:52 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:52 INFO - 'USER': 'cltbld', 07:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:52 INFO - 'XPC_FLAGS': '0x0', 07:18:52 INFO - 'XPC_SERVICE_NAME': '0', 07:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:18:53 INFO - Cleaning up... 07:18:53 INFO - Return code: 0 07:18:53 INFO - Running post-action listener: _resource_record_post_action 07:18:53 INFO - Running post-action listener: _start_resource_monitoring 07:18:53 INFO - Starting resource monitoring. 07:18:53 INFO - [mozharness: 2016-09-17 14:18:53.135135Z] Finished create-virtualenv step (success) 07:18:53 INFO - [mozharness: 2016-09-17 14:18:53.136053Z] Running install step. 07:18:53 INFO - Running pre-action listener: _resource_record_pre_action 07:18:53 INFO - Running main action method: install 07:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:53 INFO - Reading from file tmpfile_stdout 07:18:53 INFO - Detecting whether we're running mozinstall >=1.0... 07:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:53 INFO - Reading from file tmpfile_stdout 07:18:53 INFO - Output received: 07:18:53 INFO - Usage: mozinstall [options] installer 07:18:53 INFO - Options: 07:18:53 INFO - -h, --help show this help message and exit 07:18:53 INFO - -d DEST, --destination=DEST 07:18:53 INFO - Directory to install application into. [default: 07:18:53 INFO - "/builds/slave/test"] 07:18:53 INFO - --app=APP Application being installed. [default: firefox] 07:18:53 INFO - mkdir: /builds/slave/test/build/application 07:18:53 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:53 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:26 INFO - Reading from file tmpfile_stdout 07:19:26 INFO - Output received: 07:19:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:19:26 INFO - Running post-action listener: _resource_record_post_action 07:19:26 INFO - [mozharness: 2016-09-17 14:19:26.097088Z] Finished install step (success) 07:19:26 INFO - [mozharness: 2016-09-17 14:19:26.097248Z] Running run-tests step. 07:19:26 INFO - Running pre-action listener: _resource_record_pre_action 07:19:26 INFO - Running main action method: run_tests 07:19:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:26 INFO - 2016-09-17 07:19:26.112 screenresolution[1651:8238] starting screenresolution argv=screenresolution get 07:19:26 INFO - 2016-09-17 07:19:26.114 screenresolution[1651:8238] Display 0: 1600x1200x32@60 07:19:26 INFO - 2016-09-17 07:19:26.120 screenresolution[1652:8240] starting screenresolution argv=screenresolution list 07:19:26 INFO - Available Modes on Display 0 07:19:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:26 INFO - Intel Iris: 07:19:26 INFO - Chipset Model: Intel Iris 07:19:26 INFO - Type: GPU 07:19:26 INFO - Bus: Built-In 07:19:26 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:26 INFO - Vendor: Intel (0x8086) 07:19:26 INFO - Device ID: 0x0a2e 07:19:26 INFO - Revision ID: 0x0009 07:19:26 INFO - Displays: 07:19:26 INFO - Display: 07:19:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:26 INFO - Display Serial Number: 1600x1200 60 07:19:26 INFO - Main Display: Yes 07:19:26 INFO - Mirror: Off 07:19:26 INFO - Online: Yes 07:19:26 INFO - Rotation: Supported 07:19:26 INFO - Return code: 0 07:19:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:26 INFO - Python 2.7.3 07:19:26 INFO - Return code: 0 07:19:26 INFO - grabbing minidump binary from tooltool 07:19: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:19:26 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 0x10c600d30>, '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 0x10c3a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5f8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:26 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:26 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:26 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:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:26 INFO - Return code: 0 07:19:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:26 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-e10s', '--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-0030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:19:26 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-e10s --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-0030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CAYR71vx9Z/Render', 07:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:26 INFO - 'HOME': '/Users/cltbld', 07:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:26 INFO - 'LOGNAME': 'cltbld', 07:19:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:26 INFO - 'MOZ_NO_REMOTE': '1', 07:19:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:26 INFO - 'NO_EM_RESTART': '1', 07:19:26 INFO - 'PAGER': '/bin/cat', 07:19:26 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:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:26 INFO - 'PWD': '/builds/slave/test', 07:19:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:26 INFO - 'SHELL': '/bin/bash', 07:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZaDxzcvcQT/Listeners', 07:19:26 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:26 INFO - 'USER': 'cltbld', 07:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:26 INFO - 'XPC_FLAGS': '0x0', 07:19:26 INFO - 'XPC_SERVICE_NAME': '0', 07:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--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-0030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:19:26 INFO - mozversion application_buildid: 20160916162818 07:19:26 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:19:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:26 INFO - mozversion application_name: Firefox 07:19:26 INFO - mozversion application_remotingname: firefox-dev 07:19:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:26 INFO - mozversion application_vendor: Mozilla 07:19:26 INFO - mozversion application_version: 50.0a2 07:19:26 INFO - mozversion platform_buildid: 20160916162818 07:19:26 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:26 INFO - mozversion platform_version: 50.0a2 07:19:26 INFO - using testdate: 1474121966 07:19:26 INFO - actual date: 1474121966 07:19:26 INFO - starting webserver on 'localhost:49266' 07:19:26 INFO - SUITE-START | Running 2 tests 07:19:26 INFO - TEST-START | dromaeo_css 07:19:26 INFO - operating with platform_type : mac_ 07:19:26 INFO - Initialising browser for dromaeo_css test... 07:19:26 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpGWqFPN/profile http://localhost:49266/getInfo.html) 07:19:29 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 07:19:29 INFO - PROCESS | 1662 | colorDepth:24 07:19:29 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 07:19:29 INFO - PROCESS | 1662 | __metrics 07:19:29 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:19:30 INFO - TEST-INFO | 1662: exit 0 07:19:30 INFO - Browser initialized. 07:19:30 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:30 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpGWqFPN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:42 INFO - PROCESS | 1666 | RSS: Main: 226385920 07:19:42 INFO - PROCESS | 1666 | 07:20:31 INFO - PROCESS | 1666 | 1474122031937 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:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 07:22:44 INFO - PROCESS | 1666 | RSS: Main: 239714304 07:22:44 INFO - PROCESS | 1666 | 07:25:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 07:25:47 INFO - PROCESS | 1666 | RSS: Main: 249044992 07:25:47 INFO - PROCESS | 1666 | 07:28:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 07:28:50 INFO - PROCESS | 1666 | RSS: Main: 252461056 07:28:50 INFO - PROCESS | 1666 | 07:31:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 07:31:52 INFO - PROCESS | 1666 | RSS: Main: 252346368 07:31:52 INFO - PROCESS | 1666 | 07:34:55 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 07:34:56 INFO - PROCESS | 1666 | RSS: Main: 256524288 07:34:56 INFO - PROCESS | 1666 | 07:37:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 07:37:58 INFO - PROCESS | 1666 | RSS: Main: 257024000 07:37:58 INFO - PROCESS | 1666 | 07:37:58 INFO - PROCESS | 1666 | __start_tp_report 07:37:58 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 07:37:58 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 07:37:58 INFO - PROCESS | 1666 | |i|pagename|runs| 07:37:58 INFO - PROCESS | 1666 | |0;dojo.html;3418.7265018798494;3427.057559171229;3453.1712389026625;3465.081753335365;3489.494023366612;6000.879982400349;6041.395860413956;6059.212302400687;6087.11736798164;6099.5120390368775;6033.064874944391;6043.576949613529;6073.423024812647;6107.3587273336325;6116.174316467275;4639.791209395568;5384.40771515134;5390.973045134769;5401.756920938567;5459.044667183247;5339.065663508871;5390.21841832934;5408.296921400232;5446.101393270105;5500.4499550045075;5464.9180262296095;5468.0977638689765;5468.179773034037;5468.91796623049;5479.0959491684025;5408.891822163574;5451.2640793492965;5464.26232458621;5468.945310546884;5477.726113694339;5284.233786101032;5347.385050719178;5430.239766432703;5439.428859969686;5440.483154100354;6044.24446944132;6048.092786082079;6062.242219722535;6084.4828189603495;6131.908021379683;6109.34393638171;6240.5346759818085;6281.246250449975;6282.78010269643;6288.3397243289255;6189.659568723719;6357.427831495142;6361.55469117162;6361.936380636181;6369.554131210817;5142.254373115915;6024.969875150597;6117.755289788403;6171.259448866164;6184.938150618436;6515.120458738104;6517.087607734921;6520.250171230275;6521.902171467432;6526.902096468557;6168.136460895478;6258.405451482102;6264.624122552661;6266.843328916813;6273.749050038039;6169.537284703575;6211.751529938842;6218.31598524162;6303.716332765037;6327.968360158262;6212.534059945522;6244.188255526752;6246.531510136667;6251;6260.843478913063;6114.0236562776045;6229.0967809667345;6255.624662520263;6276.466500347522;6277.52918531112;5086.440491545927;5108.080545501845;5118.846434606968;5188.818391356284;5214.869628259324;4546.090781843644;4560.087982403533;4600.723956562617;4608.446986361658;4624.398828152386;5959.135925290809;6108.847278817976;6146.877062458815;6152.0156774915795;6166.167567378437;6261.5616906816995;6275.403836635513;6275.435210830956;6320.494360451153;6384.48924086072;5955.612885162451;6112.00015953023;6120.234970628671;6128.509719222451;6182.258129024546;5664.943350566524;5667.008273552195;5668.7165641718575;5672.262605861294;5677.205191273225;6338.271098823694;6405.423511883952;6446.258680251737;6448.96816509375;6578.967105164444;6265.028920517327;6322.241331040121;6340.397662222082;6344.080108384166;6350.745970161327;6388.840278992877;6524.608523488606;6570.868582628416;6613.702383392855;6627.1716035495565;6227.626342419469;6228.875422491434;6257.374262573706;6310.1796766420075;6311.621302721851;6341.4292713656;6349.714262858275;6358.014507751306;6364.77723279683;6373;4945.8021679132435;5053.29253904461;6162.383761623981;6262.15460912771;6288.905666414916;5951.494122999594;6191.102290167811;6226.719797609027;6273.215848018997;6290.2766181889665;6017.789377371771;6128.233970753656;6160.260768707783;6178.505719542336;6227.937720622917;5522.530584900329;6145.340757995227;6172.351903050295;6188.412100850412;6250.593711408744;6182.536309776695;6209.161763162096;6240.625562466266;6256.061590761422;6283.2774230962195 07:37:58 INFO - PROCESS | 1666 | |1;ext.html;515.5901058658367;528.6458073091027;536.03246140716;544.858521397672;578.0635370699467;33535.32929341411;43371.56628433715;43399.349009764876;43401.13197736047;44659;44697.106057878904;45021;45063.54936450635;45147;45188.548114518846;9769.951150244256;9838.655647052354;9866.358686685378;9947.651832185875;9964.701058968243;9587;9741.805163896717;9748.853767193463;9750.07374299444;9796.853047204298;9730.951345243264;9766.804663906718;9775.706728798112;9779.168770654504;9813.705588832347;9603.279754018477;9639.614415423375;9688.176504997084;9752.804943901083;9787.265955053328;9657.082577155159;9720.416774993522;9771.804563908752;9806.117449429586;9827.066428689262;42359.788201059106;42396.78801606003;42726.786366068285;42761.57238427607;42809.929751756456;3437.4843773433936;3511.7541772075965;3551.4140166872435;3589.73077019222;3757.906052348686;32438.351232975445;32883;32891;32947.67052329494;32991.50512742311;3208.631007434128;3264.4940034294723;3268.1176082457605;3343.8160901150427;3377.307651931348;549132;549461;552310;554471;554942.9999999959;67038.66480667566;67537.66231168812;67538.66230668816;67609;67642.66178669075;36442.90671279866;36524.086897827765;37155.6995505156;38254;38326.42510362347;17708.380206692706;17828.019458929622;17838.286468541373;17840.643187136186;17912.462626121236;13367.264800436069;13461;13476.326183690777;13480.730385392433;13528.59414217575;20646.277380291616;20997.68503472449;21006.894965525076;21064.684029739565;21089.050992705364;8244.958775206085;8414.15858414166;8426.915730842613;8438.282745966539;8478.83042339162;889.1597440418872;953.2850362228329;1018.7504061504831;1032.5611615063717;1051.6056478820442;32071.679283207337;32233.677663223538;32328;32518.512222316684;32894;1082.1613249731522;1106.6292791914639;1133.0878642692546;1140.6654214568973;1217.8599461061915;16383.508494745176;16786.992780433215;16823.495295141165;17230.483085507454;17887.10564471771;533673;626649;627567;738716.9192462118;940110;65780;66497;66508;66765;67110.66444667746;1424.7364237616075;1439.5105664074265;1454.342050062929;1458.7738900470445;1463.41463414635;31463.52804707977;33564;33660.66339336673;33693.49459758154;34032.829835851655;56865.578360541316;57136.1429578565;57577;57614.13578796405;57665;621820;624357;626212;627249;631563;28542.286442839097;28960;28968.275793105342;29337.706622933496;29517.26206844846;51359;51850.740746296025;51918;52453.68865778233;52652.736736316074;41809.79095104505;42742.358864616435;43058.138837223705;44252.114957700025;44346.55653443424;42507.7874610625;42731;43040.569594303655;43464.565354347316;44409.33385999148;41258;41563;42431.575684242765;42543.57456425396;44071.89820254391;41112.58887411207;43766;44774.77612611916;45416.77291613521;47198;32548.83725581357;33113.66886331106;33665.495017575246;34294.48558271678;34688.65311346854 07:37:58 INFO - PROCESS | 1666 | |2;jquery.html;378.5249511862612;383.9520059992501;386.7626122996344;393.28029705638676;412.6884202427167;15506.457273995413;20055.398338049865;22346.21788237412;22488.55022899541;22525.774742252594;21422.892885535588;21479.248226312084;21716.782832171673;22143.667844982338;22431.55136897261;5913.615614985012;6160.7535698572;6186.536009799283;6191.133241346192;6203.782867599635;5972.343042265347;5984.192134061889;5992.370801065891;6062.818115456544;6109.266887973449;5898.7935422260225;5987.311459182206;6002.339742628329;6077.817665470021;6101.267847858263;5660.971695141539;5785.855353616151;5944.167816505693;6062.545309101813;6087.147799308101;5759.769609215627;5814.563907706896;5840.47435730782;5866.178734977107;5914;12849.229046257255;13426.32868356588;13607.931960340135;26385.73614263852;27035;2362.9054837806466;2631.1974847671454;2689.5965605159186;3085.5217441296636;3117.9064628061196;10267.897321026843;13047.739045219043;15498.147601881892;16123.274452649659;16267.91866040674;1636.2882146266413;1867.3090956346239;1885.1328388941104;1893.4508992392189;1913.339897735279;93632;111980;121300;133619;135495.6450435493;10111.797764044677;11535.192536522363;11651.53393864253;11761.176717629854;11900.464479098462;14799.334029968462;14869.330880110421;14925.029873058216;14951.775723364159;14970.775438368433;8818.955905220433;8908.10918908104;9023.639054437748;9099.317551183556;9206.677766278148;9121.133492318218;9143.725688229364;9151.084891510796;9197.77005574866;9221.262299016063;4608.28571571407;4636.235021221528;4705.835295764632;4721.291806229093;4829.420469543677;1945.8735182213395;1990.8506861985409;2041.6631255842917;2067.9069441875154;2069.617120832651;831.9334453243847;840.890025166774;891.5943245823134;982.4350998175935;1113.9220254582262;17982.550436238937;22833.771662283165;22990.080396784277;23150.958206880394;23179.884100579387;1001.4928471858295;1024.8975102489692;1035.1460045462522;1036.7719101797593;1051.542578978147;9628.422294662343;11278.605248816251;11691.707707307385;11796.351200683874;11837.88162118385;644226;655263;661203;665573;665906.3409365844;134752.32623837015;135072;138641;141092;141638.2918085403;881.9647214111363;993.3096497934011;1041.6510468992822;1054.9683509494728;1058.4496062047847;19802.60394792125;19941.401757947286;20583.8970805145;20664.48338791542;20728;52571.73714131405;53392.73303633457;53493.19760203522;54444.18333725077;55250.171247432125;74947.62526187334;76158.6192069036;77851.61074194593;78659.60670196613;80994.5950270245;8250.174982501941;8293.129221431904;8496.53269070207;8499.065102838558;8637.093105223861;9315.673951411669;11167.497462614365;13086.672833179247;13510.594682159157;13901.930490347484;11512.769744604893;11576.88423115758;11750.177487575787;11936.880631193577;12011.939940300243;8735.645974873905;10638.308509946828;10923.781524369628;11449;11813.881861181279;5836.737346819318;9571.617135314787;11440.370779607201;11662;11956.163068585116;6597.868042639217;8246.587670616565;8390.74138879173;8536.189062039097;8729.607167677343;9431.57557909882;10487.790244195226;10506.474676266003;11908.880911191123;12086 07:37:58 INFO - PROCESS | 1666 | |3;mootools.html;1505.9096454212747;1575.4485929924529;1828;1860.246600126949;1933.39098184072;8155;8642.481451112937;8759;9261.907380926197;9285.628574857006;8912.732618021453;9091.863622045657;9122.315826313037;9124.54377281135;9130.589123489442;2390.1753894906274;2448.889799958997;2450.5098980203943;2451.6077427611585;2459.766322199388;2418.9516209675794;2439.377958620549;2449.3998970252223;2461.163204510466;2463.4210960424284;2397.0771253067605;2431.869180830913;2441.914532991346;2458.557459657261;2460.5817011108065;2365.361352434833;2433.2578563538113;2440.2191298784396;2441.4872876695913;2445.938851528717;2156.921539230385;2393.4734358441115;2415.975840241593;2435.0868424340874;2440.9389765255833;9148.542572871329;9174.403663761834;9240;9249.815003699956;9273.97986221515;5302.257683924254;5426.104692725735;5488.4237155098535;5498.1752737089355;5501.5323697485765;3229.950266163506;3542.220711443504;3672.4124140137715;3695.833687484043;3710.962890371116;1086.4513420722485;1104.845321654969;1119.52979748505;1132.365875109941;1133.0991861470152;11622.70943226426;11834.467448964817;11848.526058957717;11863.881361186364;11873.16887817853;4726.754598616751;4766.95233047672;4793.520647935178;4884.3161957326;4912.631552633567;9956.80086398268;10005.549750261256;10014.599416023424;10032.197424206046;10035.34770239932;5426.9185962210595;5429.782808687608;5438.537724293479;5442.782288708487;5449.3460784706085;4495.505494395614;4788.760561971888;4909.189983652657;4937.061958227925;4976.825811096666;3710.220853620716;3993.381025940984;4002.139539998914;4059.3505039193587;4089.488813898263;1314.2771475688332;1322.340462719281;1327.0909427042507;1334.9866501334861;1345.7981302804658;941.2940294778916;955.3455882720359;965.6813251626946;978.3885071830106;1020.06797135849;5703.17303990919;5888.941110588925;5952.940470595326;6026.789062382795;6033.969830150909;442.2172754225004;445.6167695781625;446.4999200895018;453.745902294716;463.5017356341946;3361.4957756336744;3503.649635036527;3692.1692619160476;3997.2804895118575;4094.9181016379507;10778.29941053829;10962.945185274022;11053.502592383362;11135.777284454265;11187.32876027441;10758.462076896123;10968.17738669603;11100;11108.722281943015;11189.664310070712;7201.603911784902;8010.679572817138;8491.108433614505;8575.3997220194;8608.354373422018;5946.870094681996;5965.433283838029;6055.212822333068;6107.328193898757;6206.317305096526;10071.34536255141;10214.897851021395;10286.434246116236;10301.9484902575;10404.47977601132;10272;10387.480625968823;10469.31949423285;10505.579776809147;10611.787764244826;7166.928330716834;7623.885641715489;7786.026746656668;7842.411819113703;7867.567283799446;8620.568971551524;8735.257503112307;8808.5595720215;8825.646974120964;8827.382083254368;7643.121041080124;7779.649915753918;7847.84304313922;7868.645910934136;7916.960415197887;7613.885791713239;7701.345385642283;7703.730369437043;7705.1524332324525;7773.650185741542;7043.823904402431;7046.682899269443;7109.075820143556;7117.789975704246;7176.318249766264;4839.806407743651;4982.427020892644;5021.196608542616;5122.436531981553;5258.8159414420315;5015.724135172601;5065.113605119016;5095.312132862016;5129.769160387867;5169.4830516949505 07:37:58 INFO - PROCESS | 1666 | |4;prototype.html;265.27711984841295;302.4918137528952;310.22134442549196;317.7061218373005;318.4093506545357;4727.929081063782;5395.271638328824;5472;5545.944540554593;5656.529302381379;4625.953740462594;5494.945050549493;5532.419095994923;5556.972215138919;5589.273394458714;3898.7465814722095;3964.8414063437426;4289.142171565683;4324.740515569068;4328.761918094504;3618.6200448952877;4145.523264824538;4235.009277726507;4245.384419259206;4332.696711230215;3502.807345595991;3656.433252845813;3877.592852750463;4215.978920105395;4269.5943885331;3672.0085576894357;4167.895802604929;4241.809118589671;4242.893927651802;4274.397101087093;3692.5568931728094;3703.6481534254203;3714.851405943786;3716.4982727332003;3720.2931443025736;4396.472423309191;5259.815906443256;5372.597055220835;5384.734587371964;5435.755391007414;3977.6817854571855;4847.733374664392;4909.459959404463;4915.459299477055;4942.233953737176;4867.853964381075;5664.971675141639;5881.323647780518;5891.8527036824;5917.585768996172;3718.4050551911564;3756.7370284080125;4047.817848196838;4074.6740260779065;4088.8160032798596;5604.8318550443555;6152.415520525543;6216.720247588869;6221.471174950135;6277.968610156966;3888.2223555289056;4121.91756164875;4122.690798190148;4125.102452871713;4182.707210495297;3980.3233450313514;4185.455890734185;4194.140201258735;4214.576618444406;4220.050488640093;4142.523609784913;4525.253333200051;4883.365162528919;4895.461499235082;4927.743425426494;4792.568668819823;5435.266239057755;5556.472135147154;5891.499222566044;6069.817905462844;1274.8024056271108;1416.2564653556965;1424.3946322812762;1428.5071650280636;1429.2353590828857;848.2620120495085;878.2403221213605;885.9246964008003;889.666375109334;895.5656506594297;4428.689991700615;4513.458384993822;4552.996681460147;4647.697899636512;4686.203345431217;4373.6282415994365;4854.063291708536;4878.829240976549;5049.722265275445;5064.924026139611;4095.6518695910586;4142.813573389205;4190.266703326966;4439.911201775946;4666.883327916829;2075.2529089527757;2321.409834263535;2321.8955147018423;2559.4497183105723;2672.906448274301;4344.978275108604;4480.798364073625;4485.394471746337;5065.417476990119;5255.605829562722;4788.928166077511;4957.405111386584;5015.899682006339;5203.089459344599;5307.681539107666;5083.669561478492;5769.624974376652;6290.339514351019;6315.305316415191;6367.840803979938;5848.941510584839;6153.753849845956;6168.9691551541955;6180.969095154495;6182.536309776875;4891.877703057452;5284.524392804666;6030.909536357046;6030.969845150922;6115.483596931693;5203.791848326025;5318.654287471302;5719.056355701429;5742.626729262584;6368.172137622079;3862.24686186191;4035.6771458283856;4064.30906745848;4065.8576949806616;4074.1851629673592;5708.373113662587;5857.941420585739;6124.448799608057;6169.537284703575;6217.316095229431;5391.568674505952;5456.945430545803;5781.21953536284;5910.556708246812;5911.290645122441;5059.772310245974;5707.68607726579;5947.31605865331;5965.642061476326;6002.819915402545;4592.816287348469;4938.949512295079;5273.6572122811895;5793.073108302651;5831.591788574929;5938.732757025858;5960.672163031075;5991.2510936132985;6004.519638428827;6012.068129439944;5520.447955204448;5755.158349328342;5794.2177805997335;5859.58982871212;5867.618604790675 07:37:58 INFO - PROCESS | 1666 | |5;yui.html;735.9006534117894;757.9355754760845;783.5690370296337;790.9209079092092;842.1830824100621;15514.689706205869;16005.519834404957;16413.425530106433;16530.09084500352;16809;14996.250187490636;15303.234838258099;15308.69382612347;15327.463538776145;15355.616109597266;715.3347386930153;886.3883920095146;901;917.8760867282931;917.9947956987088;755.6523998960485;801.5030680977773;908.1871724806294;923.6767131503954;926.0693003531442;944.2351695126935;965.5944503308592;968.7626531499792;968.8498282766183;969.2536746705033;936.3539157980971;958.5590628310986;959.4003747657714;960.6205548808251;961.6345788600357;699.2692636195137;706.9964650176768;707.6567864585672;710.0343532795395;712.0138608027919;15483.767743483857;16012;16132.435364762177;16169.595760105974;16245.51263462098;13389.598312050653;13449;13484;13577;14021.5092471763;13032.348382580833;13517.256550889795;13564.593062208149;13807.309634518335;14077.929610351985;4633.513481084505;4681.274402467623;4681.391419115494;4695.436547614274;4699.835505757282;123154.38422807919;124038.37980810128;126266.73733262642;128665.35667321697;129724;11029.558817647205;11178.105751539857;11259.211855170059;11332.433378331216;11395.544178232944;18523;21460.141594336365;21765.673514897288;21888.89055554712;22166.113355465925;8084.434089613671;8103.230193131761;8174.509529428254;8220.671173153007;8370.707025254087;4409.647228221735;4493.146302202571;4605.907881842411;4637.652176086807;4665.696729712626;3548.840302186408;3637.0725465006253;3649.1242101895887;3667.4865518827387;3672.8714494992546;599.4065874783932;658.1476987301456;668.3316683316683;676.8172593399754;685.1538350137577;502.30430853267757;525.4876495416938;622.6699849079995;630.3633330336394;633.395107672172;8357.788120722584;8483.879030242439;8557.914420855835;8613.69852055175;8923.821523569492;585.2216551985848;600.6456190847333;600.882827848574;687.3538873458932;702.1784512120831;4340.131973605292;4498.100379924028;4525.524819893863;4558.840440584564;4561.703489273186;143624;148783.25608371888;149794;152498;155612;114593;123453.38273308576;127100.36449817692;127919.72080279264;130639;100819.49590252002;104077.83688652521;105367;111201;112574;9789.199396146767;9863.457509837028;9937.503124843874;10077.294589378673;10113.696589102045;46763;47982.76008619935;57714.71142644428;61156.69421653041;62021.68989155025;91950.6207406876;92721;92834.53582732043;94097.529512352;94227.05772942182;13308.800367994294;13460.326983650973;13462.394192261176;13640.317984100953;14090.36593353281;15057.17185554805;15397.84602154009;15474.994125381814;15606.843931560994;15623.609409764393;13515.121517101357;13606.319684015958;13758.449662013407;14424.278786060446;14677.55967320982;13908.235047072509;14421.351039203471;14784.260786960824;15036.774448383501;15044.849551504345;13653.86346136526;14387.136771793726;14421.495247666282;14560.271986400849;14562;11144.85226510282;11476.827847582126;11797.469113890067;11817.645470635895;11895.82156267674;11588.362640054878;11689.240199387013;11734.178607497393;11846.348450835287;11921.940390297994 07:37:58 INFO - PROCESS | 1666 | __end_tp_report 07:37:58 INFO - PROCESS | 1666 | __start_cc_report 07:37:58 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,424 07:37:58 INFO - PROCESS | 1666 | __end_cc_report 07:37:58 INFO - PROCESS | 1666 | __startTimestamp1474123078926__endTimestamp 07:37:58 INFO - PROCESS | 1666 | 07:37:58 INFO - PROCESS | 1666 | ------- Summary: start ------- 07:37:58 INFO - PROCESS | 1666 | Number of tests: 6 07:37:58 INFO - PROCESS | 1666 | 07:37:58 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5904.10 Median:6166.17 stddev:640.13 (10.4%) stddev-sans-first:611.69 07:37:58 INFO - PROCESS | 1666 | Values: 3418.7 3427.1 3453.2 3465.1 3489.5 6000.9 6041.4 6059.2 6087.1 6099.5 6033.1 6043.6 6073.4 6107.4 6116.2 4639.8 5384.4 5391.0 5401.8 5459.0 5339.1 5390.2 5408.3 5446.1 5500.4 5464.9 5468.1 5468.2 5468.9 5479.1 5408.9 5451.3 5464.3 5468.9 5477.7 5284.2 5347.4 5430.2 5439.4 5440.5 6044.2 6048.1 6062.2 6084.5 6131.9 6109.3 6240.5 6281.2 6282.8 6288.3 6189.7 6357.4 6361.6 6361.9 6369.6 5142.3 6025.0 6117.8 6171.3 6184.9 6515.1 6517.1 6520.3 6521.9 6526.9 6168.1 6258.4 6264.6 6266.8 6273.7 6169.5 6211.8 6218.3 6303.7 6328.0 6212.5 6244.2 6246.5 6251.0 6260.8 6114.0 6229.1 6255.6 6276.5 6277.5 5086.4 5108.1 5118.8 5188.8 5214.9 4546.1 4560.1 4600.7 4608.4 4624.4 5959.1 6108.8 6146.9 6152.0 6166.2 6261.6 6275.4 6275.4 6320.5 6384.5 5955.6 6112.0 6120.2 6128.5 6182.3 5664.9 5667.0 5668.7 5672.3 5677.2 6338.3 6405.4 6446.3 6449.0 6579.0 6265.0 6322.2 6340.4 6344.1 6350.7 6388.8 6524.6 6570.9 6613.7 6627.2 6227.6 6228.9 6257.4 6310.2 6311.6 6341.4 6349.7 6358.0 6364.8 6373.0 4945.8 5053.3 6162.4 6262.2 6288.9 5951.5 6191.1 6226.7 6273.2 6290.3 6017.8 6128.2 6160.3 6178.5 6227.9 5522.5 6145.3 6172.4 6188.4 6250.6 6182.5 6209.2 6240.6 6256.1 6283.3 07:37:58 INFO - PROCESS | 1666 | 07:37:58 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:76492.86 Median:43385.46 stddev:169042.86 (389.6%) stddev-sans-first:169420.81 07:37:58 INFO - PROCESS | 1666 | Values: 515.6 528.6 536.0 544.9 578.1 33535.3 43371.6 43399.3 43401.1 44659.0 44697.1 45021.0 45063.5 45147.0 45188.5 9770.0 9838.7 9866.4 9947.7 9964.7 9587.0 9741.8 9748.9 9750.1 9796.9 9731.0 9766.8 9775.7 9779.2 9813.7 9603.3 9639.6 9688.2 9752.8 9787.3 9657.1 9720.4 9771.8 9806.1 9827.1 42359.8 42396.8 42726.8 42761.6 42809.9 3437.5 3511.8 3551.4 3589.7 3757.9 32438.4 32883.0 32891.0 32947.7 32991.5 3208.6 3264.5 3268.1 3343.8 3377.3 549132.0 549461.0 552310.0 554471.0 554943.0 67038.7 67537.7 67538.7 67609.0 67642.7 36442.9 36524.1 37155.7 38254.0 38326.4 17708.4 17828.0 17838.3 17840.6 17912.5 13367.3 13461.0 13476.3 13480.7 13528.6 20646.3 20997.7 21006.9 21064.7 21089.1 8245.0 8414.2 8426.9 8438.3 8478.8 889.2 953.3 1018.8 1032.6 1051.6 32071.7 32233.7 32328.0 32518.5 32894.0 1082.2 1106.6 1133.1 1140.7 1217.9 16383.5 16787.0 16823.5 17230.5 17887.1 533673.0 626649.0 627567.0 738716.9 940110.0 65780.0 66497.0 66508.0 66765.0 67110.7 1424.7 1439.5 1454.3 1458.8 1463.4 31463.5 33564.0 33660.7 33693.5 34032.8 56865.6 57136.1 57577.0 57614.1 57665.0 621820.0 624357.0 626212.0 627249.0 631563.0 28542.3 28960.0 28968.3 29337.7 29517.3 51359.0 51850.7 51918.0 52453.7 52652.7 41809.8 42742.4 43058.1 44252.1 44346.6 42507.8 42731.0 43040.6 43464.6 44409.3 41258.0 41563.0 42431.6 42543.6 44071.9 41112.6 43766.0 44774.8 45416.8 47198.0 32548.8 33113.7 33665.5 34294.5 34688.7 07:37:58 INFO - PROCESS | 1666 | 07:37:58 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:37156.75 Median:2497.05 stddev:109653.16 (4391.3%) stddev-sans-first:109925.99 07:37:58 INFO - PROCESS | 1666 | Values: 378.5 384.0 386.8 393.3 412.7 15506.5 20055.4 22346.2 22488.6 22525.8 21422.9 21479.2 21716.8 22143.7 22431.6 5913.6 6160.8 6186.5 6191.1 6203.8 5972.3 5984.2 5992.4 6062.8 6109.3 5898.8 5987.3 6002.3 6077.8 6101.3 5661.0 5785.9 5944.2 6062.5 6087.1 5759.8 5814.6 5840.5 5866.2 5914.0 12849.2 13426.3 13607.9 26385.7 27035.0 2362.9 2631.2 2689.6 3085.5 3117.9 10267.9 13047.7 15498.1 16123.3 16267.9 1636.3 1867.3 1885.1 1893.5 1913.3 93632.0 111980.0 121300.0 133619.0 135495.6 10111.8 11535.2 11651.5 11761.2 11900.5 14799.3 14869.3 14925.0 14951.8 14970.8 8819.0 8908.1 9023.6 9099.3 9206.7 9121.1 9143.7 9151.1 9197.8 9221.3 4608.3 4636.2 4705.8 4721.3 4829.4 1945.9 1990.9 2041.7 2067.9 2069.6 831.9 840.9 891.6 982.4 1113.9 17982.6 22833.8 22990.1 23151.0 23179.9 1001.5 1024.9 1035.1 1036.8 1051.5 9628.4 11278.6 11691.7 11796.4 11837.9 644226.0 655263.0 661203.0 665573.0 665906.3 134752.3 135072.0 138641.0 141092.0 141638.3 882.0 993.3 1041.7 1055.0 1058.4 19802.6 19941.4 20583.9 20664.5 20728.0 52571.7 53392.7 53493.2 54444.2 55250.2 74947.6 76158.6 77851.6 78659.6 80994.6 8250.2 8293.1 8496.5 8499.1 8637.1 9315.7 11167.5 13086.7 13510.6 13901.9 11512.8 11576.9 11750.2 11936.9 12011.9 8735.6 10638.3 10923.8 11449.0 11813.9 5836.7 9571.6 11440.4 11662.0 11956.2 6597.9 8246.6 8390.7 8536.2 8729.6 9431.6 10487.8 10506.5 11908.9 12086.0 07:37:58 INFO - PROCESS | 1666 | 07:37:58 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5847.96 Median:2595.13 stddev:3312.11 (127.6%) stddev-sans-first:3305.33 07:37:58 INFO - PROCESS | 1666 | Values: 1505.9 1575.4 1828.0 1860.2 1933.4 8155.0 8642.5 8759.0 9261.9 9285.6 8912.7 9091.9 9122.3 9124.5 9130.6 2390.2 2448.9 2450.5 2451.6 2459.8 2419.0 2439.4 2449.4 2461.2 2463.4 2397.1 2431.9 2441.9 2458.6 2460.6 2365.4 2433.3 2440.2 2441.5 2445.9 2156.9 2393.5 2416.0 2435.1 2440.9 9148.5 9174.4 9240.0 9249.8 9274.0 5302.3 5426.1 5488.4 5498.2 5501.5 3230.0 3542.2 3672.4 3695.8 3711.0 1086.5 1104.8 1119.5 1132.4 1133.1 11622.7 11834.5 11848.5 11863.9 11873.2 4726.8 4767.0 4793.5 4884.3 4912.6 9956.8 10005.5 10014.6 10032.2 10035.3 5426.9 5429.8 5438.5 5442.8 5449.3 4495.5 4788.8 4909.2 4937.1 4976.8 3710.2 3993.4 4002.1 4059.4 4089.5 1314.3 1322.3 1327.1 1335.0 1345.8 941.3 955.3 965.7 978.4 1020.1 5703.2 5888.9 5952.9 6026.8 6034.0 442.2 445.6 446.5 453.7 463.5 3361.5 3503.6 3692.2 3997.3 4094.9 10778.3 10962.9 11053.5 11135.8 11187.3 10758.5 10968.2 11100.0 11108.7 11189.7 7201.6 8010.7 8491.1 8575.4 8608.4 5946.9 5965.4 6055.2 6107.3 6206.3 10071.3 10214.9 10286.4 10301.9 10404.5 10272.0 10387.5 10469.3 10505.6 10611.8 7166.9 7623.9 7786.0 7842.4 7867.6 8620.6 8735.3 8808.6 8825.6 8827.4 7643.1 7779.6 7847.8 7868.6 7917.0 7613.9 7701.3 7703.7 7705.2 7773.7 7043.8 7046.7 7109.1 7117.8 7176.3 4839.8 4982.4 5021.2 5122.4 5258.8 5015.7 5065.1 5095.3 5129.8 5169.5 07:37:58 INFO - PROCESS | 1666 | 07:37:58 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4587.08 Median:4912.46 stddev:1439.20 (29.3%) stddev-sans-first:1406.21 07:37:58 INFO - PROCESS | 1666 | Values: 265.3 302.5 310.2 317.7 318.4 4727.9 5395.3 5472.0 5545.9 5656.5 4626.0 5494.9 5532.4 5557.0 5589.3 3898.7 3964.8 4289.1 4324.7 4328.8 3618.6 4145.5 4235.0 4245.4 4332.7 3502.8 3656.4 3877.6 4216.0 4269.6 3672.0 4167.9 4241.8 4242.9 4274.4 3692.6 3703.6 3714.9 3716.5 3720.3 4396.5 5259.8 5372.6 5384.7 5435.8 3977.7 4847.7 4909.5 4915.5 4942.2 4867.9 5665.0 5881.3 5891.9 5917.6 3718.4 3756.7 4047.8 4074.7 4088.8 5604.8 6152.4 6216.7 6221.5 6278.0 3888.2 4121.9 4122.7 4125.1 4182.7 3980.3 4185.5 4194.1 4214.6 4220.1 4142.5 4525.3 4883.4 4895.5 4927.7 4792.6 5435.3 5556.5 5891.5 6069.8 1274.8 1416.3 1424.4 1428.5 1429.2 848.3 878.2 885.9 889.7 895.6 4428.7 4513.5 4553.0 4647.7 4686.2 4373.6 4854.1 4878.8 5049.7 5064.9 4095.7 4142.8 4190.3 4439.9 4666.9 2075.3 2321.4 2321.9 2559.4 2672.9 4345.0 4480.8 4485.4 5065.4 5255.6 4788.9 4957.4 5015.9 5203.1 5307.7 5083.7 5769.6 6290.3 6315.3 6367.8 5848.9 6153.8 6169.0 6181.0 6182.5 4891.9 5284.5 6030.9 6031.0 6115.5 5203.8 5318.7 5719.1 5742.6 6368.2 3862.2 4035.7 4064.3 4065.9 4074.2 5708.4 5857.9 6124.4 6169.5 6217.3 5391.6 5456.9 5781.2 5910.6 5911.3 5059.8 5707.7 5947.3 5965.6 6002.8 4592.8 4938.9 5273.7 5793.1 5831.6 5938.7 5960.7 5991.3 6004.5 6012.1 5520.4 5755.2 5794.2 5859.6 5867.6 07:37:58 INFO - PROCESS | 1666 | 07:37:58 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:25273.92 Median:22027.50 stddev:40121.60 (182.1%) stddev-sans-first:40191.85 07:37:58 INFO - PROCESS | 1666 | Values: 735.9 757.9 783.6 790.9 842.2 15514.7 16005.5 16413.4 16530.1 16809.0 14996.3 15303.2 15308.7 15327.5 15355.6 715.3 886.4 901.0 917.9 918.0 755.7 801.5 908.2 923.7 926.1 944.2 965.6 968.8 968.8 969.3 936.4 958.6 959.4 960.6 961.6 699.3 707.0 707.7 710.0 712.0 15483.8 16012.0 16132.4 16169.6 16245.5 13389.6 13449.0 13484.0 13577.0 14021.5 13032.3 13517.3 13564.6 13807.3 14077.9 4633.5 4681.3 4681.4 4695.4 4699.8 123154.4 124038.4 126266.7 128665.4 129724.0 11029.6 11178.1 11259.2 11332.4 11395.5 18523.0 21460.1 21765.7 21888.9 22166.1 8084.4 8103.2 8174.5 8220.7 8370.7 4409.6 4493.1 4605.9 4637.7 4665.7 3548.8 3637.1 3649.1 3667.5 3672.9 599.4 658.1 668.3 676.8 685.2 502.3 525.5 622.7 630.4 633.4 8357.8 8483.9 8557.9 8613.7 8923.8 585.2 600.6 600.9 687.4 702.2 4340.1 4498.1 4525.5 4558.8 4561.7 143624.0 148783.3 149794.0 152498.0 155612.0 114593.0 123453.4 127100.4 127919.7 130639.0 100819.5 104077.8 105367.0 111201.0 112574.0 9789.2 9863.5 9937.5 10077.3 10113.7 46763.0 47982.8 57714.7 61156.7 62021.7 91950.6 92721.0 92834.5 94097.5 94227.1 13308.8 13460.3 13462.4 13640.3 14090.4 15057.2 15397.8 15475.0 15606.8 15623.6 13515.1 13606.3 13758.4 14424.3 14677.6 13908.2 14421.4 14784.3 15036.8 15044.8 13653.9 14387.1 14421.5 14560.3 14562.0 11144.9 11476.8 11797.5 11817.6 11895.8 11588.4 11689.2 11734.2 11846.3 11921.9 07:37:58 INFO - PROCESS | 1666 | -------- Summary: end -------- 07:37:58 INFO - PROCESS | 1666 | 07:37:59 INFO - TEST-INFO | 1666: exit 0 07:37:59 INFO - TEST-OK | dromaeo_css | took 1112748ms 07:37:59 INFO - TEST-START | kraken 07:37:59 INFO - operating with platform_type : mac_ 07:37:59 INFO - Initialising browser for kraken test... 07:37:59 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpU5DfgG/profile http://localhost:49266/getInfo.html) 07:38:02 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 07:38:02 INFO - PROCESS | 1675 | colorDepth:24 07:38:02 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 07:38:02 INFO - PROCESS | 1675 | __metrics 07:38:02 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:38:03 INFO - TEST-INFO | 1675: exit 0 07:38:03 INFO - Browser initialized. 07:38:03 INFO - Running cycle 1/1 for kraken test... 07:38:03 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpU5DfgG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:15 INFO - PROCESS | 1677 | RSS: Main: 226791424 07:38:15 INFO - PROCESS | 1677 | 07:38:18 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:19 INFO - PROCESS | 1677 | RSS: Main: 239316992 07:38:19 INFO - PROCESS | 1677 | 07:38:21 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 07:38:22 INFO - PROCESS | 1677 | RSS: Main: 248074240 07:38:22 INFO - PROCESS | 1677 | 07:38:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 07:38:26 INFO - PROCESS | 1677 | RSS: Main: 251588608 07:38:26 INFO - PROCESS | 1677 | 07:38:28 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 07:38:28 INFO - PROCESS | 1677 | RSS: Main: 252026880 07:38:28 INFO - PROCESS | 1677 | 07:38:31 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:31 INFO - PROCESS | 1677 | RSS: Main: 253599744 07:38:31 INFO - PROCESS | 1677 | 07:38:34 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:35 INFO - PROCESS | 1677 | RSS: Main: 253558784 07:38:35 INFO - PROCESS | 1677 | 07:38:38 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:38 INFO - PROCESS | 1677 | RSS: Main: 253857792 07:38:38 INFO - PROCESS | 1677 | 07:38:41 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 07:38:42 INFO - PROCESS | 1677 | RSS: Main: 254349312 07:38:42 INFO - PROCESS | 1677 | 07:38:44 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:44 INFO - PROCESS | 1677 | RSS: Main: 253833216 07:38:44 INFO - PROCESS | 1677 | 07:38:46 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:47 INFO - PROCESS | 1677 | RSS: Main: 253710336 07:38:47 INFO - PROCESS | 1677 | 07:38:49 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:50 INFO - PROCESS | 1677 | RSS: Main: 253775872 07:38:50 INFO - PROCESS | 1677 | 07:38:53 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:53 INFO - PROCESS | 1677 | RSS: Main: 253943808 07:38:53 INFO - PROCESS | 1677 | 07:38:56 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:57 INFO - PROCESS | 1677 | RSS: Main: 254132224 07:38:57 INFO - PROCESS | 1677 | 07:38:58 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 07:38:59 INFO - PROCESS | 1677 | RSS: Main: 255987712 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | __start_tp_report 07:38:59 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 07:38:59 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 07:38:59 INFO - PROCESS | 1677 | |i|pagename|runs| 07:38:59 INFO - PROCESS | 1677 | |0;ai-astar;110;117;120;116;168;166;119;119;116;142 07:38:59 INFO - PROCESS | 1677 | |1;audio-beat-detection;167;111;115;155;125;162;117;104;178;133 07:38:59 INFO - PROCESS | 1677 | |2;audio-dft;202;199;195;194;194;199;200;199;198;196 07:38:59 INFO - PROCESS | 1677 | |3;audio-fft;68;81;75;150;61;59;60;65;139;58 07:38:59 INFO - PROCESS | 1677 | |4;audio-oscillator;69;76;143;83;72;77;118;71;74;124 07:38:59 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;116;121;91;89;90;87;91;113;78;122 07:38:59 INFO - PROCESS | 1677 | |6;imaging-darkroom;98;141;142;99;96;97;97;98;97;105 07:38:59 INFO - PROCESS | 1677 | |7;imaging-desaturate;91;95;97;91;92;91;75;142;142;91 07:38:59 INFO - PROCESS | 1677 | |8;json-parse-financial;55;58;65;83;51;52;57;77;82;84 07:38:59 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;66;71;69;76;79;75;70;87;64;84 07:38:59 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;97;84;84;75;103;77;84;74;92;98 07:38:59 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;149;136;140;132;146;147;162;157;205;152 07:38:59 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;165;164;162;166;166;165;146;171;206;153 07:38:59 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;65;85;69;66;68;66;67;65;69;66 07:38:59 INFO - PROCESS | 1677 | __end_tp_report 07:38:59 INFO - PROCESS | 1677 | __start_cc_report 07:38:59 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1096 07:38:59 INFO - PROCESS | 1677 | __end_cc_report 07:38:59 INFO - PROCESS | 1677 | __startTimestamp1474123139216__endTimestamp 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | ------- Summary: start ------- 07:38:59 INFO - PROCESS | 1677 | Number of tests: 14 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:129.30 Median:119.50 stddev:21.56 (18.0%) stddev-sans-first:21.70 07:38:59 INFO - PROCESS | 1677 | Values: 110.0 117.0 120.0 116.0 168.0 166.0 119.0 119.0 116.0 142.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:144.00 stddev:26.54 (18.4%) stddev-sans-first:25.78 07:38:59 INFO - PROCESS | 1677 | Values: 167.0 111.0 115.0 155.0 125.0 162.0 117.0 104.0 178.0 133.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:197.60 Median:199.00 stddev:2.72 (1.4%) stddev-sans-first:2.37 07:38:59 INFO - PROCESS | 1677 | Values: 202.0 199.0 195.0 194.0 194.0 199.0 200.0 199.0 198.0 196.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:81.60 Median:63.00 stddev:34.05 (54.1%) stddev-sans-first:35.76 07:38:59 INFO - PROCESS | 1677 | Values: 68.0 81.0 75.0 150.0 61.0 59.0 60.0 65.0 139.0 58.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:73.00 stddev:26.96 (36.9%) stddev-sans-first:27.42 07:38:59 INFO - PROCESS | 1677 | Values: 69.0 76.0 143.0 83.0 72.0 77.0 118.0 71.0 74.0 124.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:99.80 Median:88.00 stddev:16.28 (18.5%) stddev-sans-first:16.18 07:38:59 INFO - PROCESS | 1677 | Values: 116.0 121.0 91.0 89.0 90.0 87.0 91.0 113.0 78.0 122.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:107.00 Median:97.00 stddev:18.35 (18.9%) stddev-sans-first:19.18 07:38:59 INFO - PROCESS | 1677 | Values: 98.0 141.0 142.0 99.0 96.0 97.0 97.0 98.0 97.0 105.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:100.70 Median:91.00 stddev:22.53 (24.8%) stddev-sans-first:23.63 07:38:59 INFO - PROCESS | 1677 | Values: 91.0 95.0 97.0 91.0 92.0 91.0 75.0 142.0 142.0 91.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:66.40 Median:71.00 stddev:13.65 (19.2%) stddev-sans-first:13.84 07:38:59 INFO - PROCESS | 1677 | Values: 55.0 58.0 65.0 83.0 51.0 52.0 57.0 77.0 82.0 84.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:74.10 Median:75.50 stddev:7.55 (10.0%) stddev-sans-first:7.42 07:38:59 INFO - PROCESS | 1677 | Values: 66.0 71.0 69.0 76.0 79.0 75.0 70.0 87.0 64.0 84.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:84.00 stddev:10.23 (12.2%) stddev-sans-first:10.16 07:38:59 INFO - PROCESS | 1677 | Values: 97.0 84.0 84.0 75.0 103.0 77.0 84.0 74.0 92.0 98.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:150.50 stddev:20.55 (13.7%) stddev-sans-first:21.75 07:38:59 INFO - PROCESS | 1677 | Values: 149.0 136.0 140.0 132.0 146.0 147.0 162.0 157.0 205.0 152.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.40 Median:165.50 stddev:15.69 (9.5%) stddev-sans-first:16.63 07:38:59 INFO - PROCESS | 1677 | Values: 165.0 164.0 162.0 166.0 166.0 165.0 146.0 171.0 206.0 153.0 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:67.50 stddev:5.95 (8.8%) stddev-sans-first:6.16 07:38:59 INFO - PROCESS | 1677 | Values: 65.0 85.0 69.0 66.0 68.0 66.0 67.0 65.0 69.0 66.0 07:38:59 INFO - PROCESS | 1677 | -------- Summary: end -------- 07:38:59 INFO - PROCESS | 1677 | 07:38:59 INFO - TEST-INFO | 1677: exit 0 07:39:00 INFO - TEST-OK | kraken | took 60505ms 07:39:00 INFO - SUITE-END | took 1173s 07:39:00 INFO - Completed test suite (00:19:33) 07:39:00 INFO - javascript benchmark 07:39:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7585.402217460343, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3418.7265018798494, 3427.057559171229, 3453.1712389026625, 3465.081753335365, 3489.494023366612, 6000.879982400349, 6041.395860413956, 6059.212302400687, 6087.11736798164, 6099.5120390368775, 6033.064874944391, 6043.576949613529, 6073.423024812647, 6107.3587273336325, 6116.174316467275, 4639.791209395568, 5384.40771515134, 5390.973045134769, 5401.756920938567, 5459.044667183247, 5339.065663508871, 5390.21841832934, 5408.296921400232, 5446.101393270105, 5500.4499550045075, 5464.9180262296095, 5468.0977638689765, 5468.179773034037, 5468.91796623049, 5479.0959491684025, 5408.891822163574, 5451.2640793492965, 5464.26232458621, 5468.945310546884, 5477.726113694339, 5284.233786101032, 5347.385050719178, 5430.239766432703, 5439.428859969686, 5440.483154100354, 6044.24446944132, 6048.092786082079, 6062.242219722535, 6084.4828189603495, 6131.908021379683, 6109.34393638171, 6240.5346759818085, 6281.246250449975, 6282.78010269643, 6288.3397243289255, 6189.659568723719, 6357.427831495142, 6361.55469117162, 6361.936380636181, 6369.554131210817, 5142.254373115915, 6024.969875150597, 6117.755289788403, 6171.259448866164, 6184.938150618436, 6515.120458738104, 6517.087607734921, 6520.250171230275, 6521.902171467432, 6526.902096468557, 6168.136460895478, 6258.405451482102, 6264.624122552661, 6266.843328916813, 6273.749050038039, 6169.537284703575, 6211.751529938842, 6218.31598524162, 6303.716332765037, 6327.968360158262, 6212.534059945522, 6244.188255526752, 6246.531510136667, 6251.0, 6260.843478913063, 6114.0236562776045, 6229.0967809667345, 6255.624662520263, 6276.466500347522, 6277.52918531112, 5086.440491545927, 5108.080545501845, 5118.846434606968, 5188.818391356284, 5214.869628259324, 4546.090781843644, 4560.087982403533, 4600.723956562617, 4608.446986361658, 4624.398828152386, 5959.135925290809, 6108.847278817976, 6146.877062458815, 6152.0156774915795, 6166.167567378437, 6261.5616906816995, 6275.403836635513, 6275.435210830956, 6320.494360451153, 6384.48924086072, 5955.612885162451, 6112.00015953023, 6120.234970628671, 6128.509719222451, 6182.258129024546, 5664.943350566524, 5667.008273552195, 5668.7165641718575, 5672.262605861294, 5677.205191273225, 6338.271098823694, 6405.423511883952, 6446.258680251737, 6448.96816509375, 6578.967105164444, 6265.028920517327, 6322.241331040121, 6340.397662222082, 6344.080108384166, 6350.745970161327, 6388.840278992877, 6524.608523488606, 6570.868582628416, 6613.702383392855, 6627.1716035495565, 6227.626342419469, 6228.875422491434, 6257.374262573706, 6310.1796766420075, 6311.621302721851, 6341.4292713656, 6349.714262858275, 6358.014507751306, 6364.77723279683, 6373.0, 4945.8021679132435, 5053.29253904461, 6162.383761623981, 6262.15460912771, 6288.905666414916, 5951.494122999594, 6191.102290167811, 6226.719797609027, 6273.215848018997, 6290.2766181889665, 6017.789377371771, 6128.233970753656, 6160.260768707783, 6178.505719542336, 6227.937720622917, 5522.530584900329, 6145.340757995227, 6172.351903050295, 6188.412100850412, 6250.593711408744, 6182.536309776695, 6209.161763162096, 6240.625562466266, 6256.061590761422, 6283.2774230962195], "value": 5864.6571213641955, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.5901058658367, 528.6458073091027, 536.03246140716, 544.858521397672, 578.0635370699467, 33535.32929341411, 43371.56628433715, 43399.349009764876, 43401.13197736047, 44659.0, 44697.106057878904, 45021.0, 45063.54936450635, 45147.0, 45188.548114518846, 9769.951150244256, 9838.655647052354, 9866.358686685378, 9947.651832185875, 9964.701058968243, 9587.0, 9741.805163896717, 9748.853767193463, 9750.07374299444, 9796.853047204298, 9730.951345243264, 9766.804663906718, 9775.706728798112, 9779.168770654504, 9813.705588832347, 9603.279754018477, 9639.614415423375, 9688.176504997084, 9752.804943901083, 9787.265955053328, 9657.082577155159, 9720.416774993522, 9771.804563908752, 9806.117449429586, 9827.066428689262, 42359.788201059106, 42396.78801606003, 42726.786366068285, 42761.57238427607, 42809.929751756456, 3437.4843773433936, 3511.7541772075965, 3551.4140166872435, 3589.73077019222, 3757.906052348686, 32438.351232975445, 32883.0, 32891.0, 32947.67052329494, 32991.50512742311, 3208.631007434128, 3264.4940034294723, 3268.1176082457605, 3343.8160901150427, 3377.307651931348, 549132.0, 549461.0, 552310.0, 554471.0, 554942.9999999959, 67038.66480667566, 67537.66231168812, 67538.66230668816, 67609.0, 67642.66178669075, 36442.90671279866, 36524.086897827765, 37155.6995505156, 38254.0, 38326.42510362347, 17708.380206692706, 17828.019458929622, 17838.286468541373, 17840.643187136186, 17912.462626121236, 13367.264800436069, 13461.0, 13476.326183690777, 13480.730385392433, 13528.59414217575, 20646.277380291616, 20997.68503472449, 21006.894965525076, 21064.684029739565, 21089.050992705364, 8244.958775206085, 8414.15858414166, 8426.915730842613, 8438.282745966539, 8478.83042339162, 889.1597440418872, 953.2850362228329, 1018.7504061504831, 1032.5611615063717, 1051.6056478820442, 32071.679283207337, 32233.677663223538, 32328.0, 32518.512222316684, 32894.0, 1082.1613249731522, 1106.6292791914639, 1133.0878642692546, 1140.6654214568973, 1217.8599461061915, 16383.508494745176, 16786.992780433215, 16823.495295141165, 17230.483085507454, 17887.10564471771, 533673.0, 626649.0, 627567.0, 738716.9192462118, 940110.0, 65780.0, 66497.0, 66508.0, 66765.0, 67110.66444667746, 1424.7364237616075, 1439.5105664074265, 1454.342050062929, 1458.7738900470445, 1463.41463414635, 31463.52804707977, 33564.0, 33660.66339336673, 33693.49459758154, 34032.829835851655, 56865.578360541316, 57136.1429578565, 57577.0, 57614.13578796405, 57665.0, 621820.0, 624357.0, 626212.0, 627249.0, 631563.0, 28542.286442839097, 28960.0, 28968.275793105342, 29337.706622933496, 29517.26206844846, 51359.0, 51850.740746296025, 51918.0, 52453.68865778233, 52652.736736316074, 41809.79095104505, 42742.358864616435, 43058.138837223705, 44252.114957700025, 44346.55653443424, 42507.7874610625, 42731.0, 43040.569594303655, 43464.565354347316, 44409.33385999148, 41258.0, 41563.0, 42431.575684242765, 42543.57456425396, 44071.89820254391, 41112.58887411207, 43766.0, 44774.77612611916, 45416.77291613521, 47198.0, 32548.83725581357, 33113.66886331106, 33665.495017575246, 34294.48558271678, 34688.65311346854], "value": 21393.46085716803, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.5249511862612, 383.9520059992501, 386.7626122996344, 393.28029705638676, 412.6884202427167, 15506.457273995413, 20055.398338049865, 22346.21788237412, 22488.55022899541, 22525.774742252594, 21422.892885535588, 21479.248226312084, 21716.782832171673, 22143.667844982338, 22431.55136897261, 5913.615614985012, 6160.7535698572, 6186.536009799283, 6191.133241346192, 6203.782867599635, 5972.343042265347, 5984.192134061889, 5992.370801065891, 6062.818115456544, 6109.266887973449, 5898.7935422260225, 5987.311459182206, 6002.339742628329, 6077.817665470021, 6101.267847858263, 5660.971695141539, 5785.855353616151, 5944.167816505693, 6062.545309101813, 6087.147799308101, 5759.769609215627, 5814.563907706896, 5840.47435730782, 5866.178734977107, 5914.0, 12849.229046257255, 13426.32868356588, 13607.931960340135, 26385.73614263852, 27035.0, 2362.9054837806466, 2631.1974847671454, 2689.5965605159186, 3085.5217441296636, 3117.9064628061196, 10267.897321026843, 13047.739045219043, 15498.147601881892, 16123.274452649659, 16267.91866040674, 1636.2882146266413, 1867.3090956346239, 1885.1328388941104, 1893.4508992392189, 1913.339897735279, 93632.0, 111980.0, 121300.0, 133619.0, 135495.6450435493, 10111.797764044677, 11535.192536522363, 11651.53393864253, 11761.176717629854, 11900.464479098462, 14799.334029968462, 14869.330880110421, 14925.029873058216, 14951.775723364159, 14970.775438368433, 8818.955905220433, 8908.10918908104, 9023.639054437748, 9099.317551183556, 9206.677766278148, 9121.133492318218, 9143.725688229364, 9151.084891510796, 9197.77005574866, 9221.262299016063, 4608.28571571407, 4636.235021221528, 4705.835295764632, 4721.291806229093, 4829.420469543677, 1945.8735182213395, 1990.8506861985409, 2041.6631255842917, 2067.9069441875154, 2069.617120832651, 831.9334453243847, 840.890025166774, 891.5943245823134, 982.4350998175935, 1113.9220254582262, 17982.550436238937, 22833.771662283165, 22990.080396784277, 23150.958206880394, 23179.884100579387, 1001.4928471858295, 1024.8975102489692, 1035.1460045462522, 1036.7719101797593, 1051.542578978147, 9628.422294662343, 11278.605248816251, 11691.707707307385, 11796.351200683874, 11837.88162118385, 644226.0, 655263.0, 661203.0, 665573.0, 665906.3409365844, 134752.32623837015, 135072.0, 138641.0, 141092.0, 141638.2918085403, 881.9647214111363, 993.3096497934011, 1041.6510468992822, 1054.9683509494728, 1058.4496062047847, 19802.60394792125, 19941.401757947286, 20583.8970805145, 20664.48338791542, 20728.0, 52571.73714131405, 53392.73303633457, 53493.19760203522, 54444.18333725077, 55250.171247432125, 74947.62526187334, 76158.6192069036, 77851.61074194593, 78659.60670196613, 80994.5950270245, 8250.174982501941, 8293.129221431904, 8496.53269070207, 8499.065102838558, 8637.093105223861, 9315.673951411669, 11167.497462614365, 13086.672833179247, 13510.594682159157, 13901.930490347484, 11512.769744604893, 11576.88423115758, 11750.177487575787, 11936.880631193577, 12011.939940300243, 8735.645974873905, 10638.308509946828, 10923.781524369628, 11449.0, 11813.881861181279, 5836.737346819318, 9571.617135314787, 11440.370779607201, 11662.0, 11956.163068585116, 6597.868042639217, 8246.587670616565, 8390.74138879173, 8536.189062039097, 8729.607167677343, 9431.57557909882, 10487.790244195226, 10506.474676266003, 11908.880911191123, 12086.0], "value": 9870.317337739361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.9096454212747, 1575.4485929924529, 1828.0, 1860.246600126949, 1933.39098184072, 8155.0, 8642.481451112937, 8759.0, 9261.907380926197, 9285.628574857006, 8912.732618021453, 9091.863622045657, 9122.315826313037, 9124.54377281135, 9130.589123489442, 2390.1753894906274, 2448.889799958997, 2450.5098980203943, 2451.6077427611585, 2459.766322199388, 2418.9516209675794, 2439.377958620549, 2449.3998970252223, 2461.163204510466, 2463.4210960424284, 2397.0771253067605, 2431.869180830913, 2441.914532991346, 2458.557459657261, 2460.5817011108065, 2365.361352434833, 2433.2578563538113, 2440.2191298784396, 2441.4872876695913, 2445.938851528717, 2156.921539230385, 2393.4734358441115, 2415.975840241593, 2435.0868424340874, 2440.9389765255833, 9148.542572871329, 9174.403663761834, 9240.0, 9249.815003699956, 9273.97986221515, 5302.257683924254, 5426.104692725735, 5488.4237155098535, 5498.1752737089355, 5501.5323697485765, 3229.950266163506, 3542.220711443504, 3672.4124140137715, 3695.833687484043, 3710.962890371116, 1086.4513420722485, 1104.845321654969, 1119.52979748505, 1132.365875109941, 1133.0991861470152, 11622.70943226426, 11834.467448964817, 11848.526058957717, 11863.881361186364, 11873.16887817853, 4726.754598616751, 4766.95233047672, 4793.520647935178, 4884.3161957326, 4912.631552633567, 9956.80086398268, 10005.549750261256, 10014.599416023424, 10032.197424206046, 10035.34770239932, 5426.9185962210595, 5429.782808687608, 5438.537724293479, 5442.782288708487, 5449.3460784706085, 4495.505494395614, 4788.760561971888, 4909.189983652657, 4937.061958227925, 4976.825811096666, 3710.220853620716, 3993.381025940984, 4002.139539998914, 4059.3505039193587, 4089.488813898263, 1314.2771475688332, 1322.340462719281, 1327.0909427042507, 1334.9866501334861, 1345.7981302804658, 941.2940294778916, 955.3455882720359, 965.6813251626946, 978.3885071830106, 1020.06797135849, 5703.17303990919, 5888.941110588925, 5952.940470595326, 6026.789062382795, 6033.969830150909, 442.2172754225004, 445.6167695781625, 446.4999200895018, 453.745902294716, 463.5017356341946, 3361.4957756336744, 3503.649635036527, 3692.1692619160476, 3997.2804895118575, 4094.9181016379507, 10778.29941053829, 10962.945185274022, 11053.502592383362, 11135.777284454265, 11187.32876027441, 10758.462076896123, 10968.17738669603, 11100.0, 11108.722281943015, 11189.664310070712, 7201.603911784902, 8010.679572817138, 8491.108433614505, 8575.3997220194, 8608.354373422018, 5946.870094681996, 5965.433283838029, 6055.212822333068, 6107.328193898757, 6206.317305096526, 10071.34536255141, 10214.897851021395, 10286.434246116236, 10301.9484902575, 10404.47977601132, 10272.0, 10387.480625968823, 10469.31949423285, 10505.579776809147, 10611.787764244826, 7166.928330716834, 7623.885641715489, 7786.026746656668, 7842.411819113703, 7867.567283799446, 8620.568971551524, 8735.257503112307, 8808.5595720215, 8825.646974120964, 8827.382083254368, 7643.121041080124, 7779.649915753918, 7847.84304313922, 7868.645910934136, 7916.960415197887, 7613.885791713239, 7701.345385642283, 7703.730369437043, 7705.1524332324525, 7773.650185741542, 7043.823904402431, 7046.682899269443, 7109.075820143556, 7117.789975704246, 7176.318249766264, 4839.806407743651, 4982.427020892644, 5021.196608542616, 5122.436531981553, 5258.8159414420315, 5015.724135172601, 5065.113605119016, 5095.312132862016, 5129.769160387867, 5169.4830516949505], "value": 4619.927589622984, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.27711984841295, 302.4918137528952, 310.22134442549196, 317.7061218373005, 318.4093506545357, 4727.929081063782, 5395.271638328824, 5472.0, 5545.944540554593, 5656.529302381379, 4625.953740462594, 5494.945050549493, 5532.419095994923, 5556.972215138919, 5589.273394458714, 3898.7465814722095, 3964.8414063437426, 4289.142171565683, 4324.740515569068, 4328.761918094504, 3618.6200448952877, 4145.523264824538, 4235.009277726507, 4245.384419259206, 4332.696711230215, 3502.807345595991, 3656.433252845813, 3877.592852750463, 4215.978920105395, 4269.5943885331, 3672.0085576894357, 4167.895802604929, 4241.809118589671, 4242.893927651802, 4274.397101087093, 3692.5568931728094, 3703.6481534254203, 3714.851405943786, 3716.4982727332003, 3720.2931443025736, 4396.472423309191, 5259.815906443256, 5372.597055220835, 5384.734587371964, 5435.755391007414, 3977.6817854571855, 4847.733374664392, 4909.459959404463, 4915.459299477055, 4942.233953737176, 4867.853964381075, 5664.971675141639, 5881.323647780518, 5891.8527036824, 5917.585768996172, 3718.4050551911564, 3756.7370284080125, 4047.817848196838, 4074.6740260779065, 4088.8160032798596, 5604.8318550443555, 6152.415520525543, 6216.720247588869, 6221.471174950135, 6277.968610156966, 3888.2223555289056, 4121.91756164875, 4122.690798190148, 4125.102452871713, 4182.707210495297, 3980.3233450313514, 4185.455890734185, 4194.140201258735, 4214.576618444406, 4220.050488640093, 4142.523609784913, 4525.253333200051, 4883.365162528919, 4895.461499235082, 4927.743425426494, 4792.568668819823, 5435.266239057755, 5556.472135147154, 5891.499222566044, 6069.817905462844, 1274.8024056271108, 1416.2564653556965, 1424.3946322812762, 1428.5071650280636, 1429.2353590828857, 848.2620120495085, 878.2403221213605, 885.9246964008003, 889.666375109334, 895.5656506594297, 4428.689991700615, 4513.458384993822, 4552.996681460147, 4647.697899636512, 4686.203345431217, 4373.6282415994365, 4854.063291708536, 4878.829240976549, 5049.722265275445, 5064.924026139611, 4095.6518695910586, 4142.813573389205, 4190.266703326966, 4439.911201775946, 4666.883327916829, 2075.2529089527757, 2321.409834263535, 2321.8955147018423, 2559.4497183105723, 2672.906448274301, 4344.978275108604, 4480.798364073625, 4485.394471746337, 5065.417476990119, 5255.605829562722, 4788.928166077511, 4957.405111386584, 5015.899682006339, 5203.089459344599, 5307.681539107666, 5083.669561478492, 5769.624974376652, 6290.339514351019, 6315.305316415191, 6367.840803979938, 5848.941510584839, 6153.753849845956, 6168.9691551541955, 6180.969095154495, 6182.536309776875, 4891.877703057452, 5284.524392804666, 6030.909536357046, 6030.969845150922, 6115.483596931693, 5203.791848326025, 5318.654287471302, 5719.056355701429, 5742.626729262584, 6368.172137622079, 3862.24686186191, 4035.6771458283856, 4064.30906745848, 4065.8576949806616, 4074.1851629673592, 5708.373113662587, 5857.941420585739, 6124.448799608057, 6169.537284703575, 6217.316095229431, 5391.568674505952, 5456.945430545803, 5781.21953536284, 5910.556708246812, 5911.290645122441, 5059.772310245974, 5707.68607726579, 5947.31605865331, 5965.642061476326, 6002.819915402545, 4592.816287348469, 4938.949512295079, 5273.6572122811895, 5793.073108302651, 5831.591788574929, 5938.732757025858, 5960.672163031075, 5991.2510936132985, 6004.519638428827, 6012.068129439944, 5520.447955204448, 5755.158349328342, 5794.2177805997335, 5859.58982871212, 5867.618604790675], "value": 4127.4641738112705, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.9006534117894, 757.9355754760845, 783.5690370296337, 790.9209079092092, 842.1830824100621, 15514.689706205869, 16005.519834404957, 16413.425530106433, 16530.09084500352, 16809.0, 14996.250187490636, 15303.234838258099, 15308.69382612347, 15327.463538776145, 15355.616109597266, 715.3347386930153, 886.3883920095146, 901.0, 917.8760867282931, 917.9947956987088, 755.6523998960485, 801.5030680977773, 908.1871724806294, 923.6767131503954, 926.0693003531442, 944.2351695126935, 965.5944503308592, 968.7626531499792, 968.8498282766183, 969.2536746705033, 936.3539157980971, 958.5590628310986, 959.4003747657714, 960.6205548808251, 961.6345788600357, 699.2692636195137, 706.9964650176768, 707.6567864585672, 710.0343532795395, 712.0138608027919, 15483.767743483857, 16012.0, 16132.435364762177, 16169.595760105974, 16245.51263462098, 13389.598312050653, 13449.0, 13484.0, 13577.0, 14021.5092471763, 13032.348382580833, 13517.256550889795, 13564.593062208149, 13807.309634518335, 14077.929610351985, 4633.513481084505, 4681.274402467623, 4681.391419115494, 4695.436547614274, 4699.835505757282, 123154.38422807919, 124038.37980810128, 126266.73733262642, 128665.35667321697, 129724.0, 11029.558817647205, 11178.105751539857, 11259.211855170059, 11332.433378331216, 11395.544178232944, 18523.0, 21460.141594336365, 21765.673514897288, 21888.89055554712, 22166.113355465925, 8084.434089613671, 8103.230193131761, 8174.509529428254, 8220.671173153007, 8370.707025254087, 4409.647228221735, 4493.146302202571, 4605.907881842411, 4637.652176086807, 4665.696729712626, 3548.840302186408, 3637.0725465006253, 3649.1242101895887, 3667.4865518827387, 3672.8714494992546, 599.4065874783932, 658.1476987301456, 668.3316683316683, 676.8172593399754, 685.1538350137577, 502.30430853267757, 525.4876495416938, 622.6699849079995, 630.3633330336394, 633.395107672172, 8357.788120722584, 8483.879030242439, 8557.914420855835, 8613.69852055175, 8923.821523569492, 585.2216551985848, 600.6456190847333, 600.882827848574, 687.3538873458932, 702.1784512120831, 4340.131973605292, 4498.100379924028, 4525.524819893863, 4558.840440584564, 4561.703489273186, 143624.0, 148783.25608371888, 149794.0, 152498.0, 155612.0, 114593.0, 123453.38273308576, 127100.36449817692, 127919.72080279264, 130639.0, 100819.49590252002, 104077.83688652521, 105367.0, 111201.0, 112574.0, 9789.199396146767, 9863.457509837028, 9937.503124843874, 10077.294589378673, 10113.696589102045, 46763.0, 47982.76008619935, 57714.71142644428, 61156.69421653041, 62021.68989155025, 91950.6207406876, 92721.0, 92834.53582732043, 94097.529512352, 94227.05772942182, 13308.800367994294, 13460.326983650973, 13462.394192261176, 13640.317984100953, 14090.36593353281, 15057.17185554805, 15397.84602154009, 15474.994125381814, 15606.843931560994, 15623.609409764393, 13515.121517101357, 13606.319684015958, 13758.449662013407, 14424.278786060446, 14677.55967320982, 13908.235047072509, 14421.351039203471, 14784.260786960824, 15036.774448383501, 15044.849551504345, 13653.86346136526, 14387.136771793726, 14421.495247666282, 14560.271986400849, 14562.0, 11144.85226510282, 11476.827847582126, 11797.469113890067, 11817.645470635895, 11895.82156267674, 11588.362640054878, 11689.240199387013, 11734.178607497393, 11846.348450835287, 11921.940390297994], "value": 8065.857230613722, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 117.0, 120.0, 116.0, 168.0, 166.0, 119.0, 119.0, 116.0, 142.0], "value": 129.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 111.0, 115.0, 155.0, 125.0, 162.0, 117.0, 104.0, 178.0, 133.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 199.0, 195.0, 194.0, 194.0, 199.0, 200.0, 199.0, 198.0, 196.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 81.0, 75.0, 150.0, 61.0, 59.0, 60.0, 65.0, 139.0, 58.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 143.0, 83.0, 72.0, 77.0, 118.0, 71.0, 74.0, 124.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 121.0, 91.0, 89.0, 90.0, 87.0, 91.0, 113.0, 78.0, 122.0], "value": 99.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 141.0, 142.0, 99.0, 96.0, 97.0, 97.0, 98.0, 97.0, 105.0], "value": 107.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 97.0, 91.0, 92.0, 91.0, 75.0, 142.0, 142.0, 91.0], "value": 100.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 65.0, 83.0, 51.0, 52.0, 57.0, 77.0, 82.0, 84.0], "value": 66.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 69.0, 76.0, 79.0, 75.0, 70.0, 87.0, 64.0, 84.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 84.0, 84.0, 75.0, 103.0, 77.0, 84.0, 74.0, 92.0, 98.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 136.0, 140.0, 132.0, 146.0, 147.0, 162.0, 157.0, 205.0, 152.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 162.0, 166.0, 166.0, 165.0, 146.0, 171.0, 206.0, 153.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 85.0, 69.0, 66.0, 68.0, 66.0, 67.0, 65.0, 69.0, 66.0], "value": 68.6, "unit": "score"}]}]} 07:39:00 INFO - Return code: 0 07:39:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:39:00 ERROR - # TBPL SUCCESS # 07:39:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:39:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:39:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:39:00 INFO - 2016-09-17 07:39:00.549 screenresolution[1682:11028] starting screenresolution argv=screenresolution get 07:39:00 INFO - 2016-09-17 07:39:00.551 screenresolution[1682:11028] Display 0: 1600x1200x32@60 07:39:00 INFO - 2016-09-17 07:39:00.557 screenresolution[1683:11031] starting screenresolution argv=screenresolution list 07:39:00 INFO - Available Modes on Display 0 07:39:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:39:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:39:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:39:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:39:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:39:00 INFO - Intel Iris: 07:39:00 INFO - Chipset Model: Intel Iris 07:39:00 INFO - Type: GPU 07:39:00 INFO - Bus: Built-In 07:39:00 INFO - VRAM (Dynamic, Max): 1536 MB 07:39:00 INFO - Vendor: Intel (0x8086) 07:39:00 INFO - Device ID: 0x0a2e 07:39:00 INFO - Revision ID: 0x0009 07:39:00 INFO - Displays: 07:39:00 INFO - Display: 07:39:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:39:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:39:00 INFO - Display Serial Number: 1600x1200 60 07:39:00 INFO - Main Display: Yes 07:39:00 INFO - Mirror: Off 07:39:00 INFO - Online: Yes 07:39:00 INFO - Rotation: Supported 07:39:00 INFO - Return code: 0 07:39:00 INFO - Running post-action listener: _resource_record_post_action 07:39:00 INFO - [mozharness: 2016-09-17 14:39:00.792447Z] Finished run-tests step (success) 07:39:00 INFO - Running post-run listener: _resource_record_post_run 07:39:00 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 31439872; Write bytes: 667063808; Read time: 368; Write time: 2041 07:39:00 INFO - TinderboxPrint: CPU usage
54.4% 07:39:00 INFO - TinderboxPrint: I/O read bytes / time
31,439,872 / 368 07:39:00 INFO - TinderboxPrint: I/O write bytes / time
667,063,808 / 2,041 07:39:00 INFO - TinderboxPrint: CPU idle
2,200.2 (45.6%) 07:39:00 INFO - TinderboxPrint: CPU system
982.1 (20.3%) 07:39:00 INFO - TinderboxPrint: CPU user
1,647.3 (34.1%) 07:39:00 INFO - TinderboxPrint: Swap in / out
391,737,344 / 0 07:39:00 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 353071616; Write bytes: 347063808; Read time: 27328; Write time: 661 07:39:00 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30497792; Write bytes: 300126208; Read time: 354; Write time: 1326 07:39:01 INFO - Running post-run listener: _upload_blobber_files 07:39:01 INFO - Blob upload gear active. 07:39:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:39:01 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:39:01 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:39:01 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:39:01 INFO - (blobuploader) - INFO - Open directory for files ... 07:39:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:39:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: 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 - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:39:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:04 INFO - (blobuploader) - INFO - Done attempting. 07:39:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:39:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:39:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:06 INFO - (blobuploader) - INFO - Done attempting. 07:39:06 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:06 INFO - Return code: 0 07:39:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa3a218ff4c35753e7878f47032fe44932b4b6a716d58dad571379d85110ffe4363411ba7009a5ec638e93ed0db1199bd47f9a73c5976f7a23af9c5a8f3b256b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82bdc0028fa7a6852904f33fc8305242eda8536f08c07d0d29b1ed5417f9e2f86bbe00e567b62040bcc9a2fb1bfe9150efc42707893e9d423d5f48b30c8c09d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e0e1d1512887afdabc469c0812cdb012e4e5ad97cd91da7a9e9823cadaaef7496e96355d5ae3f1fa7cb5323a1e9bf423efe94c796d33b5defa3e9c4414a4d4f"} 07:39:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:06 INFO - Contents: 07:39:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa3a218ff4c35753e7878f47032fe44932b4b6a716d58dad571379d85110ffe4363411ba7009a5ec638e93ed0db1199bd47f9a73c5976f7a23af9c5a8f3b256b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82bdc0028fa7a6852904f33fc8305242eda8536f08c07d0d29b1ed5417f9e2f86bbe00e567b62040bcc9a2fb1bfe9150efc42707893e9d423d5f48b30c8c09d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e0e1d1512887afdabc469c0812cdb012e4e5ad97cd91da7a9e9823cadaaef7496e96355d5ae3f1fa7cb5323a1e9bf423efe94c796d33b5defa3e9c4414a4d4f"} 07:39:06 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:06 INFO - Copying logs to upload dir... 07:39:06 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.160781 ========= master_lag: 0.45 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:39:07.146587) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-17 07:39:07.150428) ========= 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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa3a218ff4c35753e7878f47032fe44932b4b6a716d58dad571379d85110ffe4363411ba7009a5ec638e93ed0db1199bd47f9a73c5976f7a23af9c5a8f3b256b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82bdc0028fa7a6852904f33fc8305242eda8536f08c07d0d29b1ed5417f9e2f86bbe00e567b62040bcc9a2fb1bfe9150efc42707893e9d423d5f48b30c8c09d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e0e1d1512887afdabc469c0812cdb012e4e5ad97cd91da7a9e9823cadaaef7496e96355d5ae3f1fa7cb5323a1e9bf423efe94c796d33b5defa3e9c4414a4d4f"} 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.010991 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa3a218ff4c35753e7878f47032fe44932b4b6a716d58dad571379d85110ffe4363411ba7009a5ec638e93ed0db1199bd47f9a73c5976f7a23af9c5a8f3b256b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82bdc0028fa7a6852904f33fc8305242eda8536f08c07d0d29b1ed5417f9e2f86bbe00e567b62040bcc9a2fb1bfe9150efc42707893e9d423d5f48b30c8c09d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e0e1d1512887afdabc469c0812cdb012e4e5ad97cd91da7a9e9823cadaaef7496e96355d5ae3f1fa7cb5323a1e9bf423efe94c796d33b5defa3e9c4414a4d4f"}' ========= master_lag: 2.71 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-17 07:39:09.870287) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:09.870612) ========= 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.CAYR71vx9Z/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.ZaDxzcvcQT/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.004670 ========= master_lag: 0.14 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:10.010299) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:10.010603) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:10.096739) ========= ========= Total master_lag: 3.36 =========