builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0151 starttime: 1474121884.82 results: success (0) buildid: 20160917071802 builduid: 255174489d534efcba206d85d042de88 revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.816404) ========= 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.816764) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.817153) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.861701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:04.862016) ========= 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.sNTwhcFIEy/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.SISR3BKDKh/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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% 13.8M=0.001s 2016-09-17 07:18:05 (13.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.604373 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.490642) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.490947) ========= 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.sNTwhcFIEy/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.SISR3BKDKh/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.029186 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.540395) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:05.540787) ========= 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.sNTwhcFIEy/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.SISR3BKDKh/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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,618 truncating revision to first 12 chars 2016-09-17 07:18:05,619 Setting DEBUG logging. 2016-09-17 07:18:05,619 attempt 1/10 2016-09-17 07:18:05,619 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,251 unpacking tar archive at: mozilla-aurora-b9499912b323/testing/mozharness/ program finished with exit code 0 elapsedTime=0.895366 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.458011) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.458317) ========= script_repo_revision: b9499912b32324acd84792bf02b533b40e98e496 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.458695) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.458958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 07:18:06.477418) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-09-17 07:18:06.477688) ========= /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.sNTwhcFIEy/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.SISR3BKDKh/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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:06 INFO - MultiFileLogger online at 20160917 07:18:06 in /builds/slave/test 07:18:06 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:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:06 INFO - {'append_to_log': False, 07:18:06 INFO - 'base_work_dir': '/builds/slave/test', 07:18:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:06 INFO - 'branch': 'Mozilla-Aurora', 07:18:06 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:06 INFO - 'config_files': ('talos/mac_config.py',), 07:18:06 INFO - 'default_actions': ('clobber', 07:18:06 INFO - 'read-buildbot-config', 07:18:06 INFO - 'download-and-extract', 07:18:06 INFO - 'populate-webroot', 07:18:06 INFO - 'create-virtualenv', 07:18:06 INFO - 'install', 07:18:06 INFO - 'run-tests'), 07:18:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:06 INFO - 'download_minidump_stackwalk': True, 07:18:06 INFO - 'download_symbols': 'ondemand', 07:18:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:06 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:06 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:06 INFO - 'installer_path': 'installer.exe', 07:18:06 INFO - 'log_level': 'info', 07:18:06 INFO - 'log_name': 'talos', 07:18:06 INFO - 'log_to_console': True, 07:18:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:06 INFO - 'opt_config_files': (), 07:18:06 INFO - 'pip_index': False, 07:18:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:06 INFO - 'cmd': ('bash', 07:18:06 INFO - '-c', 07:18:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:06 INFO - 'enabled': True, 07:18:06 INFO - 'halt_on_failure': False, 07:18:06 INFO - 'name': 'check_screen_resolution'},), 07:18:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:06 INFO - 'cmd': ('bash', 07:18:06 INFO - '-c', 07:18:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:06 INFO - 'enabled': True, 07:18:06 INFO - 'halt_on_failure': False, 07:18:06 INFO - 'name': 'check_screen_resolution'},), 07:18:06 INFO - 'run_cmd_checks_enabled': True, 07:18:06 INFO - 'sps_profile': False, 07:18:06 INFO - 'sps_profile_interval': 0, 07:18:06 INFO - 'suite': 'dromaeojs-e10s', 07:18:06 INFO - 'system_bits': '32', 07:18:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:06 INFO - 'title': 't-yosemite-r7-0151', 07:18:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:06 INFO - 'use_talos_json': True, 07:18:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:06 INFO - 'work_dir': 'build'} 07:18:06 INFO - [mozharness: 2016-09-17 14:18:06.683379Z] Running clobber step. 07:18:06 INFO - Running pre-action listener: _resource_record_pre_action 07:18:06 INFO - Running main action method: clobber 07:18:06 INFO - rmtree: /builds/slave/test/build 07:18:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:08 INFO - Running post-action listener: _resource_record_post_action 07:18:08 INFO - [mozharness: 2016-09-17 14:18:08.860904Z] Finished clobber step (success) 07:18:08 INFO - [mozharness: 2016-09-17 14:18:08.861144Z] Running read-buildbot-config step. 07:18:08 INFO - Running pre-action listener: _resource_record_pre_action 07:18:08 INFO - Running main action method: read_buildbot_config 07:18:08 INFO - Using buildbot properties: 07:18:08 INFO - { 07:18:08 INFO - "project": "", 07:18:08 INFO - "product": "firefox", 07:18:08 INFO - "script_repo_revision": "production", 07:18:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:18:08 INFO - "repository": "", 07:18:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:18:08 INFO - "buildid": "20160917071802", 07:18:08 INFO - "stage_platform": "macosx64", 07:18:08 INFO - "basedir": "/builds/slave/test", 07:18:08 INFO - "buildnumber": 27, 07:18:08 INFO - "platform": "yosemite_r7", 07:18:08 INFO - "mozci_request": { 07:18:08 INFO - "priority": -1, 07:18:08 INFO - "type": "trigger_all_talos_jobs", 07:18:08 INFO - "times": 6 07:18:08 INFO - }, 07:18:08 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:18:08 INFO - "slavebuilddir": "test", 07:18:08 INFO - "branch": "mozilla-aurora", 07:18:08 INFO - "revision": "b9499912b32324acd84792bf02b533b40e98e496", 07:18:08 INFO - "slavename": "t-yosemite-r7-0151", 07:18:08 INFO - "builduid": "255174489d534efcba206d85d042de88", 07:18:08 INFO - "repo_path": "releases/mozilla-aurora" 07:18:08 INFO - } 07:18:08 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:08 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:08 INFO - Running post-action listener: _resource_record_post_action 07:18:08 INFO - [mozharness: 2016-09-17 14:18:08.867701Z] Finished read-buildbot-config step (success) 07:18:08 INFO - [mozharness: 2016-09-17 14:18:08.867858Z] Running download-and-extract step. 07:18:08 INFO - Running pre-action listener: _resource_record_pre_action 07:18:08 INFO - Running main action method: download_and_extract 07:18:08 INFO - mkdir: /builds/slave/test/build/tests 07:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:08 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:08 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:08 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:12 INFO - Downloaded 16309409 bytes. 07:18:12 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:12 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:16 INFO - Downloaded 14413740 bytes. 07:18:16 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:16 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:19 INFO - Running post-action listener: _resource_record_post_action 07:18:19 INFO - Running post-action listener: set_extra_try_arguments 07:18:19 INFO - [mozharness: 2016-09-17 14:18:19.740875Z] Finished download-and-extract step (success) 07:18:19 INFO - [mozharness: 2016-09-17 14:18:19.740973Z] Running populate-webroot step. 07:18:19 INFO - Running pre-action listener: _resource_record_pre_action 07:18:19 INFO - Running main action method: populate_webroot 07:18:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:19 INFO - '--tppagecycles', 07:18:19 INFO - '7'], 07:18:19 INFO - 'tests': ['tsvgm']}}, 07:18:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:19 INFO - 'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:19 INFO - 'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['damp', 'tps']}, 07:18:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:19 INFO - 'tests': ['damp', 'tps']}, 07:18:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['dromaeo_dom']}, 07:18:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['basic_compositor_video']}, 07:18:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:18:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['a11yr', 07:18:19 INFO - 'ts_paint', 07:18:19 INFO - 'tpaint', 07:18:19 INFO - 'sessionrestore', 07:18:19 INFO - 'sessionrestore_no_auto_restore', 07:18:19 INFO - 'tabpaint']}, 07:18:19 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:19 INFO - 'ts_paint', 07:18:19 INFO - 'tpaint', 07:18:19 INFO - 'sessionrestore', 07:18:19 INFO - 'sessionrestore_no_auto_restore', 07:18:19 INFO - 'tabpaint']}, 07:18:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:19 INFO - 'ts_paint', 07:18:19 INFO - 'tpaint', 07:18:19 INFO - 'sessionrestore', 07:18:19 INFO - 'sessionrestore_no_auto_restore', 07:18:19 INFO - 'tabpaint']}, 07:18:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:19 INFO - 'ts_paint', 07:18:19 INFO - 'tpaint', 07:18:19 INFO - 'sessionrestore', 07:18:19 INFO - 'sessionrestore_no_auto_restore', 07:18:19 INFO - 'tabpaint']}, 07:18:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['a11yr', 07:18:19 INFO - 'ts_paint', 07:18:19 INFO - 'tpaint', 07:18:19 INFO - 'sessionrestore', 07:18:19 INFO - 'sessionrestore_no_auto_restore', 07:18:19 INFO - 'tabpaint']}, 07:18:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['a11yr', 07:18:19 INFO - 'ts_paint', 07:18:19 INFO - 'tpaint', 07:18:19 INFO - 'sessionrestore', 07:18:19 INFO - 'sessionrestore_no_auto_restore', 07:18:19 INFO - 'tabpaint']}, 07:18:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['tsvgx', 07:18:19 INFO - 'tsvgr_opacity', 07:18:19 INFO - 'tart', 07:18:19 INFO - 'tscrollx', 07:18:19 INFO - 'cart']}, 07:18:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:19 INFO - 'tsvgr_opacity', 07:18:19 INFO - 'tart', 07:18:19 INFO - 'tscrollx', 07:18:19 INFO - 'cart']}, 07:18:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:19 INFO - 'talos_options': ['--disable-e10s'], 07:18:19 INFO - 'tests': ['tp5o']}, 07:18:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:19 INFO - 'tests': ['tp5o']}, 07:18:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:19 INFO - 'talos_options': ['--disable-e10s', 07:18:19 INFO - '--xperf_path', 07:18:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:19 INFO - 'tests': ['tp5n']}, 07:18:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:19 INFO - 'talos_options': ['--xperf_path', 07:18:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:19 INFO - 'tests': ['tp5n']}}, 07:18:19 INFO - 'talos.zip': {'path': '', 07:18:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:19 INFO - Running post-action listener: _resource_record_post_action 07:18:19 INFO - [mozharness: 2016-09-17 14:18:19.757517Z] Finished populate-webroot step (success) 07:18:19 INFO - [mozharness: 2016-09-17 14:18:19.757623Z] Running create-virtualenv step. 07:18:19 INFO - Running pre-action listener: _resource_record_pre_action 07:18:19 INFO - Running main action method: create_virtualenv 07:18:19 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:19 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:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:19 INFO - Using real prefix '/tools/python27' 07:18:19 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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 07:18:23 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:23 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:23 INFO - Cleaning up... 07:18:23 INFO - Return code: 0 07:18:23 INFO - Installing psutil>=3.1.1 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', '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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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', 'psutil>=3.1.1'] 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 psutil>=3.1.1 07:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 07:18:23 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 - 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:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:27 INFO - Installing collected packages: psutil 07:18:27 INFO - Running setup.py install for psutil 07:18:27 INFO - building 'psutil._psutil_osx' extension 07:18:27 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: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 - Successfully installed psutil 07:18:28 INFO - Cleaning up... 07:18:28 INFO - Return code: 0 07:18:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:28 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:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:28 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:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:28 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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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:28 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:28 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:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sNTwhcFIEy/Render', 07:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:28 INFO - 'HOME': '/Users/cltbld', 07:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:28 INFO - 'LOGNAME': 'cltbld', 07:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:28 INFO - 'MOZ_NO_REMOTE': '1', 07:18:28 INFO - 'NO_EM_RESTART': '1', 07:18:28 INFO - 'PAGER': '/bin/cat', 07:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:28 INFO - 'PWD': '/builds/slave/test', 07:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:28 INFO - 'SHELL': '/bin/bash', 07:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SISR3BKDKh/Listeners', 07:18:28 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:28 INFO - 'USER': 'cltbld', 07:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:28 INFO - 'XPC_FLAGS': '0x0', 07:18:28 INFO - 'XPC_SERVICE_NAME': '0', 07:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:28 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:28 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:28 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:28 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:28 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:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:18:32 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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 07:18:32 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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:33 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18: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:36 INFO - Running setup.py install for functools32 07:18:36 INFO - warning: no files found matching '*.txt' 07:18:36 INFO - no previously-included directories found matching 'build' 07:18:36 INFO - no previously-included directories found matching 'dist' 07:18:36 INFO - no previously-included directories found matching '.git*' 07:18:36 INFO - Successfully installed jsonschema functools32 07:18:36 INFO - Cleaning up... 07:18:36 INFO - Return code: 0 07:18:36 INFO - Installing functools32==3.2.3-2 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', '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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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', 'functools32==3.2.3-2'] 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 functools32==3.2.3-2 07:18:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 07:18:36 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 - 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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 07:18:36 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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:39 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:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:40 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:40 INFO - Running setup.py install for blobuploader 07:18:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:40 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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 07:18:41 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ZQStYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-chJx3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-WTithL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:41 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dlu1bL-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-O9qOQU-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-IVxyLn-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-wdPd57-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-PrsyCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:42 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-bIqPJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:42 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-f5FldJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:42 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-xIcAcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:42 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-WuuSox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:42 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-hR5VQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:42 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Gp5wzz-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-2O0BP7-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-wfzt2S-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-3j6tVe-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:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Running setup.py install for mozcrash 07:18:43 INFO - Running setup.py install for mozdebug 07:18:43 INFO - Running setup.py install for mozdevice 07:18:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:43 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:44 INFO - Running setup.py install for mozInstall 07:18:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Running setup.py install for mozleak 07:18:44 INFO - Running setup.py install for mozlog 07:18:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:44 INFO - Running setup.py install for moznetwork 07:18:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:44 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:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Running setup.py install for mozscreenshot 07:18:45 INFO - Running setup.py install for moztest 07:18:45 INFO - Running setup.py install for mozversion 07:18:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:45 INFO - Cleaning up... 07:18:45 INFO - Return code: 0 07:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:45 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:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:45 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:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:45 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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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:45 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:45 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:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sNTwhcFIEy/Render', 07:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:45 INFO - 'HOME': '/Users/cltbld', 07:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:45 INFO - 'LOGNAME': 'cltbld', 07:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:45 INFO - 'MOZ_NO_REMOTE': '1', 07:18:45 INFO - 'NO_EM_RESTART': '1', 07:18:45 INFO - 'PAGER': '/bin/cat', 07:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:45 INFO - 'PWD': '/builds/slave/test', 07:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:45 INFO - 'SHELL': '/bin/bash', 07:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SISR3BKDKh/Listeners', 07:18:45 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:45 INFO - 'USER': 'cltbld', 07:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:45 INFO - 'XPC_FLAGS': '0x0', 07:18:45 INFO - 'XPC_SERVICE_NAME': '0', 07:18:45 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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-yuBU2N-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-KKOiUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-1bhbd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-KfVv83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-lhKX9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-5FriBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-PjiUOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:46 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:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-O0rzrB-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-dTe6Vi-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-v0938h-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-s9NzNr-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/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-7Kj3vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-6QipCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-JoUBrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-uDvOLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-OPHiH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:47 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:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ZAuNTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:47 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:47 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:47 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:47 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:47 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:47 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:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:47 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:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:47 INFO - 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:47 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:47 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:47 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:50 INFO - Downloading blessings-1.6.tar.gz 07:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:50 INFO - Installing collected packages: blessings 07:18:50 INFO - Running setup.py install for blessings 07:18:50 INFO - Successfully installed blessings 07:18:50 INFO - Cleaning up... 07:18:50 INFO - Return code: 0 07:18:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:50 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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 07:18:51 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 0x1074bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107551618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc39b59f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, '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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.sNTwhcFIEy/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.SISR3BKDKh/Listeners', 07:18:51 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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): jsonschema in ./venv/lib/python2.7/site-packages 07:18:51 INFO - Cleaning up... 07:18:51 INFO - Return code: 0 07:18:51 INFO - Running post-action listener: _resource_record_post_action 07:18:51 INFO - Running post-action listener: _start_resource_monitoring 07:18:51 INFO - Starting resource monitoring. 07:18:51 INFO - [mozharness: 2016-09-17 14:18:51.609639Z] Finished create-virtualenv step (success) 07:18:51 INFO - [mozharness: 2016-09-17 14:18:51.610542Z] Running install step. 07:18:51 INFO - Running pre-action listener: _resource_record_pre_action 07:18:51 INFO - Running main action method: install 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 - Detecting whether we're running mozinstall >=1.0... 07:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:51 INFO - Reading from file tmpfile_stdout 07:18:51 INFO - Output received: 07:18:51 INFO - Usage: mozinstall [options] installer 07:18:51 INFO - Options: 07:18:51 INFO - -h, --help show this help message and exit 07:18:51 INFO - -d DEST, --destination=DEST 07:18:51 INFO - Directory to install application into. [default: 07:18:51 INFO - "/builds/slave/test"] 07:18:51 INFO - --app=APP Application being installed. [default: firefox] 07:18:51 INFO - mkdir: /builds/slave/test/build/application 07:18:51 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:51 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:23 INFO - Reading from file tmpfile_stdout 07:19:23 INFO - Output received: 07:19:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:19:23 INFO - Running post-action listener: _resource_record_post_action 07:19:23 INFO - [mozharness: 2016-09-17 14:19:23.994987Z] Finished install step (success) 07:19:23 INFO - [mozharness: 2016-09-17 14:19:23.995121Z] Running run-tests step. 07:19:23 INFO - Running pre-action listener: _resource_record_pre_action 07:19:23 INFO - Running main action method: run_tests 07:19:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:24 INFO - 2016-09-17 07:19:24.009 screenresolution[1651:8703] starting screenresolution argv=screenresolution get 07:19:24 INFO - 2016-09-17 07:19:24.012 screenresolution[1651:8703] Display 0: 1600x1200x32@60 07:19:24 INFO - 2016-09-17 07:19:24.017 screenresolution[1652:8705] starting screenresolution argv=screenresolution list 07:19:24 INFO - Available Modes on Display 0 07:19:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:24 INFO - Intel Iris: 07:19:24 INFO - Chipset Model: Intel Iris 07:19:24 INFO - Type: GPU 07:19:24 INFO - Bus: Built-In 07:19:24 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:24 INFO - Vendor: Intel (0x8086) 07:19:24 INFO - Device ID: 0x0a2e 07:19:24 INFO - Revision ID: 0x0009 07:19:24 INFO - Displays: 07:19:24 INFO - Display: 07:19:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:24 INFO - Display Serial Number: 1600x1200 60 07:19:24 INFO - Main Display: Yes 07:19:24 INFO - Mirror: Off 07:19:24 INFO - Online: Yes 07:19:24 INFO - Rotation: Supported 07:19:24 INFO - Return code: 0 07:19:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:24 INFO - Python 2.7.3 07:19:24 INFO - Return code: 0 07:19:24 INFO - grabbing minidump binary from tooltool 07:19: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:19:24 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 0x107420d30>, '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 0x1071c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107418b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:24 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:24 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:24 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:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:24 INFO - Return code: 0 07:19:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:24 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-0151', '--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:24 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-0151 --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:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sNTwhcFIEy/Render', 07:19:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:24 INFO - 'HOME': '/Users/cltbld', 07:19:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:24 INFO - 'LOGNAME': 'cltbld', 07:19:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:24 INFO - 'MOZ_NO_REMOTE': '1', 07:19:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:24 INFO - 'NO_EM_RESTART': '1', 07:19:24 INFO - 'PAGER': '/bin/cat', 07:19:24 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:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:24 INFO - 'PWD': '/builds/slave/test', 07:19:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:24 INFO - 'SHELL': '/bin/bash', 07:19:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SISR3BKDKh/Listeners', 07:19:24 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 07:19:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:24 INFO - 'USER': 'cltbld', 07:19:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:24 INFO - 'XPC_FLAGS': '0x0', 07:19:24 INFO - 'XPC_SERVICE_NAME': '0', 07:19:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:24 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-0151', '--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:24 INFO - mozversion application_buildid: 20160916162818 07:19:24 INFO - mozversion application_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:19:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:24 INFO - mozversion application_name: Firefox 07:19:24 INFO - mozversion application_remotingname: firefox-dev 07:19:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:24 INFO - mozversion application_vendor: Mozilla 07:19:24 INFO - mozversion application_version: 50.0a2 07:19:24 INFO - mozversion platform_buildid: 20160916162818 07:19:24 INFO - mozversion platform_changeset: b9499912b32324acd84792bf02b533b40e98e496 07:19:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:19:24 INFO - mozversion platform_version: 50.0a2 07:19:24 INFO - using testdate: 1474121964 07:19:24 INFO - actual date: 1474121964 07:19:24 INFO - starting webserver on 'localhost:49262' 07:19:24 INFO - SUITE-START | Running 2 tests 07:19:24 INFO - TEST-START | dromaeo_css 07:19:24 INFO - operating with platform_type : mac_ 07:19:24 INFO - Initialising browser for dromaeo_css test... 07:19:24 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpo148Da/profile http://localhost:49262/getInfo.html) 07:19:27 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 07:19:27 INFO - PROCESS | 1662 | colorDepth:24 07:19:27 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 07:19:27 INFO - PROCESS | 1662 | __metrics 07:19:27 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:19:28 INFO - TEST-INFO | 1662: exit 0 07:19:28 INFO - Browser initialized. 07:19:28 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:28 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpo148Da/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:40 INFO - PROCESS | 1666 | RSS: Main: 226705408 07:19:40 INFO - PROCESS | 1666 | 07:20:29 INFO - PROCESS | 1666 | 1474122029887 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 | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49262/tests/dromaeo/cssquery-ext.html) 07:22:42 INFO - PROCESS | 1666 | RSS: Main: 239357952 07:22:42 INFO - PROCESS | 1666 | 07:25:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-ext.html (next: http://localhost:49262/tests/dromaeo/cssquery-jquery.html) 07:25:45 INFO - PROCESS | 1666 | RSS: Main: 249069568 07:25:45 INFO - PROCESS | 1666 | 07:28:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49262/tests/dromaeo/cssquery-mootools.html) 07:28:47 INFO - PROCESS | 1666 | RSS: Main: 252502016 07:28:47 INFO - PROCESS | 1666 | 07:31:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49262/tests/dromaeo/cssquery-prototype.html) 07:31:50 INFO - PROCESS | 1666 | RSS: Main: 252973056 07:31:50 INFO - PROCESS | 1666 | 07:34:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49262/tests/dromaeo/cssquery-yui.html) 07:34:53 INFO - PROCESS | 1666 | RSS: Main: 256819200 07:34:53 INFO - PROCESS | 1666 | 07:37:55 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-yui.html (next: http://localhost:49262/tests/dromaeo/cssquery-dojo.html) 07:37:56 INFO - PROCESS | 1666 | RSS: Main: 257175552 07:37:56 INFO - PROCESS | 1666 | 07:37:56 INFO - PROCESS | 1666 | __start_tp_report 07:37:56 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 07:37:56 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 07:37:56 INFO - PROCESS | 1666 | |i|pagename|runs| 07:37:56 INFO - PROCESS | 1666 | |0;dojo.html;3371.8145501997396;3377.9324413511713;3378.88173913913;3382.053025152958;3392.5080863274816;5844.824655260344;5845.181674565559;5899.587028907968;5915.378885217055;5932.614380065298;5780.104083867007;5918.082697181933;5957.493613042886;5979.760809567612;5987.101934709795;5309.973450132744;5356.625036247464;5390.703511306896;5391.137418013119;5445.782168713246;5258.316418865561;5278.524932756051;5314.893702125955;5376.784928602851;5425.321834770653;4801.783919723621;5334.3598768147685;5372.194170874381;5379.273798037254;5388.326459192601;5072.315237442935;5329.120695085326;5365.114756065244;5376.247325374451;5384.9730751346;5198.610104242197;5253.159494480903;5299.947000529984;5309.283246761715;5329.360476742816;4809.206480930642;5109.897802043938;5549.805756798492;5886.6468011919;6045.486133678643;5140.871478213037;5238.8690282742855;6137.570370074097;6206.968965155191;6209.5032397408095;6183.96908015457;6233.283172435137;6241.251049873998;6260.029695397221;6262.2172228471445;5790.7683692652245;5938.851528711817;5977.372375900555;5999.160117583542;6015.09773533969;6367.267764207176;6398.072279519443;6409.711562979677;6409.935900641027;6418.42234198915;6222.688865556704;6253.96873015632;6254.061890716429;6256.906146407808;6279.309275979638;6149.016157414792;6151.784687536005;6190.566660333733;6261.092141639437;6293.496520278367;6150.753969841246;6165.599236049642;6210.813675589739;6241.313455519889;6270.9372906271265;6205.22434695663;6215.595986260879;6219.968900155471;6225.595336303126;6242.375762423812;5003.674761140514;5079.3650793650795;5106.259592359087;5115.335006449212;5150.330457040561;4543.182227199136;4543.432070991126;4544.250198717241;4558.49856515783;4599.885002874888;5565.888682226333;5962.701864906738;6015.097735339734;6016.127661489059;6038.486728628028;6124.808163753463;6163.167972323679;6172.475339596095;6243.687815609202;6294.622322660655;4969.378827646544;5922.526197904157;6026.608270462406;6029.78895738647;6061.3029501607;5606.831795046155;5610.186522954149;5616.354119276254;5623.100303951348;5634.492895639412;6245.250569931546;6355.428011478989;6359.872802544016;6524.086627872101;6566;6266.530010249158;6274.498040156685;6276.403741644536;6277.874442511215;6288.622682639057;6424.38968298011;6512.185976752906;6522.510811689048;6526.575772574767;6528.118703975093;6124.777645458507;6137.560853825989;6185.628862268279;6186.257649082139;6257.749690012349;6231.40801623845;6263.561550691408;6279.215098112736;6283.465905398092;6291.685415729286;5106.821261255838;6191.504679625529;6194.0708893664505;6210.4100110489435;6219.471344935731;6100.75596976116;6140.140380346845;6186.1648677427975;6200.3489633589625;6206.844828879314;4796.832110876242;5057.7724002419245;6076.665783381956;6140.964409184426;6146.985747351637;5148.227765835155;6209.875802483834;6225.315215326401;6233.189685340877;6234.37656234391;5943.851403714942;6079.817605471843;6089.117078023573;6182.196314479089;6193.38066193377 07:37:56 INFO - PROCESS | 1666 | |1;ext.html;459.6782252423304;481.28288849614074;536.3858382152434;541.9701916394599;563.3155715805295;39738.80130599347;43052;43425.131497370036;43471;43481.13037739243;44241.55758442415;44729.105417891624;44731.88170295744;44775;44822.327665084966;9691.95154024229;9894.703158905244;9897.604095836157;9902.455364954925;9926.354786938862;9765.072318129767;9840.852387214198;9872.111509964101;9875.1112399884;9932.255080868963;8348.332133429341;8385.916140838604;9852.458114803683;9853.802923941552;9859.457729824857;9777.364471309342;9799.363041402357;9849.803003939953;9851.162651174624;9851.359661621971;9766.462844543546;9816.361936474177;9839.704808855746;9881.654142105064;9894.9505252474;41773.79113104415;41873.37189942153;42203;42295.365569516485;42334.57665423337;3179.554862319277;3462.8961131166106;3492.6158122606494;3507.333606614735;3611.0972256935765;29803.70196298031;31624.841875790473;31783.205419864687;31864.840675796706;31870.840645796856;3045.3300273939917;3058.556509306138;3166.477531207348;3168;3172.8730850766174;490935;497728.5113574445;501921;502581;502702;35609.82195089008;35651.4652280216;35810.64189358073;35843;35953;33058.504122438186;35460.93617191489;35618.10954726153;36751.08122296964;38203.23593528114;18003.819961800215;18185.63628727418;18189.18148683286;18201.27194912215;18236.361727339743;13707.246101464316;13723.519676811264;13731.519396821062;13750.449982000808;13769.967252456107;20530.38408847737;20609.278675246296;20746.688799668016;20809.37571872846;20888.851113188615;7126.821829454305;8328.06503158482;8409.705660301983;8434.240918317259;8480.533570653673;904.2675432899241;976.5312649928075;1050.8896565860628;1105.1048650592884;1184.1059999700344;30404.572564612325;31285.530717039263;31406.528902066486;31827.840860795546;31951;1057.4871187474068;1090.1224514265934;1094.6059418609295;1108.51225460797;1118.7315044389288;16027.958182718086;16073.276702548414;16096;16157.676846463004;16252.18739063042;717969;719418;729082;729264;730428;48226.75886620545;51431;57940.130898036565;61653;62426;1429.6140042188551;1439.1293267573176;1458.1688437590683;1464.648484363745;1468.1117923656248;29554.852225738734;30214.244643884078;30904.845475772476;31368;31504.527432088078;54565.45434545763;54662;54876.72561637166;55103.44896550983;55134.89730205454;644501;648661;649490;650137;651604.4839551544;27779;28111.71888281173;28124;28128;28211.71788282091;49682.751586243285;49792.75518112076;50363.24455133102;50889.49110508848;51366.74316628542;40548.79725601353;41136;41780.955476112125;42612.786936065124;42818.78590607027;41362.58637413587;41447.37828932629;41517.37723934083;42004.94987625334;42271.57728422676;38610.80694596509;38739.03152421212;39179.216415670955;39620.801895990335;39732.60267397289;34822.12944676403;36388.636113639586;36503.566262656066;36813;37074.25851483009;26939;26950.191494255203;27042.864785675945;27225.45549089047;27429.588556171275 07:37:56 INFO - PROCESS | 1666 | |2;jquery.html;342.4862705941088;385.2910644414277;393.17825744194636;400.2715058593361;411.218684499451;19763.1106600203;19894.60210795783;20927.37217883464;21933.981126039675;22318.442038949033;21157;21594.89202553985;21662.89168554159;21956.341309760694;22253.554928901412;5879.67661778602;5896.23348964634;6180.752769889199;6267;6268;3890.2608504384075;6078.149059131725;6195.690215489208;6238;6477.643729594871;6687.933120668779;6725.159355080615;6820.727170913157;6838.281980392062;6845.794626161223;6008.128821320905;6077.665728384937;6110.419510146551;6156.507479401637;6726.260111387744;5180.870478238036;5961.195238642815;5968.97015514924;5973.133895585159;5988.490978266853;25069.87465062663;25951.610725839128;26550.6017409739;26895;26914.5962810558;2263.388885001057;2373.8931748071377;2516.8993240270365;3048.3750831079574;3081.8921337753072;9809.705708828747;9904.207663386911;10547.73630659232;13706.24615646149;14682.926585367111;1846.2984066054814;1900.429871038683;1924.6150769845947;1929.5465565592074;1973.5527945347808;114842;115382.42308788486;120300;120886;129301.70698292991;11203.943980280046;11684.591039313584;11827.763444731057;11950.521979120911;11972.401379930969;14752.967292289437;14780.852191478163;14855;14867.92566037163;14905.329260183318;8648.654053837901;8877.822443551093;8911.688090916787;8999.235065019546;9004.279657627374;8935.821283574422;8993.910060899438;9012.053734357929;9070.455772653662;9153.954230228806;4506.887327816765;4628.490866004737;4637.466691330473;4651.790669419885;4653.302004699322;1343.7379710956466;1684.4272947197767;1699.9035622023973;1938.738270333487;1979.1291831594053;725.8112890648364;918.3204428722697;990.3513198854762;1013.8732658417698;1097.286763603664;17729.29082836672;21549.67675484869;21826.672599911013;21960.011799469063;22168.667469987962;938.5401153434768;962.3696478806253;1010.6412223660494;1072.56025029739;1078.1967434261526;11082.833757493645;11570.42147892602;11670.76658466826;11682.766344673058;11691.356975366436;569502;570257;577454;582569;584931;115111;118522.8147718529;137222.90548039708;139982;146700;995.5967833027636;1015.28422462164;1038.0605551975475;1074.1728868771158;1080.8000519903842;19369.90315048416;19955;20403.693944590545;20615;20620.072096755383;61127.388726113946;62360.68819655872;62615.6869215651;62836.37163628305;63564;88517.55741221253;88787;92055.53972230096;93483;93620;7150.070490836366;7423.369013633902;7600.96199519021;7665.386769058574;7741.690332386642;13112.08215424911;13448.1258718183;13679;13730.382132803848;13757.656058598215;10990.890091098987;11624.186306958432;11719;11764.941175294069;11882.10884183707;11281.266717663326;11364.44193720687;11655.009324207538;11988.820167697317;12113.878861211628;11654.88345116538;11809.940950295193;11959.222650527689;12074.154809163274;12214.57248996281;7233.963830180815;8268;8325.417220794729;8447.704330348408;8627.482351058956;8260.504369737835;11454.541818327507;11602.76794464123;11623.244489108181;11666.475008624462 07:37:56 INFO - PROCESS | 1666 | |3;mootools.html;1568.866646335062;1602.7756114144017;1855.5361159710073;1875.6530041942233;1925.7785354684197;6779.593224406539;8696.521691306976;8701.73894783156;9260.675876344329;9286.721398358042;7410.740624078158;8822.86765698515;8923.598438070287;8997.91002089979;9015.143561361676;1969.7833238343771;2354.788069073779;2381.3213234228247;2399.3281881073326;2404.1945948107405;1990.8506861985336;2365.5860224460735;2390.6653068570326;2407.181558270188;2424.903003879843;1841.677706401381;2412.9879350603223;2413.6862207912945;2417.16607770319;2420.7700268474555;2343.7227408895474;2357;2383.642453631943;2394.4133687246735;2400.603900356439;2406.157844754322;2423.200343886513;2445.193086281523;2452.5830608796546;2453.141400509825;9061.275097992211;9082.046385129532;9190.494522801244;9246.306527010502;9277;5240;5270.841874743764;5319.20211968204;5350.197470379435;5364.168553874156;3532.5054492371087;3622.673959343672;3698.537682789651;3704.944425833615;3716.1081340478363;1062.7290041039485;1088.6298658456162;1090.1006669497617;1109.3177695717156;1114.309128340426;11462.426878656033;11609.535618575246;11696.70758231051;11709;11717.29696218221;4584.977075114603;4726.740029298421;4818.518148185223;4823.638227132979;4840.1287768201355;9807.558659860324;10028.749281268027;10059.094681478702;10062.295639305325;10087;5250.789968401297;5356.384015838151;5363.597730170253;5364.22218778275;5378.704171270618;3974.3839704845795;4440.711353761995;4793.544613261734;4904.288878112725;4914.754262286871;3959.4456776051375;4007.318755811519;4031.1937612477036;4090.406891000788;4107.466029416157;1302.856685764565;1315.2437348524636;1318.4462525739214;1321.068646604161;1327.6879933215687;823.9546824924566;870.7953630896734;949.4398304999945;952.6379975609224;968.5593055159885;5173.870653233699;5980;6272.09054687077;6314.873702525923;6552.803415897531;968.3560432312433;991.5785791038779;1021.7687686337945;1022.7187523430968;1054.992482179532;4002.8198731057173;4106.856260030884;4115.691323150776;4153.1070819773895;4188.979055104705;9002.549872506348;11231.887681123248;11335;11343.092552595937;11381.203315767816;10845.51195196218;10921.180911431515;11073.944630276957;11255.155863310285;11263.929926656954;6980.162380514371;8511.06378298399;8513.787155321166;8527.914720852836;8533.317334613215;5889.087191485327;6137.355577664281;6152.569320147547;6202.224721909762;6275.246970363585;9829.557669905014;9961.701148965543;10303.639372622221;10401.427921464392;10412.427316497666;10376.584936602754;10478.423686697304;10573.4713264338;10664.946675266574;10690.946545267223;6611.966940165268;7124.608146551989;7584.355329797029;7635.847283054197;7845.333146682596;8773.473591584525;8806.295496360404;8889.288856891304;8905.064968178507;8915.777105572413;7648.08223013242;7688.884666729891;7784;7855.803604909923;7944.404169687181;6530.771423000172;7685.692572297045;7713.922860771545;7715.575643339445;7844.9607751960875;7240.818979525554;7320.524165929198;7349.77950661481;7362.3741981932135;7379.262073792793;4972.328735620645;5061.063703214917;5078.08594453002;5156.071907056766;5314.468553144809;5044.974775126101;5144.845654630367;5192.5586325162785;5217.556507696889;5241.89516209666 07:37:56 INFO - PROCESS | 1666 | |4;prototype.html;276.9418422131352;315.8389221497036;317.9872805087796;318.3553304558269;323.09211116761884;5257.579393648509;5615.915761263579;5619.48273966029;5637.182608521762;5643.037405362187;4761.333413322138;5399.920015996807;5489.615726899108;5512.641678290908;5556.111022236443;4009.458723072384;4127.215828992496;4212.1575684863;4234.237837189305;4237.8516751913685;4183.853565125221;4206.453161089069;4206.49522057352;4209.957900421003;4224.894377640553;4156.210320039198;4172.97913510432;4188.350805625133;4203.4115223868685;4222.239996800575;3977.4431579579027;4156.231097247019;4164.8542301019625;4181.853635122787;4219.810108545123;2848.84331361775;3030.9393812123853;3680.392735198689;3682.576503702082;3732.3654978653694;4268.850590229327;4691.132140553975;5249.291345668324;5395;5482.314710661168;4274.252005898955;4583.862484125481;4730.361401210827;4818.253170758538;4931.482194369575;4824.155772739757;5088.745562721849;5308.52223299901;5802.854928626776;5816.185733997244;3571.339302229096;3620.981895090534;4048.5748996355255;4060.218750156123;4072.6538244249273;5357.4374690657305;5805.53555715546;6046.853367054676;6147.78482753106;6149.631022138686;3628.76413033152;3650.7261955353456;4108.6646839777195;4191.706580539333;4207.705460617789;3894.6884249259992;3910.3576497870963;4221.197972385237;4239.788010599519;4272.273713468718;4139.81370838313;4257.659387249013;4582.785561826119;4962.528559786814;4970.470965614382;5603.579731520153;5799.449052340021;5844.561657875676;5867.911981320283;5882.735276912549;1380.0753495158267;1390.2214759734582;1426.629076440133;1440.4382290906556;1448.8406275309708;832.0306842528507;835.6907944060615;847.2078606502939;869.2133619074748;886.2585020434876;4526.527066295163;5262.947370526323;5495.642783219078;5511.767734067709;5550.389457159709;5903.734331955073;6069.332373439007;6153.661548614869;6154.599951003171;6155.47678447337;5286.101362768339;5452.345718513804;5472.808451704172;5519.9448005520235;5549.528290095306;2528.4184637533103;3145.1036454610785;3152.3064925716308;3178.475551534017;3186.8406579670923;5994.340622531518;6226.719797609118;6236.224879928479;6242.408185912564;6289.40250676185;5773.538116950634;6159.4456498915315;6190.452386903275;6265.498760099181;6273.498120150377;5577.6932268724795;5647.632903861154;6280.811575652738;6335.334789847;6336.651484168323;5013;5015.216554812115;5666;6062.060380641008;6093.573449858467;5332.760025798771;5915.852103697442;5953.1367951647635;5960.5231581474245;5970.223870896755;5359.892802143857;6082.95852073963;6302.275238347649;6329.398707122816;6341.936580634135;3379.459286514146;3802.4676545284237;4092.7135100542678;4098.299205829548;4142.130152667853;5990.161377407079;6061.515078793774;6080.543959202989;6089.451949324405;6140.741148064725;5470.890582188414;5494.018608772739;5848;5863.706814659336;5924.6149000314845;4813.614910807197;5848;5890;5892.852678683067;5897.3807750185615;4817.205161148371;5740.971295143498;5761.337446193741;5786.9131963019745;5797.913031304618;5183.274341592105;5450.345958485007;5764.481196692318;5785.004173560115;5842.941570584409;5295.405251426851;5304.549113325411;5406;5456.727163641723;5740.712964351682 07:37:56 INFO - PROCESS | 1666 | |5;yui.html;715.8568286342729;753.9057617797777;805.2269820971867;841.553976392512;863.3567991846076;15304.617384565392;15960.76058859115;16464.25910834014;16519.504414867544;16535.75196372054;14706.632334191623;14813.037152585075;14956.551303460914;14970;14989.77515337268;694.1669996004789;876.1632640828026;885.4864178776294;910.9134632209963;915.8717779510873;898.9325800564984;921.1341339141219;921.3136213520936;923.7782932096316;923.9399439036441;920.102899672817;922.2299380017694;968.3753978683744;969.0358093696747;969.1374676537852;916.8945571259324;932.1610550504566;961.8461046232604;963.0225321298888;963.02734238419;682.0723815610766;691.695653912277;695.513140801436;718.8023293594251;720.9819754506127;15082.396704131821;15094.320755566026;15095.54713358601;15110.168940708256;15116.94181407302;14908.403663853433;14947.177905215209;14950.775738363824;14971.70056598862;15045.548633540902;11638.127140464414;11759;11759.118066145073;11998.52005919762;12366.567170149092;3880.3791393377205;3938.6061393860673;3963.6829053675638;4011.2779699654343;4030.6775457963295;106515;109619;115583.47523432349;121125;121899;11360.943195284137;11398.430078496041;11548.711282218012;11549.13381496391;11599.594014209631;19338.516537086405;23114.53770924606;23469.06123755065;23610;23876.044958201826;8125.146859579659;8288.254057134887;8345.248927596545;8450.577471126542;8567.828643427096;4550.158220729176;4597.816087356468;4638.327442520816;4654.650901182425;4671.205894997858;3700.3154416433044;3794.715396345285;3820.3887378019385;3846.442265871489;3852.075501879585;699.7445932234698;725.2747252747253;739.9408047356198;744.6202436757293;747.7756672998188;579.73622001989;689.262489136619;703.3423748794893;709.5458906299972;711.8787909043174;9398.624055037722;9576.952115239379;9653.758656033655;9957.55191016406;9999.650012249536;621.3879328861068;659.7558903205845;666.8932970724661;685.95541289816;686.7939618114546;4554.043650833362;4794.376731025014;4835.758212089381;4847.750641716263;4849.854504364875;110924.4453777742;117171;164181;167793;171210;139935.30032349774;142339.28830355988;144821;144836.82744758838;147055.26472367783;116313.41843290729;118436;119811;119969;120769.99999999824;8825.787411388166;9178.54107294646;9181.724548263563;9192.58633361487;9361.4383137012;49594.50405495899;55237.44762552323;64354.67822661044;66653;66676.66661666661;96677.51661241648;97446;97468.56328591843;102405;104295.4785226069;11888.108391870817;11891.167618266638;12037.458314375699;12188.268703877795;12396.752064958831;12622.936885315514;13251.46994120263;13365.933170334474;13456.25990570528;14109.929450352682;12261.632151035483;12574.685632859251;12615.81076283838;12694.873051269738;12879.871201287868;12255.877441225473;12455.813162802384;12561;12823.294718790556;13346.933265333611;12162.695932601755;12326.32205228721;12354.320512371905;12447.253164810141;12657.493700251887;10397.272190946864;10455.268131230741;10528.105111065644;10740.516676749408;11057.06014988735;10809.41400970021;10834.729131771768;10918.563257469612;11117.499712513118;11141.554337826396 07:37:56 INFO - PROCESS | 1666 | __end_tp_report 07:37:56 INFO - PROCESS | 1666 | __start_cc_report 07:37:56 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,432 07:37:56 INFO - PROCESS | 1666 | __end_cc_report 07:37:56 INFO - PROCESS | 1666 | __startTimestamp1474123076228__endTimestamp 07:37:56 INFO - PROCESS | 1666 | 07:37:56 INFO - PROCESS | 1666 | ------- Summary: start ------- 07:37:56 INFO - PROCESS | 1666 | Number of tests: 6 07:37:56 INFO - PROCESS | 1666 | 07:37:56 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5794.26 Median:6076.67 stddev:654.10 (10.8%) stddev-sans-first:627.90 07:37:56 INFO - PROCESS | 1666 | Values: 3371.8 3377.9 3378.9 3382.1 3392.5 5844.8 5845.2 5899.6 5915.4 5932.6 5780.1 5918.1 5957.5 5979.8 5987.1 5310.0 5356.6 5390.7 5391.1 5445.8 5258.3 5278.5 5314.9 5376.8 5425.3 4801.8 5334.4 5372.2 5379.3 5388.3 5072.3 5329.1 5365.1 5376.2 5385.0 5198.6 5253.2 5299.9 5309.3 5329.4 4809.2 5109.9 5549.8 5886.6 6045.5 5140.9 5238.9 6137.6 6207.0 6209.5 6184.0 6233.3 6241.3 6260.0 6262.2 5790.8 5938.9 5977.4 5999.2 6015.1 6367.3 6398.1 6409.7 6409.9 6418.4 6222.7 6254.0 6254.1 6256.9 6279.3 6149.0 6151.8 6190.6 6261.1 6293.5 6150.8 6165.6 6210.8 6241.3 6270.9 6205.2 6215.6 6220.0 6225.6 6242.4 5003.7 5079.4 5106.3 5115.3 5150.3 4543.2 4543.4 4544.3 4558.5 4599.9 5565.9 5962.7 6015.1 6016.1 6038.5 6124.8 6163.2 6172.5 6243.7 6294.6 4969.4 5922.5 6026.6 6029.8 6061.3 5606.8 5610.2 5616.4 5623.1 5634.5 6245.3 6355.4 6359.9 6524.1 6566.0 6266.5 6274.5 6276.4 6277.9 6288.6 6424.4 6512.2 6522.5 6526.6 6528.1 6124.8 6137.6 6185.6 6186.3 6257.7 6231.4 6263.6 6279.2 6283.5 6291.7 5106.8 6191.5 6194.1 6210.4 6219.5 6100.8 6140.1 6186.2 6200.3 6206.8 4796.8 5057.8 6076.7 6141.0 6147.0 5148.2 6209.9 6225.3 6233.2 6234.4 5943.9 6079.8 6089.1 6182.2 6193.4 07:37:56 INFO - PROCESS | 1666 | 07:37:56 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:74437.97 Median:41249.29 stddev:169308.71 (410.5%) stddev-sans-first:169692.56 07:37:56 INFO - PROCESS | 1666 | Values: 459.7 481.3 536.4 542.0 563.3 39738.8 43052.0 43425.1 43471.0 43481.1 44241.6 44729.1 44731.9 44775.0 44822.3 9692.0 9894.7 9897.6 9902.5 9926.4 9765.1 9840.9 9872.1 9875.1 9932.3 8348.3 8385.9 9852.5 9853.8 9859.5 9777.4 9799.4 9849.8 9851.2 9851.4 9766.5 9816.4 9839.7 9881.7 9895.0 41773.8 41873.4 42203.0 42295.4 42334.6 3179.6 3462.9 3492.6 3507.3 3611.1 29803.7 31624.8 31783.2 31864.8 31870.8 3045.3 3058.6 3166.5 3168.0 3172.9 490935.0 497728.5 501921.0 502581.0 502702.0 35609.8 35651.5 35810.6 35843.0 35953.0 33058.5 35460.9 35618.1 36751.1 38203.2 18003.8 18185.6 18189.2 18201.3 18236.4 13707.2 13723.5 13731.5 13750.4 13770.0 20530.4 20609.3 20746.7 20809.4 20888.9 7126.8 8328.1 8409.7 8434.2 8480.5 904.3 976.5 1050.9 1105.1 1184.1 30404.6 31285.5 31406.5 31827.8 31951.0 1057.5 1090.1 1094.6 1108.5 1118.7 16028.0 16073.3 16096.0 16157.7 16252.2 717969.0 719418.0 729082.0 729264.0 730428.0 48226.8 51431.0 57940.1 61653.0 62426.0 1429.6 1439.1 1458.2 1464.6 1468.1 29554.9 30214.2 30904.8 31368.0 31504.5 54565.5 54662.0 54876.7 55103.4 55134.9 644501.0 648661.0 649490.0 650137.0 651604.5 27779.0 28111.7 28124.0 28128.0 28211.7 49682.8 49792.8 50363.2 50889.5 51366.7 40548.8 41136.0 41781.0 42612.8 42818.8 41362.6 41447.4 41517.4 42004.9 42271.6 38610.8 38739.0 39179.2 39620.8 39732.6 34822.1 36388.6 36503.6 36813.0 37074.3 26939.0 26950.2 27042.9 27225.5 27429.6 07:37:56 INFO - PROCESS | 1666 | 07:37:56 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:35575.64 Median:12290.92 stddev:96852.28 (788.0%) stddev-sans-first:97087.85 07:37:56 INFO - PROCESS | 1666 | Values: 342.5 385.3 393.2 400.3 411.2 19763.1 19894.6 20927.4 21934.0 22318.4 21157.0 21594.9 21662.9 21956.3 22253.6 5879.7 5896.2 6180.8 6267.0 6268.0 3890.3 6078.1 6195.7 6238.0 6477.6 6687.9 6725.2 6820.7 6838.3 6845.8 6008.1 6077.7 6110.4 6156.5 6726.3 5180.9 5961.2 5969.0 5973.1 5988.5 25069.9 25951.6 26550.6 26895.0 26914.6 2263.4 2373.9 2516.9 3048.4 3081.9 9809.7 9904.2 10547.7 13706.2 14682.9 1846.3 1900.4 1924.6 1929.5 1973.6 114842.0 115382.4 120300.0 120886.0 129301.7 11203.9 11684.6 11827.8 11950.5 11972.4 14753.0 14780.9 14855.0 14867.9 14905.3 8648.7 8877.8 8911.7 8999.2 9004.3 8935.8 8993.9 9012.1 9070.5 9154.0 4506.9 4628.5 4637.5 4651.8 4653.3 1343.7 1684.4 1699.9 1938.7 1979.1 725.8 918.3 990.4 1013.9 1097.3 17729.3 21549.7 21826.7 21960.0 22168.7 938.5 962.4 1010.6 1072.6 1078.2 11082.8 11570.4 11670.8 11682.8 11691.4 569502.0 570257.0 577454.0 582569.0 584931.0 115111.0 118522.8 137222.9 139982.0 146700.0 995.6 1015.3 1038.1 1074.2 1080.8 19369.9 19955.0 20403.7 20615.0 20620.1 61127.4 62360.7 62615.7 62836.4 63564.0 88517.6 88787.0 92055.5 93483.0 93620.0 7150.1 7423.4 7601.0 7665.4 7741.7 13112.1 13448.1 13679.0 13730.4 13757.7 10990.9 11624.2 11719.0 11764.9 11882.1 11281.3 11364.4 11655.0 11988.8 12113.9 11654.9 11809.9 11959.2 12074.2 12214.6 7234.0 8268.0 8325.4 8447.7 8627.5 8260.5 11454.5 11602.8 11623.2 11666.5 07:37:56 INFO - PROCESS | 1666 | 07:37:56 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5828.96 Median:4831.88 stddev:3274.42 (67.8%) stddev-sans-first:3267.95 07:37:56 INFO - PROCESS | 1666 | Values: 1568.9 1602.8 1855.5 1875.7 1925.8 6779.6 8696.5 8701.7 9260.7 9286.7 7410.7 8822.9 8923.6 8997.9 9015.1 1969.8 2354.8 2381.3 2399.3 2404.2 1990.9 2365.6 2390.7 2407.2 2424.9 1841.7 2413.0 2413.7 2417.2 2420.8 2343.7 2357.0 2383.6 2394.4 2400.6 2406.2 2423.2 2445.2 2452.6 2453.1 9061.3 9082.0 9190.5 9246.3 9277.0 5240.0 5270.8 5319.2 5350.2 5364.2 3532.5 3622.7 3698.5 3704.9 3716.1 1062.7 1088.6 1090.1 1109.3 1114.3 11462.4 11609.5 11696.7 11709.0 11717.3 4585.0 4726.7 4818.5 4823.6 4840.1 9807.6 10028.7 10059.1 10062.3 10087.0 5250.8 5356.4 5363.6 5364.2 5378.7 3974.4 4440.7 4793.5 4904.3 4914.8 3959.4 4007.3 4031.2 4090.4 4107.5 1302.9 1315.2 1318.4 1321.1 1327.7 824.0 870.8 949.4 952.6 968.6 5173.9 5980.0 6272.1 6314.9 6552.8 968.4 991.6 1021.8 1022.7 1055.0 4002.8 4106.9 4115.7 4153.1 4189.0 9002.5 11231.9 11335.0 11343.1 11381.2 10845.5 10921.2 11073.9 11255.2 11263.9 6980.2 8511.1 8513.8 8527.9 8533.3 5889.1 6137.4 6152.6 6202.2 6275.2 9829.6 9961.7 10303.6 10401.4 10412.4 10376.6 10478.4 10573.5 10664.9 10690.9 6612.0 7124.6 7584.4 7635.8 7845.3 8773.5 8806.3 8889.3 8905.1 8915.8 7648.1 7688.9 7784.0 7855.8 7944.4 6530.8 7685.7 7713.9 7715.6 7845.0 7240.8 7320.5 7349.8 7362.4 7379.3 4972.3 5061.1 5078.1 5156.1 5314.5 5045.0 5144.8 5192.6 5217.6 5241.9 07:37:56 INFO - PROCESS | 1666 | 07:37:56 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4737.20 Median:5377.45 stddev:1478.02 (27.5%) stddev-sans-first:1443.75 07:37:56 INFO - PROCESS | 1666 | Values: 276.9 315.8 318.0 318.4 323.1 5257.6 5615.9 5619.5 5637.2 5643.0 4761.3 5399.9 5489.6 5512.6 5556.1 4009.5 4127.2 4212.2 4234.2 4237.9 4183.9 4206.5 4206.5 4210.0 4224.9 4156.2 4173.0 4188.4 4203.4 4222.2 3977.4 4156.2 4164.9 4181.9 4219.8 2848.8 3030.9 3680.4 3682.6 3732.4 4268.9 4691.1 5249.3 5395.0 5482.3 4274.3 4583.9 4730.4 4818.3 4931.5 4824.2 5088.7 5308.5 5802.9 5816.2 3571.3 3621.0 4048.6 4060.2 4072.7 5357.4 5805.5 6046.9 6147.8 6149.6 3628.8 3650.7 4108.7 4191.7 4207.7 3894.7 3910.4 4221.2 4239.8 4272.3 4139.8 4257.7 4582.8 4962.5 4970.5 5603.6 5799.4 5844.6 5867.9 5882.7 1380.1 1390.2 1426.6 1440.4 1448.8 832.0 835.7 847.2 869.2 886.3 4526.5 5262.9 5495.6 5511.8 5550.4 5903.7 6069.3 6153.7 6154.6 6155.5 5286.1 5452.3 5472.8 5519.9 5549.5 2528.4 3145.1 3152.3 3178.5 3186.8 5994.3 6226.7 6236.2 6242.4 6289.4 5773.5 6159.4 6190.5 6265.5 6273.5 5577.7 5647.6 6280.8 6335.3 6336.7 5013.0 5015.2 5666.0 6062.1 6093.6 5332.8 5915.9 5953.1 5960.5 5970.2 5359.9 6083.0 6302.3 6329.4 6341.9 3379.5 3802.5 4092.7 4098.3 4142.1 5990.2 6061.5 6080.5 6089.5 6140.7 5470.9 5494.0 5848.0 5863.7 5924.6 4813.6 5848.0 5890.0 5892.9 5897.4 4817.2 5741.0 5761.3 5786.9 5797.9 5183.3 5450.3 5764.5 5785.0 5842.9 5295.4 5304.5 5406.0 5456.7 5740.7 07:37:56 INFO - PROCESS | 1666 | 07:37:56 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:25771.27 Median:23743.02 stddev:41959.31 (176.7%) stddev-sans-first:42034.85 07:37:56 INFO - PROCESS | 1666 | Values: 715.9 753.9 805.2 841.6 863.4 15304.6 15960.8 16464.3 16519.5 16535.8 14706.6 14813.0 14956.6 14970.0 14989.8 694.2 876.2 885.5 910.9 915.9 898.9 921.1 921.3 923.8 923.9 920.1 922.2 968.4 969.0 969.1 916.9 932.2 961.8 963.0 963.0 682.1 691.7 695.5 718.8 721.0 15082.4 15094.3 15095.5 15110.2 15116.9 14908.4 14947.2 14950.8 14971.7 15045.5 11638.1 11759.0 11759.1 11998.5 12366.6 3880.4 3938.6 3963.7 4011.3 4030.7 106515.0 109619.0 115583.5 121125.0 121899.0 11360.9 11398.4 11548.7 11549.1 11599.6 19338.5 23114.5 23469.1 23610.0 23876.0 8125.1 8288.3 8345.2 8450.6 8567.8 4550.2 4597.8 4638.3 4654.7 4671.2 3700.3 3794.7 3820.4 3846.4 3852.1 699.7 725.3 739.9 744.6 747.8 579.7 689.3 703.3 709.5 711.9 9398.6 9577.0 9653.8 9957.6 9999.7 621.4 659.8 666.9 686.0 686.8 4554.0 4794.4 4835.8 4847.8 4849.9 110924.4 117171.0 164181.0 167793.0 171210.0 139935.3 142339.3 144821.0 144836.8 147055.3 116313.4 118436.0 119811.0 119969.0 120770.0 8825.8 9178.5 9181.7 9192.6 9361.4 49594.5 55237.4 64354.7 66653.0 66676.7 96677.5 97446.0 97468.6 102405.0 104295.5 11888.1 11891.2 12037.5 12188.3 12396.8 12622.9 13251.5 13365.9 13456.3 14109.9 12261.6 12574.7 12615.8 12694.9 12879.9 12255.9 12455.8 12561.0 12823.3 13346.9 12162.7 12326.3 12354.3 12447.3 12657.5 10397.3 10455.3 10528.1 10740.5 11057.1 10809.4 10834.7 10918.6 11117.5 11141.6 07:37:56 INFO - PROCESS | 1666 | -------- Summary: end -------- 07:37:56 INFO - PROCESS | 1666 | 07:37:56 INFO - TEST-INFO | 1666: exit 0 07:37:56 INFO - TEST-OK | dromaeo_css | took 1112253ms 07:37:56 INFO - TEST-START | kraken 07:37:56 INFO - operating with platform_type : mac_ 07:37:56 INFO - Initialising browser for kraken test... 07:37:56 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpxiTZtW/profile http://localhost:49262/getInfo.html) 07:37:59 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 07:37:59 INFO - PROCESS | 1676 | colorDepth:24 07:37:59 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 07:37:59 INFO - PROCESS | 1676 | __metrics 07:37:59 ERROR - PROCESS | 1676 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:38:00 INFO - TEST-INFO | 1676: 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 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpxiTZtW/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 | 1678 | RSS: Main: 226611200 07:38:12 INFO - PROCESS | 1678 | 07:38:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:15 INFO - PROCESS | 1678 | RSS: Main: 239255552 07:38:15 INFO - PROCESS | 1678 | 07:38:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-dft) 07:38:19 INFO - PROCESS | 1678 | RSS: Main: 247857152 07:38:19 INFO - PROCESS | 1678 | 07:38:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-fft) 07:38:22 INFO - PROCESS | 1678 | RSS: Main: 251600896 07:38:22 INFO - PROCESS | 1678 | 07:38:24 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator) 07:38:25 INFO - PROCESS | 1678 | RSS: Main: 252190720 07:38:25 INFO - PROCESS | 1678 | 07:38:27 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:28 INFO - PROCESS | 1678 | RSS: Main: 253435904 07:38:28 INFO - PROCESS | 1678 | 07:38:30 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:31 INFO - PROCESS | 1678 | RSS: Main: 253693952 07:38:31 INFO - PROCESS | 1678 | 07:38:34 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:35 INFO - PROCESS | 1678 | RSS: Main: 253599744 07:38:35 INFO - PROCESS | 1678 | 07:38:38 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial) 07:38:39 INFO - PROCESS | 1678 | RSS: Main: 254095360 07:38:39 INFO - PROCESS | 1678 | 07:38:40 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:41 INFO - PROCESS | 1678 | RSS: Main: 253509632 07:38:41 INFO - PROCESS | 1678 | 07:38:43 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:44 INFO - PROCESS | 1678 | RSS: Main: 253669376 07:38:44 INFO - PROCESS | 1678 | 07:38:46 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:47 INFO - PROCESS | 1678 | RSS: Main: 253927424 07:38:47 INFO - PROCESS | 1678 | 07:38:49 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:50 INFO - PROCESS | 1678 | RSS: Main: 254230528 07:38:50 INFO - PROCESS | 1678 | 07:38:53 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:53 INFO - PROCESS | 1678 | RSS: Main: 254320640 07:38:53 INFO - PROCESS | 1678 | 07:38:55 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49262/tests/kraken/driver.html?testName=ai-astar) 07:38:55 INFO - PROCESS | 1678 | RSS: Main: 256430080 07:38:55 INFO - PROCESS | 1678 | 07:38:55 INFO - PROCESS | 1678 | __start_tp_report 07:38:55 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 07:38:55 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 07:38:55 INFO - PROCESS | 1678 | |i|pagename|runs| 07:38:55 INFO - PROCESS | 1678 | |0;ai-astar;114;117;105;118;120;116;100;117;116;116 07:38:55 INFO - PROCESS | 1678 | |1;audio-beat-detection;155;109;124;185;108;124;162;111;165;136 07:38:55 INFO - PROCESS | 1678 | |2;audio-dft;198;200;198;215;193;194;196;194;197;196 07:38:55 INFO - PROCESS | 1678 | |3;audio-fft;68;88;77;150;60;60;60;63;135;59 07:38:55 INFO - PROCESS | 1678 | |4;audio-oscillator;71;71;120;72;75;110;145;103;79;111 07:38:55 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;89;87;91;86;90;85;87;85;89;85 07:38:55 INFO - PROCESS | 1678 | |6;imaging-darkroom;93;93;123;90;151;151;94;150;153;95 07:38:55 INFO - PROCESS | 1678 | |7;imaging-desaturate;94;96;133;139;143;150;90;90;127;147 07:38:55 INFO - PROCESS | 1678 | |8;json-parse-financial;56;57;60;89;85;57;60;59;59;65 07:38:55 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;67;70;71;71;72;76;73;70;71;71 07:38:55 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;83;83;84;83;85;84;84;86;89;83 07:38:55 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;144;124;142;151;145;140;144;137;172;145 07:38:55 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;164;166;163;164;165;163;164;194;162;165 07:38:55 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;68;70;81;86;87;77;68;67;68;65 07:38:55 INFO - PROCESS | 1678 | __end_tp_report 07:38:55 INFO - PROCESS | 1678 | __start_cc_report 07:38:55 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1096 07:38:55 INFO - PROCESS | 1678 | __end_cc_report 07:38:55 INFO - PROCESS | 1678 | __startTimestamp1474123135987__endTimestamp 07:38:55 INFO - PROCESS | 1678 | 07:38:55 INFO - PROCESS | 1678 | ------- Summary: start ------- 07:38:55 INFO - PROCESS | 1678 | Number of tests: 14 07:38:55 INFO - PROCESS | 1678 | 07:38:55 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:113.90 Median:116.50 stddev:6.31 (5.4%) stddev-sans-first:6.70 07:38:55 INFO - PROCESS | 1678 | Values: 114.0 117.0 105.0 118.0 120.0 116.0 100.0 117.0 116.0 116.0 07:38:55 INFO - PROCESS | 1678 | 07:38:55 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:145.50 stddev:27.22 (18.7%) stddev-sans-first:28.16 07:38:55 INFO - PROCESS | 1678 | Values: 155.0 109.0 124.0 185.0 108.0 124.0 162.0 111.0 165.0 136.0 07:38:55 INFO - PROCESS | 1678 | 07:38:55 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:198.10 Median:197.50 stddev:6.31 (3.2%) stddev-sans-first:6.70 07:38:55 INFO - PROCESS | 1678 | Values: 198.0 200.0 198.0 215.0 193.0 194.0 196.0 194.0 197.0 196.0 07:38:55 INFO - PROCESS | 1678 | 07:38:55 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:82.00 Median:61.50 stddev:33.39 (54.3%) stddev-sans-first:35.03 07:38:55 INFO - PROCESS | 1678 | Values: 68.0 88.0 77.0 150.0 60.0 60.0 60.0 63.0 135.0 59.0 07:38:55 INFO - PROCESS | 1678 | 07:38:55 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:71.00 stddev:25.82 (36.4%) stddev-sans-first:25.80 07:38:55 INFO - PROCESS | 1678 | Values: 71.0 71.0 120.0 72.0 75.0 110.0 145.0 103.0 79.0 111.0 07:38:55 INFO - PROCESS | 1678 | 07:38:55 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:2.22 (2.5%) stddev-sans-first:2.28 07:38:55 INFO - PROCESS | 1678 | Values: 89.0 87.0 91.0 86.0 90.0 85.0 87.0 85.0 89.0 85.0 07:38:56 INFO - PROCESS | 1678 | 07:38:56 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:119.30 Median:91.50 stddev:29.01 (31.7%) stddev-sans-first:29.17 07:38:56 INFO - PROCESS | 1678 | Values: 93.0 93.0 123.0 90.0 151.0 151.0 94.0 150.0 153.0 95.0 07:38:56 INFO - PROCESS | 1678 | 07:38:56 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:120.90 Median:120.00 stddev:25.34 (21.1%) stddev-sans-first:24.94 07:38:56 INFO - PROCESS | 1678 | Values: 94.0 96.0 133.0 139.0 143.0 150.0 90.0 90.0 127.0 147.0 07:38:56 INFO - PROCESS | 1678 | 07:38:56 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:60.00 stddev:12.05 (20.1%) stddev-sans-first:12.36 07:38:56 INFO - PROCESS | 1678 | Values: 56.0 57.0 60.0 89.0 85.0 57.0 60.0 59.0 59.0 65.0 07:38:56 INFO - PROCESS | 1678 | 07:38:56 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.00 stddev:2.30 (3.2%) stddev-sans-first:1.87 07:38:56 INFO - PROCESS | 1678 | Values: 67.0 70.0 71.0 71.0 72.0 76.0 73.0 70.0 71.0 71.0 07:38:56 INFO - PROCESS | 1678 | 07:38:56 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.00 stddev:1.90 (2.3%) stddev-sans-first:1.94 07:38:56 INFO - PROCESS | 1678 | Values: 83.0 83.0 84.0 83.0 85.0 84.0 84.0 86.0 89.0 83.0 07:38:56 INFO - PROCESS | 1678 | 07:38:56 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:144.50 stddev:12.03 (8.3%) stddev-sans-first:12.76 07:38:56 INFO - PROCESS | 1678 | Values: 144.0 124.0 142.0 151.0 145.0 140.0 144.0 137.0 172.0 145.0 07:38:56 INFO - PROCESS | 1678 | 07:38:56 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:164.50 stddev:9.56 (5.8%) stddev-sans-first:10.07 07:38:56 INFO - PROCESS | 1678 | Values: 164.0 166.0 163.0 164.0 165.0 163.0 164.0 194.0 162.0 165.0 07:38:56 INFO - PROCESS | 1678 | 07:38:56 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:73.50 stddev:8.33 (11.3%) stddev-sans-first:8.57 07:38:56 INFO - PROCESS | 1678 | Values: 68.0 70.0 81.0 86.0 87.0 77.0 68.0 67.0 68.0 65.0 07:38:56 INFO - PROCESS | 1678 | -------- Summary: end -------- 07:38:56 INFO - PROCESS | 1678 | 07:38:56 INFO - TEST-INFO | 1678: exit 0 07:38:56 INFO - TEST-OK | kraken | took 59924ms 07:38:56 INFO - SUITE-END | took 1172s 07:38:57 INFO - Completed test suite (00:19:32) 07:38:57 INFO - javascript benchmark 07:38:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7563.246581672792, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3371.8145501997396, 3377.9324413511713, 3378.88173913913, 3382.053025152958, 3392.5080863274816, 5844.824655260344, 5845.181674565559, 5899.587028907968, 5915.378885217055, 5932.614380065298, 5780.104083867007, 5918.082697181933, 5957.493613042886, 5979.760809567612, 5987.101934709795, 5309.973450132744, 5356.625036247464, 5390.703511306896, 5391.137418013119, 5445.782168713246, 5258.316418865561, 5278.524932756051, 5314.893702125955, 5376.784928602851, 5425.321834770653, 4801.783919723621, 5334.3598768147685, 5372.194170874381, 5379.273798037254, 5388.326459192601, 5072.315237442935, 5329.120695085326, 5365.114756065244, 5376.247325374451, 5384.9730751346, 5198.610104242197, 5253.159494480903, 5299.947000529984, 5309.283246761715, 5329.360476742816, 4809.206480930642, 5109.897802043938, 5549.805756798492, 5886.6468011919, 6045.486133678643, 5140.871478213037, 5238.8690282742855, 6137.570370074097, 6206.968965155191, 6209.5032397408095, 6183.96908015457, 6233.283172435137, 6241.251049873998, 6260.029695397221, 6262.2172228471445, 5790.7683692652245, 5938.851528711817, 5977.372375900555, 5999.160117583542, 6015.09773533969, 6367.267764207176, 6398.072279519443, 6409.711562979677, 6409.935900641027, 6418.42234198915, 6222.688865556704, 6253.96873015632, 6254.061890716429, 6256.906146407808, 6279.309275979638, 6149.016157414792, 6151.784687536005, 6190.566660333733, 6261.092141639437, 6293.496520278367, 6150.753969841246, 6165.599236049642, 6210.813675589739, 6241.313455519889, 6270.9372906271265, 6205.22434695663, 6215.595986260879, 6219.968900155471, 6225.595336303126, 6242.375762423812, 5003.674761140514, 5079.3650793650795, 5106.259592359087, 5115.335006449212, 5150.330457040561, 4543.182227199136, 4543.432070991126, 4544.250198717241, 4558.49856515783, 4599.885002874888, 5565.888682226333, 5962.701864906738, 6015.097735339734, 6016.127661489059, 6038.486728628028, 6124.808163753463, 6163.167972323679, 6172.475339596095, 6243.687815609202, 6294.622322660655, 4969.378827646544, 5922.526197904157, 6026.608270462406, 6029.78895738647, 6061.3029501607, 5606.831795046155, 5610.186522954149, 5616.354119276254, 5623.100303951348, 5634.492895639412, 6245.250569931546, 6355.428011478989, 6359.872802544016, 6524.086627872101, 6566.0, 6266.530010249158, 6274.498040156685, 6276.403741644536, 6277.874442511215, 6288.622682639057, 6424.38968298011, 6512.185976752906, 6522.510811689048, 6526.575772574767, 6528.118703975093, 6124.777645458507, 6137.560853825989, 6185.628862268279, 6186.257649082139, 6257.749690012349, 6231.40801623845, 6263.561550691408, 6279.215098112736, 6283.465905398092, 6291.685415729286, 5106.821261255838, 6191.504679625529, 6194.0708893664505, 6210.4100110489435, 6219.471344935731, 6100.75596976116, 6140.140380346845, 6186.1648677427975, 6200.3489633589625, 6206.844828879314, 4796.832110876242, 5057.7724002419245, 6076.665783381956, 6140.964409184426, 6146.985747351637, 5148.227765835155, 6209.875802483834, 6225.315215326401, 6233.189685340877, 6234.37656234391, 5943.851403714942, 6079.817605471843, 6089.117078023573, 6182.196314479089, 6193.38066193377], "value": 5754.92523160467, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.6782252423304, 481.28288849614074, 536.3858382152434, 541.9701916394599, 563.3155715805295, 39738.80130599347, 43052.0, 43425.131497370036, 43471.0, 43481.13037739243, 44241.55758442415, 44729.105417891624, 44731.88170295744, 44775.0, 44822.327665084966, 9691.95154024229, 9894.703158905244, 9897.604095836157, 9902.455364954925, 9926.354786938862, 9765.072318129767, 9840.852387214198, 9872.111509964101, 9875.1112399884, 9932.255080868963, 8348.332133429341, 8385.916140838604, 9852.458114803683, 9853.802923941552, 9859.457729824857, 9777.364471309342, 9799.363041402357, 9849.803003939953, 9851.162651174624, 9851.359661621971, 9766.462844543546, 9816.361936474177, 9839.704808855746, 9881.654142105064, 9894.9505252474, 41773.79113104415, 41873.37189942153, 42203.0, 42295.365569516485, 42334.57665423337, 3179.554862319277, 3462.8961131166106, 3492.6158122606494, 3507.333606614735, 3611.0972256935765, 29803.70196298031, 31624.841875790473, 31783.205419864687, 31864.840675796706, 31870.840645796856, 3045.3300273939917, 3058.556509306138, 3166.477531207348, 3168.0, 3172.8730850766174, 490935.0, 497728.5113574445, 501921.0, 502581.0, 502702.0, 35609.82195089008, 35651.4652280216, 35810.64189358073, 35843.0, 35953.0, 33058.504122438186, 35460.93617191489, 35618.10954726153, 36751.08122296964, 38203.23593528114, 18003.819961800215, 18185.63628727418, 18189.18148683286, 18201.27194912215, 18236.361727339743, 13707.246101464316, 13723.519676811264, 13731.519396821062, 13750.449982000808, 13769.967252456107, 20530.38408847737, 20609.278675246296, 20746.688799668016, 20809.37571872846, 20888.851113188615, 7126.821829454305, 8328.06503158482, 8409.705660301983, 8434.240918317259, 8480.533570653673, 904.2675432899241, 976.5312649928075, 1050.8896565860628, 1105.1048650592884, 1184.1059999700344, 30404.572564612325, 31285.530717039263, 31406.528902066486, 31827.840860795546, 31951.0, 1057.4871187474068, 1090.1224514265934, 1094.6059418609295, 1108.51225460797, 1118.7315044389288, 16027.958182718086, 16073.276702548414, 16096.0, 16157.676846463004, 16252.18739063042, 717969.0, 719418.0, 729082.0, 729264.0, 730428.0, 48226.75886620545, 51431.0, 57940.130898036565, 61653.0, 62426.0, 1429.6140042188551, 1439.1293267573176, 1458.1688437590683, 1464.648484363745, 1468.1117923656248, 29554.852225738734, 30214.244643884078, 30904.845475772476, 31368.0, 31504.527432088078, 54565.45434545763, 54662.0, 54876.72561637166, 55103.44896550983, 55134.89730205454, 644501.0, 648661.0, 649490.0, 650137.0, 651604.4839551544, 27779.0, 28111.71888281173, 28124.0, 28128.0, 28211.71788282091, 49682.751586243285, 49792.75518112076, 50363.24455133102, 50889.49110508848, 51366.74316628542, 40548.79725601353, 41136.0, 41780.955476112125, 42612.786936065124, 42818.78590607027, 41362.58637413587, 41447.37828932629, 41517.37723934083, 42004.94987625334, 42271.57728422676, 38610.80694596509, 38739.03152421212, 39179.216415670955, 39620.801895990335, 39732.60267397289, 34822.12944676403, 36388.636113639586, 36503.566262656066, 36813.0, 37074.25851483009, 26939.0, 26950.191494255203, 27042.864785675945, 27225.45549089047, 27429.588556171275], "value": 20301.44419014496, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.4862705941088, 385.2910644414277, 393.17825744194636, 400.2715058593361, 411.218684499451, 19763.1106600203, 19894.60210795783, 20927.37217883464, 21933.981126039675, 22318.442038949033, 21157.0, 21594.89202553985, 21662.89168554159, 21956.341309760694, 22253.554928901412, 5879.67661778602, 5896.23348964634, 6180.752769889199, 6267.0, 6268.0, 3890.2608504384075, 6078.149059131725, 6195.690215489208, 6238.0, 6477.643729594871, 6687.933120668779, 6725.159355080615, 6820.727170913157, 6838.281980392062, 6845.794626161223, 6008.128821320905, 6077.665728384937, 6110.419510146551, 6156.507479401637, 6726.260111387744, 5180.870478238036, 5961.195238642815, 5968.97015514924, 5973.133895585159, 5988.490978266853, 25069.87465062663, 25951.610725839128, 26550.6017409739, 26895.0, 26914.5962810558, 2263.388885001057, 2373.8931748071377, 2516.8993240270365, 3048.3750831079574, 3081.8921337753072, 9809.705708828747, 9904.207663386911, 10547.73630659232, 13706.24615646149, 14682.926585367111, 1846.2984066054814, 1900.429871038683, 1924.6150769845947, 1929.5465565592074, 1973.5527945347808, 114842.0, 115382.42308788486, 120300.0, 120886.0, 129301.70698292991, 11203.943980280046, 11684.591039313584, 11827.763444731057, 11950.521979120911, 11972.401379930969, 14752.967292289437, 14780.852191478163, 14855.0, 14867.92566037163, 14905.329260183318, 8648.654053837901, 8877.822443551093, 8911.688090916787, 8999.235065019546, 9004.279657627374, 8935.821283574422, 8993.910060899438, 9012.053734357929, 9070.455772653662, 9153.954230228806, 4506.887327816765, 4628.490866004737, 4637.466691330473, 4651.790669419885, 4653.302004699322, 1343.7379710956466, 1684.4272947197767, 1699.9035622023973, 1938.738270333487, 1979.1291831594053, 725.8112890648364, 918.3204428722697, 990.3513198854762, 1013.8732658417698, 1097.286763603664, 17729.29082836672, 21549.67675484869, 21826.672599911013, 21960.011799469063, 22168.667469987962, 938.5401153434768, 962.3696478806253, 1010.6412223660494, 1072.56025029739, 1078.1967434261526, 11082.833757493645, 11570.42147892602, 11670.76658466826, 11682.766344673058, 11691.356975366436, 569502.0, 570257.0, 577454.0, 582569.0, 584931.0, 115111.0, 118522.8147718529, 137222.90548039708, 139982.0, 146700.0, 995.5967833027636, 1015.28422462164, 1038.0605551975475, 1074.1728868771158, 1080.8000519903842, 19369.90315048416, 19955.0, 20403.693944590545, 20615.0, 20620.072096755383, 61127.388726113946, 62360.68819655872, 62615.6869215651, 62836.37163628305, 63564.0, 88517.55741221253, 88787.0, 92055.53972230096, 93483.0, 93620.0, 7150.070490836366, 7423.369013633902, 7600.96199519021, 7665.386769058574, 7741.690332386642, 13112.08215424911, 13448.1258718183, 13679.0, 13730.382132803848, 13757.656058598215, 10990.890091098987, 11624.186306958432, 11719.0, 11764.941175294069, 11882.10884183707, 11281.266717663326, 11364.44193720687, 11655.009324207538, 11988.820167697317, 12113.878861211628, 11654.88345116538, 11809.940950295193, 11959.222650527689, 12074.154809163274, 12214.57248996281, 7233.963830180815, 8268.0, 8325.417220794729, 8447.704330348408, 8627.482351058956, 8260.504369737835, 11454.541818327507, 11602.76794464123, 11623.244489108181, 11666.475008624462], "value": 10001.011650891263, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1568.866646335062, 1602.7756114144017, 1855.5361159710073, 1875.6530041942233, 1925.7785354684197, 6779.593224406539, 8696.521691306976, 8701.73894783156, 9260.675876344329, 9286.721398358042, 7410.740624078158, 8822.86765698515, 8923.598438070287, 8997.91002089979, 9015.143561361676, 1969.7833238343771, 2354.788069073779, 2381.3213234228247, 2399.3281881073326, 2404.1945948107405, 1990.8506861985336, 2365.5860224460735, 2390.6653068570326, 2407.181558270188, 2424.903003879843, 1841.677706401381, 2412.9879350603223, 2413.6862207912945, 2417.16607770319, 2420.7700268474555, 2343.7227408895474, 2357.0, 2383.642453631943, 2394.4133687246735, 2400.603900356439, 2406.157844754322, 2423.200343886513, 2445.193086281523, 2452.5830608796546, 2453.141400509825, 9061.275097992211, 9082.046385129532, 9190.494522801244, 9246.306527010502, 9277.0, 5240.0, 5270.841874743764, 5319.20211968204, 5350.197470379435, 5364.168553874156, 3532.5054492371087, 3622.673959343672, 3698.537682789651, 3704.944425833615, 3716.1081340478363, 1062.7290041039485, 1088.6298658456162, 1090.1006669497617, 1109.3177695717156, 1114.309128340426, 11462.426878656033, 11609.535618575246, 11696.70758231051, 11709.0, 11717.29696218221, 4584.977075114603, 4726.740029298421, 4818.518148185223, 4823.638227132979, 4840.1287768201355, 9807.558659860324, 10028.749281268027, 10059.094681478702, 10062.295639305325, 10087.0, 5250.789968401297, 5356.384015838151, 5363.597730170253, 5364.22218778275, 5378.704171270618, 3974.3839704845795, 4440.711353761995, 4793.544613261734, 4904.288878112725, 4914.754262286871, 3959.4456776051375, 4007.318755811519, 4031.1937612477036, 4090.406891000788, 4107.466029416157, 1302.856685764565, 1315.2437348524636, 1318.4462525739214, 1321.068646604161, 1327.6879933215687, 823.9546824924566, 870.7953630896734, 949.4398304999945, 952.6379975609224, 968.5593055159885, 5173.870653233699, 5980.0, 6272.09054687077, 6314.873702525923, 6552.803415897531, 968.3560432312433, 991.5785791038779, 1021.7687686337945, 1022.7187523430968, 1054.992482179532, 4002.8198731057173, 4106.856260030884, 4115.691323150776, 4153.1070819773895, 4188.979055104705, 9002.549872506348, 11231.887681123248, 11335.0, 11343.092552595937, 11381.203315767816, 10845.51195196218, 10921.180911431515, 11073.944630276957, 11255.155863310285, 11263.929926656954, 6980.162380514371, 8511.06378298399, 8513.787155321166, 8527.914720852836, 8533.317334613215, 5889.087191485327, 6137.355577664281, 6152.569320147547, 6202.224721909762, 6275.246970363585, 9829.557669905014, 9961.701148965543, 10303.639372622221, 10401.427921464392, 10412.427316497666, 10376.584936602754, 10478.423686697304, 10573.4713264338, 10664.946675266574, 10690.946545267223, 6611.966940165268, 7124.608146551989, 7584.355329797029, 7635.847283054197, 7845.333146682596, 8773.473591584525, 8806.295496360404, 8889.288856891304, 8905.064968178507, 8915.777105572413, 7648.08223013242, 7688.884666729891, 7784.0, 7855.803604909923, 7944.404169687181, 6530.771423000172, 7685.692572297045, 7713.922860771545, 7715.575643339445, 7844.9607751960875, 7240.818979525554, 7320.524165929198, 7349.77950661481, 7362.3741981932135, 7379.262073792793, 4972.328735620645, 5061.063703214917, 5078.08594453002, 5156.071907056766, 5314.468553144809, 5044.974775126101, 5144.845654630367, 5192.5586325162785, 5217.556507696889, 5241.89516209666], "value": 4689.489555091239, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.9418422131352, 315.8389221497036, 317.9872805087796, 318.3553304558269, 323.09211116761884, 5257.579393648509, 5615.915761263579, 5619.48273966029, 5637.182608521762, 5643.037405362187, 4761.333413322138, 5399.920015996807, 5489.615726899108, 5512.641678290908, 5556.111022236443, 4009.458723072384, 4127.215828992496, 4212.1575684863, 4234.237837189305, 4237.8516751913685, 4183.853565125221, 4206.453161089069, 4206.49522057352, 4209.957900421003, 4224.894377640553, 4156.210320039198, 4172.97913510432, 4188.350805625133, 4203.4115223868685, 4222.239996800575, 3977.4431579579027, 4156.231097247019, 4164.8542301019625, 4181.853635122787, 4219.810108545123, 2848.84331361775, 3030.9393812123853, 3680.392735198689, 3682.576503702082, 3732.3654978653694, 4268.850590229327, 4691.132140553975, 5249.291345668324, 5395.0, 5482.314710661168, 4274.252005898955, 4583.862484125481, 4730.361401210827, 4818.253170758538, 4931.482194369575, 4824.155772739757, 5088.745562721849, 5308.52223299901, 5802.854928626776, 5816.185733997244, 3571.339302229096, 3620.981895090534, 4048.5748996355255, 4060.218750156123, 4072.6538244249273, 5357.4374690657305, 5805.53555715546, 6046.853367054676, 6147.78482753106, 6149.631022138686, 3628.76413033152, 3650.7261955353456, 4108.6646839777195, 4191.706580539333, 4207.705460617789, 3894.6884249259992, 3910.3576497870963, 4221.197972385237, 4239.788010599519, 4272.273713468718, 4139.81370838313, 4257.659387249013, 4582.785561826119, 4962.528559786814, 4970.470965614382, 5603.579731520153, 5799.449052340021, 5844.561657875676, 5867.911981320283, 5882.735276912549, 1380.0753495158267, 1390.2214759734582, 1426.629076440133, 1440.4382290906556, 1448.8406275309708, 832.0306842528507, 835.6907944060615, 847.2078606502939, 869.2133619074748, 886.2585020434876, 4526.527066295163, 5262.947370526323, 5495.642783219078, 5511.767734067709, 5550.389457159709, 5903.734331955073, 6069.332373439007, 6153.661548614869, 6154.599951003171, 6155.47678447337, 5286.101362768339, 5452.345718513804, 5472.808451704172, 5519.9448005520235, 5549.528290095306, 2528.4184637533103, 3145.1036454610785, 3152.3064925716308, 3178.475551534017, 3186.8406579670923, 5994.340622531518, 6226.719797609118, 6236.224879928479, 6242.408185912564, 6289.40250676185, 5773.538116950634, 6159.4456498915315, 6190.452386903275, 6265.498760099181, 6273.498120150377, 5577.6932268724795, 5647.632903861154, 6280.811575652738, 6335.334789847, 6336.651484168323, 5013.0, 5015.216554812115, 5666.0, 6062.060380641008, 6093.573449858467, 5332.760025798771, 5915.852103697442, 5953.1367951647635, 5960.5231581474245, 5970.223870896755, 5359.892802143857, 6082.95852073963, 6302.275238347649, 6329.398707122816, 6341.936580634135, 3379.459286514146, 3802.4676545284237, 4092.7135100542678, 4098.299205829548, 4142.130152667853, 5990.161377407079, 6061.515078793774, 6080.543959202989, 6089.451949324405, 6140.741148064725, 5470.890582188414, 5494.018608772739, 5848.0, 5863.706814659336, 5924.6149000314845, 4813.614910807197, 5848.0, 5890.0, 5892.852678683067, 5897.3807750185615, 4817.205161148371, 5740.971295143498, 5761.337446193741, 5786.9131963019745, 5797.913031304618, 5183.274341592105, 5450.345958485007, 5764.481196692318, 5785.004173560115, 5842.941570584409, 5295.405251426851, 5304.549113325411, 5406.0, 5456.727163641723, 5740.712964351682], "value": 4258.888052771435, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.8568286342729, 753.9057617797777, 805.2269820971867, 841.553976392512, 863.3567991846076, 15304.617384565392, 15960.76058859115, 16464.25910834014, 16519.504414867544, 16535.75196372054, 14706.632334191623, 14813.037152585075, 14956.551303460914, 14970.0, 14989.77515337268, 694.1669996004789, 876.1632640828026, 885.4864178776294, 910.9134632209963, 915.8717779510873, 898.9325800564984, 921.1341339141219, 921.3136213520936, 923.7782932096316, 923.9399439036441, 920.102899672817, 922.2299380017694, 968.3753978683744, 969.0358093696747, 969.1374676537852, 916.8945571259324, 932.1610550504566, 961.8461046232604, 963.0225321298888, 963.02734238419, 682.0723815610766, 691.695653912277, 695.513140801436, 718.8023293594251, 720.9819754506127, 15082.396704131821, 15094.320755566026, 15095.54713358601, 15110.168940708256, 15116.94181407302, 14908.403663853433, 14947.177905215209, 14950.775738363824, 14971.70056598862, 15045.548633540902, 11638.127140464414, 11759.0, 11759.118066145073, 11998.52005919762, 12366.567170149092, 3880.3791393377205, 3938.6061393860673, 3963.6829053675638, 4011.2779699654343, 4030.6775457963295, 106515.0, 109619.0, 115583.47523432349, 121125.0, 121899.0, 11360.943195284137, 11398.430078496041, 11548.711282218012, 11549.13381496391, 11599.594014209631, 19338.516537086405, 23114.53770924606, 23469.06123755065, 23610.0, 23876.044958201826, 8125.146859579659, 8288.254057134887, 8345.248927596545, 8450.577471126542, 8567.828643427096, 4550.158220729176, 4597.816087356468, 4638.327442520816, 4654.650901182425, 4671.205894997858, 3700.3154416433044, 3794.715396345285, 3820.3887378019385, 3846.442265871489, 3852.075501879585, 699.7445932234698, 725.2747252747253, 739.9408047356198, 744.6202436757293, 747.7756672998188, 579.73622001989, 689.262489136619, 703.3423748794893, 709.5458906299972, 711.8787909043174, 9398.624055037722, 9576.952115239379, 9653.758656033655, 9957.55191016406, 9999.650012249536, 621.3879328861068, 659.7558903205845, 666.8932970724661, 685.95541289816, 686.7939618114546, 4554.043650833362, 4794.376731025014, 4835.758212089381, 4847.750641716263, 4849.854504364875, 110924.4453777742, 117171.0, 164181.0, 167793.0, 171210.0, 139935.30032349774, 142339.28830355988, 144821.0, 144836.82744758838, 147055.26472367783, 116313.41843290729, 118436.0, 119811.0, 119969.0, 120769.99999999824, 8825.787411388166, 9178.54107294646, 9181.724548263563, 9192.58633361487, 9361.4383137012, 49594.50405495899, 55237.44762552323, 64354.67822661044, 66653.0, 66676.66661666661, 96677.51661241648, 97446.0, 97468.56328591843, 102405.0, 104295.4785226069, 11888.108391870817, 11891.167618266638, 12037.458314375699, 12188.268703877795, 12396.752064958831, 12622.936885315514, 13251.46994120263, 13365.933170334474, 13456.25990570528, 14109.929450352682, 12261.632151035483, 12574.685632859251, 12615.81076283838, 12694.873051269738, 12879.871201287868, 12255.877441225473, 12455.813162802384, 12561.0, 12823.294718790556, 13346.933265333611, 12162.695932601755, 12326.32205228721, 12354.320512371905, 12447.253164810141, 12657.493700251887, 10397.272190946864, 10455.268131230741, 10528.105111065644, 10740.516676749408, 11057.06014988735, 10809.41400970021, 10834.729131771768, 10918.563257469612, 11117.499712513118, 11141.554337826396], "value": 8019.969801377033, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1560.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 105.0, 118.0, 120.0, 116.0, 100.0, 117.0, 116.0, 116.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 109.0, 124.0, 185.0, 108.0, 124.0, 162.0, 111.0, 165.0, 136.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 200.0, 198.0, 215.0, 193.0, 194.0, 196.0, 194.0, 197.0, 196.0], "value": 198.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 88.0, 77.0, 150.0, 60.0, 60.0, 60.0, 63.0, 135.0, 59.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 120.0, 72.0, 75.0, 110.0, 145.0, 103.0, 79.0, 111.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 86.0, 90.0, 85.0, 87.0, 85.0, 89.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 123.0, 90.0, 151.0, 151.0, 94.0, 150.0, 153.0, 95.0], "value": 119.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 133.0, 139.0, 143.0, 150.0, 90.0, 90.0, 127.0, 147.0], "value": 120.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 60.0, 89.0, 85.0, 57.0, 60.0, 59.0, 59.0, 65.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 71.0, 71.0, 72.0, 76.0, 73.0, 70.0, 71.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 83.0, 85.0, 84.0, 84.0, 86.0, 89.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 124.0, 142.0, 151.0, 145.0, 140.0, 144.0, 137.0, 172.0, 145.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 163.0, 164.0, 165.0, 163.0, 164.0, 194.0, 162.0, 165.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 81.0, 86.0, 87.0, 77.0, 68.0, 67.0, 68.0, 65.0], "value": 73.7, "unit": "score"}]}]} 07:38:57 INFO - Return code: 0 07:38:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:38:57 ERROR - # TBPL SUCCESS # 07:38:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:57 INFO - 2016-09-17 07:38:57.329 screenresolution[1683:11644] starting screenresolution argv=screenresolution get 07:38:57 INFO - 2016-09-17 07:38:57.331 screenresolution[1683:11644] Display 0: 1600x1200x32@60 07:38:57 INFO - 2016-09-17 07:38:57.337 screenresolution[1684:11647] starting screenresolution argv=screenresolution list 07:38:57 INFO - Available Modes on Display 0 07:38:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:57 INFO - Intel Iris: 07:38:57 INFO - Chipset Model: Intel Iris 07:38:57 INFO - Type: GPU 07:38:57 INFO - Bus: Built-In 07:38:57 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:57 INFO - Vendor: Intel (0x8086) 07:38:57 INFO - Device ID: 0x0a2e 07:38:57 INFO - Revision ID: 0x0009 07:38:57 INFO - Displays: 07:38:57 INFO - Display: 07:38:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:57 INFO - Display Serial Number: 1600x1200 60 07:38:57 INFO - Main Display: Yes 07:38:57 INFO - Mirror: Off 07:38:57 INFO - Online: Yes 07:38:57 INFO - Rotation: Supported 07:38:57 INFO - Return code: 0 07:38:57 INFO - Running post-action listener: _resource_record_post_action 07:38:57 INFO - [mozharness: 2016-09-17 14:38:57.572927Z] Finished run-tests step (success) 07:38:57 INFO - Running post-run listener: _resource_record_post_run 07:38:57 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 31542272; Write bytes: 668240384; Read time: 309; Write time: 1944 07:38:57 INFO - TinderboxPrint: CPU usage
54.2% 07:38:57 INFO - TinderboxPrint: I/O read bytes / time
31,542,272 / 309 07:38:57 INFO - TinderboxPrint: I/O write bytes / time
668,240,384 / 1,944 07:38:57 INFO - TinderboxPrint: CPU idle
2,209.7 (45.8%) 07:38:57 INFO - TinderboxPrint: CPU system
975.0 (20.2%) 07:38:57 INFO - TinderboxPrint: CPU user
1,637.0 (33.9%) 07:38:57 INFO - TinderboxPrint: Swap in / out
392,671,232 / 0 07:38:57 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 361927168; Write bytes: 358197248; Read time: 27535; Write time: 680 07:38:57 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 29445120; Write bytes: 298947072; Read time: 284; Write time: 1228 07:38:58 INFO - Running post-run listener: _upload_blobber_files 07:38:58 INFO - Blob upload gear active. 07:38:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:58 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:58 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:58 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:58 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:38:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:59 INFO - (blobuploader) - INFO - Done attempting. 07:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:39:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:00 INFO - (blobuploader) - INFO - Done attempting. 07:39:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:39:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:39:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:02 INFO - (blobuploader) - INFO - Done attempting. 07:39:02 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:02 INFO - Return code: 0 07:39:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b29bf5fc49b054e357350baf1bcf8c4b627459e7c114b54c1a083c1fa9c556b2c06453c410115bd3a7b8bdb8d4ce34b963be4e9c30e2d7f1ada2c100d3447cd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bfeb3fa2b2a0e03f82dd53ddb3f8d7f049c4f7ac99b6b162a2bccdb98b306cc5a1a9c9a4c6ffbbe582fd04d230c11cdce3d7ab7b4fc59a0ce5aff83d9542e7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f7ed50d1c82181cc9fb666b6233f1b2177557c6385cd1525921f62cdab4b58ec82d8e5773444a0f112b0679e8b6399ee96afd16d4c9211e99bf03ef0e565935"} 07:39:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:02 INFO - Contents: 07:39:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b29bf5fc49b054e357350baf1bcf8c4b627459e7c114b54c1a083c1fa9c556b2c06453c410115bd3a7b8bdb8d4ce34b963be4e9c30e2d7f1ada2c100d3447cd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bfeb3fa2b2a0e03f82dd53ddb3f8d7f049c4f7ac99b6b162a2bccdb98b306cc5a1a9c9a4c6ffbbe582fd04d230c11cdce3d7ab7b4fc59a0ce5aff83d9542e7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f7ed50d1c82181cc9fb666b6233f1b2177557c6385cd1525921f62cdab4b58ec82d8e5773444a0f112b0679e8b6399ee96afd16d4c9211e99bf03ef0e565935"} 07:39:02 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:02 INFO - Copying logs to upload dir... 07:39:02 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.471034 ========= master_lag: 2.84 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-09-17 07:39:05.790951) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:05.796590) ========= 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.sNTwhcFIEy/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.SISR3BKDKh/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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/b29bf5fc49b054e357350baf1bcf8c4b627459e7c114b54c1a083c1fa9c556b2c06453c410115bd3a7b8bdb8d4ce34b963be4e9c30e2d7f1ada2c100d3447cd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bfeb3fa2b2a0e03f82dd53ddb3f8d7f049c4f7ac99b6b162a2bccdb98b306cc5a1a9c9a4c6ffbbe582fd04d230c11cdce3d7ab7b4fc59a0ce5aff83d9542e7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f7ed50d1c82181cc9fb666b6233f1b2177557c6385cd1525921f62cdab4b58ec82d8e5773444a0f112b0679e8b6399ee96afd16d4c9211e99bf03ef0e565935"} 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.011151 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/b29bf5fc49b054e357350baf1bcf8c4b627459e7c114b54c1a083c1fa9c556b2c06453c410115bd3a7b8bdb8d4ce34b963be4e9c30e2d7f1ada2c100d3447cd1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bfeb3fa2b2a0e03f82dd53ddb3f8d7f049c4f7ac99b6b162a2bccdb98b306cc5a1a9c9a4c6ffbbe582fd04d230c11cdce3d7ab7b4fc59a0ce5aff83d9542e7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f7ed50d1c82181cc9fb666b6233f1b2177557c6385cd1525921f62cdab4b58ec82d8e5773444a0f112b0679e8b6399ee96afd16d4c9211e99bf03ef0e565935"}' ========= master_lag: 0.51 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:06.321721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:06.322010) ========= 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.sNTwhcFIEy/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.SISR3BKDKh/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.005072 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 07:39:06.349020) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:06.349358) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 07:39:06.351350) ========= ========= Total master_lag: 3.44 =========