builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0123 starttime: 1463601629.16 results: success (0) buildid: 20160517065136 builduid: ab760662d3a1428fa4c9e673ad9c7baf revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:29.162305) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:29.162788) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:29.163069) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:29.185422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:29.186168) ========= 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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-05-18 13:00:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 11.1M=0.001s 2016-05-18 13:00:29 (11.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126660 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:29.331057) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:29.331387) ========= 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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.020112 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:29.380481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 13:00:29.380850) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3780a3a6b83aeda143f9562829c830410a0c961e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.E90vQ5Lkpi/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.joqzgJ32I7/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-05-18 13:00:29,452 truncating revision to first 12 chars 2016-05-18 13:00:29,453 Setting DEBUG logging. 2016-05-18 13:00:29,453 attempt 1/10 2016-05-18 13:00:29,453 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3780a3a6b83a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 13:00:30,603 unpacking tar archive at: mozilla-central-3780a3a6b83a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.402039 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 13:00:30.801982) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:30.802559) ========= script_repo_revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:30.803193) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:30.803700) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:30.817695) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-18 13:00:30.818024) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.E90vQ5Lkpi/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.joqzgJ32I7/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:00:30 INFO - MultiFileLogger online at 20160518 13:00:30 in /builds/slave/test 13:00:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:00:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:00:31 INFO - {'append_to_log': False, 13:00:31 INFO - 'base_work_dir': '/builds/slave/test', 13:00:31 INFO - 'blob_upload_branch': 'Firefox', 13:00:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:00:31 INFO - 'branch': 'Firefox', 13:00:31 INFO - 'buildbot_json_path': 'buildprops.json', 13:00:31 INFO - 'config_files': ('talos/mac_config.py',), 13:00:31 INFO - 'default_actions': ('clobber', 13:00:31 INFO - 'read-buildbot-config', 13:00:31 INFO - 'download-and-extract', 13:00:31 INFO - 'populate-webroot', 13:00:31 INFO - 'create-virtualenv', 13:00:31 INFO - 'install', 13:00:31 INFO - 'run-tests'), 13:00:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:00:31 INFO - 'download_minidump_stackwalk': True, 13:00:31 INFO - 'download_symbols': 'ondemand', 13:00:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:00:31 INFO - 'tooltool.py': '/tools/tooltool.py', 13:00:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:00:31 INFO - '/tools/misc-python/virtualenv.py')}, 13:00:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:00:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:00:31 INFO - 'installer_path': 'installer.exe', 13:00:31 INFO - 'log_level': 'info', 13:00:31 INFO - 'log_name': 'talos', 13:00:31 INFO - 'log_to_console': True, 13:00:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:00:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:00:31 INFO - 'opt_config_files': (), 13:00:31 INFO - 'pip_index': False, 13:00:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:00:31 INFO - 'cmd': ('bash', 13:00:31 INFO - '-c', 13:00:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:00:31 INFO - 'enabled': True, 13:00:31 INFO - 'halt_on_failure': False, 13:00:31 INFO - 'name': 'check_screen_resolution'},), 13:00:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:00:31 INFO - 'cmd': ('bash', 13:00:31 INFO - '-c', 13:00:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:00:31 INFO - 'enabled': True, 13:00:31 INFO - 'halt_on_failure': False, 13:00:31 INFO - 'name': 'check_screen_resolution'},), 13:00:31 INFO - 'run_cmd_checks_enabled': True, 13:00:31 INFO - 'sps_profile': False, 13:00:31 INFO - 'sps_profile_interval': 0, 13:00:31 INFO - 'suite': 'dromaeojs-e10s', 13:00:31 INFO - 'system_bits': '32', 13:00:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:00:31 INFO - 'title': 't-yosemite-r7-0123', 13:00:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:00:31 INFO - 'use_talos_json': True, 13:00:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:00:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:00:31 INFO - 'work_dir': 'build'} 13:00:31 INFO - ##### 13:00:31 INFO - ##### Running clobber step. 13:00:31 INFO - ##### 13:00:31 INFO - Running pre-action listener: _resource_record_pre_action 13:00:31 INFO - Running main action method: clobber 13:00:31 INFO - rmtree: /builds/slave/test/build 13:00:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:00:32 INFO - Running post-action listener: _resource_record_post_action 13:00:32 INFO - ##### 13:00:32 INFO - ##### Finished clobber step (success) 13:00:32 INFO - ##### 13:00:32 INFO - ##### 13:00:32 INFO - ##### Running read-buildbot-config step. 13:00:32 INFO - ##### 13:00:32 INFO - Running pre-action listener: _resource_record_pre_action 13:00:32 INFO - Running main action method: read_buildbot_config 13:00:32 INFO - Using buildbot properties: 13:00:32 INFO - { 13:00:32 INFO - "project": "", 13:00:32 INFO - "product": "firefox", 13:00:32 INFO - "script_repo_revision": "production", 13:00:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 13:00:32 INFO - "repository": "", 13:00:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 13:00:32 INFO - "buildid": "20160517065136", 13:00:32 INFO - "pgo_build": "False", 13:00:32 INFO - "basedir": "/builds/slave/test", 13:00:32 INFO - "buildnumber": 5, 13:00:32 INFO - "slavename": "t-yosemite-r7-0123", 13:00:32 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 13:00:32 INFO - "platform": "yosemite_r7", 13:00:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:00:32 INFO - "branch": "mozilla-central", 13:00:32 INFO - "repo_path": "mozilla-central", 13:00:32 INFO - "revision": "3780a3a6b83aeda143f9562829c830410a0c961e", 13:00:32 INFO - "stage_platform": "macosx64", 13:00:32 INFO - "builduid": "ab760662d3a1428fa4c9e673ad9c7baf", 13:00:32 INFO - "slavebuilddir": "test" 13:00:32 INFO - } 13:00:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:00:32 INFO - Running post-action listener: _resource_record_post_action 13:00:32 INFO - ##### 13:00:32 INFO - ##### Finished read-buildbot-config step (success) 13:00:32 INFO - ##### 13:00:32 INFO - ##### 13:00:32 INFO - ##### Running download-and-extract step. 13:00:32 INFO - ##### 13:00:32 INFO - Running pre-action listener: _resource_record_pre_action 13:00:32 INFO - Running main action method: download_and_extract 13:00:32 INFO - mkdir: /builds/slave/test/build/tests 13:00: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')]} 13:00:32 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:00:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:00:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:00:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 13:00:34 INFO - Downloaded 1199 bytes. 13:00:34 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:00:34 INFO - Using the following test package requirements: 13:00:34 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:00:34 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:34 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:00:34 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:34 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:00:34 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:34 INFO - u'jsshell-mac.zip'], 13:00:34 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:34 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:00:34 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:00:34 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:34 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:00:34 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:34 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:00:34 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:34 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:00:34 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:34 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:00:34 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:00:34 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')]} 13:00:34 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:00:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:00:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:00:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:00:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:00:35 INFO - Downloaded 18267690 bytes. 13:00:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:00:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:00:38 INFO - Return code: 0 13:00:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:38 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:00:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:00:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:00:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:00:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:00:39 INFO - Downloaded 18267690 bytes. 13:00:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:00:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:00:42 INFO - Return code: 0 13:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:42 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:00:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:00:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:00:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:00:43 INFO - Downloaded 10840194 bytes. 13:00:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:00:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:00:44 INFO - Return code: 0 13:00:44 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')]} 13:00:44 INFO - https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:00:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:00:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:00:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:00:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 13:00:48 INFO - Downloaded 109992951 bytes. 13:00:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:00:48 INFO - mkdir: /builds/slave/test/properties 13:00:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:00:48 INFO - Writing to file /builds/slave/test/properties/build_url 13:00:48 INFO - Contents: 13:00:48 INFO - build_url:https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:00:49 INFO - Running post-action listener: _resource_record_post_action 13:00:49 INFO - Running post-action listener: set_extra_try_arguments 13:00:49 INFO - ##### 13:00:49 INFO - ##### Finished download-and-extract step (success) 13:00:49 INFO - ##### 13:00:49 INFO - ##### 13:00:49 INFO - ##### Running populate-webroot step. 13:00:49 INFO - ##### 13:00:49 INFO - Running pre-action listener: _resource_record_pre_action 13:00:49 INFO - Running main action method: populate_webroot 13:00:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:00:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:00:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:00:49 INFO - '--tppagecycles', 13:00:49 INFO - '7'], 13:00:49 INFO - 'tests': ['tsvgm']}}, 13:00:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:00:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:00:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:00:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:00:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:49 INFO - 'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:49 INFO - 'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['damp', 'tps']}, 13:00:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:49 INFO - 'tests': ['damp', 'tps']}, 13:00:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['dromaeo_dom']}, 13:00:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:00:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['a11yr', 13:00:49 INFO - 'ts_paint', 13:00:49 INFO - 'tpaint', 13:00:49 INFO - 'sessionrestore', 13:00:49 INFO - 'sessionrestore_no_auto_restore', 13:00:49 INFO - 'tabpaint']}, 13:00:49 INFO - 'other-e10s': {'tests': ['a11yr', 13:00:49 INFO - 'ts_paint', 13:00:49 INFO - 'tpaint', 13:00:49 INFO - 'sessionrestore', 13:00:49 INFO - 'sessionrestore_no_auto_restore', 13:00:49 INFO - 'tabpaint']}, 13:00:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:00:49 INFO - 'ts_paint', 13:00:49 INFO - 'tpaint', 13:00:49 INFO - 'sessionrestore', 13:00:49 INFO - 'sessionrestore_no_auto_restore', 13:00:49 INFO - 'tabpaint']}, 13:00:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:00:49 INFO - 'ts_paint', 13:00:49 INFO - 'tpaint', 13:00:49 INFO - 'sessionrestore', 13:00:49 INFO - 'sessionrestore_no_auto_restore', 13:00:49 INFO - 'tabpaint']}, 13:00:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['a11yr', 13:00:49 INFO - 'ts_paint', 13:00:49 INFO - 'tpaint', 13:00:49 INFO - 'sessionrestore', 13:00:49 INFO - 'sessionrestore_no_auto_restore', 13:00:49 INFO - 'tabpaint']}, 13:00:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['a11yr', 13:00:49 INFO - 'ts_paint', 13:00:49 INFO - 'tpaint', 13:00:49 INFO - 'sessionrestore', 13:00:49 INFO - 'sessionrestore_no_auto_restore', 13:00:49 INFO - 'tabpaint']}, 13:00:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['tsvgx', 13:00:49 INFO - 'tsvgr_opacity', 13:00:49 INFO - 'tart', 13:00:49 INFO - 'tscrollx', 13:00:49 INFO - 'cart']}, 13:00:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:00:49 INFO - 'tsvgr_opacity', 13:00:49 INFO - 'tart', 13:00:49 INFO - 'tscrollx', 13:00:49 INFO - 'cart']}, 13:00:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:49 INFO - 'talos_options': ['--disable-e10s'], 13:00:49 INFO - 'tests': ['tp5o']}, 13:00:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:49 INFO - 'tests': ['tp5o']}, 13:00:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:49 INFO - 'talos_options': ['--disable-e10s', 13:00:49 INFO - '--xperf_path', 13:00:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:49 INFO - 'tests': ['tp5n']}, 13:00:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:49 INFO - 'talos_options': ['--xperf_path', 13:00:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:49 INFO - 'tests': ['tp5n']}}, 13:00:49 INFO - 'talos.zip': {'path': '', 13:00:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:00:49 INFO - Running post-action listener: _resource_record_post_action 13:00:49 INFO - ##### 13:00:49 INFO - ##### Finished populate-webroot step (success) 13:00:49 INFO - ##### 13:00:49 INFO - ##### 13:00:49 INFO - ##### Running create-virtualenv step. 13:00:49 INFO - ##### 13:00:49 INFO - Running pre-action listener: _resource_record_pre_action 13:00:49 INFO - Running main action method: create_virtualenv 13:00:49 INFO - Creating virtualenv /builds/slave/test/build/venv 13:00:49 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 13:00:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:00:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:00:49 INFO - Using real prefix '/tools/python27' 13:00:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:00:50 INFO - Installing distribute.............................................................................................................................................................................................done. 13:00:53 INFO - Installing pip.................done. 13:00:53 INFO - Return code: 0 13:00:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:00:53 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')]} 13:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:53 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')]} 13:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:53 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 0x10f548a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8ad53160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, '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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:00:53 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 13:00:53 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 13:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:53 INFO - 'HOME': '/Users/cltbld', 13:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:53 INFO - 'LOGNAME': 'cltbld', 13:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:53 INFO - 'MOZ_NO_REMOTE': '1', 13:00:53 INFO - 'NO_EM_RESTART': '1', 13:00:53 INFO - 'PAGER': '/bin/cat', 13:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:53 INFO - 'PWD': '/builds/slave/test', 13:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:53 INFO - 'SHELL': '/bin/bash', 13:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:00:53 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:53 INFO - 'USER': 'cltbld', 13:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:53 INFO - 'XPC_FLAGS': '0x0', 13:00:53 INFO - 'XPC_SERVICE_NAME': '0', 13:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:53 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 13:00:53 INFO - Cleaning up... 13:00:53 INFO - Return code: 0 13:00:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:00:53 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')]} 13:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:53 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')]} 13:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:53 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 0x10f548a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8ad53160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, '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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:00:53 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 13:00:53 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 13:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:53 INFO - 'HOME': '/Users/cltbld', 13:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:53 INFO - 'LOGNAME': 'cltbld', 13:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:53 INFO - 'MOZ_NO_REMOTE': '1', 13:00:53 INFO - 'NO_EM_RESTART': '1', 13:00:53 INFO - 'PAGER': '/bin/cat', 13:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:53 INFO - 'PWD': '/builds/slave/test', 13:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:53 INFO - 'SHELL': '/bin/bash', 13:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:00:53 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:53 INFO - 'USER': 'cltbld', 13:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:53 INFO - 'XPC_FLAGS': '0x0', 13:00:53 INFO - 'XPC_SERVICE_NAME': '0', 13:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:54 INFO - Downloading/unpacking psutil>=3.1.1 13:00:54 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 13:00:54 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 13:00:54 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 13:00:54 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 13:00:54 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 13:00:54 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 13:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:00:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:57 INFO - Installing collected packages: psutil 13:00:57 INFO - Running setup.py install for psutil 13:00:57 INFO - building 'psutil._psutil_osx' extension 13:00:57 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 13:00:57 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 13:00:57 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 13:00:57 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 13:00:57 INFO - building 'psutil._psutil_posix' extension 13:00:57 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 13:00:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:00:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:00:57 INFO - ^ 13:00:57 INFO - 1 warning generated. 13:00:57 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 13:00:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:57 INFO - Successfully installed psutil 13:00:57 INFO - Cleaning up... 13:00:58 INFO - Return code: 0 13:00:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:00:58 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')]} 13:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:58 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')]} 13:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:58 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 0x10f548a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8ad53160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, '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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:00:58 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 13:00:58 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 13:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:58 INFO - 'HOME': '/Users/cltbld', 13:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:58 INFO - 'LOGNAME': 'cltbld', 13:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:58 INFO - 'MOZ_NO_REMOTE': '1', 13:00:58 INFO - 'NO_EM_RESTART': '1', 13:00:58 INFO - 'PAGER': '/bin/cat', 13:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:58 INFO - 'PWD': '/builds/slave/test', 13:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:58 INFO - 'SHELL': '/bin/bash', 13:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:00:58 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:58 INFO - 'USER': 'cltbld', 13:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:58 INFO - 'XPC_FLAGS': '0x0', 13:00:58 INFO - 'XPC_SERVICE_NAME': '0', 13:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:00:58 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 13:00:58 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 13:00:58 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 13:00:58 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 13:00:58 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 13:00:58 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 13:01:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:01:00 INFO - Installing collected packages: mozsystemmonitor 13:01:00 INFO - Running setup.py install for mozsystemmonitor 13:01:00 INFO - Successfully installed mozsystemmonitor 13:01:00 INFO - Cleaning up... 13:01:01 INFO - Return code: 0 13:01:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:01:01 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')]} 13:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:01 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')]} 13:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:01 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 0x10f548a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8ad53160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, '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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:01:01 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 13:01:01 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 13:01:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:01:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:01 INFO - 'HOME': '/Users/cltbld', 13:01:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:01 INFO - 'LOGNAME': 'cltbld', 13:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:01 INFO - 'MOZ_NO_REMOTE': '1', 13:01:01 INFO - 'NO_EM_RESTART': '1', 13:01:01 INFO - 'PAGER': '/bin/cat', 13:01:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:01 INFO - 'PWD': '/builds/slave/test', 13:01:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:01 INFO - 'SHELL': '/bin/bash', 13:01:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:01:01 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:01:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:01 INFO - 'USER': 'cltbld', 13:01:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:01 INFO - 'XPC_FLAGS': '0x0', 13:01:01 INFO - 'XPC_SERVICE_NAME': '0', 13:01:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:01 INFO - Downloading/unpacking blobuploader==1.2.4 13:01:01 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 13:01:01 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 13:01:01 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 13:01:01 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 13:01:01 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 13:01:01 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 13:01:03 INFO - Downloading blobuploader-1.2.4.tar.gz 13:01:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:01:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:01:03 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 13:01:03 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 13:01:03 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 13:01:03 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 13:01:03 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 13:01:03 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 13:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:01:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:01:04 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 13:01:04 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 13:01:04 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 13:01:04 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 13:01:04 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 13:01:04 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 13:01:04 INFO - Downloading docopt-0.6.1.tar.gz 13:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:01:04 INFO - Installing collected packages: blobuploader, requests, docopt 13:01:04 INFO - Running setup.py install for blobuploader 13:01:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:01:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:01:05 INFO - Running setup.py install for requests 13:01:05 INFO - Running setup.py install for docopt 13:01:05 INFO - Successfully installed blobuploader requests docopt 13:01:05 INFO - Cleaning up... 13:01:05 INFO - Return code: 0 13:01:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:01:05 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')]} 13:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:05 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')]} 13:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:05 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 0x10f548a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8ad53160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, '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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:01:05 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 13:01:05 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 13:01:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:01:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:05 INFO - 'HOME': '/Users/cltbld', 13:01:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:05 INFO - 'LOGNAME': 'cltbld', 13:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:05 INFO - 'MOZ_NO_REMOTE': '1', 13:01:05 INFO - 'NO_EM_RESTART': '1', 13:01:05 INFO - 'PAGER': '/bin/cat', 13:01:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:05 INFO - 'PWD': '/builds/slave/test', 13:01:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:05 INFO - 'SHELL': '/bin/bash', 13:01:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:01:05 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:01:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:05 INFO - 'USER': 'cltbld', 13:01:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:05 INFO - 'XPC_FLAGS': '0x0', 13:01:05 INFO - 'XPC_SERVICE_NAME': '0', 13:01:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:01:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-jvIXp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:01:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-cdEWta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:01:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-OKeq7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:01:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-8sCVx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:01:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-2ocNOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:01:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-X5J0gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:01:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KMuoOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:01:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-bUg3B3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:01:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1NKenM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:01:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-JI0U7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:01:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-YIUgxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:01:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-TaWYDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:01:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-O80T1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:01:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KiEQ0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ePIxgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:01:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-3qmgw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:01:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-skpBka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:01:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:01:07 INFO - Running setup.py install for manifestparser 13:01:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:01:08 INFO - Running setup.py install for mozcrash 13:01:08 INFO - Running setup.py install for mozdebug 13:01:08 INFO - Running setup.py install for mozdevice 13:01:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:01:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:01:08 INFO - Running setup.py install for mozfile 13:01:08 INFO - Running setup.py install for mozhttpd 13:01:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:01:08 INFO - Running setup.py install for mozinfo 13:01:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:01:08 INFO - Running setup.py install for mozInstall 13:01:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:01:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:01:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:01:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:01:09 INFO - Running setup.py install for mozleak 13:01:09 INFO - Running setup.py install for mozlog 13:01:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:01:09 INFO - Running setup.py install for moznetwork 13:01:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:01:09 INFO - Running setup.py install for mozprocess 13:01:09 INFO - Running setup.py install for mozprofile 13:01:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:01:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:01:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:01:09 INFO - Running setup.py install for mozrunner 13:01:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:01:10 INFO - Running setup.py install for mozscreenshot 13:01:10 INFO - Running setup.py install for moztest 13:01:10 INFO - Running setup.py install for mozversion 13:01:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:01:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:01:10 INFO - Cleaning up... 13:01:10 INFO - Return code: 0 13:01:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:10 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 0x10f548a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8ad53160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, '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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:01:10 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 13:01:10 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 13:01:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:01:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:10 INFO - 'HOME': '/Users/cltbld', 13:01:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:10 INFO - 'LOGNAME': 'cltbld', 13:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:10 INFO - 'MOZ_NO_REMOTE': '1', 13:01:10 INFO - 'NO_EM_RESTART': '1', 13:01:10 INFO - 'PAGER': '/bin/cat', 13:01:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:10 INFO - 'PWD': '/builds/slave/test', 13:01:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:10 INFO - 'SHELL': '/bin/bash', 13:01:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:01:10 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:01:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:10 INFO - 'USER': 'cltbld', 13:01:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:10 INFO - 'XPC_FLAGS': '0x0', 13:01:10 INFO - 'XPC_SERVICE_NAME': '0', 13:01:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:01:10 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-TSeijb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:01:10 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)) 13:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:01:10 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-L3ShyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:01:10 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)) 13:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:01:10 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-xHIiGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:01:11 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-PInpbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:01:11 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LohU3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:01:11 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-vv8TBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:01:11 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-0sMQdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:01:11 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-GlaZI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:01:11 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-O4qlbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:01:11 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LLdSBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-O_KeJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:01:11 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-gBXZWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 13:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:01:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-teWAmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:01:12 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)) 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:01:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-JWT4Tx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:01:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-cDVmKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:12 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)) 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:01:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-cdUIUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:01:12 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)) 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:01:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-7X6gJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:01:12 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)) 13:01:12 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)) 13:01:12 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)) 13:01:12 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)) 13:01:12 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)) 13:01:12 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)) 13:01:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:01:12 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 13:01:12 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 13:01:12 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 13:01:12 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 13:01:12 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 13:01:12 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 13:01:14 INFO - Downloading blessings-1.6.tar.gz 13:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:01:15 INFO - Installing collected packages: blessings 13:01:15 INFO - Running setup.py install for blessings 13:01:15 INFO - Successfully installed blessings 13:01:15 INFO - Cleaning up... 13:01:15 INFO - Return code: 0 13:01:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:01:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:01:15 INFO - Reading from file tmpfile_stdout 13:01:15 INFO - Current package versions: 13:01:15 INFO - blessings == 1.6 13:01:15 INFO - blobuploader == 1.2.4 13:01:15 INFO - docopt == 0.6.1 13:01:15 INFO - manifestparser == 1.1 13:01:15 INFO - mozInstall == 1.12 13:01:15 INFO - mozcrash == 0.17 13:01:15 INFO - mozdebug == 0.1 13:01:15 INFO - mozdevice == 0.48 13:01:15 INFO - mozfile == 1.2 13:01:15 INFO - mozhttpd == 0.7 13:01:15 INFO - mozinfo == 0.9 13:01:15 INFO - mozleak == 0.1 13:01:15 INFO - mozlog == 3.2 13:01:15 INFO - moznetwork == 0.27 13:01:15 INFO - mozprocess == 0.22 13:01:15 INFO - mozprofile == 0.28 13:01:15 INFO - mozrunner == 6.11 13:01:15 INFO - mozscreenshot == 0.1 13:01:15 INFO - mozsystemmonitor == 0.3 13:01:15 INFO - moztest == 0.7 13:01:15 INFO - mozversion == 1.4 13:01:15 INFO - psutil == 3.1.1 13:01:15 INFO - requests == 1.2.3 13:01:15 INFO - wsgiref == 0.1.2 13:01:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:15 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 0x10f548a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8ad53160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, '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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:01:15 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 13:01:15 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 13:01:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:01:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:15 INFO - 'HOME': '/Users/cltbld', 13:01:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:15 INFO - 'LOGNAME': 'cltbld', 13:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:15 INFO - 'MOZ_NO_REMOTE': '1', 13:01:15 INFO - 'NO_EM_RESTART': '1', 13:01:15 INFO - 'PAGER': '/bin/cat', 13:01:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:15 INFO - 'PWD': '/builds/slave/test', 13:01:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:15 INFO - 'SHELL': '/bin/bash', 13:01:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:01:15 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:01:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:15 INFO - 'USER': 'cltbld', 13:01:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:15 INFO - 'XPC_FLAGS': '0x0', 13:01:15 INFO - 'XPC_SERVICE_NAME': '0', 13:01:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:15 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)) 13:01:15 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)) 13:01:15 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)) 13:01:15 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)) 13:01:15 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)) 13:01:15 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)) 13:01:15 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)) 13:01:15 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)) 13:01:15 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)) 13:01:15 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)) 13:01:15 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)) 13:01:15 INFO - Cleaning up... 13:01:15 INFO - Return code: 0 13:01:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:15 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 0x10f548a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8ad53160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, '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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:01:15 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 13:01:15 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 13:01:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:01:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:15 INFO - 'HOME': '/Users/cltbld', 13:01:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:15 INFO - 'LOGNAME': 'cltbld', 13:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:15 INFO - 'MOZ_NO_REMOTE': '1', 13:01:15 INFO - 'NO_EM_RESTART': '1', 13:01:15 INFO - 'PAGER': '/bin/cat', 13:01:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:15 INFO - 'PWD': '/builds/slave/test', 13:01:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:15 INFO - 'SHELL': '/bin/bash', 13:01:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:01:15 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:01:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:15 INFO - 'USER': 'cltbld', 13:01:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:15 INFO - 'XPC_FLAGS': '0x0', 13:01:15 INFO - 'XPC_SERVICE_NAME': '0', 13:01:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:16 INFO - Downloading/unpacking jsonschema 13:01:16 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 13:01:16 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 13:01:16 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 13:01:16 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 13:01:16 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 13:01:16 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 13:01:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:01:18 INFO - Downloading/unpacking functools32 (from jsonschema) 13:01:18 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 13:01:18 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 13:01:18 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 13:01:18 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 13:01:18 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 13:01:18 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 13:01:18 INFO - Downloading functools32-3.2.3-2.tar.gz 13:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:01:19 INFO - warning: no files found matching '*.txt' 13:01:19 INFO - no previously-included directories found matching 'build' 13:01:19 INFO - no previously-included directories found matching 'dist' 13:01:19 INFO - no previously-included directories found matching '.git*' 13:01:19 INFO - Installing collected packages: jsonschema, functools32 13:01:19 INFO - Running setup.py install for functools32 13:01:19 INFO - warning: no files found matching '*.txt' 13:01:19 INFO - no previously-included directories found matching 'build' 13:01:19 INFO - no previously-included directories found matching 'dist' 13:01:19 INFO - no previously-included directories found matching '.git*' 13:01:19 INFO - Successfully installed jsonschema functools32 13:01:19 INFO - Cleaning up... 13:01:19 INFO - Return code: 0 13:01:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:01:19 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')]} 13:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:19 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')]} 13:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:19 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f548a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f54e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c8ad53160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, '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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 13:01:19 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', 'flake8'] in /builds/slave/test/build 13:01:19 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 flake8 13:01:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:01:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:19 INFO - 'HOME': '/Users/cltbld', 13:01:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:19 INFO - 'LOGNAME': 'cltbld', 13:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:19 INFO - 'MOZ_NO_REMOTE': '1', 13:01:19 INFO - 'NO_EM_RESTART': '1', 13:01:19 INFO - 'PAGER': '/bin/cat', 13:01:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:19 INFO - 'PWD': '/builds/slave/test', 13:01:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:19 INFO - 'SHELL': '/bin/bash', 13:01:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:01:19 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:01:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:19 INFO - 'USER': 'cltbld', 13:01:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:19 INFO - 'XPC_FLAGS': '0x0', 13:01:19 INFO - 'XPC_SERVICE_NAME': '0', 13:01:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:19 INFO - Downloading/unpacking flake8 13:01:19 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 13:01:19 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 13:01:19 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 13:01:19 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 13:01:19 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 13:01:19 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 13:01:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:01:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:01:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:01:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:01:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:01:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:01:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:01:23 INFO - no previously-included directories found matching 'docs/_build' 13:01:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:01:23 INFO - Running setup.py install for pep8 13:01:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:01:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:01:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:01:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:01:23 INFO - no previously-included directories found matching 'docs/_build' 13:01:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:01:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:01:23 INFO - Cleaning up... 13:01:23 INFO - Return code: 0 13:01:23 INFO - Running post-action listener: _resource_record_post_action 13:01:23 INFO - Running post-action listener: _start_resource_monitoring 13:01:23 INFO - Starting resource monitoring. 13:01:23 INFO - ##### 13:01:23 INFO - ##### Finished create-virtualenv step (success) 13:01:23 INFO - ##### 13:01:23 INFO - ##### 13:01:23 INFO - ##### Running install step. 13:01:23 INFO - ##### 13:01:23 INFO - Running pre-action listener: _resource_record_pre_action 13:01:23 INFO - Running main action method: install 13:01:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:01:24 INFO - Reading from file tmpfile_stdout 13:01:24 INFO - Detecting whether we're running mozinstall >=1.0... 13:01:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:01:24 INFO - Reading from file tmpfile_stdout 13:01:24 INFO - Output received: 13:01:24 INFO - Usage: mozinstall [options] installer 13:01:24 INFO - Options: 13:01:24 INFO - -h, --help show this help message and exit 13:01:24 INFO - -d DEST, --destination=DEST 13:01:24 INFO - Directory to install application into. [default: 13:01:24 INFO - "/builds/slave/test"] 13:01:24 INFO - --app=APP Application being installed. [default: firefox] 13:01:24 INFO - mkdir: /builds/slave/test/build/application 13:01:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:01:58 INFO - Reading from file tmpfile_stdout 13:01:58 INFO - Output received: 13:01:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:01:58 INFO - Running post-action listener: _resource_record_post_action 13:01:58 INFO - ##### 13:01:58 INFO - ##### Finished install step (success) 13:01:58 INFO - ##### 13:01:58 INFO - ##### 13:01:58 INFO - ##### Running run-tests step. 13:01:58 INFO - ##### 13:01:58 INFO - Running pre-action listener: _resource_record_pre_action 13:01:58 INFO - Running main action method: run_tests 13:01:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:01:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:01:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:01:58 INFO - 2016-05-18 13:01:58.064 screenresolution[1911:14395] starting screenresolution argv=screenresolution get 13:01:58 INFO - 2016-05-18 13:01:58.066 screenresolution[1911:14395] Display 0: 1600x1200x32@60 13:01:58 INFO - 2016-05-18 13:01:58.072 screenresolution[1912:14397] starting screenresolution argv=screenresolution list 13:01:58 INFO - Available Modes on Display 0 13:01:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:01:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:01:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:01:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:01:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:01:58 INFO - Intel Iris: 13:01:58 INFO - Chipset Model: Intel Iris 13:01:58 INFO - Type: GPU 13:01:58 INFO - Bus: Built-In 13:01:58 INFO - VRAM (Dynamic, Max): 1536 MB 13:01:58 INFO - Vendor: Intel (0x8086) 13:01:58 INFO - Device ID: 0x0a2e 13:01:58 INFO - Revision ID: 0x0009 13:01:58 INFO - Displays: 13:01:58 INFO - Display: 13:01:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:01:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:01:58 INFO - Display Serial Number: 1600x1200 60 13:01:58 INFO - Main Display: Yes 13:01:58 INFO - Mirror: Off 13:01:58 INFO - Online: Yes 13:01:58 INFO - Rotation: Supported 13:01:58 INFO - Return code: 0 13:01:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:01:58 INFO - Python 2.7.3 13:01:58 INFO - Return code: 0 13:01:58 INFO - grabbing minidump binary from tooltool 13:01:58 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')]} 13:01:58 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 0x10f3ecd30>, '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 0x10f210db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:01:58 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 13:01:58 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 13:01:58 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 13:01:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:01:58 INFO - Return code: 0 13:01:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:01:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:01:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:01:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:01:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:01:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:01:59 INFO - Return code: 0 13:01:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 13:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0123 --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 13:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E90vQ5Lkpi/Render', 13:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:59 INFO - 'HOME': '/Users/cltbld', 13:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:59 INFO - 'LOGNAME': 'cltbld', 13:01:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:01:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:59 INFO - 'MOZ_NO_REMOTE': '1', 13:01:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:01:59 INFO - 'NO_EM_RESTART': '1', 13:01:59 INFO - 'PAGER': '/bin/cat', 13:01:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:59 INFO - 'PWD': '/builds/slave/test', 13:01:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:59 INFO - 'SHELL': '/bin/bash', 13:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.joqzgJ32I7/Listeners', 13:01:59 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 13:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:59 INFO - 'USER': 'cltbld', 13:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:59 INFO - 'XPC_FLAGS': '0x0', 13:01:59 INFO - 'XPC_SERVICE_NAME': '0', 13:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 13:01:59 INFO - mozversion application_buildid: 20160517065136 13:01:59 INFO - mozversion application_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:01:59 INFO - mozversion application_display_name: Nightly 13:01:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:01:59 INFO - mozversion application_name: Firefox 13:01:59 INFO - mozversion application_remotingname: firefox 13:01:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:01:59 INFO - mozversion application_vendor: Mozilla 13:01:59 INFO - mozversion application_version: 49.0a1 13:01:59 INFO - mozversion platform_buildid: 20160517065136 13:01:59 INFO - mozversion platform_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:01:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:01:59 INFO - mozversion platform_version: 49.0a1 13:01:59 INFO - using testdate: 1463601719 13:01:59 INFO - actual date: 1463601719 13:01:59 INFO - starting webserver on 'localhost:53737' 13:01:59 INFO - SUITE-START | Running 2 tests 13:01:59 INFO - TEST-START | dromaeo_css 13:01:59 INFO - operating with platform_type : mac_ 13:01:59 INFO - Initialising browser for dromaeo_css test... 13:01:59 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpFKTkQw/profile http://localhost:53737/getInfo.html) 13:02:02 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 13:02:02 INFO - PROCESS | 1930 | colorDepth:24 13:02:02 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 13:02:02 INFO - PROCESS | 1930 | __metrics 13:02:02 ERROR - PROCESS | 1930 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:02:03 INFO - TEST-INFO | 1930: exit 0 13:02:03 INFO - Browser initialized. 13:02:03 INFO - Running cycle 1/1 for dromaeo_css test... 13:02:03 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpFKTkQw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:02:15 INFO - PROCESS | 1933 | RSS: Main: 249688064 13:02:15 INFO - PROCESS | 1933 | 13:03:05 INFO - PROCESS | 1933 | 1463601785280 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] 13:05:16 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:53737/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53737/tests/dromaeo/cssquery-ext.html) 13:05:17 INFO - PROCESS | 1933 | RSS: Main: 261410816 13:05:17 INFO - PROCESS | 1933 | 13:08:20 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:53737/tests/dromaeo/cssquery-ext.html (next: http://localhost:53737/tests/dromaeo/cssquery-jquery.html) 13:08:20 INFO - PROCESS | 1933 | RSS: Main: 273149952 13:08:20 INFO - PROCESS | 1933 | 13:11:22 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:53737/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53737/tests/dromaeo/cssquery-mootools.html) 13:11:23 INFO - PROCESS | 1933 | RSS: Main: 275259392 13:11:23 INFO - PROCESS | 1933 | 13:14:24 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:53737/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53737/tests/dromaeo/cssquery-prototype.html) 13:14:25 INFO - PROCESS | 1933 | RSS: Main: 277086208 13:14:25 INFO - PROCESS | 1933 | 13:17:28 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:53737/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53737/tests/dromaeo/cssquery-yui.html) 13:17:28 INFO - PROCESS | 1933 | RSS: Main: 279904256 13:17:28 INFO - PROCESS | 1933 | 13:20:31 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:53737/tests/dromaeo/cssquery-yui.html (next: http://localhost:53737/tests/dromaeo/cssquery-dojo.html) 13:20:31 INFO - PROCESS | 1933 | RSS: Main: 280801280 13:20:31 INFO - PROCESS | 1933 | 13:20:31 INFO - PROCESS | 1933 | __start_tp_report 13:20:31 INFO - PROCESS | 1933 | _x_x_mozilla_page_load 13:20:31 INFO - PROCESS | 1933 | _x_x_mozilla_page_load_details 13:20:31 INFO - PROCESS | 1933 | |i|pagename|runs| 13:20:31 INFO - PROCESS | 1933 | |0;dojo.html;3497.702695270902;3525.929481410372;3529.7705649132804;3549.751517393782;3568.214992701605;5908.40915908409;5957.880842383155;6035.5473339499495;6050.757969681213;6052.576319657625;5400.756965936533;5833.125031245316;5925.88148237035;5970.955082860493;6056.878862422749;5261.447548007462;5300.469953004707;5392.91910621341;5423.2136340230645;5456.645318054334;326.0930354615421;5284.365876094874;5348.652337598063;5354.464553544633;5356.946430535684;5323.866903327409;5359.410464848864;5359.812406565751;5372.650777699476;5373.570114390839;5340.732963351856;5399.9730001350135;5401.756920938567;5407.810726624549;5417.349918009824;5197.986392653434;5217.713025783581;5283.6037297202865;5297.258383826267;5365.3293338332705;5674.546036317085;5770.990076736554;5818.5636077294375;5841;5879.294484661825;6178.691065446755;6300.621962682254;6307.085472606446;6327.145835312266;6354.523410744213;4954.900901981976;6238.625682459067;6281.3404592518045;6361.459275961549;6418.807435776934;6092.878142437171;6110.450059494667;6111.633302001894;6143.170671959318;6146.108814221912;6286.968565157236;6379.681015949184;6511.120998665215;6513;6570.5729127606555;6198.783042593578;6224.751009959641;6229.781957631461;6253.624782513064;6285.465735412541;6384.361563843579;6438.6136831790245;6439.034144878306;6457.160569125984;6481.870362592722;6203.534734894901;6247.625142491465;6258.3428739982555;6294.118823364733;6327.462165715873;6274.937250627435;6292.182016337938;6306.054091886253;6310.8422289442215;6330.177076979963;5134.743262836843;5137.820176293886;5138.922916156261;5148.794048238104;5153.304303918923;4465.843695470643;4478.977605111953;4497.078098989699;4554.772261386917;4577.793999270041;6042.244719410074;6047.637141771508;6080.300765411946;6099.249254464568;6135;6393.6483493408305;6448.419642232222;6472.838179045561;6476.384743449365;6488.351164883568;5931.377205393458;5970.283565972111;6009.579329446897;6072.696365181723;6111.480524155408;4404.55954404557;4756.952430475721;5500.477454641803;5547.861303467364;5569.081101618188;5482.862928426821;5574.275344205227;6591.274959754519;6592.967035164794;6593.208814942238;6223.626582405071;6345.302016778243;6381.617102973836;6387.265464471459;6467.644279564669;6648.16897887764;6662.067310576426;6669.2997235289595;6677.09859169006;6678.298778628368;5473.370562385217;6349.428551430394;6349.460295874902;6352.396522330371;6357.841053973688;5386.973065134806;6295.496360291074;6378.45783108441;6398.104265402754;6447.258565264867;6223.253209614875;6269.96865015672;6283.120363149072;6288.905666415099;6289.622622642656;6133.548895741652;6196.628202307876;6219.160413344141;6257.937420625735;6274.1843560336865;6030.728617212324;6150.015997440388;6236.937630623635;6249.593776404701;6265.342139075514;6224.906626400517;6244.344343843831;6247.9062814056915;6259.092431597484;6264.68676566179;6110.26676798787;6113.993245360445;6138.447539721446;6225.315215326401;6250.843728906813 13:20:31 INFO - PROCESS | 1933 | |1;ext.html;491.45939466586753;506.6909185396907;574.652335337121;577.3043482603463;590.3152343281794;38456.615433845654;39301.01747456315;40680.796596017055;40764.79617601912;40948.79525602368;41598;42388.788056059675;42441.57558424422;42529.7873510632;42576.57423425765;8844.408006558817;9518.952405237982;11785.646430607096;11803.881961180365;12057.879421205807;11695.766084678344;11776.11679124069;11780.352080635561;11847.526098956032;11885.989690876286;11810.291382517033;11894.345810980392;11898.405079746022;11996.400179990966;12003.879961200364;11821.645350639494;11939.104567157454;11939.880601193963;11941.761164776743;11963.1625786195;8772.649094036231;11113.666590002313;11791.29252244868;11949.402529873472;12056.578019769353;41255.58744412548;41751;42747.35878961818;43360.56639433597;43428;3280.6883346082323;3299.653536378694;3340.5657264555693;3548.8935331940083;3808.638179372955;29522.70477295221;29856.805727770865;29915.073887706545;30002.099937001924;30168;2700.243931699127;2765.9723402765917;2801.1036468330144;2913.1697466222026;3000.5199168133213;534743;540456;542778;543497;545130;70098;70918;71525.6423717878;71573;71716.64141679258;74110.62944685241;74270.62864685642;74493.62753186199;74544;75259.24740752632;16001.519954401387;16705.498835034967;17728.55678608045;17750.20124094419;17987;11538.78842721519;13459;13635.931820340835;13815.309234538434;13854.930725346308;19828;21116.366509004754;21416.357509274745;21932.903354832193;22002.559948800936;7287.927120728725;8719.912800872036;8825.76060049512;8864.512451815082;8921.955390223007;880.2210044111008;892.4377642085444;960.3901522533241;1025.5128813813378;1027.671145233518;30036.549451757804;30329.93845215451;30380.87333886651;31101.688983110333;31163.688363116533;1063.547992103353;1126.8704099028553;1140.7604403075447;1155.3414553704308;1192.7793358228755;15533.689326213413;15717.371305147666;15831.208439578206;16013.279402426919;16087.356505739639;667353;675388;683405;684946.8763280858;685891;61874;62097.37902621007;62430.687846560475;62553;62701.43246418876;1426.8359138699182;1448.4857875454063;1460.7443697353133;1463.1806188534454;1498.5654160293393;28910.27724306825;29674.81300747946;30198.84900575483;30509.847450763493;30579.388412231187;50078.74960625174;54430;58836;60204;63614;656102;663409;666045;668039;668328;24279.75720242775;27937;28254;28573.857130714212;28713;46649.30026049544;47283.763581181876;47710.522894770605;48584.271235932196;49347.25979110244;33266;36063.819680901426;37370.626293736714;38866;40491.79754101211;31743.682563174072;38810.6118938807;38891.41662875003;39412;39564.2087158261;37979;38222.23555528929;38327.42508862425;39645.40531892081;39743.80128099341;33916.83041584776;33931.66068339285;34494.82752586221;34545.48181777326;34759.82620086883;20840.68738968947;21063.789362106796;25333.366665832764;25550.872245638653;26076.608850867633 13:20:31 INFO - PROCESS | 1933 | |2;jquery.html;346.3108414255631;386.73508646577096;391.6123038192189;404.64593480704383;414.20886107534596;19021.809781902175;21430.464238394048;21735.565288694237;22175.334739957787;22322.995465204065;19416.611667766636;20265.885376304297;20510.17959281631;20817.687734683954;21332.680009799864;5097.388313402378;5670.914936275949;5901.763929442817;5910.615809972338;5913.9112913306335;5929.703514824241;5930;5933.465988061073;5969.104634304846;5971.701414929258;4443.333499974998;5713.685747283897;5810.012297909345;5836.854078648046;5873.148393482964;5468.589855760814;5715.457031581993;5799.362070172257;5909.940900590982;5911.349751527328;5617.185508101344;5627.184058311522;5642.238297829823;5642.633228840154;5645.350784659735;12451.75096498074;13089.803652945213;13114.082014259006;13124.540641077612;13154.802677959839;2032.7763945965933;2127.563849410867;2149.9247526336503;2285.8057065149483;2296.5636529059425;9746.171575416098;9942.90057099427;10031.49842507879;10249;12609.928156106742;1771.2206629083162;1797.182282061672;1797.5146710388121;1805.3328582457323;1818.690822560168;122081;124356.75643243542;124916;126653;126667;11734.765304693858;11739.88260117405;11852.525898964117;11942.462589183508;12003.339816310187;14594;14761.77857332141;14830.925845370704;14876.62808429798;14938.850611493963;8701.086385929513;8747.431416958005;8799.648014079365;8811.20699137068;8910.910890891008;8682.262007729287;8717.259032982141;8777.736667899973;8837.469751814782;8889.199972002421;3756.962430375716;4607.170709272296;4618.168729628632;4647.16351056813;4701.64737644678;1661.6676664667116;1952.580195257998;1967.1639553189837;1982.0882394098587;2005.1578337098454;881.8412685716504;945.2579724915907;1009.1523120578603;1014.7513859104419;1016.588281745894;19527.925964072114;19811.10850011753;20291.492712682302;20931.581368372852;20952.161913523592;961.274237950343;982.5824024789435;987.6049580167846;1014.7361685961555;1040.469360626071;8899.510526921082;10655.62705305326;11084.944575277234;11106.61126860556;11159.163062770162;546574;549859.5014049808;553247;563555;565439;118340.40829795795;118671;118796;122573;122871;1005.1657124586576;1014.8477728340808;1045.0437849367895;1064.0689396777818;1064.7178497698117;16357.018578885305;17687.38094166698;18234.817651823843;18584.070796460393;18818.34135805295;50589.24116138335;51203.743981281346;51849;52518;52555.94888102293;67134.66432667806;69448.65275673791;69926;71086;77153;7053.294670532906;7271.818204544928;7358.521696089737;7411.4811963162065;7647.808804779925;10746.62386816489;11801.70495737579;11809.940950295193;11841.9934305585;12006.699832504257;10238.641647542301;10261.897381026094;10341.276110672181;10355.068043875786;10446.477676116012;9370.531473426438;9939.254555908192;9942.15491683215;10255.179585633281;10298.279120461495;9886.653967111117;10342.482875856027;10528;10751.354918704903;10777;6142.662153581417;7185.317394847481;7354.852902942018;7358;7513.586752728653;7576.659050342638;9568.330216884751;10327.32872363294;10636.680899573024;10649.041586257274 13:20:31 INFO - PROCESS | 1933 | |3;mootools.html;1608.107500337313;1645.2514106081735;1913.2825190553542;1969.251684359943;2007.187089228861;8132.878006829902;8723.781905452366;8731.083236260196;9194.218491428228;9211.81576368473;8814.294856411489;8821.911780882188;9001.639934402616;9021.729348119567;9030.277577793777;2413.589689752742;2435.4398488347692;2438.1222759806456;2440.8779561021965;2458.8278820482574;2424.5150969806023;2428.101602407112;2463.6550882876413;2471.0115953618515;2474.9133780317693;2430.5503481855826;2447.3147518694796;2467.90128394864;2471.0115953618515;2472.629105634151;2414.2757172848073;2419.794317483016;2425.9757402425926;2427.8664673442954;2436.135172013945;2400.135951057618;2402.387391215246;2402.9279121626378;2410.626352915301;2424.1273141668985;7692.499987500851;9033.412828166149;9089.318301127441;9113.863292050624;9131.497767622777;4316.460442444694;5292.38582232419;5487.176923461473;5517;5557.138643510222;3214.4214041472524;3484.285721427101;3622.7826330420257;3623.3115708015393;3650.6349365063547;840.4285086141422;933.9673111441067;954.4654993203828;986.4919566423226;1013.7060252526759;12062.758744825054;12194.57318993831;12243.20419172751;12257.448414821265;12263.754724905451;4656.743879086615;4791.185498465269;4869.14789911764;4904.190808516555;4945.332380128709;10117.848232276521;10136.189104871593;10261.435621040766;10267.640632577824;10273.897261027443;5452.645578037414;5499.807506737325;5501.312335958005;5515.4760297771645;5521.751521181556;4501.617362524222;4846.491118432514;4866.294387313819;5000.1749711297225;5009.198528235464;4069.5116586009867;4077.936065012029;4090.141070375195;4131.462909821704;4149.813258403379;1303.2766814417898;1329.4217015598247;1334.9599512014654;1341.0411555737694;1342.5502456677123;855.6491838346249;918.9724308270762;933.7338858425317;936.639393833379;943.773494361362;5777.19119323295;5959.642421454727;5989.880202396015;6025.728842202024;6177.135201071854;940.1209868772719;961.1781926452921;967.4872317671504;975.9947254328054;976.9560369783377;3951.920961580752;4022.899427514277;4042.2319759245647;4064.6748260139116;4157.438745769343;10641.255112142077;11108.16688748347;11122.666320010412;11164.720881978015;11206.495707693173;10967.945160274148;11002.834957475645;11082.168837337233;11111.555537778399;11122.49948752308;8298.170182981774;8328.8334233315;8357.707480238285;8452.704155354533;8464.83070338602;5212.192110222922;5793.652380857162;5827.155062515996;5932.080527518242;5997.070454079625;9879.259055570717;9945.453000084775;10040.397576145455;10242.283040187116;10261.384316941007;7651.770446886602;9860.605575776888;10345.689629311133;10375.636852710119;10433.373997560171;7066.681999310146;7351.8529629408185;7477;7520.435967302365;7803.492772969739;8602.61288242018;8614.3108551317;8638.611262493077;8694.08712085222;8703.69537066225;7624.733134340271;7668.849672549162;7727.111382190895;7799.844003119792;7852.332551733167;6325.430711236011;6807.183138023271;7529.360004399468;7530.698772049057;7574.772756817304;7189.964050179716;7240.094988126484;7269.091363579552;7292.978982942286;7293.124825021031;4852.781624826821;4997.075541024922;5021.6735912165595;5033.748312584429;5117.961184394048;4947.282644016525;5001.64988450805;5039.823606173766;5098.719570423662;5263.473652634859 13:20:31 INFO - PROCESS | 1933 | |4;prototype.html;306.7469337796317;316.15428728152193;319.35171601648653;324.236423223309;327.6756011548566;4991.479302952013;5426.240326354314;5557.861053473665;5660.745266463009;5689.943100568988;5128.179491281396;5348.304720386354;5352.678839269637;5573.665580065189;5598.992181407345;3367.848446819899;3683.3922402803505;3844.692424606039;4206.852760153395;4214.304639734448;3599.4357694546084;3673.3020726062095;4033.588244114566;4086.795660216992;4204.096119334343;3144.2925341798027;3958.6540576204084;4108.876733697979;4193.783802697214;4212.747235165884;3867.9613203867884;3883.417487376902;3981.6615587675083;4000.479937608107;4188.183304255671;3343.3814744272386;3603.1892824114625;3608.729345299113;3610.530631017951;3624.5650521937273;5176.5858731301405;5335.771858025916;5358.142697168473;5370.355557333106;5543.307086614173;4186.890474024386;4525.891156666605;4792.353032340624;4816.157172494834;4817.879553011167;5638.4925356718095;5722.141678748179;5934.228550288478;5965.463108320272;5975.282966044103;3831.1379939513663;3968.226195891802;3995.5405128410316;4009.3785463253244;4015.959840401588;4759;5384.623076384654;5432.185172224158;5471.890562188734;5887.970560147171;3882.893375388001;3976.4035394691027;4036.8385264589087;4036.990752311922;4051.642699695635;3466.791992480459;3867.8259478323544;4083.611572065512;4090.570490098497;4091.161311931047;4059.654929330981;4200.789960501963;4527.3435351873795;4724.102420540155;4819.734914579732;5230.057595572148;5423.484768946943;5658.773649053992;5872.915115134151;5894.469497745224;1363.180154493742;1399.6710772968547;1412.216219997911;1441.7116576684705;1460.2406748490725;741.2624438683545;753.6909866954522;815.9796005099802;864.7189663359434;887.7070566712969;3978.960210397917;4092.7135100542678;4567.840125595588;4622.260438329851;4650.744209068466;4640.167133982126;5017.84946451607;5048.747562621854;5082.974585127125;5118.872028199325;3998.9800051000143;4200.7479551227025;4303.225419424471;4543.863684089482;4567.862964111082;2212.557488502306;2432.6959130108735;2552.1322750265;2565.7434256574193;2607.3090630982806;4447.266201076786;4967.031428871408;5101.489851014869;5309.548688361532;5365.202657109912;4618.67669263155;4632.976835115803;4923.606111511071;5071.315372424676;5255.527002569787;4802.831900883522;5275.841724748264;5292.047431462373;5299.496547828027;5343.7175077981;4469.346341853535;5112.3415823772175;5120.539151476385;5144.536991670618;5192.065428222956;4038.91922161561;4448.021435284232;4669.346291519122;5064.442911279682;5098.796048158032;4316.553954425177;4362.716423432466;4381.430414046154;5089.720065396439;5293.841184764463;3150.3384289298588;3261.902142935716;3594.6764791167875;3665.9552027673053;3677.2461645363173;4446.73319600825;4687.929681054855;4928.310036594808;5117.078925793393;5751.884962300815;5369.865753356197;5732.512736417284;5852.648841069549;5884.852878678067;5950.880982380415;4984.501549844987;5341.893162136813;5503.2020357048805;5860.919373622302;5877.265341832271;4467.419235499365;5580.604848787803;5724.370319264961;5925.614835035709;5928.436798504135;6041.426064523863;6065.907261994605;6070.605410648293;6071.878562428815;6085.634861908123;5503.3120859892515;5817.76728930838;5852.7658893643775;5876.676782776817;5881.529757501774 13:20:31 INFO - PROCESS | 1933 | |5;yui.html;733.8458923626039;736.5543845973186;740.3817812126874;748.1022772672794;813.6663967773214;15079.773803392944;15172.482751724821;15652.530424087281;15683.137427441487;15703.371865125395;14341.569752907404;14428.422863085463;14443.350049247783;14444.783328250085;14461.5661530154;711.1679335177854;747.723342363325;896.7802888292377;918.554501066982;930.0652843891879;911.9954400227989;915.0712027292303;917.4311926605513;919.6781126605667;922.0088404964656;962.4417837654144;972.8881178664438;972.9708108756748;973.7760315127525;974.0746291023556;955.1403736637013;966.3573723473882;966.4491239993207;967.1730670276883;969.0938972061145;711.4166383565479;711.7508871894847;712.5760172697237;712.7113519024804;712.7469748239404;14678.853211467855;14847.406103755837;14925.925370373187;15040.17279049652;15040.699186016218;10888.693356797236;12609.432575534123;12616.05379596534;12689.365531723377;12847.164934279243;9936.503174841302;10100.292979491438;10144.543495542644;10402.167826573856;10699.465026748632;3479.1823921378455;3514.262004978932;3605.891823245307;3611.711063114971;3642.0809616685706;84247;85380.29239415239;85765.14234857634;85998.57000714957;86742;9347.953260233655;9473.384230025027;9498.19265362452;9604.85592716096;9685.564149613143;21901.233456828933;22295;22385;22387.216447424587;22395.21616743406;7325.450591205575;7383.446241531907;7442;7460.104787425544;7519.699212031567;3858.401947698111;3907.1208977979727;3967.147063381387;3967.2263908538134;3983.0042489377656;3377.527146199534;3441.4149594568985;3589.8205089745406;3675.61406052366;3735.906602334909;623.8721062182242;635.5297080160648;638.237306418825;650.2099948562519;650.9349065093453;561.2339157050678;573.4466240078261;579.623244890816;606.8984792601502;612.6079309242089;7080.716771329192;8010.719624813103;8118.715844945398;8160.877586836202;8281.130481299497;502.2290783647084;541.6939429222476;580.3818932836516;587.9299674592287;620.2866703291161;3777.2823163598828;3824.1204522959874;3883.165119499321;3890.280298144852;4090.3864420337186;93401.5329923346;96787.51606241924;96963.51518242364;97244;98215;83839.16160838435;84797;85200.8699782498;86514;86673.13326866651;75247.62376188084;75472.24527754763;76116;76801;76968.61515692498;7277.16312624055;7447.5531468112085;7652.1582625912215;7681.923180768121;7684.3084122429245;41349.37975930303;44657.33014004728;45211.64365068921;46517.76741116273;47741.52258477371;65224.69550609056;66224;66834.66582667055;66966.66516667386;67304.6634766823;8455;9662.985386534594;9812.460314682761;9984.251181161588;10103.444310562696;10725.03474687282;10727.40999245049;10739.248252622241;10924.126069914546;11079.72300692489;10133.898661013296;10187.694369168938;10272.897271027494;10432.321899076536;10534.157267418435;10248.743781405525;10249.077583017564;10318.742031448974;10353.119984801377;10621.681349559525;10119.190464762654;10139.2395570334;10183.592656293962;10458.68623941283;10483.161347092368;8564.785880353042;8637.654493820177;8938.4190027648;8977.012528621977;9018.368714190206;7483.32650061497;8141.592920354077;8656.567171641518;8878.911210887809;9347 13:20:31 INFO - PROCESS | 1933 | __end_tp_report 13:20:31 INFO - PROCESS | 1933 | __start_cc_report 13:20:31 INFO - PROCESS | 1933 | _x_x_mozilla_cycle_collect,428 13:20:31 INFO - PROCESS | 1933 | __end_cc_report 13:20:31 INFO - PROCESS | 1933 | __startTimestamp1463602831769__endTimestamp 13:20:31 INFO - PROCESS | 1933 | 13:20:31 INFO - PROCESS | 1933 | ------- Summary: start ------- 13:20:31 INFO - PROCESS | 1933 | Number of tests: 6 13:20:31 INFO - PROCESS | 1933 | 13:20:31 INFO - PROCESS | 1933 | [#0] dojo.html Cycles:165 Average:5850.66 Median:6138.45 stddev:793.05 (12.9%) stddev-sans-first:773.70 13:20:31 INFO - PROCESS | 1933 | Values: 3497.7 3525.9 3529.8 3549.8 3568.2 5908.4 5957.9 6035.5 6050.8 6052.6 5400.8 5833.1 5925.9 5971.0 6056.9 5261.4 5300.5 5392.9 5423.2 5456.6 326.1 5284.4 5348.7 5354.5 5356.9 5323.9 5359.4 5359.8 5372.7 5373.6 5340.7 5400.0 5401.8 5407.8 5417.3 5198.0 5217.7 5283.6 5297.3 5365.3 5674.5 5771.0 5818.6 5841.0 5879.3 6178.7 6300.6 6307.1 6327.1 6354.5 4954.9 6238.6 6281.3 6361.5 6418.8 6092.9 6110.5 6111.6 6143.2 6146.1 6287.0 6379.7 6511.1 6513.0 6570.6 6198.8 6224.8 6229.8 6253.6 6285.5 6384.4 6438.6 6439.0 6457.2 6481.9 6203.5 6247.6 6258.3 6294.1 6327.5 6274.9 6292.2 6306.1 6310.8 6330.2 5134.7 5137.8 5138.9 5148.8 5153.3 4465.8 4479.0 4497.1 4554.8 4577.8 6042.2 6047.6 6080.3 6099.2 6135.0 6393.6 6448.4 6472.8 6476.4 6488.4 5931.4 5970.3 6009.6 6072.7 6111.5 4404.6 4757.0 5500.5 5547.9 5569.1 5482.9 5574.3 6591.3 6593.0 6593.2 6223.6 6345.3 6381.6 6387.3 6467.6 6648.2 6662.1 6669.3 6677.1 6678.3 5473.4 6349.4 6349.5 6352.4 6357.8 5387.0 6295.5 6378.5 6398.1 6447.3 6223.3 6270.0 6283.1 6288.9 6289.6 6133.5 6196.6 6219.2 6257.9 6274.2 6030.7 6150.0 6236.9 6249.6 6265.3 6224.9 6244.3 6247.9 6259.1 6264.7 6110.3 6114.0 6138.4 6225.3 6250.8 13:20:31 INFO - PROCESS | 1933 | 13:20:31 INFO - PROCESS | 1933 | [#1] ext.html Cycles:180 Average:76589.40 Median:34213.24 stddev:168939.91 (493.8%) stddev-sans-first:169317.22 13:20:31 INFO - PROCESS | 1933 | Values: 491.5 506.7 574.7 577.3 590.3 38456.6 39301.0 40680.8 40764.8 40948.8 41598.0 42388.8 42441.6 42529.8 42576.6 8844.4 9519.0 11785.6 11803.9 12057.9 11695.8 11776.1 11780.4 11847.5 11886.0 11810.3 11894.3 11898.4 11996.4 12003.9 11821.6 11939.1 11939.9 11941.8 11963.2 8772.6 11113.7 11791.3 11949.4 12056.6 41255.6 41751.0 42747.4 43360.6 43428.0 3280.7 3299.7 3340.6 3548.9 3808.6 29522.7 29856.8 29915.1 30002.1 30168.0 2700.2 2766.0 2801.1 2913.2 3000.5 534743.0 540456.0 542778.0 543497.0 545130.0 70098.0 70918.0 71525.6 71573.0 71716.6 74110.6 74270.6 74493.6 74544.0 75259.2 16001.5 16705.5 17728.6 17750.2 17987.0 11538.8 13459.0 13635.9 13815.3 13854.9 19828.0 21116.4 21416.4 21932.9 22002.6 7287.9 8719.9 8825.8 8864.5 8922.0 880.2 892.4 960.4 1025.5 1027.7 30036.5 30329.9 30380.9 31101.7 31163.7 1063.5 1126.9 1140.8 1155.3 1192.8 15533.7 15717.4 15831.2 16013.3 16087.4 667353.0 675388.0 683405.0 684946.9 685891.0 61874.0 62097.4 62430.7 62553.0 62701.4 1426.8 1448.5 1460.7 1463.2 1498.6 28910.3 29674.8 30198.8 30509.8 30579.4 50078.7 54430.0 58836.0 60204.0 63614.0 656102.0 663409.0 666045.0 668039.0 668328.0 24279.8 27937.0 28254.0 28573.9 28713.0 46649.3 47283.8 47710.5 48584.3 49347.3 33266.0 36063.8 37370.6 38866.0 40491.8 31743.7 38810.6 38891.4 39412.0 39564.2 37979.0 38222.2 38327.4 39645.4 39743.8 33916.8 33931.7 34494.8 34545.5 34759.8 20840.7 21063.8 25333.4 25550.9 26076.6 13:20:31 INFO - PROCESS | 1933 | 13:20:31 INFO - PROCESS | 1933 | [#2] jquery.html Cycles:180 Average:33080.63 Median:11942.75 stddev:93155.41 (780.0%) stddev-sans-first:93384.31 13:20:31 INFO - PROCESS | 1933 | Values: 346.3 386.7 391.6 404.6 414.2 19021.8 21430.5 21735.6 22175.3 22323.0 19416.6 20265.9 20510.2 20817.7 21332.7 5097.4 5670.9 5901.8 5910.6 5913.9 5929.7 5930.0 5933.5 5969.1 5971.7 4443.3 5713.7 5810.0 5836.9 5873.1 5468.6 5715.5 5799.4 5909.9 5911.3 5617.2 5627.2 5642.2 5642.6 5645.4 12451.8 13089.8 13114.1 13124.5 13154.8 2032.8 2127.6 2149.9 2285.8 2296.6 9746.2 9942.9 10031.5 10249.0 12609.9 1771.2 1797.2 1797.5 1805.3 1818.7 122081.0 124356.8 124916.0 126653.0 126667.0 11734.8 11739.9 11852.5 11942.5 12003.3 14594.0 14761.8 14830.9 14876.6 14938.9 8701.1 8747.4 8799.6 8811.2 8910.9 8682.3 8717.3 8777.7 8837.5 8889.2 3757.0 4607.2 4618.2 4647.2 4701.6 1661.7 1952.6 1967.2 1982.1 2005.2 881.8 945.3 1009.2 1014.8 1016.6 19527.9 19811.1 20291.5 20931.6 20952.2 961.3 982.6 987.6 1014.7 1040.5 8899.5 10655.6 11084.9 11106.6 11159.2 546574.0 549859.5 553247.0 563555.0 565439.0 118340.4 118671.0 118796.0 122573.0 122871.0 1005.2 1014.8 1045.0 1064.1 1064.7 16357.0 17687.4 18234.8 18584.1 18818.3 50589.2 51203.7 51849.0 52518.0 52555.9 67134.7 69448.7 69926.0 71086.0 77153.0 7053.3 7271.8 7358.5 7411.5 7647.8 10746.6 11801.7 11809.9 11842.0 12006.7 10238.6 10261.9 10341.3 10355.1 10446.5 9370.5 9939.3 9942.2 10255.2 10298.3 9886.7 10342.5 10528.0 10751.4 10777.0 6142.7 7185.3 7354.9 7358.0 7513.6 7576.7 9568.3 10327.3 10636.7 10649.0 13:20:31 INFO - PROCESS | 1933 | 13:20:31 INFO - PROCESS | 1933 | [#3] mootools.html Cycles:180 Average:5821.10 Median:5005.42 stddev:3269.39 (65.3%) stddev-sans-first:3263.23 13:20:31 INFO - PROCESS | 1933 | Values: 1608.1 1645.3 1913.3 1969.3 2007.2 8132.9 8723.8 8731.1 9194.2 9211.8 8814.3 8821.9 9001.6 9021.7 9030.3 2413.6 2435.4 2438.1 2440.9 2458.8 2424.5 2428.1 2463.7 2471.0 2474.9 2430.6 2447.3 2467.9 2471.0 2472.6 2414.3 2419.8 2426.0 2427.9 2436.1 2400.1 2402.4 2402.9 2410.6 2424.1 7692.5 9033.4 9089.3 9113.9 9131.5 4316.5 5292.4 5487.2 5517.0 5557.1 3214.4 3484.3 3622.8 3623.3 3650.6 840.4 934.0 954.5 986.5 1013.7 12062.8 12194.6 12243.2 12257.4 12263.8 4656.7 4791.2 4869.1 4904.2 4945.3 10117.8 10136.2 10261.4 10267.6 10273.9 5452.6 5499.8 5501.3 5515.5 5521.8 4501.6 4846.5 4866.3 5000.2 5009.2 4069.5 4077.9 4090.1 4131.5 4149.8 1303.3 1329.4 1335.0 1341.0 1342.6 855.6 919.0 933.7 936.6 943.8 5777.2 5959.6 5989.9 6025.7 6177.1 940.1 961.2 967.5 976.0 977.0 3951.9 4022.9 4042.2 4064.7 4157.4 10641.3 11108.2 11122.7 11164.7 11206.5 10967.9 11002.8 11082.2 11111.6 11122.5 8298.2 8328.8 8357.7 8452.7 8464.8 5212.2 5793.7 5827.2 5932.1 5997.1 9879.3 9945.5 10040.4 10242.3 10261.4 7651.8 9860.6 10345.7 10375.6 10433.4 7066.7 7351.9 7477.0 7520.4 7803.5 8602.6 8614.3 8638.6 8694.1 8703.7 7624.7 7668.8 7727.1 7799.8 7852.3 6325.4 6807.2 7529.4 7530.7 7574.8 7190.0 7240.1 7269.1 7293.0 7293.1 4852.8 4997.1 5021.7 5033.7 5118.0 4947.3 5001.6 5039.8 5098.7 5263.5 13:20:31 INFO - PROCESS | 1933 | 13:20:31 INFO - PROCESS | 1933 | [#4] prototype.html Cycles:180 Average:4405.93 Median:4775.68 stddev:1366.34 (28.6%) stddev-sans-first:1335.09 13:20:31 INFO - PROCESS | 1933 | Values: 306.7 316.2 319.4 324.2 327.7 4991.5 5426.2 5557.9 5660.7 5689.9 5128.2 5348.3 5352.7 5573.7 5599.0 3367.8 3683.4 3844.7 4206.9 4214.3 3599.4 3673.3 4033.6 4086.8 4204.1 3144.3 3958.7 4108.9 4193.8 4212.7 3868.0 3883.4 3981.7 4000.5 4188.2 3343.4 3603.2 3608.7 3610.5 3624.6 5176.6 5335.8 5358.1 5370.4 5543.3 4186.9 4525.9 4792.4 4816.2 4817.9 5638.5 5722.1 5934.2 5965.5 5975.3 3831.1 3968.2 3995.5 4009.4 4016.0 4759.0 5384.6 5432.2 5471.9 5888.0 3882.9 3976.4 4036.8 4037.0 4051.6 3466.8 3867.8 4083.6 4090.6 4091.2 4059.7 4200.8 4527.3 4724.1 4819.7 5230.1 5423.5 5658.8 5872.9 5894.5 1363.2 1399.7 1412.2 1441.7 1460.2 741.3 753.7 816.0 864.7 887.7 3979.0 4092.7 4567.8 4622.3 4650.7 4640.2 5017.8 5048.7 5083.0 5118.9 3999.0 4200.7 4303.2 4543.9 4567.9 2212.6 2432.7 2552.1 2565.7 2607.3 4447.3 4967.0 5101.5 5309.5 5365.2 4618.7 4633.0 4923.6 5071.3 5255.5 4802.8 5275.8 5292.0 5299.5 5343.7 4469.3 5112.3 5120.5 5144.5 5192.1 4038.9 4448.0 4669.3 5064.4 5098.8 4316.6 4362.7 4381.4 5089.7 5293.8 3150.3 3261.9 3594.7 3666.0 3677.2 4446.7 4687.9 4928.3 5117.1 5751.9 5369.9 5732.5 5852.6 5884.9 5950.9 4984.5 5341.9 5503.2 5860.9 5877.3 4467.4 5580.6 5724.4 5925.6 5928.4 6041.4 6065.9 6070.6 6071.9 6085.6 5503.3 5817.8 5852.8 5876.7 5881.5 13:20:31 INFO - PROCESS | 1933 | 13:20:31 INFO - PROCESS | 1933 | [#5] yui.html Cycles:180 Average:18555.38 Median:65724.35 stddev:27073.46 (41.2%) stddev-sans-first:27116.34 13:20:31 INFO - PROCESS | 1933 | Values: 733.8 736.6 740.4 748.1 813.7 15079.8 15172.5 15652.5 15683.1 15703.4 14341.6 14428.4 14443.4 14444.8 14461.6 711.2 747.7 896.8 918.6 930.1 912.0 915.1 917.4 919.7 922.0 962.4 972.9 973.0 973.8 974.1 955.1 966.4 966.4 967.2 969.1 711.4 711.8 712.6 712.7 712.7 14678.9 14847.4 14925.9 15040.2 15040.7 10888.7 12609.4 12616.1 12689.4 12847.2 9936.5 10100.3 10144.5 10402.2 10699.5 3479.2 3514.3 3605.9 3611.7 3642.1 84247.0 85380.3 85765.1 85998.6 86742.0 9348.0 9473.4 9498.2 9604.9 9685.6 21901.2 22295.0 22385.0 22387.2 22395.2 7325.5 7383.4 7442.0 7460.1 7519.7 3858.4 3907.1 3967.1 3967.2 3983.0 3377.5 3441.4 3589.8 3675.6 3735.9 623.9 635.5 638.2 650.2 650.9 561.2 573.4 579.6 606.9 612.6 7080.7 8010.7 8118.7 8160.9 8281.1 502.2 541.7 580.4 587.9 620.3 3777.3 3824.1 3883.2 3890.3 4090.4 93401.5 96787.5 96963.5 97244.0 98215.0 83839.2 84797.0 85200.9 86514.0 86673.1 75247.6 75472.2 76116.0 76801.0 76968.6 7277.2 7447.6 7652.2 7681.9 7684.3 41349.4 44657.3 45211.6 46517.8 47741.5 65224.7 66224.0 66834.7 66966.7 67304.7 8455.0 9663.0 9812.5 9984.3 10103.4 10725.0 10727.4 10739.2 10924.1 11079.7 10133.9 10187.7 10272.9 10432.3 10534.2 10248.7 10249.1 10318.7 10353.1 10621.7 10119.2 10139.2 10183.6 10458.7 10483.2 8564.8 8637.7 8938.4 8977.0 9018.4 7483.3 8141.6 8656.6 8878.9 9347.0 13:20:31 INFO - PROCESS | 1933 | -------- Summary: end -------- 13:20:31 INFO - PROCESS | 1933 | 13:20:32 INFO - TEST-INFO | 1933: exit 0 13:20:32 INFO - TEST-OK | dromaeo_css | took 1112547ms 13:20:32 INFO - TEST-START | kraken 13:20:32 INFO - operating with platform_type : mac_ 13:20:32 INFO - Initialising browser for kraken test... 13:20:32 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpZbwb1Y/profile http://localhost:53737/getInfo.html) 13:20:35 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 13:20:35 INFO - PROCESS | 1939 | colorDepth:24 13:20:35 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 13:20:35 INFO - PROCESS | 1939 | __metrics 13:20:35 ERROR - PROCESS | 1939 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:20:36 INFO - TEST-INFO | 1939: exit 0 13:20:36 INFO - Browser initialized. 13:20:36 INFO - Running cycle 1/1 for kraken test... 13:20:36 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpZbwb1Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:20:48 INFO - PROCESS | 1942 | RSS: Main: 249663488 13:20:48 INFO - PROCESS | 1942 | 13:20:51 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53737/tests/kraken/driver.html?testName=audio-beat-detection) 13:20:52 INFO - PROCESS | 1942 | RSS: Main: 261574656 13:20:52 INFO - PROCESS | 1942 | 13:20:54 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53737/tests/kraken/driver.html?testName=audio-dft) 13:20:55 INFO - PROCESS | 1942 | RSS: Main: 266338304 13:20:55 INFO - PROCESS | 1942 | 13:20:59 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53737/tests/kraken/driver.html?testName=audio-fft) 13:20:59 INFO - PROCESS | 1942 | RSS: Main: 267300864 13:20:59 INFO - PROCESS | 1942 | 13:21:01 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53737/tests/kraken/driver.html?testName=audio-oscillator) 13:21:02 INFO - PROCESS | 1942 | RSS: Main: 267919360 13:21:02 INFO - PROCESS | 1942 | 13:21:04 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53737/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:21:05 INFO - PROCESS | 1942 | RSS: Main: 268484608 13:21:05 INFO - PROCESS | 1942 | 13:21:08 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53737/tests/kraken/driver.html?testName=imaging-darkroom) 13:21:08 INFO - PROCESS | 1942 | RSS: Main: 269443072 13:21:08 INFO - PROCESS | 1942 | 13:21:11 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53737/tests/kraken/driver.html?testName=imaging-desaturate) 13:21:12 INFO - PROCESS | 1942 | RSS: Main: 270278656 13:21:12 INFO - PROCESS | 1942 | 13:21:15 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53737/tests/kraken/driver.html?testName=json-parse-financial) 13:21:16 INFO - PROCESS | 1942 | RSS: Main: 270544896 13:21:16 INFO - PROCESS | 1942 | 13:21:17 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53737/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:21:18 INFO - PROCESS | 1942 | RSS: Main: 270012416 13:21:18 INFO - PROCESS | 1942 | 13:21:20 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53737/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:21:21 INFO - PROCESS | 1942 | RSS: Main: 270016512 13:21:21 INFO - PROCESS | 1942 | 13:21:23 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53737/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:21:24 INFO - PROCESS | 1942 | RSS: Main: 270032896 13:21:24 INFO - PROCESS | 1942 | 13:21:27 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53737/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:21:27 INFO - PROCESS | 1942 | RSS: Main: 270745600 13:21:27 INFO - PROCESS | 1942 | 13:21:30 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53737/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:21:31 INFO - PROCESS | 1942 | RSS: Main: 270729216 13:21:31 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53737/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53737/tests/kraken/driver.html?testName=ai-astar) 13:21:33 INFO - PROCESS | 1942 | RSS: Main: 272510976 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | __start_tp_report 13:21:33 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 13:21:33 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 13:21:33 INFO - PROCESS | 1942 | |i|pagename|runs| 13:21:33 INFO - PROCESS | 1942 | |0;ai-astar;167;117;110;114;117;115;108;115;115;115 13:21:33 INFO - PROCESS | 1942 | |1;audio-beat-detection;150;130;173;205;118;139;134;126;181;148 13:21:33 INFO - PROCESS | 1942 | |2;audio-dft;200;203;244;273;241;200;229;192;195;279 13:21:33 INFO - PROCESS | 1942 | |3;audio-fft;75;82;76;177;58;58;65;66;127;57 13:21:33 INFO - PROCESS | 1942 | |4;audio-oscillator;77;89;155;78;81;103;162;85;80;125 13:21:33 INFO - PROCESS | 1942 | |5;imaging-gaussian-blur;90;86;89;86;89;87;93;87;91;86 13:21:33 INFO - PROCESS | 1942 | |6;imaging-darkroom;96;95;96;96;94;96;96;98;95;96 13:21:33 INFO - PROCESS | 1942 | |7;imaging-desaturate;90;92;114;145;90;96;94;89;90;135 13:21:33 INFO - PROCESS | 1942 | |8;json-parse-financial;57;61;59;57;57;59;58;120;71;76 13:21:33 INFO - PROCESS | 1942 | |9;json-stringify-tinderbox;63;69;70;100;68;82;100;92;91;67 13:21:33 INFO - PROCESS | 1942 | |10;stanford-crypto-aes;87;85;88;85;87;106;110;111;113;85 13:21:33 INFO - PROCESS | 1942 | |11;stanford-crypto-ccm;143;146;149;149;134;137;140;142;215;151 13:21:33 INFO - PROCESS | 1942 | |12;stanford-crypto-pbkdf2;172;230;200;167;165;172;167;164;164;180 13:21:33 INFO - PROCESS | 1942 | |13;stanford-crypto-sha256-iterative;66;65;66;64;63;62;64;66;67;64 13:21:33 INFO - PROCESS | 1942 | __end_tp_report 13:21:33 INFO - PROCESS | 1942 | __start_cc_report 13:21:33 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,1092 13:21:33 INFO - PROCESS | 1942 | __end_cc_report 13:21:33 INFO - PROCESS | 1942 | __startTimestamp1463602893400__endTimestamp 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | ------- Summary: start ------- 13:21:33 INFO - PROCESS | 1942 | Number of tests: 14 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#0] ai-astar Cycles:10 Average:119.30 Median:115.00 stddev:17.00 (14.8%) stddev-sans-first:3.04 13:21:33 INFO - PROCESS | 1942 | Values: 167.0 117.0 110.0 114.0 117.0 115.0 108.0 115.0 115.0 115.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#1] audio-beat-detection Cycles:10 Average:150.40 Median:149.00 stddev:27.68 (18.6%) stddev-sans-first:29.36 13:21:33 INFO - PROCESS | 1942 | Values: 150.0 130.0 173.0 205.0 118.0 139.0 134.0 126.0 181.0 148.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#2] audio-dft Cycles:10 Average:225.60 Median:235.00 stddev:32.61 (13.9%) stddev-sans-first:33.25 13:21:33 INFO - PROCESS | 1942 | Values: 200.0 203.0 244.0 273.0 241.0 200.0 229.0 192.0 195.0 279.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#3] audio-fft Cycles:10 Average:84.10 Median:65.50 stddev:38.60 (58.9%) stddev-sans-first:40.81 13:21:33 INFO - PROCESS | 1942 | Values: 75.0 82.0 76.0 177.0 58.0 58.0 65.0 66.0 127.0 57.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#4] audio-oscillator Cycles:10 Average:103.50 Median:79.00 stddev:32.49 (41.1%) stddev-sans-first:33.02 13:21:33 INFO - PROCESS | 1942 | Values: 77.0 89.0 155.0 78.0 81.0 103.0 162.0 85.0 80.0 125.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:89.00 stddev:2.41 (2.7%) stddev-sans-first:2.49 13:21:33 INFO - PROCESS | 1942 | Values: 90.0 86.0 89.0 86.0 89.0 87.0 93.0 87.0 91.0 86.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:1.03 (1.1%) stddev-sans-first:1.09 13:21:33 INFO - PROCESS | 1942 | Values: 96.0 95.0 96.0 96.0 94.0 96.0 96.0 98.0 95.0 96.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:90.00 stddev:20.71 (23.0%) stddev-sans-first:21.38 13:21:33 INFO - PROCESS | 1942 | Values: 90.0 92.0 114.0 145.0 90.0 96.0 94.0 89.0 90.0 135.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#8] json-parse-financial Cycles:10 Average:67.50 Median:59.00 stddev:19.57 (33.2%) stddev-sans-first:20.39 13:21:33 INFO - PROCESS | 1942 | Values: 57.0 61.0 59.0 57.0 57.0 59.0 58.0 120.0 71.0 76.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#9] json-stringify-tinderbox Cycles:10 Average:80.20 Median:69.50 stddev:14.50 (20.9%) stddev-sans-first:13.98 13:21:33 INFO - PROCESS | 1942 | Values: 63.0 69.0 70.0 100.0 68.0 82.0 100.0 92.0 91.0 67.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#10] stanford-crypto-aes Cycles:10 Average:95.70 Median:85.00 stddev:12.46 (14.7%) stddev-sans-first:12.82 13:21:33 INFO - PROCESS | 1942 | Values: 87.0 85.0 88.0 85.0 87.0 106.0 110.0 111.0 113.0 85.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:147.50 stddev:23.28 (15.8%) stddev-sans-first:24.53 13:21:33 INFO - PROCESS | 1942 | Values: 143.0 146.0 149.0 149.0 134.0 137.0 140.0 142.0 215.0 151.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.10 Median:172.00 stddev:21.26 (12.4%) stddev-sans-first:22.43 13:21:33 INFO - PROCESS | 1942 | Values: 172.0 230.0 200.0 167.0 165.0 172.0 167.0 164.0 164.0 180.0 13:21:33 INFO - PROCESS | 1942 | 13:21:33 INFO - PROCESS | 1942 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.50 stddev:1.57 (2.4%) stddev-sans-first:1.59 13:21:33 INFO - PROCESS | 1942 | Values: 66.0 65.0 66.0 64.0 63.0 62.0 64.0 66.0 67.0 64.0 13:21:33 INFO - PROCESS | 1942 | -------- Summary: end -------- 13:21:33 INFO - PROCESS | 1942 | 13:21:34 INFO - TEST-INFO | 1942: exit 0 13:21:34 INFO - TEST-OK | kraken | took 62016ms 13:21:34 INFO - SUITE-END | took 1174s 13:21:34 INFO - Completed test suite (00:19:34) 13:21:34 INFO - javascript benchmark 13:21:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7235.110245218907, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3497.702695270902, 3525.929481410372, 3529.7705649132804, 3549.751517393782, 3568.214992701605, 5908.40915908409, 5957.880842383155, 6035.5473339499495, 6050.757969681213, 6052.576319657625, 5400.756965936533, 5833.125031245316, 5925.88148237035, 5970.955082860493, 6056.878862422749, 5261.447548007462, 5300.469953004707, 5392.91910621341, 5423.2136340230645, 5456.645318054334, 326.0930354615421, 5284.365876094874, 5348.652337598063, 5354.464553544633, 5356.946430535684, 5323.866903327409, 5359.410464848864, 5359.812406565751, 5372.650777699476, 5373.570114390839, 5340.732963351856, 5399.9730001350135, 5401.756920938567, 5407.810726624549, 5417.349918009824, 5197.986392653434, 5217.713025783581, 5283.6037297202865, 5297.258383826267, 5365.3293338332705, 5674.546036317085, 5770.990076736554, 5818.5636077294375, 5841.0, 5879.294484661825, 6178.691065446755, 6300.621962682254, 6307.085472606446, 6327.145835312266, 6354.523410744213, 4954.900901981976, 6238.625682459067, 6281.3404592518045, 6361.459275961549, 6418.807435776934, 6092.878142437171, 6110.450059494667, 6111.633302001894, 6143.170671959318, 6146.108814221912, 6286.968565157236, 6379.681015949184, 6511.120998665215, 6513.0, 6570.5729127606555, 6198.783042593578, 6224.751009959641, 6229.781957631461, 6253.624782513064, 6285.465735412541, 6384.361563843579, 6438.6136831790245, 6439.034144878306, 6457.160569125984, 6481.870362592722, 6203.534734894901, 6247.625142491465, 6258.3428739982555, 6294.118823364733, 6327.462165715873, 6274.937250627435, 6292.182016337938, 6306.054091886253, 6310.8422289442215, 6330.177076979963, 5134.743262836843, 5137.820176293886, 5138.922916156261, 5148.794048238104, 5153.304303918923, 4465.843695470643, 4478.977605111953, 4497.078098989699, 4554.772261386917, 4577.793999270041, 6042.244719410074, 6047.637141771508, 6080.300765411946, 6099.249254464568, 6135.0, 6393.6483493408305, 6448.419642232222, 6472.838179045561, 6476.384743449365, 6488.351164883568, 5931.377205393458, 5970.283565972111, 6009.579329446897, 6072.696365181723, 6111.480524155408, 4404.55954404557, 4756.952430475721, 5500.477454641803, 5547.861303467364, 5569.081101618188, 5482.862928426821, 5574.275344205227, 6591.274959754519, 6592.967035164794, 6593.208814942238, 6223.626582405071, 6345.302016778243, 6381.617102973836, 6387.265464471459, 6467.644279564669, 6648.16897887764, 6662.067310576426, 6669.2997235289595, 6677.09859169006, 6678.298778628368, 5473.370562385217, 6349.428551430394, 6349.460295874902, 6352.396522330371, 6357.841053973688, 5386.973065134806, 6295.496360291074, 6378.45783108441, 6398.104265402754, 6447.258565264867, 6223.253209614875, 6269.96865015672, 6283.120363149072, 6288.905666415099, 6289.622622642656, 6133.548895741652, 6196.628202307876, 6219.160413344141, 6257.937420625735, 6274.1843560336865, 6030.728617212324, 6150.015997440388, 6236.937630623635, 6249.593776404701, 6265.342139075514, 6224.906626400517, 6244.344343843831, 6247.9062814056915, 6259.092431597484, 6264.68676566179, 6110.26676798787, 6113.993245360445, 6138.447539721446, 6225.315215326401, 6250.843728906813], "value": 5803.7464741205395, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.45939466586753, 506.6909185396907, 574.652335337121, 577.3043482603463, 590.3152343281794, 38456.615433845654, 39301.01747456315, 40680.796596017055, 40764.79617601912, 40948.79525602368, 41598.0, 42388.788056059675, 42441.57558424422, 42529.7873510632, 42576.57423425765, 8844.408006558817, 9518.952405237982, 11785.646430607096, 11803.881961180365, 12057.879421205807, 11695.766084678344, 11776.11679124069, 11780.352080635561, 11847.526098956032, 11885.989690876286, 11810.291382517033, 11894.345810980392, 11898.405079746022, 11996.400179990966, 12003.879961200364, 11821.645350639494, 11939.104567157454, 11939.880601193963, 11941.761164776743, 11963.1625786195, 8772.649094036231, 11113.666590002313, 11791.29252244868, 11949.402529873472, 12056.578019769353, 41255.58744412548, 41751.0, 42747.35878961818, 43360.56639433597, 43428.0, 3280.6883346082323, 3299.653536378694, 3340.5657264555693, 3548.8935331940083, 3808.638179372955, 29522.70477295221, 29856.805727770865, 29915.073887706545, 30002.099937001924, 30168.0, 2700.243931699127, 2765.9723402765917, 2801.1036468330144, 2913.1697466222026, 3000.5199168133213, 534743.0, 540456.0, 542778.0, 543497.0, 545130.0, 70098.0, 70918.0, 71525.6423717878, 71573.0, 71716.64141679258, 74110.62944685241, 74270.62864685642, 74493.62753186199, 74544.0, 75259.24740752632, 16001.519954401387, 16705.498835034967, 17728.55678608045, 17750.20124094419, 17987.0, 11538.78842721519, 13459.0, 13635.931820340835, 13815.309234538434, 13854.930725346308, 19828.0, 21116.366509004754, 21416.357509274745, 21932.903354832193, 22002.559948800936, 7287.927120728725, 8719.912800872036, 8825.76060049512, 8864.512451815082, 8921.955390223007, 880.2210044111008, 892.4377642085444, 960.3901522533241, 1025.5128813813378, 1027.671145233518, 30036.549451757804, 30329.93845215451, 30380.87333886651, 31101.688983110333, 31163.688363116533, 1063.547992103353, 1126.8704099028553, 1140.7604403075447, 1155.3414553704308, 1192.7793358228755, 15533.689326213413, 15717.371305147666, 15831.208439578206, 16013.279402426919, 16087.356505739639, 667353.0, 675388.0, 683405.0, 684946.8763280858, 685891.0, 61874.0, 62097.37902621007, 62430.687846560475, 62553.0, 62701.43246418876, 1426.8359138699182, 1448.4857875454063, 1460.7443697353133, 1463.1806188534454, 1498.5654160293393, 28910.27724306825, 29674.81300747946, 30198.84900575483, 30509.847450763493, 30579.388412231187, 50078.74960625174, 54430.0, 58836.0, 60204.0, 63614.0, 656102.0, 663409.0, 666045.0, 668039.0, 668328.0, 24279.75720242775, 27937.0, 28254.0, 28573.857130714212, 28713.0, 46649.30026049544, 47283.763581181876, 47710.522894770605, 48584.271235932196, 49347.25979110244, 33266.0, 36063.819680901426, 37370.626293736714, 38866.0, 40491.79754101211, 31743.682563174072, 38810.6118938807, 38891.41662875003, 39412.0, 39564.2087158261, 37979.0, 38222.23555528929, 38327.42508862425, 39645.40531892081, 39743.80128099341, 33916.83041584776, 33931.66068339285, 34494.82752586221, 34545.48181777326, 34759.82620086883, 20840.68738968947, 21063.789362106796, 25333.366665832764, 25550.872245638653, 26076.608850867633], "value": 21248.611738031344, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.3108414255631, 386.73508646577096, 391.6123038192189, 404.64593480704383, 414.20886107534596, 19021.809781902175, 21430.464238394048, 21735.565288694237, 22175.334739957787, 22322.995465204065, 19416.611667766636, 20265.885376304297, 20510.17959281631, 20817.687734683954, 21332.680009799864, 5097.388313402378, 5670.914936275949, 5901.763929442817, 5910.615809972338, 5913.9112913306335, 5929.703514824241, 5930.0, 5933.465988061073, 5969.104634304846, 5971.701414929258, 4443.333499974998, 5713.685747283897, 5810.012297909345, 5836.854078648046, 5873.148393482964, 5468.589855760814, 5715.457031581993, 5799.362070172257, 5909.940900590982, 5911.349751527328, 5617.185508101344, 5627.184058311522, 5642.238297829823, 5642.633228840154, 5645.350784659735, 12451.75096498074, 13089.803652945213, 13114.082014259006, 13124.540641077612, 13154.802677959839, 2032.7763945965933, 2127.563849410867, 2149.9247526336503, 2285.8057065149483, 2296.5636529059425, 9746.171575416098, 9942.90057099427, 10031.49842507879, 10249.0, 12609.928156106742, 1771.2206629083162, 1797.182282061672, 1797.5146710388121, 1805.3328582457323, 1818.690822560168, 122081.0, 124356.75643243542, 124916.0, 126653.0, 126667.0, 11734.765304693858, 11739.88260117405, 11852.525898964117, 11942.462589183508, 12003.339816310187, 14594.0, 14761.77857332141, 14830.925845370704, 14876.62808429798, 14938.850611493963, 8701.086385929513, 8747.431416958005, 8799.648014079365, 8811.20699137068, 8910.910890891008, 8682.262007729287, 8717.259032982141, 8777.736667899973, 8837.469751814782, 8889.199972002421, 3756.962430375716, 4607.170709272296, 4618.168729628632, 4647.16351056813, 4701.64737644678, 1661.6676664667116, 1952.580195257998, 1967.1639553189837, 1982.0882394098587, 2005.1578337098454, 881.8412685716504, 945.2579724915907, 1009.1523120578603, 1014.7513859104419, 1016.588281745894, 19527.925964072114, 19811.10850011753, 20291.492712682302, 20931.581368372852, 20952.161913523592, 961.274237950343, 982.5824024789435, 987.6049580167846, 1014.7361685961555, 1040.469360626071, 8899.510526921082, 10655.62705305326, 11084.944575277234, 11106.61126860556, 11159.163062770162, 546574.0, 549859.5014049808, 553247.0, 563555.0, 565439.0, 118340.40829795795, 118671.0, 118796.0, 122573.0, 122871.0, 1005.1657124586576, 1014.8477728340808, 1045.0437849367895, 1064.0689396777818, 1064.7178497698117, 16357.018578885305, 17687.38094166698, 18234.817651823843, 18584.070796460393, 18818.34135805295, 50589.24116138335, 51203.743981281346, 51849.0, 52518.0, 52555.94888102293, 67134.66432667806, 69448.65275673791, 69926.0, 71086.0, 77153.0, 7053.294670532906, 7271.818204544928, 7358.521696089737, 7411.4811963162065, 7647.808804779925, 10746.62386816489, 11801.70495737579, 11809.940950295193, 11841.9934305585, 12006.699832504257, 10238.641647542301, 10261.897381026094, 10341.276110672181, 10355.068043875786, 10446.477676116012, 9370.531473426438, 9939.254555908192, 9942.15491683215, 10255.179585633281, 10298.279120461495, 9886.653967111117, 10342.482875856027, 10528.0, 10751.354918704903, 10777.0, 6142.662153581417, 7185.317394847481, 7354.852902942018, 7358.0, 7513.586752728653, 7576.659050342638, 9568.330216884751, 10327.32872363294, 10636.680899573024, 10649.041586257274], "value": 9196.244336114005, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1608.107500337313, 1645.2514106081735, 1913.2825190553542, 1969.251684359943, 2007.187089228861, 8132.878006829902, 8723.781905452366, 8731.083236260196, 9194.218491428228, 9211.81576368473, 8814.294856411489, 8821.911780882188, 9001.639934402616, 9021.729348119567, 9030.277577793777, 2413.589689752742, 2435.4398488347692, 2438.1222759806456, 2440.8779561021965, 2458.8278820482574, 2424.5150969806023, 2428.101602407112, 2463.6550882876413, 2471.0115953618515, 2474.9133780317693, 2430.5503481855826, 2447.3147518694796, 2467.90128394864, 2471.0115953618515, 2472.629105634151, 2414.2757172848073, 2419.794317483016, 2425.9757402425926, 2427.8664673442954, 2436.135172013945, 2400.135951057618, 2402.387391215246, 2402.9279121626378, 2410.626352915301, 2424.1273141668985, 7692.499987500851, 9033.412828166149, 9089.318301127441, 9113.863292050624, 9131.497767622777, 4316.460442444694, 5292.38582232419, 5487.176923461473, 5517.0, 5557.138643510222, 3214.4214041472524, 3484.285721427101, 3622.7826330420257, 3623.3115708015393, 3650.6349365063547, 840.4285086141422, 933.9673111441067, 954.4654993203828, 986.4919566423226, 1013.7060252526759, 12062.758744825054, 12194.57318993831, 12243.20419172751, 12257.448414821265, 12263.754724905451, 4656.743879086615, 4791.185498465269, 4869.14789911764, 4904.190808516555, 4945.332380128709, 10117.848232276521, 10136.189104871593, 10261.435621040766, 10267.640632577824, 10273.897261027443, 5452.645578037414, 5499.807506737325, 5501.312335958005, 5515.4760297771645, 5521.751521181556, 4501.617362524222, 4846.491118432514, 4866.294387313819, 5000.1749711297225, 5009.198528235464, 4069.5116586009867, 4077.936065012029, 4090.141070375195, 4131.462909821704, 4149.813258403379, 1303.2766814417898, 1329.4217015598247, 1334.9599512014654, 1341.0411555737694, 1342.5502456677123, 855.6491838346249, 918.9724308270762, 933.7338858425317, 936.639393833379, 943.773494361362, 5777.19119323295, 5959.642421454727, 5989.880202396015, 6025.728842202024, 6177.135201071854, 940.1209868772719, 961.1781926452921, 967.4872317671504, 975.9947254328054, 976.9560369783377, 3951.920961580752, 4022.899427514277, 4042.2319759245647, 4064.6748260139116, 4157.438745769343, 10641.255112142077, 11108.16688748347, 11122.666320010412, 11164.720881978015, 11206.495707693173, 10967.945160274148, 11002.834957475645, 11082.168837337233, 11111.555537778399, 11122.49948752308, 8298.170182981774, 8328.8334233315, 8357.707480238285, 8452.704155354533, 8464.83070338602, 5212.192110222922, 5793.652380857162, 5827.155062515996, 5932.080527518242, 5997.070454079625, 9879.259055570717, 9945.453000084775, 10040.397576145455, 10242.283040187116, 10261.384316941007, 7651.770446886602, 9860.605575776888, 10345.689629311133, 10375.636852710119, 10433.373997560171, 7066.681999310146, 7351.8529629408185, 7477.0, 7520.435967302365, 7803.492772969739, 8602.61288242018, 8614.3108551317, 8638.611262493077, 8694.08712085222, 8703.69537066225, 7624.733134340271, 7668.849672549162, 7727.111382190895, 7799.844003119792, 7852.332551733167, 6325.430711236011, 6807.183138023271, 7529.360004399468, 7530.698772049057, 7574.772756817304, 7189.964050179716, 7240.094988126484, 7269.091363579552, 7292.978982942286, 7293.124825021031, 4852.781624826821, 4997.075541024922, 5021.6735912165595, 5033.748312584429, 5117.961184394048, 4947.282644016525, 5001.64988450805, 5039.823606173766, 5098.719570423662, 5263.473652634859], "value": 4680.597840978249, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.7469337796317, 316.15428728152193, 319.35171601648653, 324.236423223309, 327.6756011548566, 4991.479302952013, 5426.240326354314, 5557.861053473665, 5660.745266463009, 5689.943100568988, 5128.179491281396, 5348.304720386354, 5352.678839269637, 5573.665580065189, 5598.992181407345, 3367.848446819899, 3683.3922402803505, 3844.692424606039, 4206.852760153395, 4214.304639734448, 3599.4357694546084, 3673.3020726062095, 4033.588244114566, 4086.795660216992, 4204.096119334343, 3144.2925341798027, 3958.6540576204084, 4108.876733697979, 4193.783802697214, 4212.747235165884, 3867.9613203867884, 3883.417487376902, 3981.6615587675083, 4000.479937608107, 4188.183304255671, 3343.3814744272386, 3603.1892824114625, 3608.729345299113, 3610.530631017951, 3624.5650521937273, 5176.5858731301405, 5335.771858025916, 5358.142697168473, 5370.355557333106, 5543.307086614173, 4186.890474024386, 4525.891156666605, 4792.353032340624, 4816.157172494834, 4817.879553011167, 5638.4925356718095, 5722.141678748179, 5934.228550288478, 5965.463108320272, 5975.282966044103, 3831.1379939513663, 3968.226195891802, 3995.5405128410316, 4009.3785463253244, 4015.959840401588, 4759.0, 5384.623076384654, 5432.185172224158, 5471.890562188734, 5887.970560147171, 3882.893375388001, 3976.4035394691027, 4036.8385264589087, 4036.990752311922, 4051.642699695635, 3466.791992480459, 3867.8259478323544, 4083.611572065512, 4090.570490098497, 4091.161311931047, 4059.654929330981, 4200.789960501963, 4527.3435351873795, 4724.102420540155, 4819.734914579732, 5230.057595572148, 5423.484768946943, 5658.773649053992, 5872.915115134151, 5894.469497745224, 1363.180154493742, 1399.6710772968547, 1412.216219997911, 1441.7116576684705, 1460.2406748490725, 741.2624438683545, 753.6909866954522, 815.9796005099802, 864.7189663359434, 887.7070566712969, 3978.960210397917, 4092.7135100542678, 4567.840125595588, 4622.260438329851, 4650.744209068466, 4640.167133982126, 5017.84946451607, 5048.747562621854, 5082.974585127125, 5118.872028199325, 3998.9800051000143, 4200.7479551227025, 4303.225419424471, 4543.863684089482, 4567.862964111082, 2212.557488502306, 2432.6959130108735, 2552.1322750265, 2565.7434256574193, 2607.3090630982806, 4447.266201076786, 4967.031428871408, 5101.489851014869, 5309.548688361532, 5365.202657109912, 4618.67669263155, 4632.976835115803, 4923.606111511071, 5071.315372424676, 5255.527002569787, 4802.831900883522, 5275.841724748264, 5292.047431462373, 5299.496547828027, 5343.7175077981, 4469.346341853535, 5112.3415823772175, 5120.539151476385, 5144.536991670618, 5192.065428222956, 4038.91922161561, 4448.021435284232, 4669.346291519122, 5064.442911279682, 5098.796048158032, 4316.553954425177, 4362.716423432466, 4381.430414046154, 5089.720065396439, 5293.841184764463, 3150.3384289298588, 3261.902142935716, 3594.6764791167875, 3665.9552027673053, 3677.2461645363173, 4446.73319600825, 4687.929681054855, 4928.310036594808, 5117.078925793393, 5751.884962300815, 5369.865753356197, 5732.512736417284, 5852.648841069549, 5884.852878678067, 5950.880982380415, 4984.501549844987, 5341.893162136813, 5503.2020357048805, 5860.919373622302, 5877.265341832271, 4467.419235499365, 5580.604848787803, 5724.370319264961, 5925.614835035709, 5928.436798504135, 6041.426064523863, 6065.907261994605, 6070.605410648293, 6071.878562428815, 6085.634861908123, 5503.3120859892515, 5817.76728930838, 5852.7658893643775, 5876.676782776817, 5881.529757501774], "value": 3978.6407356057416, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.8458923626039, 736.5543845973186, 740.3817812126874, 748.1022772672794, 813.6663967773214, 15079.773803392944, 15172.482751724821, 15652.530424087281, 15683.137427441487, 15703.371865125395, 14341.569752907404, 14428.422863085463, 14443.350049247783, 14444.783328250085, 14461.5661530154, 711.1679335177854, 747.723342363325, 896.7802888292377, 918.554501066982, 930.0652843891879, 911.9954400227989, 915.0712027292303, 917.4311926605513, 919.6781126605667, 922.0088404964656, 962.4417837654144, 972.8881178664438, 972.9708108756748, 973.7760315127525, 974.0746291023556, 955.1403736637013, 966.3573723473882, 966.4491239993207, 967.1730670276883, 969.0938972061145, 711.4166383565479, 711.7508871894847, 712.5760172697237, 712.7113519024804, 712.7469748239404, 14678.853211467855, 14847.406103755837, 14925.925370373187, 15040.17279049652, 15040.699186016218, 10888.693356797236, 12609.432575534123, 12616.05379596534, 12689.365531723377, 12847.164934279243, 9936.503174841302, 10100.292979491438, 10144.543495542644, 10402.167826573856, 10699.465026748632, 3479.1823921378455, 3514.262004978932, 3605.891823245307, 3611.711063114971, 3642.0809616685706, 84247.0, 85380.29239415239, 85765.14234857634, 85998.57000714957, 86742.0, 9347.953260233655, 9473.384230025027, 9498.19265362452, 9604.85592716096, 9685.564149613143, 21901.233456828933, 22295.0, 22385.0, 22387.216447424587, 22395.21616743406, 7325.450591205575, 7383.446241531907, 7442.0, 7460.104787425544, 7519.699212031567, 3858.401947698111, 3907.1208977979727, 3967.147063381387, 3967.2263908538134, 3983.0042489377656, 3377.527146199534, 3441.4149594568985, 3589.8205089745406, 3675.61406052366, 3735.906602334909, 623.8721062182242, 635.5297080160648, 638.237306418825, 650.2099948562519, 650.9349065093453, 561.2339157050678, 573.4466240078261, 579.623244890816, 606.8984792601502, 612.6079309242089, 7080.716771329192, 8010.719624813103, 8118.715844945398, 8160.877586836202, 8281.130481299497, 502.2290783647084, 541.6939429222476, 580.3818932836516, 587.9299674592287, 620.2866703291161, 3777.2823163598828, 3824.1204522959874, 3883.165119499321, 3890.280298144852, 4090.3864420337186, 93401.5329923346, 96787.51606241924, 96963.51518242364, 97244.0, 98215.0, 83839.16160838435, 84797.0, 85200.8699782498, 86514.0, 86673.13326866651, 75247.62376188084, 75472.24527754763, 76116.0, 76801.0, 76968.61515692498, 7277.16312624055, 7447.5531468112085, 7652.1582625912215, 7681.923180768121, 7684.3084122429245, 41349.37975930303, 44657.33014004728, 45211.64365068921, 46517.76741116273, 47741.52258477371, 65224.69550609056, 66224.0, 66834.66582667055, 66966.66516667386, 67304.6634766823, 8455.0, 9662.985386534594, 9812.460314682761, 9984.251181161588, 10103.444310562696, 10725.03474687282, 10727.40999245049, 10739.248252622241, 10924.126069914546, 11079.72300692489, 10133.898661013296, 10187.694369168938, 10272.897271027494, 10432.321899076536, 10534.157267418435, 10248.743781405525, 10249.077583017564, 10318.742031448974, 10353.119984801377, 10621.681349559525, 10119.190464762654, 10139.2395570334, 10183.592656293962, 10458.68623941283, 10483.161347092368, 8564.785880353042, 8637.654493820177, 8938.4190027648, 8977.012528621977, 9018.368714190206, 7483.32650061497, 8141.592920354077, 8656.567171641518, 8878.911210887809, 9347.0], "value": 6791.053493577554, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 117.0, 110.0, 114.0, 117.0, 115.0, 108.0, 115.0, 115.0, 115.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 130.0, 173.0, 205.0, 118.0, 139.0, 134.0, 126.0, 181.0, 148.0], "value": 150.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 203.0, 244.0, 273.0, 241.0, 200.0, 229.0, 192.0, 195.0, 279.0], "value": 225.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 82.0, 76.0, 177.0, 58.0, 58.0, 65.0, 66.0, 127.0, 57.0], "value": 84.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 89.0, 155.0, 78.0, 81.0, 103.0, 162.0, 85.0, 80.0, 125.0], "value": 103.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 86.0, 89.0, 87.0, 93.0, 87.0, 91.0, 86.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 94.0, 96.0, 96.0, 98.0, 95.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 114.0, 145.0, 90.0, 96.0, 94.0, 89.0, 90.0, 135.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 59.0, 57.0, 57.0, 59.0, 58.0, 120.0, 71.0, 76.0], "value": 67.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 70.0, 100.0, 68.0, 82.0, 100.0, 92.0, 91.0, 67.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 87.0, 106.0, 110.0, 111.0, 113.0, 85.0], "value": 95.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 146.0, 149.0, 149.0, 134.0, 137.0, 140.0, 142.0, 215.0, 151.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 230.0, 200.0, 167.0, 165.0, 172.0, 167.0, 164.0, 164.0, 180.0], "value": 178.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 64.0, 63.0, 62.0, 64.0, 66.0, 67.0, 64.0], "value": 64.7, "unit": "score"}]}]} 13:21:34 INFO - Return code: 0 13:21:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:21:34 ERROR - # TBPL SUCCESS # 13:21:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:21:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:21:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:21:34 INFO - 2016-05-18 13:21:34.705 screenresolution[1947:16974] starting screenresolution argv=screenresolution get 13:21:34 INFO - 2016-05-18 13:21:34.708 screenresolution[1947:16974] Display 0: 1600x1200x32@60 13:21:34 INFO - 2016-05-18 13:21:34.714 screenresolution[1948:16977] starting screenresolution argv=screenresolution list 13:21:34 INFO - Available Modes on Display 0 13:21:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:21:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:21:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:21:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:21:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:21:34 INFO - Intel Iris: 13:21:34 INFO - Chipset Model: Intel Iris 13:21:34 INFO - Type: GPU 13:21:34 INFO - Bus: Built-In 13:21:34 INFO - VRAM (Dynamic, Max): 1536 MB 13:21:34 INFO - Vendor: Intel (0x8086) 13:21:34 INFO - Device ID: 0x0a2e 13:21:34 INFO - Revision ID: 0x0009 13:21:34 INFO - Displays: 13:21:34 INFO - Display: 13:21:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:21:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:21:34 INFO - Display Serial Number: 1600x1200 60 13:21:34 INFO - Main Display: Yes 13:21:34 INFO - Mirror: Off 13:21:34 INFO - Online: Yes 13:21:34 INFO - Rotation: Supported 13:21:34 INFO - Return code: 0 13:21:34 INFO - Running post-action listener: _resource_record_post_action 13:21:34 INFO - ##### 13:21:34 INFO - ##### Finished run-tests step (success) 13:21:34 INFO - ##### 13:21:34 INFO - Running post-run listener: _resource_record_post_run 13:21:35 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 712704; Write bytes: 696501248; Read time: 10; Write time: 2019 13:21:35 INFO - TinderboxPrint: CPU usage
54.7% 13:21:35 INFO - TinderboxPrint: I/O read bytes / time
712,704 / 10 13:21:35 INFO - TinderboxPrint: I/O write bytes / time
696,501,248 / 2,019 13:21:35 INFO - TinderboxPrint: CPU idle
2,191.6 (45.3%) 13:21:35 INFO - TinderboxPrint: CPU system
977.9 (20.2%) 13:21:35 INFO - TinderboxPrint: CPU user
1,672.2 (34.5%) 13:21:35 INFO - TinderboxPrint: Swap in / out
395,796,480 / 0 13:21:35 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 392901120; Write bytes: 389225472; Read time: 30022; Write time: 723 13:21:35 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 712704; Write bytes: 297211904; Read time: 10; Write time: 1265 13:21:35 INFO - Running post-run listener: _upload_blobber_files 13:21:35 INFO - Blob upload gear active. 13:21:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:21:35 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 13:21:35 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:21:35 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:21:35 INFO - (blobuploader) - INFO - Open directory for files ... 13:21:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:21:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:21:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:21:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:21:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:21:37 INFO - (blobuploader) - INFO - Done attempting. 13:21:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:21:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:21:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:21:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:21:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:21:38 INFO - (blobuploader) - INFO - Done attempting. 13:21:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:21:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:21:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:21:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:21:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:21:40 INFO - (blobuploader) - INFO - Done attempting. 13:21:40 INFO - (blobuploader) - INFO - Iteration through files over. 13:21:40 INFO - Return code: 0 13:21:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:21:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:21:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/773913e6a6fe4c4917861bdaefbffdfaa1de3527d82c4573b2325a965e433e7f8f19b7f93fd8673340a82b66845c6f199aaa8ddfcfa68b0568e6e4838d43c9e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f82cd7d048b450996231439421761a17538bfc08b7c3980e5d315b2d1435c702269ced055aa68a37c63d84cd839945143f949b4c438242f9752dcebb47255585", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b57a458a353ff70db82f22cbd96d9b32338be984313a2eff4b2d99f7fb6708c7a2d0043d2bff3445abdda34dc74712af852acd11a63618be7cc5228d879aee8b"} 13:21:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:21:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:21:40 INFO - Contents: 13:21:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/773913e6a6fe4c4917861bdaefbffdfaa1de3527d82c4573b2325a965e433e7f8f19b7f93fd8673340a82b66845c6f199aaa8ddfcfa68b0568e6e4838d43c9e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f82cd7d048b450996231439421761a17538bfc08b7c3980e5d315b2d1435c702269ced055aa68a37c63d84cd839945143f949b4c438242f9752dcebb47255585", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b57a458a353ff70db82f22cbd96d9b32338be984313a2eff4b2d99f7fb6708c7a2d0043d2bff3445abdda34dc74712af852acd11a63618be7cc5228d879aee8b"} 13:21:40 INFO - Running post-run listener: copy_logs_to_upload_dir 13:21:40 INFO - Copying logs to upload dir... 13:21:40 INFO - mkdir: /builds/slave/test/build/upload/logs 13:21:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.792469 ========= master_lag: 2.54 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-18 13:21:43.151584) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:21:43.155940) ========= 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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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/Firefox/sha512/773913e6a6fe4c4917861bdaefbffdfaa1de3527d82c4573b2325a965e433e7f8f19b7f93fd8673340a82b66845c6f199aaa8ddfcfa68b0568e6e4838d43c9e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f82cd7d048b450996231439421761a17538bfc08b7c3980e5d315b2d1435c702269ced055aa68a37c63d84cd839945143f949b4c438242f9752dcebb47255585", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b57a458a353ff70db82f22cbd96d9b32338be984313a2eff4b2d99f7fb6708c7a2d0043d2bff3445abdda34dc74712af852acd11a63618be7cc5228d879aee8b"} build_url:https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011066 build_url: 'https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/773913e6a6fe4c4917861bdaefbffdfaa1de3527d82c4573b2325a965e433e7f8f19b7f93fd8673340a82b66845c6f199aaa8ddfcfa68b0568e6e4838d43c9e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f82cd7d048b450996231439421761a17538bfc08b7c3980e5d315b2d1435c702269ced055aa68a37c63d84cd839945143f949b4c438242f9752dcebb47255585", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b57a458a353ff70db82f22cbd96d9b32338be984313a2eff4b2d99f7fb6708c7a2d0043d2bff3445abdda34dc74712af852acd11a63618be7cc5228d879aee8b"}' ========= master_lag: 0.21 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:21:43.379275) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:21:43.379668) ========= 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.E90vQ5Lkpi/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.joqzgJ32I7/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005233 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:21:43.402443) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:21:43.402787) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:21:43.403200) ========= ========= Total master_lag: 2.84 =========