builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0375 starttime: 1474121884.8 results: success (0) buildid: 20160917071801 builduid: bf21b1e765484cdb91e3f9ee5830a9b9 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.805096) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.811899) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.812204) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.859185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.859462) ========= 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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:04-- 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% 61.3M=0s 2016-09-17 07:18:05 (61.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.567969 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.450454) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.450733) ========= 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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.025416 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.498620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:05.498927) ========= 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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:05,583 truncating revision to first 12 chars 2016-09-17 07:18:05,584 Setting DEBUG logging. 2016-09-17 07:18:05,584 attempt 1/10 2016-09-17 07:18:05,584 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:06,949 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=1.625604 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 07:18:07.148358) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:07.148668) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:07.149037) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:07.149325) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:07.167672) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:18:07.167938) ========= /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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:07 INFO - MultiFileLogger online at 20160917 07:18:07 in /builds/slave/test 07:18:07 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:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:07 INFO - {'append_to_log': False, 07:18:07 INFO - 'base_work_dir': '/builds/slave/test', 07:18:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:07 INFO - 'branch': 'Mozilla-Aurora', 07:18:07 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:07 INFO - 'config_files': ('talos/mac_config.py',), 07:18:07 INFO - 'default_actions': ('clobber', 07:18:07 INFO - 'read-buildbot-config', 07:18:07 INFO - 'download-and-extract', 07:18:07 INFO - 'populate-webroot', 07:18:07 INFO - 'create-virtualenv', 07:18:07 INFO - 'install', 07:18:07 INFO - 'run-tests'), 07:18:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:07 INFO - 'download_minidump_stackwalk': True, 07:18:07 INFO - 'download_symbols': 'ondemand', 07:18:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:07 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:07 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:07 INFO - 'installer_path': 'installer.exe', 07:18:07 INFO - 'log_level': 'info', 07:18:07 INFO - 'log_name': 'talos', 07:18:07 INFO - 'log_to_console': True, 07:18:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:07 INFO - 'opt_config_files': (), 07:18:07 INFO - 'pip_index': False, 07:18:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:07 INFO - 'cmd': ('bash', 07:18:07 INFO - '-c', 07:18:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:07 INFO - 'enabled': True, 07:18:07 INFO - 'halt_on_failure': False, 07:18:07 INFO - 'name': 'check_screen_resolution'},), 07:18:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:07 INFO - 'cmd': ('bash', 07:18:07 INFO - '-c', 07:18:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:07 INFO - 'enabled': True, 07:18:07 INFO - 'halt_on_failure': False, 07:18:07 INFO - 'name': 'check_screen_resolution'},), 07:18:07 INFO - 'run_cmd_checks_enabled': True, 07:18:07 INFO - 'sps_profile': False, 07:18:07 INFO - 'sps_profile_interval': 0, 07:18:07 INFO - 'suite': 'dromaeojs-e10s', 07:18:07 INFO - 'system_bits': '32', 07:18:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:07 INFO - 'title': 't-yosemite-r7-0375', 07:18:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:07 INFO - 'use_talos_json': True, 07:18:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:07 INFO - 'work_dir': 'build'} 07:18:07 INFO - [mozharness: 2016-09-17 14:18:07.361388Z] Running clobber step. 07:18:07 INFO - Running pre-action listener: _resource_record_pre_action 07:18:07 INFO - Running main action method: clobber 07:18:07 INFO - rmtree: /builds/slave/test/build 07:18:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:09 INFO - Running post-action listener: _resource_record_post_action 07:18:09 INFO - [mozharness: 2016-09-17 14:18:09.486480Z] Finished clobber step (success) 07:18:09 INFO - [mozharness: 2016-09-17 14:18:09.486556Z] Running read-buildbot-config step. 07:18:09 INFO - Running pre-action listener: _resource_record_pre_action 07:18:09 INFO - Running main action method: read_buildbot_config 07:18:09 INFO - Using buildbot properties: 07:18:09 INFO - { 07:18:09 INFO - "project": "", 07:18:09 INFO - "product": "firefox", 07:18:09 INFO - "script_repo_revision": "production", 07:18:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:18:09 INFO - "repository": "", 07:18:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:18:09 INFO - "buildid": "20160917071801", 07:18:09 INFO - "stage_platform": "macosx64", 07:18:09 INFO - "basedir": "/builds/slave/test", 07:18:09 INFO - "buildnumber": 26, 07:18:09 INFO - "platform": "yosemite_r7", 07:18:09 INFO - "mozci_request": { 07:18:09 INFO - "priority": -1, 07:18:09 INFO - "type": "trigger_all_talos_jobs", 07:18:09 INFO - "times": 6 07:18:09 INFO - }, 07:18:09 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:18:09 INFO - "slavebuilddir": "test", 07:18:09 INFO - "branch": "mozilla-aurora", 07:18:09 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:18:09 INFO - "slavename": "t-yosemite-r7-0375", 07:18:09 INFO - "builduid": "bf21b1e765484cdb91e3f9ee5830a9b9", 07:18:09 INFO - "repo_path": "releases/mozilla-aurora" 07:18:09 INFO - } 07:18:09 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:09 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:09 INFO - Running post-action listener: _resource_record_post_action 07:18:09 INFO - [mozharness: 2016-09-17 14:18:09.490442Z] Finished read-buildbot-config step (success) 07:18:09 INFO - [mozharness: 2016-09-17 14:18:09.490582Z] Running download-and-extract step. 07:18:09 INFO - Running pre-action listener: _resource_record_pre_action 07:18:09 INFO - Running main action method: download_and_extract 07:18:09 INFO - mkdir: /builds/slave/test/build/tests 07:18:09 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:09 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:09 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:09 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:09 INFO - Downloaded 1199 bytes. 07:18:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.mac.test_packages.json 07:18:09 INFO - Using the following test package requirements: 07:18:09 INFO - {u'common': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:09 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.cppunittest.tests.zip'], 07:18:09 INFO - u'gtest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.gtest.tests.zip'], 07:18:09 INFO - u'jittest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'jsshell-mac.zip'], 07:18:09 INFO - u'mochitest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.mochitest.tests.zip'], 07:18:09 INFO - u'mozbase': [u'firefox-50.0a2.en-US.mac.common.tests.zip'], 07:18:09 INFO - u'reftest': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.reftest.tests.zip'], 07:18:09 INFO - u'talos': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.talos.tests.zip'], 07:18:09 INFO - u'web-platform': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.web-platform.tests.zip'], 07:18:09 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.mac.common.tests.zip', 07:18:09 INFO - u'firefox-50.0a2.en-US.mac.xpcshell.tests.zip']} 07:18:09 INFO - Downloading packages: [u'firefox-50.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:18:09 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:09 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:09 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:09 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:09 INFO - Downloaded 16309409 bytes. 07:18:09 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:09 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:12 INFO - Return code: 0 07:18:12 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:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:12 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:12 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:12 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:13 INFO - Downloaded 16309409 bytes. 07:18:13 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:13 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:16 INFO - Return code: 0 07:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:16 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:16 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:16 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:17 INFO - Downloaded 14413740 bytes. 07:18:17 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:17 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:17 INFO - Return code: 0 07:18:17 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:17 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:17 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:17 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:19 INFO - Downloaded 115349660 bytes. 07:18:19 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:19 INFO - mkdir: /builds/slave/test/properties 07:18:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:19 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:19 INFO - Contents: 07:18:19 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1474068498/firefox-50.0a2.en-US.mac.dmg 07:18:20 INFO - Running post-action listener: _resource_record_post_action 07:18:20 INFO - Running post-action listener: set_extra_try_arguments 07:18:20 INFO - [mozharness: 2016-09-17 14:18:20.118099Z] Finished download-and-extract step (success) 07:18:20 INFO - [mozharness: 2016-09-17 14:18:20.118250Z] Running populate-webroot step. 07:18:20 INFO - Running pre-action listener: _resource_record_pre_action 07:18:20 INFO - Running main action method: populate_webroot 07:18:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:20 INFO - '--tppagecycles', 07:18:20 INFO - '7'], 07:18:20 INFO - 'tests': ['tsvgm']}}, 07:18:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['damp', 'tps']}, 07:18:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'tests': ['damp', 'tps']}, 07:18:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['dromaeo_dom']}, 07:18:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['basic_compositor_video']}, 07:18:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:18:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['a11yr', 07:18:20 INFO - 'ts_paint', 07:18:20 INFO - 'tpaint', 07:18:20 INFO - 'sessionrestore', 07:18:20 INFO - 'sessionrestore_no_auto_restore', 07:18:20 INFO - 'tabpaint']}, 07:18:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['tsvgx', 07:18:20 INFO - 'tsvgr_opacity', 07:18:20 INFO - 'tart', 07:18:20 INFO - 'tscrollx', 07:18:20 INFO - 'cart']}, 07:18:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:20 INFO - 'tsvgr_opacity', 07:18:20 INFO - 'tart', 07:18:20 INFO - 'tscrollx', 07:18:20 INFO - 'cart']}, 07:18:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--disable-e10s'], 07:18:20 INFO - 'tests': ['tp5o']}, 07:18:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'tests': ['tp5o']}, 07:18:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--disable-e10s', 07:18:20 INFO - '--xperf_path', 07:18:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:20 INFO - 'tests': ['tp5n']}, 07:18:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:20 INFO - 'talos_options': ['--xperf_path', 07:18:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:20 INFO - 'tests': ['tp5n']}}, 07:18:20 INFO - 'talos.zip': {'path': '', 07:18:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:20 INFO - Running post-action listener: _resource_record_post_action 07:18:20 INFO - [mozharness: 2016-09-17 14:18:20.138138Z] Finished populate-webroot step (success) 07:18:20 INFO - [mozharness: 2016-09-17 14:18:20.138273Z] Running create-virtualenv step. 07:18:20 INFO - Running pre-action listener: _resource_record_pre_action 07:18:20 INFO - Running main action method: create_virtualenv 07:18:20 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:20 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:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:20 INFO - Using real prefix '/tools/python27' 07:18:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:20 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:23 INFO - Installing pip.................done. 07:18:23 INFO - Return code: 0 07:18:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:23 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 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:23 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:23 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:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:23 INFO - 'HOME': '/Users/cltbld', 07:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:23 INFO - 'LOGNAME': 'cltbld', 07:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:23 INFO - 'MOZ_NO_REMOTE': '1', 07:18:23 INFO - 'NO_EM_RESTART': '1', 07:18:23 INFO - 'PAGER': '/bin/cat', 07:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:23 INFO - 'PWD': '/builds/slave/test', 07:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:23 INFO - 'SHELL': '/bin/bash', 07:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:23 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:23 INFO - 'USER': 'cltbld', 07:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:23 INFO - 'XPC_FLAGS': '0x0', 07:18:23 INFO - 'XPC_SERVICE_NAME': '0', 07:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:24 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:24 INFO - Cleaning up... 07:18:24 INFO - Return code: 0 07:18:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:24 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:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:24 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:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:24 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 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:24 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:24 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:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:24 INFO - 'HOME': '/Users/cltbld', 07:18:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:24 INFO - 'LOGNAME': 'cltbld', 07:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:24 INFO - 'MOZ_NO_REMOTE': '1', 07:18:24 INFO - 'NO_EM_RESTART': '1', 07:18:24 INFO - 'PAGER': '/bin/cat', 07:18:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:24 INFO - 'PWD': '/builds/slave/test', 07:18:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:24 INFO - 'SHELL': '/bin/bash', 07:18:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:24 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:24 INFO - 'USER': 'cltbld', 07:18:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:24 INFO - 'XPC_FLAGS': '0x0', 07:18:24 INFO - 'XPC_SERVICE_NAME': '0', 07:18:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:24 INFO - Downloading/unpacking psutil>=3.1.1 07:18:24 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:24 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:24 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:24 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:24 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:24 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:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:28 INFO - Installing collected packages: psutil 07:18:28 INFO - Running setup.py install for psutil 07:18:28 INFO - building 'psutil._psutil_osx' extension 07:18:28 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:28 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:28 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:28 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:28 INFO - building 'psutil._psutil_posix' extension 07:18:28 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:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:28 INFO - ^ 07:18:28 INFO - 1 warning generated. 07:18:28 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: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 - Successfully installed psutil 07:18:29 INFO - Cleaning up... 07:18:29 INFO - Return code: 0 07:18:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:29 INFO - 'HOME': '/Users/cltbld', 07:18:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:29 INFO - 'LOGNAME': 'cltbld', 07:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:29 INFO - 'MOZ_NO_REMOTE': '1', 07:18:29 INFO - 'NO_EM_RESTART': '1', 07:18:29 INFO - 'PAGER': '/bin/cat', 07:18:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:29 INFO - 'PWD': '/builds/slave/test', 07:18:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:29 INFO - 'SHELL': '/bin/bash', 07:18:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:29 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:29 INFO - 'USER': 'cltbld', 07:18:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:29 INFO - 'XPC_FLAGS': '0x0', 07:18:29 INFO - 'XPC_SERVICE_NAME': '0', 07:18:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:32 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:32 INFO - Installing collected packages: mozsystemmonitor 07:18:32 INFO - Running setup.py install for mozsystemmonitor 07:18:32 INFO - Successfully installed mozsystemmonitor 07:18:32 INFO - Cleaning up... 07:18:32 INFO - Return code: 0 07:18:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:32 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 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:32 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:32 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:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:32 INFO - 'HOME': '/Users/cltbld', 07:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:32 INFO - 'LOGNAME': 'cltbld', 07:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:32 INFO - 'MOZ_NO_REMOTE': '1', 07:18:32 INFO - 'NO_EM_RESTART': '1', 07:18:32 INFO - 'PAGER': '/bin/cat', 07:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:32 INFO - 'PWD': '/builds/slave/test', 07:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:32 INFO - 'SHELL': '/bin/bash', 07:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:32 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:32 INFO - 'USER': 'cltbld', 07:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:32 INFO - 'XPC_FLAGS': '0x0', 07:18:32 INFO - 'XPC_SERVICE_NAME': '0', 07:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:32 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:18:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - Downloading functools32-3.2.3-2.tar.gz 07:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:35 INFO - warning: no files found matching '*.txt' 07:18:35 INFO - no previously-included directories found matching 'build' 07:18:35 INFO - no previously-included directories found matching 'dist' 07:18:35 INFO - no previously-included directories found matching '.git*' 07:18:35 INFO - Installing collected packages: jsonschema, functools32 07:18:35 INFO - Running setup.py install for functools32 07:18:35 INFO - warning: no files found matching '*.txt' 07:18:35 INFO - no previously-included directories found matching 'build' 07:18:35 INFO - no previously-included directories found matching 'dist' 07:18:35 INFO - no previously-included directories found matching '.git*' 07:18:35 INFO - Successfully installed jsonschema functools32 07:18:35 INFO - Cleaning up... 07:18:35 INFO - Return code: 0 07:18:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:18:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:35 INFO - 'HOME': '/Users/cltbld', 07:18:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:35 INFO - 'LOGNAME': 'cltbld', 07:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:35 INFO - 'MOZ_NO_REMOTE': '1', 07:18:35 INFO - 'NO_EM_RESTART': '1', 07:18:35 INFO - 'PAGER': '/bin/cat', 07:18:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:35 INFO - 'PWD': '/builds/slave/test', 07:18:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:35 INFO - 'SHELL': '/bin/bash', 07:18:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:35 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:35 INFO - 'USER': 'cltbld', 07:18:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:35 INFO - 'XPC_FLAGS': '0x0', 07:18:35 INFO - 'XPC_SERVICE_NAME': '0', 07:18:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:18:36 INFO - Cleaning up... 07:18:36 INFO - Return code: 0 07:18:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:18:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:36 INFO - 'HOME': '/Users/cltbld', 07:18:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:36 INFO - 'LOGNAME': 'cltbld', 07:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:36 INFO - 'MOZ_NO_REMOTE': '1', 07:18:36 INFO - 'NO_EM_RESTART': '1', 07:18:36 INFO - 'PAGER': '/bin/cat', 07:18:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:36 INFO - 'PWD': '/builds/slave/test', 07:18:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:36 INFO - 'SHELL': '/bin/bash', 07:18:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:36 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:36 INFO - 'USER': 'cltbld', 07:18:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:36 INFO - 'XPC_FLAGS': '0x0', 07:18:36 INFO - 'XPC_SERVICE_NAME': '0', 07:18:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:36 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:39 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:40 INFO - Downloading/unpacking docopt==0.6.1 (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:40 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:41 INFO - Running setup.py install for docopt 07:18:41 INFO - Successfully installed blobuploader requests docopt 07:18:41 INFO - Cleaning up... 07:18:41 INFO - Return code: 0 07:18:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:41 INFO - 'HOME': '/Users/cltbld', 07:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:41 INFO - 'LOGNAME': 'cltbld', 07:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:41 INFO - 'MOZ_NO_REMOTE': '1', 07:18:41 INFO - 'NO_EM_RESTART': '1', 07:18:41 INFO - 'PAGER': '/bin/cat', 07:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:41 INFO - 'PWD': '/builds/slave/test', 07:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:41 INFO - 'SHELL': '/bin/bash', 07:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:41 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:41 INFO - 'USER': 'cltbld', 07:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:41 INFO - 'XPC_FLAGS': '0x0', 07:18:41 INFO - 'XPC_SERVICE_NAME': '0', 07:18:41 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/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ue1rnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:42 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-zpdTH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:42 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Ma8Gb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:42 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-kpwjLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:42 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-xMxYhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:42 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-zH5TaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:42 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-9SCgis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:42 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-fouGBI-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/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-DxVQJS-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/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-nN8Nf0-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/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-XLAM5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:43 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-794bF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:43 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-XrM7Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:43 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-I1Ruwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:43 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-GLkykl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:43 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-WR_eSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:43 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-4O3Nbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:43 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:44 INFO - Running setup.py install for mozdebug 07:18:44 INFO - Running setup.py install for mozdevice 07:18:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Running setup.py install for mozfile 07:18:44 INFO - Running setup.py install for mozhttpd 07:18:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Running setup.py install for mozinfo 07:18:44 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:45 INFO - Running setup.py install for mozlog 07:18:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Running setup.py install for moznetwork 07:18:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Running setup.py install for mozprocess 07:18:45 INFO - Running setup.py install for mozprofile 07:18:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:45 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:46 INFO - Running setup.py install for moztest 07:18:46 INFO - Running setup.py install for mozversion 07:18:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:46 INFO - Cleaning up... 07:18:46 INFO - Return code: 0 07:18:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:46 INFO - 'HOME': '/Users/cltbld', 07:18:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:46 INFO - 'LOGNAME': 'cltbld', 07:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:46 INFO - 'MOZ_NO_REMOTE': '1', 07:18:46 INFO - 'NO_EM_RESTART': '1', 07:18:46 INFO - 'PAGER': '/bin/cat', 07:18:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:46 INFO - 'PWD': '/builds/slave/test', 07:18:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:46 INFO - 'SHELL': '/bin/bash', 07:18:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:46 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:46 INFO - 'USER': 'cltbld', 07:18:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:46 INFO - 'XPC_FLAGS': '0x0', 07:18:46 INFO - 'XPC_SERVICE_NAME': '0', 07:18:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:46 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-B7QQl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:46 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-d0o9nO-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/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-TZRQ8O-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/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-TE5eQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-HrXE1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-435t2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-wK7u8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-3snSVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-XlwkP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-LBoKK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-mvk6Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Qz6qVK-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/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-VQsj3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-_iO5p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-52AzKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-BC77qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-vb8ppU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 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:51 INFO - Successfully installed blessings 07:18:51 INFO - Cleaning up... 07:18:51 INFO - Return code: 0 07:18:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:51 INFO - Reading from file tmpfile_stdout 07:18:51 INFO - Current package versions: 07:18:51 INFO - blessings == 1.6 07:18:51 INFO - blobuploader == 1.2.4 07:18:51 INFO - docopt == 0.6.1 07:18:51 INFO - functools32 == 3.2.3-2 07:18:51 INFO - jsonschema == 2.5.1 07:18:51 INFO - manifestparser == 1.1 07:18:51 INFO - mozInstall == 1.12 07:18:51 INFO - mozcrash == 0.17 07:18:51 INFO - mozdebug == 0.1 07:18:51 INFO - mozdevice == 0.48 07:18:51 INFO - mozfile == 1.2 07:18:51 INFO - mozhttpd == 0.7 07:18:51 INFO - mozinfo == 0.9 07:18:51 INFO - mozleak == 0.1 07:18:51 INFO - mozlog == 3.3 07:18:51 INFO - moznetwork == 0.27 07:18:51 INFO - mozprocess == 0.23 07:18:51 INFO - mozprofile == 0.28 07:18:51 INFO - mozrunner == 6.12 07:18:51 INFO - mozscreenshot == 0.1 07:18:51 INFO - mozsystemmonitor == 0.3 07:18:51 INFO - moztest == 0.7 07:18:51 INFO - mozversion == 1.4 07:18:51 INFO - psutil == 3.1.1 07:18:51 INFO - requests == 1.2.3 07:18:51 INFO - wsgiref == 0.1.2 07:18:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:51 INFO - 'HOME': '/Users/cltbld', 07:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:51 INFO - 'LOGNAME': 'cltbld', 07:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:51 INFO - 'MOZ_NO_REMOTE': '1', 07:18:51 INFO - 'NO_EM_RESTART': '1', 07:18:51 INFO - 'PAGER': '/bin/cat', 07:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:51 INFO - 'PWD': '/builds/slave/test', 07:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:51 INFO - 'SHELL': '/bin/bash', 07:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:51 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:51 INFO - 'USER': 'cltbld', 07:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:51 INFO - 'XPC_FLAGS': '0x0', 07:18:51 INFO - 'XPC_SERVICE_NAME': '0', 07:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:18:51 INFO - Cleaning up... 07:18:51 INFO - Return code: 0 07:18:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e95a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f2a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07bd6b980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, '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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:51 INFO - 'HOME': '/Users/cltbld', 07:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:51 INFO - 'LOGNAME': 'cltbld', 07:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:51 INFO - 'MOZ_NO_REMOTE': '1', 07:18:51 INFO - 'NO_EM_RESTART': '1', 07:18:51 INFO - 'PAGER': '/bin/cat', 07:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:51 INFO - 'PWD': '/builds/slave/test', 07:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:51 INFO - 'SHELL': '/bin/bash', 07:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:18:51 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:51 INFO - 'USER': 'cltbld', 07:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:51 INFO - 'XPC_FLAGS': '0x0', 07:18:51 INFO - 'XPC_SERVICE_NAME': '0', 07:18:51 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): jsonschema in ./venv/lib/python2.7/site-packages 07:18:52 INFO - Cleaning up... 07:18:52 INFO - Return code: 0 07:18:52 INFO - Running post-action listener: _resource_record_post_action 07:18:52 INFO - Running post-action listener: _start_resource_monitoring 07:18:52 INFO - Starting resource monitoring. 07:18:52 INFO - [mozharness: 2016-09-17 14:18:52.230637Z] Finished create-virtualenv step (success) 07:18:52 INFO - [mozharness: 2016-09-17 14:18:52.231564Z] Running install step. 07:18:52 INFO - Running pre-action listener: _resource_record_pre_action 07:18:52 INFO - Running main action method: install 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 - Detecting whether we're running mozinstall >=1.0... 07:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:52 INFO - Reading from file tmpfile_stdout 07:18:52 INFO - Output received: 07:18:52 INFO - Usage: mozinstall [options] installer 07:18:52 INFO - Options: 07:18:52 INFO - -h, --help show this help message and exit 07:18:52 INFO - -d DEST, --destination=DEST 07:18:52 INFO - Directory to install application into. [default: 07:18:52 INFO - "/builds/slave/test"] 07:18:52 INFO - --app=APP Application being installed. [default: firefox] 07:18:52 INFO - mkdir: /builds/slave/test/build/application 07:18:52 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:52 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:25 INFO - Reading from file tmpfile_stdout 07:19:25 INFO - Output received: 07:19:25 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:19:25 INFO - Running post-action listener: _resource_record_post_action 07:19:25 INFO - [mozharness: 2016-09-17 14:19:25.224083Z] Finished install step (success) 07:19:25 INFO - [mozharness: 2016-09-17 14:19:25.224231Z] Running run-tests step. 07:19:25 INFO - Running pre-action listener: _resource_record_pre_action 07:19:25 INFO - Running main action method: run_tests 07:19:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:25 INFO - 2016-09-17 07:19:25.238 screenresolution[1650:8532] starting screenresolution argv=screenresolution get 07:19:25 INFO - 2016-09-17 07:19:25.241 screenresolution[1650:8532] Display 0: 1600x1200x32@60 07:19:25 INFO - 2016-09-17 07:19:25.247 screenresolution[1651:8535] starting screenresolution argv=screenresolution list 07:19:25 INFO - Available Modes on Display 0 07:19:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:25 INFO - Intel Iris: 07:19:25 INFO - Chipset Model: Intel Iris 07:19:25 INFO - Type: GPU 07:19:25 INFO - Bus: Built-In 07:19:25 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:25 INFO - Vendor: Intel (0x8086) 07:19:25 INFO - Device ID: 0x0a2e 07:19:25 INFO - Revision ID: 0x0009 07:19:25 INFO - Displays: 07:19:25 INFO - Display: 07:19:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:25 INFO - Display Serial Number: 1600x1200 60 07:19:25 INFO - Main Display: Yes 07:19:25 INFO - Mirror: Off 07:19:25 INFO - Online: Yes 07:19:25 INFO - Rotation: Supported 07:19:25 INFO - Return code: 0 07:19:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:25 INFO - Python 2.7.3 07:19:25 INFO - Return code: 0 07:19:25 INFO - grabbing minidump binary from tooltool 07:19: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:19:25 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 0x107df9d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:25 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:25 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:25 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:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:25 INFO - Return code: 0 07:19:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:25 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-0375', '--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:25 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-0375 --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:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Oy1vPaFLG/Render', 07:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:25 INFO - 'HOME': '/Users/cltbld', 07:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:25 INFO - 'LOGNAME': 'cltbld', 07:19:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:25 INFO - 'MOZ_NO_REMOTE': '1', 07:19:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:25 INFO - 'NO_EM_RESTART': '1', 07:19:25 INFO - 'PAGER': '/bin/cat', 07:19:25 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:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:25 INFO - 'PWD': '/builds/slave/test', 07:19:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:25 INFO - 'SHELL': '/bin/bash', 07:19:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4gV3LlDuj2/Listeners', 07:19:25 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 07:19:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:25 INFO - 'USER': 'cltbld', 07:19:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:25 INFO - 'XPC_FLAGS': '0x0', 07:19:25 INFO - 'XPC_SERVICE_NAME': '0', 07:19:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:25 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-0375', '--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:25 INFO - mozversion application_buildid: 20160916162818 07:19:25 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:19:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:25 INFO - mozversion application_name: Firefox 07:19:25 INFO - mozversion application_remotingname: firefox-dev 07:19:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:25 INFO - mozversion application_vendor: Mozilla 07:19:25 INFO - mozversion application_version: 50.0a2 07:19:25 INFO - mozversion platform_buildid: 20160916162818 07:19:25 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:25 INFO - mozversion platform_version: 50.0a2 07:19:25 INFO - using testdate: 1474121965 07:19:25 INFO - actual date: 1474121965 07:19:25 INFO - starting webserver on 'localhost:49267' 07:19:25 INFO - SUITE-START | Running 2 tests 07:19:25 INFO - TEST-START | dromaeo_css 07:19:25 INFO - operating with platform_type : mac_ 07:19:25 INFO - Initialising browser for dromaeo_css test... 07:19:25 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpWe_nOe/profile http://localhost:49267/getInfo.html) 07:19:28 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 07:19:28 INFO - PROCESS | 1661 | colorDepth:24 07:19:28 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 07:19:28 INFO - PROCESS | 1661 | __metrics 07:19:28 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:19:29 INFO - TEST-INFO | 1661: exit 0 07:19:29 INFO - Browser initialized. 07:19:29 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:29 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpWe_nOe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:41 INFO - PROCESS | 1665 | RSS: Main: 226492416 07:19:41 INFO - PROCESS | 1665 | 07:20:30 INFO - PROCESS | 1665 | 1474122030728 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:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 07:22:43 INFO - PROCESS | 1665 | RSS: Main: 239345664 07:22:43 INFO - PROCESS | 1665 | 07:25:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 07:25:46 INFO - PROCESS | 1665 | RSS: Main: 248762368 07:25:46 INFO - PROCESS | 1665 | 07:28:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 07:28:48 INFO - PROCESS | 1665 | RSS: Main: 252391424 07:28:48 INFO - PROCESS | 1665 | 07:31:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 07:31:51 INFO - PROCESS | 1665 | RSS: Main: 252420096 07:31:51 INFO - PROCESS | 1665 | 07:34:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 07:34:54 INFO - PROCESS | 1665 | RSS: Main: 256552960 07:34:54 INFO - PROCESS | 1665 | 07:37:56 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 07:37:56 INFO - PROCESS | 1665 | RSS: Main: 257019904 07:37:56 INFO - PROCESS | 1665 | 07:37:56 INFO - PROCESS | 1665 | __start_tp_report 07:37:56 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 07:37:56 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 07:37:56 INFO - PROCESS | 1665 | |i|pagename|runs| 07:37:56 INFO - PROCESS | 1665 | |0;dojo.html;3350.162459385155;3362.8150451725155;3367.562216911801;3372.15696075981;3381.1885147564576;5834.358220595731;5836.708164591764;5870.647761134335;5916.201312822767;5945.464908158265;5953;5985.4014598540125;6033.1855199548045;6074.119252708355;6091.421314975081;5342.839714808562;5412.485813847678;5432.728363581825;5443.156310771817;5468.9726551367185;5318.9202161967605;5321.228421878825;5360.303160589118;5434.8913021739545;5461.317335333083;5388.703621300827;5452.345718513784;5453.64551304164;5454.318210223722;5478.534324582395;4931.90136197274;5385.703786291791;5435.537979271767;5458.1266997280445;5472.206530053159;5305.840824775263;5414.566834653218;5416.972915135439;5434.483724046209;5439.619226654136;5814.215080964058;6017.548683848729;6065.63606183626;6110.908336374958;6112.908306375363;6193.504519638418;6210.565260431772;6227.501799856001;6284;6301.558890877641;6330.87338253237;6376.9681151594405;6393.552451328409;6403.807885763435;6435.646039467827;5941.881162376728;6143.047827586731;6149.169862068608;6149.477294429934;6161.199044124325;6386.744530218739;6453.515986301046;6469.256035555877;6603.80188594343;6622.569532980341;6265.780697675559;6269.122322874801;6272;6277.2153480814895;6292.653904035231;6211.9750241209695;6402.487800976004;6438.549301548846;6441.3880681335195;6456.0961465394885;6203.658798766111;6222.81331560054;6240.157578726905;6243.43809057187;6313.494920406357;5251.816186433456;5346.037713211582;6256.906146407717;6268.216472940882;6308.936910630835;4319.654427645781;5050.166722490748;5191.922121168185;5194.870128246748;5200.245964335226;3770.321342158383;4514.503404625489;4527.886802829956;4556.362109304634;4611.5619016193405;5032.220005899091;5926.970365148233;6057.606255593372;6058.818235452944;6082.482988945989;6203.503719702413;6437.452816510556;6442.098106265126;6455.548111632141;6467.482601391877;4906.773306673332;5039.891223930729;5995.160677505153;6092.329843717188;6097.4817140543555;5559.63862348946;5645.633033852869;5653.265075540154;5657.15142728594;5658.122990936411;6253.468455181361;6632.96683516589;6633.270340262471;6657;6658.201015878126;6279.089532017741;6299.811005669837;6339.809805705836;6417.679116044085;6424.453921416732;6197.752089916533;6623.867522649423;6630.138082049302;6646.136002319668;6678.565893216925;6189.1025801257665;6329.556931014785;6340.682965851781;6353.396427339396;6367.808965731036;6211.689415529296;6252.374762523711;6257.5932564383165;6345.682715864281;6370.044493326038;5035.874103147451;6172.22847144107;6248.875022499434;6258.780942666985;6289.685515724104;6148.538859585459;6243.531735119794;6279.937200627935;6317.6209427434505;6321.683915804099;4694.413198350207;4763.618910487222;6079.817605471843;6139.6009259398;6225.470834979077;6249.218847644044;6265.060240963892;6278.874422511615;6279.372062793684;6312.147860038836;6061.242344706911;6135.325114237341;6153.292371377349;6248.937510625017;6254.906176407449 07:37:56 INFO - PROCESS | 1665 | |1;ext.html;482.46687410411494;488.38219652140026;515.8787684894047;536.1609081787003;556.2990631803927;37053.44419833701;39481.60518394815;39499.40750888739;39593.81218563441;39734.20531589366;41724;42308.57691423085;42372.9406764831;42411.363829542504;42455.78772106135;9672.70981870545;9871.90128098717;9894.406335619886;9903.257255705816;9927;9715.951420242889;9742.902570974305;9812.803743925153;9815.214782817358;9829.115379615832;9775.169110625606;9781.706548803548;9815.950920245388;9830.803383932318;9846.064623860757;9698.999999999965;9725.513724313756;9748.853767193497;9789.070038346346;9806.509674516246;8981.236594889442;9598.280128990353;9709.757256068584;9742.464164470952;9818.116369526777;39989.000274993065;40277.79166625006;40379.59620403788;40632.59367406317;40733.38899916474;3139.4819854724174;3439.94840077399;3453.9827300863335;3481.5125882376487;3554.2713743682484;29255.853720731473;30845.691543084507;30860;31161.220969475944;31269.37461250762;3036.5445183222473;3100.503919372912;3141.277506173593;3186.9840650796828;3189.1708155879405;487210;492017;495537;497440;497468;33003;33256;33346.3330733384;33380.16549586231;34115.488267676;34739.65260347415;34790.47814282788;34946.82526587351;35508.112297192776;35699.4645080324;15035.924820375829;15057.397704091714;15068.849311506745;15454.38182472711;15621.375144994301;13173.341332933314;13238.073334866467;13243.602691919257;13259.801102983463;13265.270410127343;7781.5331080135375;7804.570748608881;7809.258120478546;7859.882101768478;7873.409494287951;3318.3363327334628;3332.7000569948823;3349.6482869298375;3353.748468864845;3356.9328613427597;962.6678795815433;971.8493633486821;1021.6730646193296;1050.9054185123375;1086.6142519405498;30685.38629227403;30826.075217743502;31109;31112.84443577813;31155.53266701001;1017.8982101789762;1036.6371769880482;1142.5486932661718;1211.3821950804977;1218.7318789866215;14089.36597853099;14253.714925701428;15720.92139539318;15988.920055399649;16137.596560085856;721264;729165;731956;733966;734299;57834.7108264456;58755;59387;60376;60925.69537152286;1435.9138451692932;1451.6153219396867;1477.748782706964;1483.5178566965778;1491.8582734640192;24543.63184552269;27651.30871728223;28987.7101228985;30583;34662;62354.688226558574;65191;66201.66899165665;66203.33796662165;66294.67410651856;661742;668611;673524;674576;675730.6213468901;48367.51632483771;48766;49058;49923.75038124786;50242.99514009772;53075.93848123093;53128.46871531235;53537.46462535325;53621.73189134029;53894.19158712544;41138.9715257109;44350;44433.555664442945;45209.32186017278;45413.54586454093;43386.349204762584;43559.34660980151;43687.68936931897;44302.89879708389;45383.7730811357;35513.467297990035;41643.58356416397;41793.16413671771;42150;42426.78786606047;36676.81661591675;38212.61787382202;38736.03159921025;38877.6112238874;39012.41481377725;22442.32673019812;28023.71976280211;28147.43705125845;28175.872965081988;28207.57688634631 07:37:56 INFO - PROCESS | 1665 | |2;jquery.html;321.8535566317326;380.1465709482211;390.2468236303933;399.6862462966572;409.6047314341662;20528.486787830294;22288;22710.88644556775;22802.771972280272;23105.07579696812;20659;21391.358259252207;21665.916704164807;21723.565528689418;22498.55002899941;5205.401378841434;5451.918221226685;5493.917591236134;5551.555875529948;5553.944460555404;3922.548906875703;5495.752691128889;5506.476884695948;5540.058190107671;6617.966910165443;6821.386075253226;6956.547824391424;6959.095317608704;6961.338672826073;6966.860662786767;6079;6177.135201071854;6190.690465476753;6215.96892015537;6870.868355717745;5319.627626066177;5988.221531200916;5997.430244126801;6056.091586262052;6113.969430152821;24736;26285.817138228827;26819.463610727675;26968.59547106795;27047;2210.160139147114;2223.3107736601705;2284.5088306014122;2438.7317395086525;2965.555166724987;10196.184305255561;10569.947150264275;10599.84100238497;11888.583899563473;16150.434734784343;1896.0804010055112;1906.733057371969;1913.6938089905686;1925.6918892977055;1952.9804701952942;129837.35081324533;130650;133186;135529;140619.29690351585;10786.514606842711;10873.510692018877;11925.463354149084;11930.56833180024;11973.940130299467;15345.693086138437;15349.386024558893;15356.769648455282;15368;15406.768898466533;8957.88026496688;9082.591283392263;9083.545822708971;9111.498867562348;9230.953845230732;9039.186473217442;9116.270698344117;9156.587953542106;9270.30472714549;9316.813663726687;4611.884702882455;4748.287756836433;4754.881127971759;4766.046790641885;4847.054824309296;1523.2612183091192;1551.201131417321;1670.4988503448917;1956.1979588368702;2022.2820898581085;846.8645016797283;951.8048799995984;1023.2734758321675;1046.2571574182264;1066.7546464313252;22670.093196271966;22694;22744.886275568515;23233.30300091;23275.418114547272;999.715081201854;1005.5877090392904;1008.0473952115327;1017.5827910556636;1066.6959916423782;11120.38837863909;11851;12814.295213763146;12820.743585128244;12822.35888205586;631089;631226;631526;656872;661517;80700.19299806927;97684;100212;101737.49131254444;113266;1033.3128469567653;1060.4591658254346;1072.0994364733817;1082.1234799812178;1085.3650614390513;16136;20694.689579656617;20922;21244.36266911995;21383.786162138178;59149.40850591611;60434;60967;61566.0765088533;62487.06269405872;86919.56540217258;89063.10936890548;89304.5534772348;89521.55239223762;92679.07320926705;8332.583370831555;8439.282660973886;8512.191341822518;8658.134186581292;8706.390552661253;9387.906120938704;12994.35028248565;13487.32563371847;13541.255230962392;14109.012369134061;11554.364509951698;11633.767324653629;11737.119716021161;11840.82238766402;12148.028157747181;11583.362915039754;11597;11767.293962362286;11926.105542084204;11969.401529923294;9573.138417542175;10030.598776048877;11866.881331186578;11904;12066.577669781867;5428.945710542844;6229.2524897012645;8476.576171191538;8792.47245165292;8801.559922003746;9912.207023438252;10629.574817007233;10824;11131.777364452828;11285.605003825156 07:37:56 INFO - PROCESS | 1665 | |3;mootools.html;1384.5015794314045;1538.446159382622;1606.309287006587;1925.7977912319197;1936.2932529626692;6283.685815709219;8271.875921861161;8383.874241886377;9338.486383248919;9363.157315841581;7654.349380302681;8896.599653015614;9134.588943497542;9143.45139291643;9162.22121119704;2330.790228879405;2376.940576485584;2380.4762952150586;2381.452265978826;2386.05750728463;2006.8494862885268;2377.4888398994212;2413.3363325085566;2421.067888862783;2442.010985550846;2413.6379543068506;2416.3838221253645;2427.2475532584867;2430.295214387834;2433.5862903306393;2351.5532048910654;2353.482233908537;2360.752121027293;2373.4066483379156;2424.6726691896542;2387.6537902004284;2404.446977195255;2409.7831195192516;2420.394901274681;2425.927222183337;9345.766355841159;9388.014258502895;9405.811883762355;9418.764530886714;9433.339666223366;5408.8377348679605;5576.33084029915;5577.888442231173;5578.2748242728185;5580.469855363734;3729.850805967758;3734.8795361391476;3740;3751.887443376676;3814.713896457749;1100.6808025672544;1101.1576144249655;1103.536514663843;1111.6553868300853;1124.527698366688;9632.614695412176;11394.829077563843;12070.758584828342;12157.331346775924;12188.329641869694;4725.36207611968;4766.951763574926;4793.760311984387;4899.804007839646;4919.458859525449;10237.027482389161;10340.586376544854;10353.844692329621;10375.84436233457;10385.584576617002;5359.785608575613;5380.91928621071;5413.079776438015;5421.5391691706645;5519.834404967857;4478.39541661878;4761.285807128958;4876.5854902332985;4907.779149938261;4982.551570358685;4018.537868145142;4108.219438306712;4134.607212314825;4162.791860406967;4192.559781222989;996.8704068471052;1244.6930722741085;1313.5468263449095;1329.3884812986134;1338.2706424998398;880.859062549989;953.5280036382036;954.61815273891;955.7323949294208;984.2618036472645;5076.974615126901;5867.413258674099;6091.634501929899;6272.153259310026;6283.59156654816;438.1937235486656;444.2026562320642;444.6420631391696;446.8302045222795;457.32772823948744;4017.5178978522763;4023.2355852388537;4032.2137183248983;4040.1416647101946;4162.063535704503;10972.341659500455;11196.160287978435;11343.602973895873;11344.829827552594;11346.716332091764;10189.184865210766;10374.118199953089;11287.040601548797;11287.266327688674;11306.265092768943;8574.056853746082;8580.442271252348;8669.349798765117;8679.392442528962;8689.695860644848;5137.8201762938115;5868.794592189253;5997.190379298739;6125.9693701531205;6169;8368.539730314891;10078.8488172676;10202.132818710492;10228.53971571296;10391.272610917164;9970.50147492637;10289.588416463257;10428.58285668595;10435.426051566934;10552.84170737455;7303.342699157101;7686.423518236043;7765.301122898966;7812.023497062867;7919.802004949923;8661.307095432225;8819.11808819113;8833.867491987497;8893.155150260714;8919.777005574913;7706.499077559941;7819.687212511436;7845.529268243924;7935.523868567904;8091.109977902308;7739.961300193463;7825.921740782519;7848.489848159851;7849.568273744999;7880.921190788019;7216.855662886818;7284.963575182091;7303.269673032654;7317.414606831334;7328.890066648898;4958.479359667183;4986.276989836526;5094.617903657167;5142.922856157235;5211.739413029409;4981.62637802159;5074.035933172685;5096.719680417465;5144.6655967362;5258.211268309784 07:37:56 INFO - PROCESS | 1665 | |4;prototype.html;293.7003758369216;298.1339209596121;301.02167954149013;311.2374682029029;318.1949667341627;5304.840854774353;5610.80362187323;5655.953648575011;5699.401562835905;5704.172894930232;4102.815373308201;4831.497164555695;5650.773969041234;5680.289963754531;5686.232358631594;3376.1053320869987;4081.2857749893724;4159.438475805773;4180.662188099809;4277;3527.677121079595;4227.978860105695;4249.277622804131;4250.766207858566;4274.935875961863;3531.6644918732677;3868.303705333039;4006.719529632927;4240.427542281799;4311.8706438806885;3880.5164694595483;4119.838962647732;4168;4210.810513526899;4247.851325203618;3663.3772258715817;3667.6332366763377;3670.3209906167444;3670.7063434925144;3673.2286219893854;5433.609211946731;5435.950217088255;5477.589180811416;5488.53347465466;5546.805861794858;4084.264832330179;4801.351817504627;4922.114019476493;4949.950500494985;4978.900421991576;4999.650024498286;5023.572996295319;5855.44830619886;5952.404759524013;5986.760529578812;3427.8971630851115;4103.440692536847;4105.3020986432375;4108.650764685005;4116.75299482029;4991.850244492671;6055.273367195965;6293.9999999999545;6317.652529110897;6356.523260755415;3493.63316851732;4137.275976704064;4154.169166166779;4158.542560318362;4170.16596680665;3580.427131658922;4251.383549385322;4267.850625228164;4276.123394704078;4283.121959998193;3957.465742124776;4797.544233297831;4929.457759646436;4984.476629953875;4989;4912.508749125131;5127.5897928166405;5148.536631703165;6054.969725151346;6103.755849765959;1215.3254943506438;1328.1842493912966;1362.821159696875;1411.9505817296345;1452.208546342246;744.6239648977232;850.9914900850912;854.9700760473478;885.964561417549;894.2130924786146;5420.430854760272;5423.969445805364;5498.45015498447;5568.6658800472105;5576.880785301641;4615.284630882219;5687.630304030225;5913.881722365529;6152.8769424611255;6199.3490683478485;5318.883034562785;5354.8661283467445;5573.749181286852;5637.408072152447;5649.406812284733;2849.575212393803;3242.3839470501002;3286.5234540991423;3289.9177520562175;3300.7689461737928;6096.573239873255;6118.663473509;6137.662428566472;6148.385161483816;6441.227052753699;5746.080627099644;6336.778212762623;6448.871022579522;6453.806385808433;6490.318516555693;5437.401885792559;6242.438180563771;6487.994360874072;6506.707198176094;6528.050529429816;5212.635115541876;5829.912551311649;6243.906341404791;6323.525735569759;6333.809985700436;6164.722587483664;6231.688415579112;6243.188385509855;6249.062640603764;6263.718132683949;6171.876562468815;6353.936460635519;6498.220213574401;6563.1796025496815;6607;3790.4882840816135;3848.903062627138;4056.7363121397016;4059.5331536873637;4076.612721791425;6201.658908759881;6220.906686399617;6255.53083518747;6257.464532539101;6283.900317444338;5189.818356357509;5504.779808807602;5839.299284085937;6034.2155519782145;6043.848903777441;5788.247527821356;5980.671063091571;6010;6020.555066784028;6081.422264884829;5674.205611214516;5870.236869206976;5929.792457263975;5942.10868369748;6008.87982240344;5107.642465027412;6035.36628653985;6046.54650901193;6081.300650425258;6140.785072522441;5931.703414829328;5951.55363347742;5977.5217982560425;5985.6408615483215;5997.790077347272 07:37:56 INFO - PROCESS | 1665 | |5;yui.html;618.7679620142446;699.3356311504073;743.3607097895812;833.9249467547927;856.6016802186983;15375.846241537596;16075.598110047225;16391.180440977932;16547;16553.503394898158;15037.77443338348;15075.472358467456;15107.546773596781;15112.622184445367;15131;707.7204504220842;893.6872094766844;921.0237148622482;924.4638109896277;933.9906600933972;915.2677857713836;916.4134953629682;924.2375040591505;926.0924294191697;926.2358554192817;973.2943615878467;974.3666616699167;977.0669011094424;978.9265805064613;983.1151963233111;933.0436302789694;958.8226178156993;959.2374062620199;968.8837339519267;968.9031096890325;720.3732752505301;723.6779633063289;724.2395484741054;726.8182954261434;727.3344889426169;15135.848641513665;15139.621509462242;15141.015833970756;15476.613084672974;15481.14853683047;14139.858601413956;14191.574252772434;14284.285785710777;14605.561833145022;14617.926910365486;12948.935255323757;13345.532906348231;13416.664583385396;13888.583342499742;13961.441542338294;4830.009847981156;4845;4883.5604795568215;4903.043906438246;4908.680935739201;124344;128877;128930.3553482236;129717;130252.69747302501;11687.006604438719;11706.239094459002;11837.112216583619;11845.703857403634;11882.243903411381;23324.950377233065;23426.53146937052;23597.174098906456;23609;23643.7635623645;7957.482763620346;8024.759257222293;8265.917340826634;8425.36809739272;8592.5703714814;3827.8468861245797;4603.516630753789;4666.60333871618;4703.106409782131;4755.714657120584;3841.769493830379;3987.063040185554;4005.7396269242404;4006.278869803405;4011.4584531088512;736.2674139231499;775.441681988967;777.0946846923384;778.0740918307197;790.6244533846377;580.6603137164806;609.4667166229549;641.1216633212435;678.5691086160324;700.3626699703245;9120.361574689709;9745.756356091155;9780.462074585825;9866.654667086617;10084.848727269096;612.0238220039031;615.3630991923338;627.2786295759821;637.4008432073836;663.3002182697301;4560.293154561048;4822.539698342391;4876.975615121973;4878.658493905464;4884.3047639797405;105288.47355763172;155950;159358.2032089832;172409;177922;121351;121603.39198303952;122834;124012;127581;119382;126319.99999999817;127894;129822.35088824495;129864.70135298526;9246.306527010636;9350.251979841732;9429.339946203974;9440.291978101532;9462.2430205582;68303;68914;69866.65066674634;70462.64768676124;71489;97930;107151;108073;111193.44403277931;112999.43500282775;10347.844782328422;12366.19619724715;12628.558000469939;12783.680407989874;13142;13285.13646612967;13501.662458438617;13762.449502019806;13764.724705505634;14283;11577.131715121232;11733.76532469363;11774;12234.510619575118;13018.349082545645;10738.463076846283;12264;12421.689457763629;12557.246565206116;12889.162204456681;11937.940310298392;12024.939875300568;12266.386680665753;12349.93825030869;12724.872751272738;8332.458390204618;10529.842052369373;10959.232853700165;10998.835017474905;11201.887981120084;8041.597920104088;8435.662573496991;9072.775175351244;11129.83305250438;11252.606158784403 07:37:56 INFO - PROCESS | 1665 | __end_tp_report 07:37:56 INFO - PROCESS | 1665 | __start_cc_report 07:37:56 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,422 07:37:56 INFO - PROCESS | 1665 | __end_cc_report 07:37:56 INFO - PROCESS | 1665 | __startTimestamp1474123076907__endTimestamp 07:37:56 INFO - PROCESS | 1665 | 07:37:56 INFO - PROCESS | 1665 | ------- Summary: start ------- 07:37:56 INFO - PROCESS | 1665 | Number of tests: 6 07:37:56 INFO - PROCESS | 1665 | 07:37:56 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5866.01 Median:6149.17 stddev:700.69 (11.4%) stddev-sans-first:674.47 07:37:56 INFO - PROCESS | 1665 | Values: 3350.2 3362.8 3367.6 3372.2 3381.2 5834.4 5836.7 5870.6 5916.2 5945.5 5953.0 5985.4 6033.2 6074.1 6091.4 5342.8 5412.5 5432.7 5443.2 5469.0 5318.9 5321.2 5360.3 5434.9 5461.3 5388.7 5452.3 5453.6 5454.3 5478.5 4931.9 5385.7 5435.5 5458.1 5472.2 5305.8 5414.6 5417.0 5434.5 5439.6 5814.2 6017.5 6065.6 6110.9 6112.9 6193.5 6210.6 6227.5 6284.0 6301.6 6330.9 6377.0 6393.6 6403.8 6435.6 5941.9 6143.0 6149.2 6149.5 6161.2 6386.7 6453.5 6469.3 6603.8 6622.6 6265.8 6269.1 6272.0 6277.2 6292.7 6212.0 6402.5 6438.5 6441.4 6456.1 6203.7 6222.8 6240.2 6243.4 6313.5 5251.8 5346.0 6256.9 6268.2 6308.9 4319.7 5050.2 5191.9 5194.9 5200.2 3770.3 4514.5 4527.9 4556.4 4611.6 5032.2 5927.0 6057.6 6058.8 6082.5 6203.5 6437.5 6442.1 6455.5 6467.5 4906.8 5039.9 5995.2 6092.3 6097.5 5559.6 5645.6 5653.3 5657.2 5658.1 6253.5 6633.0 6633.3 6657.0 6658.2 6279.1 6299.8 6339.8 6417.7 6424.5 6197.8 6623.9 6630.1 6646.1 6678.6 6189.1 6329.6 6340.7 6353.4 6367.8 6211.7 6252.4 6257.6 6345.7 6370.0 5035.9 6172.2 6248.9 6258.8 6289.7 6148.5 6243.5 6279.9 6317.6 6321.7 4694.4 4763.6 6079.8 6139.6 6225.5 6249.2 6265.1 6278.9 6279.4 6312.1 6061.2 6135.3 6153.3 6248.9 6254.9 07:37:56 INFO - PROCESS | 1665 | 07:37:56 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:75335.33 Median:41683.79 stddev:171662.34 (411.8%) stddev-sans-first:172051.90 07:37:56 INFO - PROCESS | 1665 | Values: 482.5 488.4 515.9 536.2 556.3 37053.4 39481.6 39499.4 39593.8 39734.2 41724.0 42308.6 42372.9 42411.4 42455.8 9672.7 9871.9 9894.4 9903.3 9927.0 9716.0 9742.9 9812.8 9815.2 9829.1 9775.2 9781.7 9816.0 9830.8 9846.1 9699.0 9725.5 9748.9 9789.1 9806.5 8981.2 9598.3 9709.8 9742.5 9818.1 39989.0 40277.8 40379.6 40632.6 40733.4 3139.5 3439.9 3454.0 3481.5 3554.3 29255.9 30845.7 30860.0 31161.2 31269.4 3036.5 3100.5 3141.3 3187.0 3189.2 487210.0 492017.0 495537.0 497440.0 497468.0 33003.0 33256.0 33346.3 33380.2 34115.5 34739.7 34790.5 34946.8 35508.1 35699.5 15035.9 15057.4 15068.8 15454.4 15621.4 13173.3 13238.1 13243.6 13259.8 13265.3 7781.5 7804.6 7809.3 7859.9 7873.4 3318.3 3332.7 3349.6 3353.7 3356.9 962.7 971.8 1021.7 1050.9 1086.6 30685.4 30826.1 31109.0 31112.8 31155.5 1017.9 1036.6 1142.5 1211.4 1218.7 14089.4 14253.7 15720.9 15988.9 16137.6 721264.0 729165.0 731956.0 733966.0 734299.0 57834.7 58755.0 59387.0 60376.0 60925.7 1435.9 1451.6 1477.7 1483.5 1491.9 24543.6 27651.3 28987.7 30583.0 34662.0 62354.7 65191.0 66201.7 66203.3 66294.7 661742.0 668611.0 673524.0 674576.0 675730.6 48367.5 48766.0 49058.0 49923.8 50243.0 53075.9 53128.5 53537.5 53621.7 53894.2 41139.0 44350.0 44433.6 45209.3 45413.5 43386.3 43559.3 43687.7 44302.9 45383.8 35513.5 41643.6 41793.2 42150.0 42426.8 36676.8 38212.6 38736.0 38877.6 39012.4 22442.3 28023.7 28147.4 28175.9 28207.6 07:37:56 INFO - PROCESS | 1665 | 07:37:56 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:36848.98 Median:12543.64 stddev:106761.17 (851.1%) stddev-sans-first:107025.43 07:37:56 INFO - PROCESS | 1665 | Values: 321.9 380.1 390.2 399.7 409.6 20528.5 22288.0 22710.9 22802.8 23105.1 20659.0 21391.4 21665.9 21723.6 22498.6 5205.4 5451.9 5493.9 5551.6 5553.9 3922.5 5495.8 5506.5 5540.1 6618.0 6821.4 6956.5 6959.1 6961.3 6966.9 6079.0 6177.1 6190.7 6216.0 6870.9 5319.6 5988.2 5997.4 6056.1 6114.0 24736.0 26285.8 26819.5 26968.6 27047.0 2210.2 2223.3 2284.5 2438.7 2965.6 10196.2 10569.9 10599.8 11888.6 16150.4 1896.1 1906.7 1913.7 1925.7 1953.0 129837.4 130650.0 133186.0 135529.0 140619.3 10786.5 10873.5 11925.5 11930.6 11973.9 15345.7 15349.4 15356.8 15368.0 15406.8 8957.9 9082.6 9083.5 9111.5 9231.0 9039.2 9116.3 9156.6 9270.3 9316.8 4611.9 4748.3 4754.9 4766.0 4847.1 1523.3 1551.2 1670.5 1956.2 2022.3 846.9 951.8 1023.3 1046.3 1066.8 22670.1 22694.0 22744.9 23233.3 23275.4 999.7 1005.6 1008.0 1017.6 1066.7 11120.4 11851.0 12814.3 12820.7 12822.4 631089.0 631226.0 631526.0 656872.0 661517.0 80700.2 97684.0 100212.0 101737.5 113266.0 1033.3 1060.5 1072.1 1082.1 1085.4 16136.0 20694.7 20922.0 21244.4 21383.8 59149.4 60434.0 60967.0 61566.1 62487.1 86919.6 89063.1 89304.6 89521.6 92679.1 8332.6 8439.3 8512.2 8658.1 8706.4 9387.9 12994.4 13487.3 13541.3 14109.0 11554.4 11633.8 11737.1 11840.8 12148.0 11583.4 11597.0 11767.3 11926.1 11969.4 9573.1 10030.6 11866.9 11904.0 12066.6 5428.9 6229.3 8476.6 8792.5 8801.6 9912.2 10629.6 10824.0 11131.8 11285.6 07:37:56 INFO - PROCESS | 1665 | 07:37:56 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5851.41 Median:4835.17 stddev:3312.33 (68.5%) stddev-sans-first:3304.61 07:37:56 INFO - PROCESS | 1665 | Values: 1384.5 1538.4 1606.3 1925.8 1936.3 6283.7 8271.9 8383.9 9338.5 9363.2 7654.3 8896.6 9134.6 9143.5 9162.2 2330.8 2376.9 2380.5 2381.5 2386.1 2006.8 2377.5 2413.3 2421.1 2442.0 2413.6 2416.4 2427.2 2430.3 2433.6 2351.6 2353.5 2360.8 2373.4 2424.7 2387.7 2404.4 2409.8 2420.4 2425.9 9345.8 9388.0 9405.8 9418.8 9433.3 5408.8 5576.3 5577.9 5578.3 5580.5 3729.9 3734.9 3740.0 3751.9 3814.7 1100.7 1101.2 1103.5 1111.7 1124.5 9632.6 11394.8 12070.8 12157.3 12188.3 4725.4 4767.0 4793.8 4899.8 4919.5 10237.0 10340.6 10353.8 10375.8 10385.6 5359.8 5380.9 5413.1 5421.5 5519.8 4478.4 4761.3 4876.6 4907.8 4982.6 4018.5 4108.2 4134.6 4162.8 4192.6 996.9 1244.7 1313.5 1329.4 1338.3 880.9 953.5 954.6 955.7 984.3 5077.0 5867.4 6091.6 6272.2 6283.6 438.2 444.2 444.6 446.8 457.3 4017.5 4023.2 4032.2 4040.1 4162.1 10972.3 11196.2 11343.6 11344.8 11346.7 10189.2 10374.1 11287.0 11287.3 11306.3 8574.1 8580.4 8669.3 8679.4 8689.7 5137.8 5868.8 5997.2 6126.0 6169.0 8368.5 10078.8 10202.1 10228.5 10391.3 9970.5 10289.6 10428.6 10435.4 10552.8 7303.3 7686.4 7765.3 7812.0 7919.8 8661.3 8819.1 8833.9 8893.2 8919.8 7706.5 7819.7 7845.5 7935.5 8091.1 7740.0 7825.9 7848.5 7849.6 7880.9 7216.9 7285.0 7303.3 7317.4 7328.9 4958.5 4986.3 5094.6 5142.9 5211.7 4981.6 5074.0 5096.7 5144.7 5258.2 07:37:56 INFO - PROCESS | 1665 | 07:37:56 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4840.43 Median:5483.06 stddev:1542.90 (28.1%) stddev-sans-first:1509.01 07:37:56 INFO - PROCESS | 1665 | Values: 293.7 298.1 301.0 311.2 318.2 5304.8 5610.8 5656.0 5699.4 5704.2 4102.8 4831.5 5650.8 5680.3 5686.2 3376.1 4081.3 4159.4 4180.7 4277.0 3527.7 4228.0 4249.3 4250.8 4274.9 3531.7 3868.3 4006.7 4240.4 4311.9 3880.5 4119.8 4168.0 4210.8 4247.9 3663.4 3667.6 3670.3 3670.7 3673.2 5433.6 5436.0 5477.6 5488.5 5546.8 4084.3 4801.4 4922.1 4950.0 4978.9 4999.7 5023.6 5855.4 5952.4 5986.8 3427.9 4103.4 4105.3 4108.7 4116.8 4991.9 6055.3 6294.0 6317.7 6356.5 3493.6 4137.3 4154.2 4158.5 4170.2 3580.4 4251.4 4267.9 4276.1 4283.1 3957.5 4797.5 4929.5 4984.5 4989.0 4912.5 5127.6 5148.5 6055.0 6103.8 1215.3 1328.2 1362.8 1412.0 1452.2 744.6 851.0 855.0 886.0 894.2 5420.4 5424.0 5498.5 5568.7 5576.9 4615.3 5687.6 5913.9 6152.9 6199.3 5318.9 5354.9 5573.7 5637.4 5649.4 2849.6 3242.4 3286.5 3289.9 3300.8 6096.6 6118.7 6137.7 6148.4 6441.2 5746.1 6336.8 6448.9 6453.8 6490.3 5437.4 6242.4 6488.0 6506.7 6528.1 5212.6 5829.9 6243.9 6323.5 6333.8 6164.7 6231.7 6243.2 6249.1 6263.7 6171.9 6353.9 6498.2 6563.2 6607.0 3790.5 3848.9 4056.7 4059.5 4076.6 6201.7 6220.9 6255.5 6257.5 6283.9 5189.8 5504.8 5839.3 6034.2 6043.8 5788.2 5980.7 6010.0 6020.6 6081.4 5674.2 5870.2 5929.8 5942.1 6008.9 5107.6 6035.4 6046.5 6081.3 6140.8 5931.7 5951.6 5977.5 5985.6 5997.8 07:37:56 INFO - PROCESS | 1665 | 07:37:56 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:26527.93 Median:13735.81 stddev:43096.39 (313.8%) stddev-sans-first:43173.38 07:37:56 INFO - PROCESS | 1665 | Values: 618.8 699.3 743.4 833.9 856.6 15375.8 16075.6 16391.2 16547.0 16553.5 15037.8 15075.5 15107.5 15112.6 15131.0 707.7 893.7 921.0 924.5 934.0 915.3 916.4 924.2 926.1 926.2 973.3 974.4 977.1 978.9 983.1 933.0 958.8 959.2 968.9 968.9 720.4 723.7 724.2 726.8 727.3 15135.8 15139.6 15141.0 15476.6 15481.1 14139.9 14191.6 14284.3 14605.6 14617.9 12948.9 13345.5 13416.7 13888.6 13961.4 4830.0 4845.0 4883.6 4903.0 4908.7 124344.0 128877.0 128930.4 129717.0 130252.7 11687.0 11706.2 11837.1 11845.7 11882.2 23325.0 23426.5 23597.2 23609.0 23643.8 7957.5 8024.8 8265.9 8425.4 8592.6 3827.8 4603.5 4666.6 4703.1 4755.7 3841.8 3987.1 4005.7 4006.3 4011.5 736.3 775.4 777.1 778.1 790.6 580.7 609.5 641.1 678.6 700.4 9120.4 9745.8 9780.5 9866.7 10084.8 612.0 615.4 627.3 637.4 663.3 4560.3 4822.5 4877.0 4878.7 4884.3 105288.5 155950.0 159358.2 172409.0 177922.0 121351.0 121603.4 122834.0 124012.0 127581.0 119382.0 126320.0 127894.0 129822.4 129864.7 9246.3 9350.3 9429.3 9440.3 9462.2 68303.0 68914.0 69866.7 70462.6 71489.0 97930.0 107151.0 108073.0 111193.4 112999.4 10347.8 12366.2 12628.6 12783.7 13142.0 13285.1 13501.7 13762.4 13764.7 14283.0 11577.1 11733.8 11774.0 12234.5 13018.3 10738.5 12264.0 12421.7 12557.2 12889.2 11937.9 12024.9 12266.4 12349.9 12724.9 8332.5 10529.8 10959.2 10998.8 11201.9 8041.6 8435.7 9072.8 11129.8 11252.6 07:37:56 INFO - PROCESS | 1665 | -------- Summary: end -------- 07:37:56 INFO - PROCESS | 1665 | 07:37:57 INFO - TEST-INFO | 1665: exit 0 07:37:57 INFO - TEST-OK | dromaeo_css | took 1111710ms 07:37:57 INFO - TEST-START | kraken 07:37:57 INFO - operating with platform_type : mac_ 07:37:57 INFO - Initialising browser for kraken test... 07:37:57 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpolYves/profile http://localhost:49267/getInfo.html) 07:38:00 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 07:38:00 INFO - PROCESS | 1674 | colorDepth:24 07:38:00 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 07:38:00 INFO - PROCESS | 1674 | __metrics 07:38:00 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:38:00 INFO - TEST-INFO | 1674: exit 0 07:38:00 INFO - Browser initialized. 07:38:00 INFO - Running cycle 1/1 for kraken test... 07:38:00 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpolYves/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:12 INFO - PROCESS | 1676 | RSS: Main: 226852864 07:38:12 INFO - PROCESS | 1676 | 07:38:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:16 INFO - PROCESS | 1676 | RSS: Main: 239689728 07:38:16 INFO - PROCESS | 1676 | 07:38:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 07:38:20 INFO - PROCESS | 1676 | RSS: Main: 248381440 07:38:20 INFO - PROCESS | 1676 | 07:38:23 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 07:38:23 INFO - PROCESS | 1676 | RSS: Main: 251969536 07:38:23 INFO - PROCESS | 1676 | 07:38:26 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 07:38:26 INFO - PROCESS | 1676 | RSS: Main: 252432384 07:38:26 INFO - PROCESS | 1676 | 07:38:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:29 INFO - PROCESS | 1676 | RSS: Main: 252567552 07:38:29 INFO - PROCESS | 1676 | 07:38:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:32 INFO - PROCESS | 1676 | RSS: Main: 253054976 07:38:32 INFO - PROCESS | 1676 | 07:38:36 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:36 INFO - PROCESS | 1676 | RSS: Main: 253296640 07:38:36 INFO - PROCESS | 1676 | 07:38:39 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 07:38:40 INFO - PROCESS | 1676 | RSS: Main: 253440000 07:38:40 INFO - PROCESS | 1676 | 07:38:42 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:42 INFO - PROCESS | 1676 | RSS: Main: 254484480 07:38:42 INFO - PROCESS | 1676 | 07:38:44 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:45 INFO - PROCESS | 1676 | RSS: Main: 254074880 07:38:45 INFO - PROCESS | 1676 | 07:38:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:48 INFO - PROCESS | 1676 | RSS: Main: 254447616 07:38:48 INFO - PROCESS | 1676 | 07:38:51 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:51 INFO - PROCESS | 1676 | RSS: Main: 254623744 07:38:51 INFO - PROCESS | 1676 | 07:38:54 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:55 INFO - PROCESS | 1676 | RSS: Main: 254566400 07:38:55 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 07:38:57 INFO - PROCESS | 1676 | RSS: Main: 256454656 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | __start_tp_report 07:38:57 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 07:38:57 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 07:38:57 INFO - PROCESS | 1676 | |i|pagename|runs| 07:38:57 INFO - PROCESS | 1676 | |0;ai-astar;115;119;108;117;121;119;101;156;172;147 07:38:57 INFO - PROCESS | 1676 | |1;audio-beat-detection;140;111;132;178;140;135;128;110;153;110 07:38:57 INFO - PROCESS | 1676 | |2;audio-dft;198;266;216;197;197;194;194;196;201;196 07:38:57 INFO - PROCESS | 1676 | |3;audio-fft;67;100;111;188;59;61;58;60;132;60 07:38:57 INFO - PROCESS | 1676 | |4;audio-oscillator;69;74;120;101;103;112;113;71;81;126 07:38:57 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;88;85;133;131;87;85;91;85;102;76 07:38:57 INFO - PROCESS | 1676 | |6;imaging-darkroom;106;91;104;126;150;152;150;94;96;94 07:38:57 INFO - PROCESS | 1676 | |7;imaging-desaturate;135;84;142;145;77;145;148;128;94;91 07:38:57 INFO - PROCESS | 1676 | |8;json-parse-financial;53;55;58;57;60;59;58;58;58;61 07:38:57 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;68;73;75;69;78;73;70;70;71;71 07:38:57 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;85;102;107;81;81;84;82;89;91;88 07:38:57 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;137;158;154;148;144;175;133;149;197;137 07:38:57 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;205;206;165;220;181;167;162;163;163;167 07:38:57 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;68;65;68;69;79;86;84;88;88;88 07:38:57 INFO - PROCESS | 1676 | __end_tp_report 07:38:57 INFO - PROCESS | 1676 | __start_cc_report 07:38:57 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1086 07:38:57 INFO - PROCESS | 1676 | __end_cc_report 07:38:57 INFO - PROCESS | 1676 | __startTimestamp1474123137525__endTimestamp 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | ------- Summary: start ------- 07:38:57 INFO - PROCESS | 1676 | Number of tests: 14 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:127.50 Median:120.00 stddev:22.87 (19.1%) stddev-sans-first:23.81 07:38:57 INFO - PROCESS | 1676 | Values: 115.0 119.0 108.0 117.0 121.0 119.0 101.0 156.0 172.0 147.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:137.50 stddev:21.32 (15.5%) stddev-sans-first:22.49 07:38:57 INFO - PROCESS | 1676 | Values: 140.0 111.0 132.0 178.0 140.0 135.0 128.0 110.0 153.0 110.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:205.50 Median:197.50 stddev:22.20 (11.2%) stddev-sans-first:23.38 07:38:57 INFO - PROCESS | 1676 | Values: 198.0 266.0 216.0 197.0 197.0 194.0 194.0 196.0 201.0 196.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:89.60 Median:59.50 stddev:43.54 (73.2%) stddev-sans-first:45.41 07:38:57 INFO - PROCESS | 1676 | Values: 67.0 100.0 111.0 188.0 59.0 61.0 58.0 60.0 132.0 60.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:97.50 stddev:21.47 (22.0%) stddev-sans-first:20.24 07:38:57 INFO - PROCESS | 1676 | Values: 69.0 74.0 120.0 101.0 103.0 112.0 113.0 71.0 81.0 126.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:96.30 Median:85.00 stddev:19.89 (23.4%) stddev-sans-first:20.87 07:38:57 INFO - PROCESS | 1676 | Values: 88.0 85.0 133.0 131.0 87.0 85.0 91.0 85.0 102.0 76.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:116.30 Median:121.50 stddev:25.70 (21.2%) stddev-sans-first:26.99 07:38:57 INFO - PROCESS | 1676 | Values: 106.0 91.0 104.0 126.0 150.0 152.0 150.0 94.0 96.0 94.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:118.90 Median:112.50 stddev:28.78 (25.6%) stddev-sans-first:29.94 07:38:57 INFO - PROCESS | 1676 | Values: 135.0 84.0 142.0 145.0 77.0 145.0 148.0 128.0 94.0 91.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:58.00 stddev:2.31 (4.0%) stddev-sans-first:1.72 07:38:57 INFO - PROCESS | 1676 | Values: 53.0 55.0 58.0 57.0 60.0 59.0 58.0 58.0 58.0 61.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.00 stddev:3.01 (4.2%) stddev-sans-first:2.86 07:38:57 INFO - PROCESS | 1676 | Values: 68.0 73.0 75.0 69.0 78.0 73.0 70.0 70.0 71.0 71.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:84.50 stddev:8.92 (10.6%) stddev-sans-first:9.34 07:38:57 INFO - PROCESS | 1676 | Values: 85.0 102.0 107.0 81.0 81.0 84.0 82.0 89.0 91.0 88.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:153.20 Median:151.50 stddev:19.66 (13.0%) stddev-sans-first:19.96 07:38:57 INFO - PROCESS | 1676 | Values: 137.0 158.0 154.0 148.0 144.0 175.0 133.0 149.0 197.0 137.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.90 Median:174.00 stddev:22.03 (12.7%) stddev-sans-first:21.41 07:38:57 INFO - PROCESS | 1676 | Values: 205.0 206.0 165.0 220.0 181.0 167.0 162.0 163.0 163.0 167.0 07:38:57 INFO - PROCESS | 1676 | 07:38:57 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.30 Median:85.00 stddev:9.72 (11.4%) stddev-sans-first:9.57 07:38:57 INFO - PROCESS | 1676 | Values: 68.0 65.0 68.0 69.0 79.0 86.0 84.0 88.0 88.0 88.0 07:38:57 INFO - PROCESS | 1676 | -------- Summary: end -------- 07:38:57 INFO - PROCESS | 1676 | 07:38:58 INFO - TEST-INFO | 1676: exit 0 07:38:58 INFO - TEST-OK | kraken | took 60787ms 07:38:58 INFO - SUITE-END | took 1172s 07:38:58 INFO - Completed test suite (00:19:32) 07:38:58 INFO - javascript benchmark 07:38:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7545.06787158021, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.162459385155, 3362.8150451725155, 3367.562216911801, 3372.15696075981, 3381.1885147564576, 5834.358220595731, 5836.708164591764, 5870.647761134335, 5916.201312822767, 5945.464908158265, 5953.0, 5985.4014598540125, 6033.1855199548045, 6074.119252708355, 6091.421314975081, 5342.839714808562, 5412.485813847678, 5432.728363581825, 5443.156310771817, 5468.9726551367185, 5318.9202161967605, 5321.228421878825, 5360.303160589118, 5434.8913021739545, 5461.317335333083, 5388.703621300827, 5452.345718513784, 5453.64551304164, 5454.318210223722, 5478.534324582395, 4931.90136197274, 5385.703786291791, 5435.537979271767, 5458.1266997280445, 5472.206530053159, 5305.840824775263, 5414.566834653218, 5416.972915135439, 5434.483724046209, 5439.619226654136, 5814.215080964058, 6017.548683848729, 6065.63606183626, 6110.908336374958, 6112.908306375363, 6193.504519638418, 6210.565260431772, 6227.501799856001, 6284.0, 6301.558890877641, 6330.87338253237, 6376.9681151594405, 6393.552451328409, 6403.807885763435, 6435.646039467827, 5941.881162376728, 6143.047827586731, 6149.169862068608, 6149.477294429934, 6161.199044124325, 6386.744530218739, 6453.515986301046, 6469.256035555877, 6603.80188594343, 6622.569532980341, 6265.780697675559, 6269.122322874801, 6272.0, 6277.2153480814895, 6292.653904035231, 6211.9750241209695, 6402.487800976004, 6438.549301548846, 6441.3880681335195, 6456.0961465394885, 6203.658798766111, 6222.81331560054, 6240.157578726905, 6243.43809057187, 6313.494920406357, 5251.816186433456, 5346.037713211582, 6256.906146407717, 6268.216472940882, 6308.936910630835, 4319.654427645781, 5050.166722490748, 5191.922121168185, 5194.870128246748, 5200.245964335226, 3770.321342158383, 4514.503404625489, 4527.886802829956, 4556.362109304634, 4611.5619016193405, 5032.220005899091, 5926.970365148233, 6057.606255593372, 6058.818235452944, 6082.482988945989, 6203.503719702413, 6437.452816510556, 6442.098106265126, 6455.548111632141, 6467.482601391877, 4906.773306673332, 5039.891223930729, 5995.160677505153, 6092.329843717188, 6097.4817140543555, 5559.63862348946, 5645.633033852869, 5653.265075540154, 5657.15142728594, 5658.122990936411, 6253.468455181361, 6632.96683516589, 6633.270340262471, 6657.0, 6658.201015878126, 6279.089532017741, 6299.811005669837, 6339.809805705836, 6417.679116044085, 6424.453921416732, 6197.752089916533, 6623.867522649423, 6630.138082049302, 6646.136002319668, 6678.565893216925, 6189.1025801257665, 6329.556931014785, 6340.682965851781, 6353.396427339396, 6367.808965731036, 6211.689415529296, 6252.374762523711, 6257.5932564383165, 6345.682715864281, 6370.044493326038, 5035.874103147451, 6172.22847144107, 6248.875022499434, 6258.780942666985, 6289.685515724104, 6148.538859585459, 6243.531735119794, 6279.937200627935, 6317.6209427434505, 6321.683915804099, 4694.413198350207, 4763.618910487222, 6079.817605471843, 6139.6009259398, 6225.470834979077, 6249.218847644044, 6265.060240963892, 6278.874422511615, 6279.372062793684, 6312.147860038836, 6061.242344706911, 6135.325114237341, 6153.292371377349, 6248.937510625017, 6254.906176407449], "value": 5821.421878839185, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.46687410411494, 488.38219652140026, 515.8787684894047, 536.1609081787003, 556.2990631803927, 37053.44419833701, 39481.60518394815, 39499.40750888739, 39593.81218563441, 39734.20531589366, 41724.0, 42308.57691423085, 42372.9406764831, 42411.363829542504, 42455.78772106135, 9672.70981870545, 9871.90128098717, 9894.406335619886, 9903.257255705816, 9927.0, 9715.951420242889, 9742.902570974305, 9812.803743925153, 9815.214782817358, 9829.115379615832, 9775.169110625606, 9781.706548803548, 9815.950920245388, 9830.803383932318, 9846.064623860757, 9698.999999999965, 9725.513724313756, 9748.853767193497, 9789.070038346346, 9806.509674516246, 8981.236594889442, 9598.280128990353, 9709.757256068584, 9742.464164470952, 9818.116369526777, 39989.000274993065, 40277.79166625006, 40379.59620403788, 40632.59367406317, 40733.38899916474, 3139.4819854724174, 3439.94840077399, 3453.9827300863335, 3481.5125882376487, 3554.2713743682484, 29255.853720731473, 30845.691543084507, 30860.0, 31161.220969475944, 31269.37461250762, 3036.5445183222473, 3100.503919372912, 3141.277506173593, 3186.9840650796828, 3189.1708155879405, 487210.0, 492017.0, 495537.0, 497440.0, 497468.0, 33003.0, 33256.0, 33346.3330733384, 33380.16549586231, 34115.488267676, 34739.65260347415, 34790.47814282788, 34946.82526587351, 35508.112297192776, 35699.4645080324, 15035.924820375829, 15057.397704091714, 15068.849311506745, 15454.38182472711, 15621.375144994301, 13173.341332933314, 13238.073334866467, 13243.602691919257, 13259.801102983463, 13265.270410127343, 7781.5331080135375, 7804.570748608881, 7809.258120478546, 7859.882101768478, 7873.409494287951, 3318.3363327334628, 3332.7000569948823, 3349.6482869298375, 3353.748468864845, 3356.9328613427597, 962.6678795815433, 971.8493633486821, 1021.6730646193296, 1050.9054185123375, 1086.6142519405498, 30685.38629227403, 30826.075217743502, 31109.0, 31112.84443577813, 31155.53266701001, 1017.8982101789762, 1036.6371769880482, 1142.5486932661718, 1211.3821950804977, 1218.7318789866215, 14089.36597853099, 14253.714925701428, 15720.92139539318, 15988.920055399649, 16137.596560085856, 721264.0, 729165.0, 731956.0, 733966.0, 734299.0, 57834.7108264456, 58755.0, 59387.0, 60376.0, 60925.69537152286, 1435.9138451692932, 1451.6153219396867, 1477.748782706964, 1483.5178566965778, 1491.8582734640192, 24543.63184552269, 27651.30871728223, 28987.7101228985, 30583.0, 34662.0, 62354.688226558574, 65191.0, 66201.66899165665, 66203.33796662165, 66294.67410651856, 661742.0, 668611.0, 673524.0, 674576.0, 675730.6213468901, 48367.51632483771, 48766.0, 49058.0, 49923.75038124786, 50242.99514009772, 53075.93848123093, 53128.46871531235, 53537.46462535325, 53621.73189134029, 53894.19158712544, 41138.9715257109, 44350.0, 44433.555664442945, 45209.32186017278, 45413.54586454093, 43386.349204762584, 43559.34660980151, 43687.68936931897, 44302.89879708389, 45383.7730811357, 35513.467297990035, 41643.58356416397, 41793.16413671771, 42150.0, 42426.78786606047, 36676.81661591675, 38212.61787382202, 38736.03159921025, 38877.6112238874, 39012.41481377725, 22442.32673019812, 28023.71976280211, 28147.43705125845, 28175.872965081988, 28207.57688634631], "value": 19530.020486945377, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.8535566317326, 380.1465709482211, 390.2468236303933, 399.6862462966572, 409.6047314341662, 20528.486787830294, 22288.0, 22710.88644556775, 22802.771972280272, 23105.07579696812, 20659.0, 21391.358259252207, 21665.916704164807, 21723.565528689418, 22498.55002899941, 5205.401378841434, 5451.918221226685, 5493.917591236134, 5551.555875529948, 5553.944460555404, 3922.548906875703, 5495.752691128889, 5506.476884695948, 5540.058190107671, 6617.966910165443, 6821.386075253226, 6956.547824391424, 6959.095317608704, 6961.338672826073, 6966.860662786767, 6079.0, 6177.135201071854, 6190.690465476753, 6215.96892015537, 6870.868355717745, 5319.627626066177, 5988.221531200916, 5997.430244126801, 6056.091586262052, 6113.969430152821, 24736.0, 26285.817138228827, 26819.463610727675, 26968.59547106795, 27047.0, 2210.160139147114, 2223.3107736601705, 2284.5088306014122, 2438.7317395086525, 2965.555166724987, 10196.184305255561, 10569.947150264275, 10599.84100238497, 11888.583899563473, 16150.434734784343, 1896.0804010055112, 1906.733057371969, 1913.6938089905686, 1925.6918892977055, 1952.9804701952942, 129837.35081324533, 130650.0, 133186.0, 135529.0, 140619.29690351585, 10786.514606842711, 10873.510692018877, 11925.463354149084, 11930.56833180024, 11973.940130299467, 15345.693086138437, 15349.386024558893, 15356.769648455282, 15368.0, 15406.768898466533, 8957.88026496688, 9082.591283392263, 9083.545822708971, 9111.498867562348, 9230.953845230732, 9039.186473217442, 9116.270698344117, 9156.587953542106, 9270.30472714549, 9316.813663726687, 4611.884702882455, 4748.287756836433, 4754.881127971759, 4766.046790641885, 4847.054824309296, 1523.2612183091192, 1551.201131417321, 1670.4988503448917, 1956.1979588368702, 2022.2820898581085, 846.8645016797283, 951.8048799995984, 1023.2734758321675, 1046.2571574182264, 1066.7546464313252, 22670.093196271966, 22694.0, 22744.886275568515, 23233.30300091, 23275.418114547272, 999.715081201854, 1005.5877090392904, 1008.0473952115327, 1017.5827910556636, 1066.6959916423782, 11120.38837863909, 11851.0, 12814.295213763146, 12820.743585128244, 12822.35888205586, 631089.0, 631226.0, 631526.0, 656872.0, 661517.0, 80700.19299806927, 97684.0, 100212.0, 101737.49131254444, 113266.0, 1033.3128469567653, 1060.4591658254346, 1072.0994364733817, 1082.1234799812178, 1085.3650614390513, 16136.0, 20694.689579656617, 20922.0, 21244.36266911995, 21383.786162138178, 59149.40850591611, 60434.0, 60967.0, 61566.0765088533, 62487.06269405872, 86919.56540217258, 89063.10936890548, 89304.5534772348, 89521.55239223762, 92679.07320926705, 8332.583370831555, 8439.282660973886, 8512.191341822518, 8658.134186581292, 8706.390552661253, 9387.906120938704, 12994.35028248565, 13487.32563371847, 13541.255230962392, 14109.012369134061, 11554.364509951698, 11633.767324653629, 11737.119716021161, 11840.82238766402, 12148.028157747181, 11583.362915039754, 11597.0, 11767.293962362286, 11926.105542084204, 11969.401529923294, 9573.138417542175, 10030.598776048877, 11866.881331186578, 11904.0, 12066.577669781867, 5428.945710542844, 6229.2524897012645, 8476.576171191538, 8792.47245165292, 8801.559922003746, 9912.207023438252, 10629.574817007233, 10824.0, 11131.777364452828, 11285.605003825156], "value": 9985.211326272456, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.5015794314045, 1538.446159382622, 1606.309287006587, 1925.7977912319197, 1936.2932529626692, 6283.685815709219, 8271.875921861161, 8383.874241886377, 9338.486383248919, 9363.157315841581, 7654.349380302681, 8896.599653015614, 9134.588943497542, 9143.45139291643, 9162.22121119704, 2330.790228879405, 2376.940576485584, 2380.4762952150586, 2381.452265978826, 2386.05750728463, 2006.8494862885268, 2377.4888398994212, 2413.3363325085566, 2421.067888862783, 2442.010985550846, 2413.6379543068506, 2416.3838221253645, 2427.2475532584867, 2430.295214387834, 2433.5862903306393, 2351.5532048910654, 2353.482233908537, 2360.752121027293, 2373.4066483379156, 2424.6726691896542, 2387.6537902004284, 2404.446977195255, 2409.7831195192516, 2420.394901274681, 2425.927222183337, 9345.766355841159, 9388.014258502895, 9405.811883762355, 9418.764530886714, 9433.339666223366, 5408.8377348679605, 5576.33084029915, 5577.888442231173, 5578.2748242728185, 5580.469855363734, 3729.850805967758, 3734.8795361391476, 3740.0, 3751.887443376676, 3814.713896457749, 1100.6808025672544, 1101.1576144249655, 1103.536514663843, 1111.6553868300853, 1124.527698366688, 9632.614695412176, 11394.829077563843, 12070.758584828342, 12157.331346775924, 12188.329641869694, 4725.36207611968, 4766.951763574926, 4793.760311984387, 4899.804007839646, 4919.458859525449, 10237.027482389161, 10340.586376544854, 10353.844692329621, 10375.84436233457, 10385.584576617002, 5359.785608575613, 5380.91928621071, 5413.079776438015, 5421.5391691706645, 5519.834404967857, 4478.39541661878, 4761.285807128958, 4876.5854902332985, 4907.779149938261, 4982.551570358685, 4018.537868145142, 4108.219438306712, 4134.607212314825, 4162.791860406967, 4192.559781222989, 996.8704068471052, 1244.6930722741085, 1313.5468263449095, 1329.3884812986134, 1338.2706424998398, 880.859062549989, 953.5280036382036, 954.61815273891, 955.7323949294208, 984.2618036472645, 5076.974615126901, 5867.413258674099, 6091.634501929899, 6272.153259310026, 6283.59156654816, 438.1937235486656, 444.2026562320642, 444.6420631391696, 446.8302045222795, 457.32772823948744, 4017.5178978522763, 4023.2355852388537, 4032.2137183248983, 4040.1416647101946, 4162.063535704503, 10972.341659500455, 11196.160287978435, 11343.602973895873, 11344.829827552594, 11346.716332091764, 10189.184865210766, 10374.118199953089, 11287.040601548797, 11287.266327688674, 11306.265092768943, 8574.056853746082, 8580.442271252348, 8669.349798765117, 8679.392442528962, 8689.695860644848, 5137.8201762938115, 5868.794592189253, 5997.190379298739, 6125.9693701531205, 6169.0, 8368.539730314891, 10078.8488172676, 10202.132818710492, 10228.53971571296, 10391.272610917164, 9970.50147492637, 10289.588416463257, 10428.58285668595, 10435.426051566934, 10552.84170737455, 7303.342699157101, 7686.423518236043, 7765.301122898966, 7812.023497062867, 7919.802004949923, 8661.307095432225, 8819.11808819113, 8833.867491987497, 8893.155150260714, 8919.777005574913, 7706.499077559941, 7819.687212511436, 7845.529268243924, 7935.523868567904, 8091.109977902308, 7739.961300193463, 7825.921740782519, 7848.489848159851, 7849.568273744999, 7880.921190788019, 7216.855662886818, 7284.963575182091, 7303.269673032654, 7317.414606831334, 7328.890066648898, 4958.479359667183, 4986.276989836526, 5094.617903657167, 5142.922856157235, 5211.739413029409, 4981.62637802159, 5074.035933172685, 5096.719680417465, 5144.6655967362, 5258.211268309784], "value": 4612.106201506008, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.7003758369216, 298.1339209596121, 301.02167954149013, 311.2374682029029, 318.1949667341627, 5304.840854774353, 5610.80362187323, 5655.953648575011, 5699.401562835905, 5704.172894930232, 4102.815373308201, 4831.497164555695, 5650.773969041234, 5680.289963754531, 5686.232358631594, 3376.1053320869987, 4081.2857749893724, 4159.438475805773, 4180.662188099809, 4277.0, 3527.677121079595, 4227.978860105695, 4249.277622804131, 4250.766207858566, 4274.935875961863, 3531.6644918732677, 3868.303705333039, 4006.719529632927, 4240.427542281799, 4311.8706438806885, 3880.5164694595483, 4119.838962647732, 4168.0, 4210.810513526899, 4247.851325203618, 3663.3772258715817, 3667.6332366763377, 3670.3209906167444, 3670.7063434925144, 3673.2286219893854, 5433.609211946731, 5435.950217088255, 5477.589180811416, 5488.53347465466, 5546.805861794858, 4084.264832330179, 4801.351817504627, 4922.114019476493, 4949.950500494985, 4978.900421991576, 4999.650024498286, 5023.572996295319, 5855.44830619886, 5952.404759524013, 5986.760529578812, 3427.8971630851115, 4103.440692536847, 4105.3020986432375, 4108.650764685005, 4116.75299482029, 4991.850244492671, 6055.273367195965, 6293.9999999999545, 6317.652529110897, 6356.523260755415, 3493.63316851732, 4137.275976704064, 4154.169166166779, 4158.542560318362, 4170.16596680665, 3580.427131658922, 4251.383549385322, 4267.850625228164, 4276.123394704078, 4283.121959998193, 3957.465742124776, 4797.544233297831, 4929.457759646436, 4984.476629953875, 4989.0, 4912.508749125131, 5127.5897928166405, 5148.536631703165, 6054.969725151346, 6103.755849765959, 1215.3254943506438, 1328.1842493912966, 1362.821159696875, 1411.9505817296345, 1452.208546342246, 744.6239648977232, 850.9914900850912, 854.9700760473478, 885.964561417549, 894.2130924786146, 5420.430854760272, 5423.969445805364, 5498.45015498447, 5568.6658800472105, 5576.880785301641, 4615.284630882219, 5687.630304030225, 5913.881722365529, 6152.8769424611255, 6199.3490683478485, 5318.883034562785, 5354.8661283467445, 5573.749181286852, 5637.408072152447, 5649.406812284733, 2849.575212393803, 3242.3839470501002, 3286.5234540991423, 3289.9177520562175, 3300.7689461737928, 6096.573239873255, 6118.663473509, 6137.662428566472, 6148.385161483816, 6441.227052753699, 5746.080627099644, 6336.778212762623, 6448.871022579522, 6453.806385808433, 6490.318516555693, 5437.401885792559, 6242.438180563771, 6487.994360874072, 6506.707198176094, 6528.050529429816, 5212.635115541876, 5829.912551311649, 6243.906341404791, 6323.525735569759, 6333.809985700436, 6164.722587483664, 6231.688415579112, 6243.188385509855, 6249.062640603764, 6263.718132683949, 6171.876562468815, 6353.936460635519, 6498.220213574401, 6563.1796025496815, 6607.0, 3790.4882840816135, 3848.903062627138, 4056.7363121397016, 4059.5331536873637, 4076.612721791425, 6201.658908759881, 6220.906686399617, 6255.53083518747, 6257.464532539101, 6283.900317444338, 5189.818356357509, 5504.779808807602, 5839.299284085937, 6034.2155519782145, 6043.848903777441, 5788.247527821356, 5980.671063091571, 6010.0, 6020.555066784028, 6081.422264884829, 5674.205611214516, 5870.236869206976, 5929.792457263975, 5942.10868369748, 6008.87982240344, 5107.642465027412, 6035.36628653985, 6046.54650901193, 6081.300650425258, 6140.785072522441, 5931.703414829328, 5951.55363347742, 5977.5217982560425, 5985.6408615483215, 5997.790077347272], "value": 4334.17214784526, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.7679620142446, 699.3356311504073, 743.3607097895812, 833.9249467547927, 856.6016802186983, 15375.846241537596, 16075.598110047225, 16391.180440977932, 16547.0, 16553.503394898158, 15037.77443338348, 15075.472358467456, 15107.546773596781, 15112.622184445367, 15131.0, 707.7204504220842, 893.6872094766844, 921.0237148622482, 924.4638109896277, 933.9906600933972, 915.2677857713836, 916.4134953629682, 924.2375040591505, 926.0924294191697, 926.2358554192817, 973.2943615878467, 974.3666616699167, 977.0669011094424, 978.9265805064613, 983.1151963233111, 933.0436302789694, 958.8226178156993, 959.2374062620199, 968.8837339519267, 968.9031096890325, 720.3732752505301, 723.6779633063289, 724.2395484741054, 726.8182954261434, 727.3344889426169, 15135.848641513665, 15139.621509462242, 15141.015833970756, 15476.613084672974, 15481.14853683047, 14139.858601413956, 14191.574252772434, 14284.285785710777, 14605.561833145022, 14617.926910365486, 12948.935255323757, 13345.532906348231, 13416.664583385396, 13888.583342499742, 13961.441542338294, 4830.009847981156, 4845.0, 4883.5604795568215, 4903.043906438246, 4908.680935739201, 124344.0, 128877.0, 128930.3553482236, 129717.0, 130252.69747302501, 11687.006604438719, 11706.239094459002, 11837.112216583619, 11845.703857403634, 11882.243903411381, 23324.950377233065, 23426.53146937052, 23597.174098906456, 23609.0, 23643.7635623645, 7957.482763620346, 8024.759257222293, 8265.917340826634, 8425.36809739272, 8592.5703714814, 3827.8468861245797, 4603.516630753789, 4666.60333871618, 4703.106409782131, 4755.714657120584, 3841.769493830379, 3987.063040185554, 4005.7396269242404, 4006.278869803405, 4011.4584531088512, 736.2674139231499, 775.441681988967, 777.0946846923384, 778.0740918307197, 790.6244533846377, 580.6603137164806, 609.4667166229549, 641.1216633212435, 678.5691086160324, 700.3626699703245, 9120.361574689709, 9745.756356091155, 9780.462074585825, 9866.654667086617, 10084.848727269096, 612.0238220039031, 615.3630991923338, 627.2786295759821, 637.4008432073836, 663.3002182697301, 4560.293154561048, 4822.539698342391, 4876.975615121973, 4878.658493905464, 4884.3047639797405, 105288.47355763172, 155950.0, 159358.2032089832, 172409.0, 177922.0, 121351.0, 121603.39198303952, 122834.0, 124012.0, 127581.0, 119382.0, 126319.99999999817, 127894.0, 129822.35088824495, 129864.70135298526, 9246.306527010636, 9350.251979841732, 9429.339946203974, 9440.291978101532, 9462.2430205582, 68303.0, 68914.0, 69866.65066674634, 70462.64768676124, 71489.0, 97930.0, 107151.0, 108073.0, 111193.44403277931, 112999.43500282775, 10347.844782328422, 12366.19619724715, 12628.558000469939, 12783.680407989874, 13142.0, 13285.13646612967, 13501.662458438617, 13762.449502019806, 13764.724705505634, 14283.0, 11577.131715121232, 11733.76532469363, 11774.0, 12234.510619575118, 13018.349082545645, 10738.463076846283, 12264.0, 12421.689457763629, 12557.246565206116, 12889.162204456681, 11937.940310298392, 12024.939875300568, 12266.386680665753, 12349.93825030869, 12724.872751272738, 8332.458390204618, 10529.842052369373, 10959.232853700165, 10998.835017474905, 11201.887981120084, 8041.597920104088, 8435.662573496991, 9072.775175351244, 11129.83305250438, 11252.606158784403], "value": 8129.084951750515, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1614.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 119.0, 108.0, 117.0, 121.0, 119.0, 101.0, 156.0, 172.0, 147.0], "value": 127.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 132.0, 178.0, 140.0, 135.0, 128.0, 110.0, 153.0, 110.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 266.0, 216.0, 197.0, 197.0, 194.0, 194.0, 196.0, 201.0, 196.0], "value": 205.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 100.0, 111.0, 188.0, 59.0, 61.0, 58.0, 60.0, 132.0, 60.0], "value": 89.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 120.0, 101.0, 103.0, 112.0, 113.0, 71.0, 81.0, 126.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 133.0, 131.0, 87.0, 85.0, 91.0, 85.0, 102.0, 76.0], "value": 96.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 91.0, 104.0, 126.0, 150.0, 152.0, 150.0, 94.0, 96.0, 94.0], "value": 116.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 84.0, 142.0, 145.0, 77.0, 145.0, 148.0, 128.0, 94.0, 91.0], "value": 118.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 58.0, 57.0, 60.0, 59.0, 58.0, 58.0, 58.0, 61.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 75.0, 69.0, 78.0, 73.0, 70.0, 70.0, 71.0, 71.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 102.0, 107.0, 81.0, 81.0, 84.0, 82.0, 89.0, 91.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 158.0, 154.0, 148.0, 144.0, 175.0, 133.0, 149.0, 197.0, 137.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 206.0, 165.0, 220.0, 181.0, 167.0, 162.0, 163.0, 163.0, 167.0], "value": 179.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 68.0, 69.0, 79.0, 86.0, 84.0, 88.0, 88.0, 88.0], "value": 78.3, "unit": "score"}]}]} 07:38:58 INFO - Return code: 0 07:38:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:38:58 ERROR - # TBPL SUCCESS # 07:38:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:58 INFO - 2016-09-17 07:38:58.804 screenresolution[1681:11268] starting screenresolution argv=screenresolution get 07:38:58 INFO - 2016-09-17 07:38:58.806 screenresolution[1681:11268] Display 0: 1600x1200x32@60 07:38:58 INFO - 2016-09-17 07:38:58.812 screenresolution[1682:11271] starting screenresolution argv=screenresolution list 07:38:58 INFO - Available Modes on Display 0 07:38:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:59 INFO - Intel Iris: 07:38:59 INFO - Chipset Model: Intel Iris 07:38:59 INFO - Type: GPU 07:38:59 INFO - Bus: Built-In 07:38:59 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:59 INFO - Vendor: Intel (0x8086) 07:38:59 INFO - Device ID: 0x0a2e 07:38:59 INFO - Revision ID: 0x0009 07:38:59 INFO - Displays: 07:38:59 INFO - Display: 07:38:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:59 INFO - Display Serial Number: 1600x1200 60 07:38:59 INFO - Main Display: Yes 07:38:59 INFO - Mirror: Off 07:38:59 INFO - Online: Yes 07:38:59 INFO - Rotation: Supported 07:38:59 INFO - Return code: 0 07:38:59 INFO - Running post-action listener: _resource_record_post_action 07:38:59 INFO - [mozharness: 2016-09-17 14:38:59.046475Z] Finished run-tests step (success) 07:38:59 INFO - Running post-run listener: _resource_record_post_run 07:38:59 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 31407104; Write bytes: 664812544; Read time: 200; Write time: 1875 07:38:59 INFO - TinderboxPrint: CPU usage
54.1% 07:38:59 INFO - TinderboxPrint: I/O read bytes / time
31,407,104 / 200 07:38:59 INFO - TinderboxPrint: I/O write bytes / time
664,812,544 / 1,875 07:38:59 INFO - TinderboxPrint: CPU idle
2,213.5 (45.9%) 07:38:59 INFO - TinderboxPrint: CPU system
976.5 (20.2%) 07:38:59 INFO - TinderboxPrint: CPU user
1,635.7 (33.9%) 07:38:59 INFO - TinderboxPrint: Swap in / out
392,323,072 / 0 07:38:59 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 350978560; Write bytes: 346396672; Read time: 27761; Write time: 703 07:38:59 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 30477312; Write bytes: 297493504; Read time: 187; Write time: 1111 07:38:59 INFO - Running post-run listener: _upload_blobber_files 07:38:59 INFO - Blob upload gear active. 07:38:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:38:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:38:59 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:39:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:01 INFO - (blobuploader) - INFO - Done attempting. 07:39:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.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_raw.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/resource-usage.json ... 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: resource-usage.json: 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 - Iteration through files over. 07:39:04 INFO - Return code: 0 07:39:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc8fa2c437f34f63f1138e8f3fe3f54369f551dd2338e48812a8d87d2d3cb9575b5335709f97f757f2d8e7ce52d39a90dc9bc7535061b5e128213522c5311d60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9bca59ab04799baafd70e13034a2ffc8c622a51b789c34e42893b79f098ebe181034e5fbc6ed5accf690bcb316beb6dbb7fcf81eb77730a7661639c9d67244d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e01385754e8eaed100cff0509537c761ee95315b3bbce5f4e6d562691b6b9f3a792c17eb6bb0eb733b8c720d3476a453a85ae2a71e22b6c0bd3d7253e02b3e7a"} 07:39:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:04 INFO - Contents: 07:39:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc8fa2c437f34f63f1138e8f3fe3f54369f551dd2338e48812a8d87d2d3cb9575b5335709f97f757f2d8e7ce52d39a90dc9bc7535061b5e128213522c5311d60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9bca59ab04799baafd70e13034a2ffc8c622a51b789c34e42893b79f098ebe181034e5fbc6ed5accf690bcb316beb6dbb7fcf81eb77730a7661639c9d67244d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e01385754e8eaed100cff0509537c761ee95315b3bbce5f4e6d562691b6b9f3a792c17eb6bb0eb733b8c720d3476a453a85ae2a71e22b6c0bd3d7253e02b3e7a"} 07:39:04 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:04 INFO - Copying logs to upload dir... 07:39:04 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.242790 ========= master_lag: 1.35 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-09-17 07:39:05.761913) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:05.765540) ========= 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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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/bc8fa2c437f34f63f1138e8f3fe3f54369f551dd2338e48812a8d87d2d3cb9575b5335709f97f757f2d8e7ce52d39a90dc9bc7535061b5e128213522c5311d60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9bca59ab04799baafd70e13034a2ffc8c622a51b789c34e42893b79f098ebe181034e5fbc6ed5accf690bcb316beb6dbb7fcf81eb77730a7661639c9d67244d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e01385754e8eaed100cff0509537c761ee95315b3bbce5f4e6d562691b6b9f3a792c17eb6bb0eb733b8c720d3476a453a85ae2a71e22b6c0bd3d7253e02b3e7a"} 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.011024 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/bc8fa2c437f34f63f1138e8f3fe3f54369f551dd2338e48812a8d87d2d3cb9575b5335709f97f757f2d8e7ce52d39a90dc9bc7535061b5e128213522c5311d60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9bca59ab04799baafd70e13034a2ffc8c622a51b789c34e42893b79f098ebe181034e5fbc6ed5accf690bcb316beb6dbb7fcf81eb77730a7661639c9d67244d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e01385754e8eaed100cff0509537c761ee95315b3bbce5f4e6d562691b6b9f3a792c17eb6bb0eb733b8c720d3476a453a85ae2a71e22b6c0bd3d7253e02b3e7a"}' ========= master_lag: 0.54 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:06.318487) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-09-17 07:39:06.318776) ========= 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.5Oy1vPaFLG/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.4gV3LlDuj2/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.005285 ========= master_lag: 3.52 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-09-17 07:39:09.848177) ========= ========= Started reboot skipped (results: 3, elapsed: 10 secs) (at 2016-09-17 07:39:09.848456) ========= ========= Finished reboot skipped (results: 3, elapsed: 10 secs) (at 2016-09-17 07:39:20.474852) ========= ========= Total master_lag: 5.49 =========