builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0173 starttime: 1463601631.26 results: success (0) buildid: 20160517065136 builduid: ab760662d3a1428fa4c9e673ad9c7baf revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:31.259136) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:31.259582) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:31.259867) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:31.328769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:31.329086) ========= 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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.7M=0.001s 2016-05-18 13:00:31 (17.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120302 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:31.467450) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:31.467761) ========= 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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.026642 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:31.516466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:31.516811) ========= 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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:31,592 truncating revision to first 12 chars 2016-05-18 13:00:31,592 Setting DEBUG logging. 2016-05-18 13:00:31,592 attempt 1/10 2016-05-18 13:00:31,592 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:32,326 unpacking tar archive at: mozilla-central-3780a3a6b83a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.975343 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:32.510616) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:32.510935) ========= script_repo_revision: 3780a3a6b83aeda143f9562829c830410a0c961e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:32.511309) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:32.511586) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 13:00:32.525291) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-18 13:00:32.525637) ========= /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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:32 INFO - MultiFileLogger online at 20160518 13:00:32 in /builds/slave/test 13:00:32 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:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:00:32 INFO - {'append_to_log': False, 13:00:32 INFO - 'base_work_dir': '/builds/slave/test', 13:00:32 INFO - 'blob_upload_branch': 'Firefox', 13:00:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:00:32 INFO - 'branch': 'Firefox', 13:00:32 INFO - 'buildbot_json_path': 'buildprops.json', 13:00:32 INFO - 'config_files': ('talos/mac_config.py',), 13:00:32 INFO - 'default_actions': ('clobber', 13:00:32 INFO - 'read-buildbot-config', 13:00:32 INFO - 'download-and-extract', 13:00:32 INFO - 'populate-webroot', 13:00:32 INFO - 'create-virtualenv', 13:00:32 INFO - 'install', 13:00:32 INFO - 'run-tests'), 13:00:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:00:32 INFO - 'download_minidump_stackwalk': True, 13:00:32 INFO - 'download_symbols': 'ondemand', 13:00:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:00:32 INFO - 'tooltool.py': '/tools/tooltool.py', 13:00:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:00:32 INFO - '/tools/misc-python/virtualenv.py')}, 13:00:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:00:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:00:32 INFO - 'installer_path': 'installer.exe', 13:00:32 INFO - 'log_level': 'info', 13:00:32 INFO - 'log_name': 'talos', 13:00:32 INFO - 'log_to_console': True, 13:00:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:00:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:00:32 INFO - 'opt_config_files': (), 13:00:32 INFO - 'pip_index': False, 13:00:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:00:32 INFO - 'cmd': ('bash', 13:00:32 INFO - '-c', 13:00:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:00:32 INFO - 'enabled': True, 13:00:32 INFO - 'halt_on_failure': False, 13:00:32 INFO - 'name': 'check_screen_resolution'},), 13:00:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:00:32 INFO - 'cmd': ('bash', 13:00:32 INFO - '-c', 13:00:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:00:32 INFO - 'enabled': True, 13:00:32 INFO - 'halt_on_failure': False, 13:00:32 INFO - 'name': 'check_screen_resolution'},), 13:00:32 INFO - 'run_cmd_checks_enabled': True, 13:00:32 INFO - 'sps_profile': False, 13:00:32 INFO - 'sps_profile_interval': 0, 13:00:32 INFO - 'suite': 'dromaeojs-e10s', 13:00:32 INFO - 'system_bits': '32', 13:00:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:00:32 INFO - 'title': 't-yosemite-r7-0173', 13:00:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:00:32 INFO - 'use_talos_json': True, 13:00:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:00:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:00:32 INFO - 'work_dir': 'build'} 13:00:32 INFO - ##### 13:00:32 INFO - ##### Running clobber 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: clobber 13:00:32 INFO - rmtree: /builds/slave/test/build 13:00:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:00:34 INFO - Running post-action listener: _resource_record_post_action 13:00:34 INFO - ##### 13:00:34 INFO - ##### Finished clobber step (success) 13:00:34 INFO - ##### 13:00:34 INFO - ##### 13:00:34 INFO - ##### Running read-buildbot-config step. 13:00:34 INFO - ##### 13:00:34 INFO - Running pre-action listener: _resource_record_pre_action 13:00:34 INFO - Running main action method: read_buildbot_config 13:00:34 INFO - Using buildbot properties: 13:00:34 INFO - { 13:00:34 INFO - "project": "", 13:00:34 INFO - "product": "firefox", 13:00:34 INFO - "script_repo_revision": "production", 13:00:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 13:00:34 INFO - "repository": "", 13:00:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 13:00:34 INFO - "buildid": "20160517065136", 13:00:34 INFO - "pgo_build": "False", 13:00:34 INFO - "basedir": "/builds/slave/test", 13:00:34 INFO - "buildnumber": 280, 13:00:34 INFO - "slavename": "t-yosemite-r7-0173", 13:00:34 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 13:00:34 INFO - "platform": "yosemite_r7", 13:00:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:00:34 INFO - "branch": "mozilla-central", 13:00:34 INFO - "repo_path": "mozilla-central", 13:00:34 INFO - "revision": "3780a3a6b83aeda143f9562829c830410a0c961e", 13:00:34 INFO - "stage_platform": "macosx64", 13:00:34 INFO - "builduid": "ab760662d3a1428fa4c9e673ad9c7baf", 13:00:34 INFO - "slavebuilddir": "test" 13:00:34 INFO - } 13:00:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:00:34 INFO - Running post-action listener: _resource_record_post_action 13:00:34 INFO - ##### 13:00:34 INFO - ##### Finished read-buildbot-config step (success) 13:00:34 INFO - ##### 13:00:34 INFO - ##### 13:00:34 INFO - ##### Running download-and-extract step. 13:00:34 INFO - ##### 13:00:34 INFO - Running pre-action listener: _resource_record_pre_action 13:00:34 INFO - Running main action method: download_and_extract 13:00:34 INFO - mkdir: /builds/slave/test/build/tests 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.test_packages.json 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.test_packages.json 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.test_packages.json 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.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:00:34 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:36 INFO - Downloaded 1199 bytes. 13:00:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:00:36 INFO - Using the following test package requirements: 13:00:36 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:00:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:36 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:00:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:36 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:00:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:36 INFO - u'jsshell-mac.zip'], 13:00:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:36 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:00:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:00:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:36 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:00:36 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:36 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:00:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:36 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:00:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:00:36 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:00:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:36 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:36 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:36 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:36 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:36 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:37 INFO - Downloaded 18267690 bytes. 13:00:37 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:37 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:40 INFO - Return code: 0 13:00:40 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:40 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:40 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:40 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:40 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:40 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:40 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:41 INFO - Downloaded 18267690 bytes. 13:00:41 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:41 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: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.talos.tests.zip 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.talos.tests.zip 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.talos.tests.zip 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.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:00:44 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:45 INFO - Downloaded 10840194 bytes. 13:00:45 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:45 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:46 INFO - Return code: 0 13:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:46 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:46 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:46 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:46 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:46 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:49 INFO - Downloaded 109992951 bytes. 13:00:49 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:49 INFO - mkdir: /builds/slave/test/properties 13:00:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:00:49 INFO - Writing to file /builds/slave/test/properties/build_url 13:00:49 INFO - Contents: 13:00:49 INFO - build_url:https://queue.taskcluster.net/v1/task/TDdhSzHtQFC9HXew4o4CZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:00:55 INFO - Running post-action listener: _resource_record_post_action 13:00:55 INFO - Running post-action listener: set_extra_try_arguments 13:00:55 INFO - ##### 13:00:55 INFO - ##### Finished download-and-extract step (success) 13:00:55 INFO - ##### 13:00:55 INFO - ##### 13:00:55 INFO - ##### Running populate-webroot step. 13:00:55 INFO - ##### 13:00:55 INFO - Running pre-action listener: _resource_record_pre_action 13:00:55 INFO - Running main action method: populate_webroot 13:00:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:00:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:00:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:00:55 INFO - '--tppagecycles', 13:00:55 INFO - '7'], 13:00:55 INFO - 'tests': ['tsvgm']}}, 13:00:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:00:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:00:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:00:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:00:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:55 INFO - 'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:55 INFO - 'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['damp', 'tps']}, 13:00:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:55 INFO - 'tests': ['damp', 'tps']}, 13:00:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['dromaeo_dom']}, 13:00:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:00:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['a11yr', 13:00:55 INFO - 'ts_paint', 13:00:55 INFO - 'tpaint', 13:00:55 INFO - 'sessionrestore', 13:00:55 INFO - 'sessionrestore_no_auto_restore', 13:00:55 INFO - 'tabpaint']}, 13:00:55 INFO - 'other-e10s': {'tests': ['a11yr', 13:00:55 INFO - 'ts_paint', 13:00:55 INFO - 'tpaint', 13:00:55 INFO - 'sessionrestore', 13:00:55 INFO - 'sessionrestore_no_auto_restore', 13:00:55 INFO - 'tabpaint']}, 13:00:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:00:55 INFO - 'ts_paint', 13:00:55 INFO - 'tpaint', 13:00:55 INFO - 'sessionrestore', 13:00:55 INFO - 'sessionrestore_no_auto_restore', 13:00:55 INFO - 'tabpaint']}, 13:00:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:00:55 INFO - 'ts_paint', 13:00:55 INFO - 'tpaint', 13:00:55 INFO - 'sessionrestore', 13:00:55 INFO - 'sessionrestore_no_auto_restore', 13:00:55 INFO - 'tabpaint']}, 13:00:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['a11yr', 13:00:55 INFO - 'ts_paint', 13:00:55 INFO - 'tpaint', 13:00:55 INFO - 'sessionrestore', 13:00:55 INFO - 'sessionrestore_no_auto_restore', 13:00:55 INFO - 'tabpaint']}, 13:00:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['a11yr', 13:00:55 INFO - 'ts_paint', 13:00:55 INFO - 'tpaint', 13:00:55 INFO - 'sessionrestore', 13:00:55 INFO - 'sessionrestore_no_auto_restore', 13:00:55 INFO - 'tabpaint']}, 13:00:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['tsvgx', 13:00:55 INFO - 'tsvgr_opacity', 13:00:55 INFO - 'tart', 13:00:55 INFO - 'tscrollx', 13:00:55 INFO - 'cart']}, 13:00:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:00:55 INFO - 'tsvgr_opacity', 13:00:55 INFO - 'tart', 13:00:55 INFO - 'tscrollx', 13:00:55 INFO - 'cart']}, 13:00:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:55 INFO - 'talos_options': ['--disable-e10s'], 13:00:55 INFO - 'tests': ['tp5o']}, 13:00:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:55 INFO - 'tests': ['tp5o']}, 13:00:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:55 INFO - 'talos_options': ['--disable-e10s', 13:00:55 INFO - '--xperf_path', 13:00:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:55 INFO - 'tests': ['tp5n']}, 13:00:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:55 INFO - 'talos_options': ['--xperf_path', 13:00:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:55 INFO - 'tests': ['tp5n']}}, 13:00:55 INFO - 'talos.zip': {'path': '', 13:00:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:00:55 INFO - Running post-action listener: _resource_record_post_action 13:00:55 INFO - ##### 13:00:55 INFO - ##### Finished populate-webroot step (success) 13:00:55 INFO - ##### 13:00:55 INFO - ##### 13:00:55 INFO - ##### Running create-virtualenv step. 13:00:55 INFO - ##### 13:00:55 INFO - Running pre-action listener: _resource_record_pre_action 13:00:55 INFO - Running main action method: create_virtualenv 13:00:55 INFO - Creating virtualenv /builds/slave/test/build/venv 13:00:55 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:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:00:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:00:55 INFO - Using real prefix '/tools/python27' 13:00:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:00:56 INFO - Installing distribute.............................................................................................................................................................................................done. 13:00:59 INFO - Installing pip.................done. 13:00:59 INFO - Return code: 0 13:00:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:00:59 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:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:59 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:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:59 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 0x103a73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef94ba6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, '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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:59 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:59 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:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:59 INFO - 'HOME': '/Users/cltbld', 13:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:59 INFO - 'LOGNAME': 'cltbld', 13:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:59 INFO - 'MOZ_NO_REMOTE': '1', 13:00:59 INFO - 'NO_EM_RESTART': '1', 13:00:59 INFO - 'PAGER': '/bin/cat', 13:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:59 INFO - 'PWD': '/builds/slave/test', 13:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:59 INFO - 'SHELL': '/bin/bash', 13:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:00:59 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:59 INFO - 'USER': 'cltbld', 13:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:59 INFO - 'XPC_FLAGS': '0x0', 13:00:59 INFO - 'XPC_SERVICE_NAME': '0', 13:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:59 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:59 INFO - Cleaning up... 13:00:59 INFO - Return code: 0 13:00:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:00:59 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:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:59 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:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:59 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 0x103a73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef94ba6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, '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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:59 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:59 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:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:59 INFO - 'HOME': '/Users/cltbld', 13:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:59 INFO - 'LOGNAME': 'cltbld', 13:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:59 INFO - 'MOZ_NO_REMOTE': '1', 13:00:59 INFO - 'NO_EM_RESTART': '1', 13:00:59 INFO - 'PAGER': '/bin/cat', 13:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:59 INFO - 'PWD': '/builds/slave/test', 13:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:59 INFO - 'SHELL': '/bin/bash', 13:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:00:59 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:59 INFO - 'USER': 'cltbld', 13:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:59 INFO - 'XPC_FLAGS': '0x0', 13:00:59 INFO - 'XPC_SERVICE_NAME': '0', 13:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:59 INFO - Downloading/unpacking psutil>=3.1.1 13:00:59 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:59 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:59 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:59 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:59 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:59 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:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:01:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:01:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:01:02 INFO - Installing collected packages: psutil 13:01:02 INFO - Running setup.py install for psutil 13:01:02 INFO - building 'psutil._psutil_osx' extension 13:01:02 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:01:03 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:01:03 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:01:03 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:01:03 INFO - building 'psutil._psutil_posix' extension 13:01:03 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:01:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:01:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:01:03 INFO - ^ 13:01:03 INFO - 1 warning generated. 13:01:03 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:01:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:01:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:01:03 INFO - Successfully installed psutil 13:01:03 INFO - Cleaning up... 13:01:03 INFO - Return code: 0 13:01:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:01:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:03 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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:03 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 0x103a73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef94ba6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, '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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:03 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:01:03 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:01:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:01:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:03 INFO - 'HOME': '/Users/cltbld', 13:01:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:03 INFO - 'LOGNAME': 'cltbld', 13:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:03 INFO - 'MOZ_NO_REMOTE': '1', 13:01:03 INFO - 'NO_EM_RESTART': '1', 13:01:03 INFO - 'PAGER': '/bin/cat', 13:01:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:03 INFO - 'PWD': '/builds/slave/test', 13:01:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:03 INFO - 'SHELL': '/bin/bash', 13:01:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:01:03 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:01:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:03 INFO - 'USER': 'cltbld', 13:01:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:03 INFO - 'XPC_FLAGS': '0x0', 13:01:03 INFO - 'XPC_SERVICE_NAME': '0', 13:01:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 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:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:01:06 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:06 INFO - Installing collected packages: mozsystemmonitor 13:01:06 INFO - Running setup.py install for mozsystemmonitor 13:01:06 INFO - Successfully installed mozsystemmonitor 13:01:06 INFO - Cleaning up... 13:01:07 INFO - Return code: 0 13:01:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:07 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 0x103a73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef94ba6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, '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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:07 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:07 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:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:01:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:07 INFO - 'HOME': '/Users/cltbld', 13:01:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:07 INFO - 'LOGNAME': 'cltbld', 13:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:07 INFO - 'MOZ_NO_REMOTE': '1', 13:01:07 INFO - 'NO_EM_RESTART': '1', 13:01:07 INFO - 'PAGER': '/bin/cat', 13:01:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:07 INFO - 'PWD': '/builds/slave/test', 13:01:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:07 INFO - 'SHELL': '/bin/bash', 13:01:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:01:07 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:01:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:07 INFO - 'USER': 'cltbld', 13:01:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:07 INFO - 'XPC_FLAGS': '0x0', 13:01:07 INFO - 'XPC_SERVICE_NAME': '0', 13:01:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:07 INFO - Downloading/unpacking blobuploader==1.2.4 13:01:07 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:07 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:07 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:07 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:07 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:07 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:09 INFO - Downloading blobuploader-1.2.4.tar.gz 13:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:01:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:01:09 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:09 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:09 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:09 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:09 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:09 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:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:01:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:01:10 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:10 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:10 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:10 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:10 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:10 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:10 INFO - Downloading docopt-0.6.1.tar.gz 13:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:01:11 INFO - Installing collected packages: blobuploader, requests, docopt 13:01:11 INFO - Running setup.py install for blobuploader 13:01:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:01:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:01:11 INFO - Running setup.py install for requests 13:01:11 INFO - Running setup.py install for docopt 13:01:11 INFO - Successfully installed blobuploader requests docopt 13:01:11 INFO - Cleaning up... 13:01:11 INFO - Return code: 0 13:01:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:01:11 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:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:11 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:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:11 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 0x103a73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef94ba6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, '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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:11 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:11 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:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:11 INFO - 'HOME': '/Users/cltbld', 13:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:11 INFO - 'LOGNAME': 'cltbld', 13:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:11 INFO - 'MOZ_NO_REMOTE': '1', 13:01:11 INFO - 'NO_EM_RESTART': '1', 13:01:11 INFO - 'PAGER': '/bin/cat', 13:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:11 INFO - 'PWD': '/builds/slave/test', 13:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:11 INFO - 'SHELL': '/bin/bash', 13:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:01:11 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:11 INFO - 'USER': 'cltbld', 13:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:11 INFO - 'XPC_FLAGS': '0x0', 13:01:11 INFO - 'XPC_SERVICE_NAME': '0', 13:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:01:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-puBDx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:01:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-LKkC9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:01:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-WXK2tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:01:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-QcSRjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:01:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-MHa1M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:01:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-T6O0L3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:01:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-GipV5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:01:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-KMVmiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:01:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-tI4XH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:01:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-_bmsR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:01:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Mrm8is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:01:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Nzf1JP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:01:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-wHRkOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:01:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-HJ2cgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Rtzoj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:01:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-DMEc74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:01:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-o6K6dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:01:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:01:13 INFO - Running setup.py install for manifestparser 13:01:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:01:14 INFO - Running setup.py install for mozcrash 13:01:14 INFO - Running setup.py install for mozdebug 13:01:14 INFO - Running setup.py install for mozdevice 13:01:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:01:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:01:14 INFO - Running setup.py install for mozfile 13:01:14 INFO - Running setup.py install for mozhttpd 13:01:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:01:14 INFO - Running setup.py install for mozinfo 13:01:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:01:14 INFO - Running setup.py install for mozInstall 13:01:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:01:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:01:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:01:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:01:15 INFO - Running setup.py install for mozleak 13:01:15 INFO - Running setup.py install for mozlog 13:01:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:01:15 INFO - Running setup.py install for moznetwork 13:01:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:01:15 INFO - Running setup.py install for mozprocess 13:01:15 INFO - Running setup.py install for mozprofile 13:01:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:01:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:01:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:01:15 INFO - Running setup.py install for mozrunner 13:01:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:01:16 INFO - Running setup.py install for mozscreenshot 13:01:16 INFO - Running setup.py install for moztest 13:01:16 INFO - Running setup.py install for mozversion 13:01:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:01:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:01:16 INFO - Cleaning up... 13:01:16 INFO - Return code: 0 13:01:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:16 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 0x103a73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef94ba6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, '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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:16 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:16 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:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:16 INFO - 'HOME': '/Users/cltbld', 13:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:16 INFO - 'LOGNAME': 'cltbld', 13:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:16 INFO - 'MOZ_NO_REMOTE': '1', 13:01:16 INFO - 'NO_EM_RESTART': '1', 13:01:16 INFO - 'PAGER': '/bin/cat', 13:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:16 INFO - 'PWD': '/builds/slave/test', 13:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:16 INFO - 'SHELL': '/bin/bash', 13:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:01:16 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:16 INFO - 'USER': 'cltbld', 13:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:16 INFO - 'XPC_FLAGS': '0x0', 13:01:16 INFO - 'XPC_SERVICE_NAME': '0', 13:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-9Ao62k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:01:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-dZwgE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:01:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-6QCCfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:01:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-D06T1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:01:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-BOtVhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:01:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-o_3TKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:01:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-7aDayP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:01:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-jHgDRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:01:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-1psxPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:01:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-ehWdpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:01:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Vxk6Hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:01:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-zbhTU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:01:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:01:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-WyFRA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:01:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:01:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-MX9T84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:01:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Y9JKnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:01:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-JiYpkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:01:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:01:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-w7O8Z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:01:18 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:18 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:18 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:18 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:18 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:18 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:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 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:20 INFO - Downloading blessings-1.6.tar.gz 13:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:01:21 INFO - Installing collected packages: blessings 13:01:21 INFO - Running setup.py install for blessings 13:01:21 INFO - Successfully installed blessings 13:01:21 INFO - Cleaning up... 13:01:21 INFO - Return code: 0 13:01:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:01:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:01:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:01:21 INFO - Reading from file tmpfile_stdout 13:01:21 INFO - Current package versions: 13:01:21 INFO - blessings == 1.6 13:01:21 INFO - blobuploader == 1.2.4 13:01:21 INFO - docopt == 0.6.1 13:01:21 INFO - manifestparser == 1.1 13:01:21 INFO - mozInstall == 1.12 13:01:21 INFO - mozcrash == 0.17 13:01:21 INFO - mozdebug == 0.1 13:01:21 INFO - mozdevice == 0.48 13:01:21 INFO - mozfile == 1.2 13:01:21 INFO - mozhttpd == 0.7 13:01:21 INFO - mozinfo == 0.9 13:01:21 INFO - mozleak == 0.1 13:01:21 INFO - mozlog == 3.2 13:01:21 INFO - moznetwork == 0.27 13:01:21 INFO - mozprocess == 0.22 13:01:21 INFO - mozprofile == 0.28 13:01:21 INFO - mozrunner == 6.11 13:01:21 INFO - mozscreenshot == 0.1 13:01:21 INFO - mozsystemmonitor == 0.3 13:01:21 INFO - moztest == 0.7 13:01:21 INFO - mozversion == 1.4 13:01:21 INFO - psutil == 3.1.1 13:01:21 INFO - requests == 1.2.3 13:01:21 INFO - wsgiref == 0.1.2 13:01:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:21 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 0x103a73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef94ba6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, '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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:21 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:21 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:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:01:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:21 INFO - 'HOME': '/Users/cltbld', 13:01:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:21 INFO - 'LOGNAME': 'cltbld', 13:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:21 INFO - 'MOZ_NO_REMOTE': '1', 13:01:21 INFO - 'NO_EM_RESTART': '1', 13:01:21 INFO - 'PAGER': '/bin/cat', 13:01:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:21 INFO - 'PWD': '/builds/slave/test', 13:01:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:21 INFO - 'SHELL': '/bin/bash', 13:01:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:01:21 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:01:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:21 INFO - 'USER': 'cltbld', 13:01:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:21 INFO - 'XPC_FLAGS': '0x0', 13:01:21 INFO - 'XPC_SERVICE_NAME': '0', 13:01:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:21 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:21 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:21 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:21 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:21 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:21 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:21 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:21 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:21 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:21 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:21 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:21 INFO - Cleaning up... 13:01:21 INFO - Return code: 0 13:01:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef94ba6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, '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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:01:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:01:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:01:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:21 INFO - 'HOME': '/Users/cltbld', 13:01:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:21 INFO - 'LOGNAME': 'cltbld', 13:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:21 INFO - 'MOZ_NO_REMOTE': '1', 13:01:21 INFO - 'NO_EM_RESTART': '1', 13:01:21 INFO - 'PAGER': '/bin/cat', 13:01:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:21 INFO - 'PWD': '/builds/slave/test', 13:01:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:21 INFO - 'SHELL': '/bin/bash', 13:01:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:01:21 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:01:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:21 INFO - 'USER': 'cltbld', 13:01:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:21 INFO - 'XPC_FLAGS': '0x0', 13:01:21 INFO - 'XPC_SERVICE_NAME': '0', 13:01:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:22 INFO - Downloading/unpacking jsonschema 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:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:01:24 INFO - Downloading/unpacking functools32 (from jsonschema) 13:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:24 INFO - Downloading functools32-3.2.3-2.tar.gz 13:01:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:01:24 INFO - warning: no files found matching '*.txt' 13:01:24 INFO - no previously-included directories found matching 'build' 13:01:24 INFO - no previously-included directories found matching 'dist' 13:01:24 INFO - no previously-included directories found matching '.git*' 13:01:24 INFO - Installing collected packages: jsonschema, functools32 13:01:25 INFO - Running setup.py install for functools32 13:01:25 INFO - warning: no files found matching '*.txt' 13:01:25 INFO - no previously-included directories found matching 'build' 13:01:25 INFO - no previously-included directories found matching 'dist' 13:01:25 INFO - no previously-included directories found matching '.git*' 13:01:25 INFO - Successfully installed jsonschema functools32 13:01:25 INFO - Cleaning up... 13:01:25 INFO - Return code: 0 13:01:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a73a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a79570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef94ba6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, '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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:01:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:01:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:01:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:25 INFO - 'HOME': '/Users/cltbld', 13:01:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:25 INFO - 'LOGNAME': 'cltbld', 13:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:25 INFO - 'MOZ_NO_REMOTE': '1', 13:01:25 INFO - 'NO_EM_RESTART': '1', 13:01:25 INFO - 'PAGER': '/bin/cat', 13:01:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:25 INFO - 'PWD': '/builds/slave/test', 13:01:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:25 INFO - 'SHELL': '/bin/bash', 13:01:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:01:25 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:01:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:25 INFO - 'USER': 'cltbld', 13:01:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:25 INFO - 'XPC_FLAGS': '0x0', 13:01:25 INFO - 'XPC_SERVICE_NAME': '0', 13:01:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:25 INFO - Downloading/unpacking flake8 13:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:01:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:01:27 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:27 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:27 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:27 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:27 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:27 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:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:01:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:01:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:01:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:01:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:01:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:01:29 INFO - no previously-included directories found matching 'docs/_build' 13:01:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:01:29 INFO - Running setup.py install for pep8 13:01:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:01:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:01:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:01:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:01:29 INFO - no previously-included directories found matching 'docs/_build' 13:01:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:01:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:01:29 INFO - Cleaning up... 13:01:29 INFO - Return code: 0 13:01:29 INFO - Running post-action listener: _resource_record_post_action 13:01:29 INFO - Running post-action listener: _start_resource_monitoring 13:01:29 INFO - Starting resource monitoring. 13:01:29 INFO - ##### 13:01:29 INFO - ##### Finished create-virtualenv step (success) 13:01:29 INFO - ##### 13:01:29 INFO - ##### 13:01:29 INFO - ##### Running install step. 13:01:29 INFO - ##### 13:01:29 INFO - Running pre-action listener: _resource_record_pre_action 13:01:29 INFO - Running main action method: install 13:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:01:29 INFO - Reading from file tmpfile_stdout 13:01:29 INFO - Detecting whether we're running mozinstall >=1.0... 13:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:01:29 INFO - Reading from file tmpfile_stdout 13:01:29 INFO - Output received: 13:01:29 INFO - Usage: mozinstall [options] installer 13:01:29 INFO - Options: 13:01:29 INFO - -h, --help show this help message and exit 13:01:29 INFO - -d DEST, --destination=DEST 13:01:29 INFO - Directory to install application into. [default: 13:01:29 INFO - "/builds/slave/test"] 13:01:29 INFO - --app=APP Application being installed. [default: firefox] 13:01:29 INFO - mkdir: /builds/slave/test/build/application 13:01:29 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:29 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:02:04 INFO - Reading from file tmpfile_stdout 13:02:04 INFO - Output received: 13:02:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:02:04 INFO - Running post-action listener: _resource_record_post_action 13:02:04 INFO - ##### 13:02:04 INFO - ##### Finished install step (success) 13:02:04 INFO - ##### 13:02:04 INFO - ##### 13:02:04 INFO - ##### Running run-tests step. 13:02:04 INFO - ##### 13:02:04 INFO - Running pre-action listener: _resource_record_pre_action 13:02:04 INFO - Running main action method: run_tests 13:02:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:02:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:02:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:02:04 INFO - 2016-05-18 13:02:04.131 screenresolution[1638:7513] starting screenresolution argv=screenresolution get 13:02:04 INFO - 2016-05-18 13:02:04.134 screenresolution[1638:7513] Display 0: 1600x1200x32@60 13:02:04 INFO - 2016-05-18 13:02:04.140 screenresolution[1639:7515] starting screenresolution argv=screenresolution list 13:02:04 INFO - Available Modes on Display 0 13:02:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:02:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:02:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:02:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:02:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:02:04 INFO - Intel Iris: 13:02:04 INFO - Chipset Model: Intel Iris 13:02:04 INFO - Type: GPU 13:02:04 INFO - Bus: Built-In 13:02:04 INFO - VRAM (Dynamic, Max): 1536 MB 13:02:04 INFO - Vendor: Intel (0x8086) 13:02:04 INFO - Device ID: 0x0a2e 13:02:04 INFO - Revision ID: 0x0009 13:02:04 INFO - Displays: 13:02:04 INFO - Display: 13:02:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:02:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:02:04 INFO - Display Serial Number: 1600x1200 60 13:02:04 INFO - Main Display: Yes 13:02:04 INFO - Mirror: Off 13:02:04 INFO - Online: Yes 13:02:04 INFO - Rotation: Supported 13:02:04 INFO - Return code: 0 13:02:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:02:04 INFO - Python 2.7.3 13:02:04 INFO - Return code: 0 13:02:04 INFO - grabbing minidump binary from tooltool 13:02:04 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:02:04 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 0x103917d30>, '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 0x10373bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10390eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:02:04 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:02:04 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:02:04 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:02:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:02:04 INFO - Return code: 0 13:02:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:02:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:02:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:02:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:02:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:02:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:02:05 INFO - Return code: 0 13:02:05 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-0173', '--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:02:05 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-0173 --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:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a9SMYp3C1l/Render', 13:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:02:05 INFO - 'HOME': '/Users/cltbld', 13:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:02:05 INFO - 'LOGNAME': 'cltbld', 13:02:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:02:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:05 INFO - 'MOZ_NO_REMOTE': '1', 13:02:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:02:05 INFO - 'NO_EM_RESTART': '1', 13:02:05 INFO - 'PAGER': '/bin/cat', 13:02:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:02:05 INFO - 'PWD': '/builds/slave/test', 13:02:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:02:05 INFO - 'SHELL': '/bin/bash', 13:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YqFAw3hDhJ/Listeners', 13:02:05 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 13:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:02:05 INFO - 'USER': 'cltbld', 13:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:02:05 INFO - 'XPC_FLAGS': '0x0', 13:02:05 INFO - 'XPC_SERVICE_NAME': '0', 13:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:02:05 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-0173', '--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:02:05 INFO - mozversion application_buildid: 20160517065136 13:02:05 INFO - mozversion application_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:02:05 INFO - mozversion application_display_name: Nightly 13:02:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:02:05 INFO - mozversion application_name: Firefox 13:02:05 INFO - mozversion application_remotingname: firefox 13:02:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:02:05 INFO - mozversion application_vendor: Mozilla 13:02:05 INFO - mozversion application_version: 49.0a1 13:02:05 INFO - mozversion platform_buildid: 20160517065136 13:02:05 INFO - mozversion platform_changeset: 3780a3a6b83aeda143f9562829c830410a0c961e 13:02:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:02:05 INFO - mozversion platform_version: 49.0a1 13:02:05 INFO - using testdate: 1463601725 13:02:05 INFO - actual date: 1463601725 13:02:05 INFO - starting webserver on 'localhost:49278' 13:02:05 INFO - SUITE-START | Running 2 tests 13:02:05 INFO - TEST-START | dromaeo_css 13:02:05 INFO - operating with platform_type : mac_ 13:02:05 INFO - Initialising browser for dromaeo_css test... 13:02:05 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpoZfhHH/profile http://localhost:49278/getInfo.html) 13:02:08 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 13:02:08 INFO - PROCESS | 1657 | colorDepth:24 13:02:08 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 13:02:08 INFO - PROCESS | 1657 | __metrics 13:02:08 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:02:09 INFO - TEST-INFO | 1657: exit 0 13:02:09 INFO - Browser initialized. 13:02:09 INFO - Running cycle 1/1 for dromaeo_css test... 13:02:09 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpoZfhHH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:02:21 INFO - PROCESS | 1660 | RSS: Main: 250212352 13:02:21 INFO - PROCESS | 1660 | 13:03:11 INFO - PROCESS | 1660 | 1463601791257 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:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 13:05:23 INFO - PROCESS | 1660 | RSS: Main: 261951488 13:05:23 INFO - PROCESS | 1660 | 13:08:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 13:08:26 INFO - PROCESS | 1660 | RSS: Main: 273743872 13:08:26 INFO - PROCESS | 1660 | 13:11:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 13:11:29 INFO - PROCESS | 1660 | RSS: Main: 275705856 13:11:29 INFO - PROCESS | 1660 | 13:14:31 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 13:14:31 INFO - PROCESS | 1660 | RSS: Main: 277331968 13:14:31 INFO - PROCESS | 1660 | 13:17:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 13:17:35 INFO - PROCESS | 1660 | RSS: Main: 280240128 13:17:35 INFO - PROCESS | 1660 | 13:20:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 13:20:38 INFO - PROCESS | 1660 | RSS: Main: 281411584 13:20:38 INFO - PROCESS | 1660 | 13:20:38 INFO - PROCESS | 1660 | __start_tp_report 13:20:38 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 13:20:38 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 13:20:38 INFO - PROCESS | 1660 | |i|pagename|runs| 13:20:38 INFO - PROCESS | 1660 | |0;dojo.html;2926.7658587313017;3459.273552553964;3486.180747524333;3493.12671832042;3506;5786.450287222712;5819.418058194178;5828.388019257975;5874;5880.882382352351;4449.532799056093;5781.508571771405;5865.648061116336;5951.732172052257;5983.0726237433155;5215;5292.655977361478;5401.567874570044;5416.91874621881;5473.534749546274;387.1634406774351;5266.683998960056;5271.103912334893;5383.977044361559;5397.298351214336;5288.947110528903;5305.4164041955355;5313.574914006909;5343.839684809443;5361.597880159004;5341.332333458317;5351.331083614548;5365.436629153922;5379.515843574097;5389.973050134763;5174.456682048369;5228.8692782680355;5293.206019097128;5331.466853314676;5359.490848369399;5222.895542089175;5566.443355664442;5741.1388291756575;5755.769769209227;5768.538516918637;5964.194833697438;6243.188385509855;6304.1804565406665;6310.5898116622275;6312.810615681537;6222.502199824004;6266.811995640138;6288.151099601587;6337.144485494446;6340.841478963017;6058.363871793443;6083.908741368883;6095.786647467317;6100.359462256399;6110.663913484756;6240.438360547572;6288.27684816252;6458.869697802866;6481.546291759531;6496.512761542903;6205.503559715212;6217;6262.968685156637;6271.6550589718;6288.056791481315;6266.811995640138;6277;6364.840878978062;6368.649724265118;6373.521985850987;6216.067589861466;6236.345183755731;6276.560640755103;6294.866923953732;6304.464120549713;6266.561340706106;6283.842903927347;6292.937070629235;6297.811065668037;6302;5076.035553244872;5112.821051263262;5114.232865070269;5114.43741188469;5119.948800512022;3807.371783655721;4422.226110430662;4492.124035812985;4556.886077848014;4652.534746525321;5505.898820235889;5823.126531020381;5851.1223316502865;5973.910391344133;6009.06859436788;5739.253896993364;6323.9683801580695;6325.746970121236;6339.2709838368255;6342.5243106767175;5825.38833422493;5865.618734782311;5992.610480318765;6038;6080.69596520181;5547.472990065938;5580.748866301026;5588.385277619459;5595.356533998643;5598.356189038232;6131.816045518642;6583.407493325623;6586.077949087132;6609.239937407259;6613.867722645617;6159.538034647509;6230.43926046658;6272.843178920563;6275.96862015687;6331.366863313632;4963.975180124076;5761.164631128547;6038.9396106038375;6515.902261465987;6561.704723287559;4770.9980904010545;5690.857728556819;6205.22434695663;6307.148534947723;6316.305206427198;6129.387061293835;6231.781887633911;6232.8130156095385;6260.968695156495;6329.778457754141;6217.689115544114;6273.466755325666;6281.654509002049;6283.71723272463;6320.905186422116;6105.399096011382;6105.725242364016;6269.404406581368;6275.278342990432;6344.302126766144;5835.912461313169;5891.1457838612305;6017.789377371771;6065.909011364922;6110.511159107172;6166.167567378347;6179.104029915727;6190.164327815687;6192.845178870564;6193.81418557444;6050.092486127116;6087.269527656709;6101.60339577926;6111.561351782648;6148.923938310688 13:20:38 INFO - PROCESS | 1660 | |1;ext.html;504.6089280807375;528.3474908488016;529.4044200274691;530.0459173487724;561.7444062951357;36414.635853641455;38488;39597.406038909365;39727.80136099315;39778;40626.59373406265;41316;41359.58640413595;41506.79246603763;41582.58417415825;8652.61063252152;9368.999999999984;11722.062235021178;11764.529418823236;11963.461644225987;11627.244229125079;11628.941855290712;11637.592684256053;11647.941760291229;11712.180147389687;11648.126390520703;11840.815918408176;11854.822177667342;11869;11874.465649045771;11739.0021848143;11763.35301558414;11828;11838.763224735458;11861.932426081694;10013.949930250375;11727.824082638766;11853.703657408547;11876.16866819323;12005.939970300093;39689.60310396888;39828.402573961415;40009.79970600887;40181.99545011369;40222;3215.1962009497624;3325.6840600142946;3449;3791.374423220165;3872.806359682033;28713.995010174753;29026.709732902822;29264.853675731698;29270.41459170826;29783.65973531196;2698.892044318225;2713.9050133245437;2723.305557082931;2843.644544431946;2906.6999880994867;534456;541115.999999996;541118;541259;541750;59227.81544369089;59369.10946335809;59740;59837.401625983184;59917.10124348138;63217.683911581065;63653;63816;63981;64043;14924.403023878924;16105.758413623806;16397.59006024859;16592.170391480377;16741.079240641637;11166.720831979266;12412.751744965231;12434.502619895104;12735.917447016922;13002.804957925639;15576.706274380665;15631.3747450103;15633.296501657453;15670.216489175496;15725.685486290438;5891.646501209855;6933.722651094001;7004.509684322151;7030.543014704028;7034.050403195605;920.2270093121676;981.66623348062;1009.6415772400838;1084.3277168155794;1135.1259530161728;28378.574321385197;29434.705652943197;29632.851835741116;29902.401951960837;30321.24196895095;883.743714322852;1061.7027232374946;1114.2979922648678;1121.7363919478917;1226.8037114061708;12225.93887030577;15312.387504499695;15515.37938482448;15520.92239538795;15631.3747450103;664851;677620;684253;684302;686617;59745;60241.39758602445;60553.69723151356;60719.08921366183;60733.69633151894;1427.9500217492339;1448.6740483391363;1480.8671366404924;1488.4492737686908;1490.7987421697933;25126.748732512442;29401;29514.409711805216;29732.256693582833;30078.699213008465;51186.74406627943;51217.7439112802;51257.71855703637;51277.7436112817;51489.97020059503;499810;502047;504329.9134017372;580219;581857;32499.400047996576;38506;38875.6112438872;39898.800505997286;41859;44709.55290447054;45135.32297015482;45763.54236457593;46437.53562464332;47658.04683906372;35683.82158089193;37856;37971.62028379681;38433.23133537258;38744;36560.45159322665;37583.62416375911;37717;37951.62048379481;38160.80919595384;36807;37245.81377093206;37991.81004094962;38359.42460863033;38810.41784373293;32758.34483310273;33066.834665826515;33177;33613.83193084019;33651;23615.055397784585;24110.276691699277;24734.505309894063;24804.131855384974;25265.87367063153 13:20:38 INFO - PROCESS | 1660 | |2;jquery.html;331.47851362247286;391.5418959817012;393.1782574419463;407.5761208343323;411.9320312148496;16078.954867933591;17587.91206043968;19074.809251907493;20910.790892091074;21061.789382106195;17925.551861203476;20874.26940057098;21382.144714211452;21467.46331341717;21702.565948681055;5876.353601103875;5976.402359764011;6212.78255261066;6240.282367527747;6336.112944187817;6092.634441933476;6114.571979961404;6149.2313460817395;6159.7536098556;6164.691765411734;5526.364468086182;5858.7949421770245;5901.645901245917;5907.822765317026;6100.969495152518;4890.24201248807;5872.677002764846;5879.147523609074;5934.109883517485;6082.1180928765525;4996.875078123039;5755.913661295084;5843.8539036524;5851.005329094064;5855.151003104568;13374.780726961912;13583;13600.591982240549;13736;13830.792538111937;2085.384811480617;2155.2025750472267;2175.8041776240057;2235.787600177997;2241.7197850268717;9344.766380840465;9761.511924403823;10047.497625118714;10170.796584068352;10272.589096436133;1737.287712038071;1780.1455301455244;1804.684180268461;1806.9168858076373;1844.3729132095152;76909;106658;125377;126926;130761;9154.588043538057;11766.294022358687;11786.94106529479;11906;11983.161178717406;14729.04261223017;15080.095194288158;15082.773758393632;15201.31594078247;15244.695106097815;8687.435316704394;8818.074102219303;8858.911410885938;8945.37382383227;8962.86555701665;8701.825963480695;8765.693200737944;8791.780205494786;8854.424462409923;8877.845880035555;4573.47405048417;4587.42657167854;4654.627629789608;4709.811607535728;4757.881052973634;1973.58554703511;1998.8500862435085;2018.0515157875775;2026.8682535635137;2075.9169633214806;939.6053657463882;939.8815409662479;969.5927710361524;990.7424069741919;1016.7559785651538;18652.720209196603;20116.497087572927;21109.68335474969;21302.893485532473;21411;1009.6163457885956;1014.6854475112739;1017.877854657431;1022.6420752736483;1055.0451841083832;9089.136532029446;9789.070038346346;11300.039496642878;11312.264702794291;11321.433928303717;518443;522198.55602887727;536060;592679;629823;112671.87328126613;113900;115430;143273;148332;848.758103940374;931.2968708624945;1068.3856782350024;1086.1962148010416;1088.3034857691084;18672.25310987545;18688.25246990105;18925.526861828563;18975.292223699933;19228.327008554632;52311.430657080346;54762.452375477325;55035.72482137564;55364;56115.15827262676;78954.6052269735;79891.20108798837;80070;80212.59893700494;80228.59885700535;7003.789886303419;7746.419018573517;7976;8102.959485202537;8600.22597966186;10961.56153753841;13018.023648226232;13191.142575732547;13374.531891383755;13441.529546465827;5202.3497062867145;6687.899681504879;11128.499217535069;11277.041451476389;11402.600908968147;8453.239208471266;10526.473676316;11181;11283.153763467608;11300;10885.836712449161;11122.332660040423;11568;11589.01493373039;11644.475998579916;7415.740449084256;7463.552186868806;7491.775246742606;7760.146383897654;7796;8755.474671519474;8914.465132092095;10661.786764264827;10702.250842440955;10749.19381046409 13:20:38 INFO - PROCESS | 1660 | |3;mootools.html;1477.3499660149535;1575.5430925031737;1862.7205919112132;1990.4625751047208;1996.3911007142824;8235.505869647825;8554.52950087745;8835.867461988075;9266.768330791725;9275.489848058363;8723.345749068814;8849.008972981075;8872.024077351502;8905.198532132106;8953.05992870749;2342.781753488185;2411.1440438644336;2456.7666071723156;2469.938251543708;2471.530409222251;2202.3392982105383;2435.5250726108416;2477.913273035444;2480.255923223026;2482.2925466242123;2457.606782914734;2460.692413448319;2470.5429495543294;2471.789897858684;2492.5513407586627;2396.35298469414;2448.8285819992607;2456.9385765355833;2462.101333013455;2462.347477918353;2422.321749910028;2434.0993832281993;2446.5351583199135;2458.754124587545;2464.9753502465105;8734.781630459225;8775.385722999392;8779.824403511893;8815;8878.955605221932;5456.045192091368;5500.1749737539285;5594.412586678422;5600.327960644708;5642.294713160855;3321.90034298969;3522.2427178156563;3589.7128229741556;3613.403788374915;3631.0377749896297;840.1220724343076;961.5096300886528;995.5619527407918;998.8401855703125;998.8501724741275;10101.393916365041;11871.52513899443;11926.58256961011;11979.700507487296;12019.699507512294;4470.530594287618;4650.907036846338;4926.211806111005;4972.129877271463;4987.351644286219;8880.51157186361;10175.982401759913;10340.586376545005;10390.272680912414;10418.791624167627;5193.974030129825;5258.947410525922;5274.340707411574;5276.34045744282;5576.414476479992;4522.412086428743;4866.29438731389;4902.534259245366;5038.118329292359;5066.569341605931;4129.616578446194;4158.812853421603;4190;4214.704970652087;4269.978650106729;1317.841858976929;1345.9125156864773;1364.3178410794603;1368.4184221705734;1372.3961456958907;847.169773621854;859.5659192107945;905.791667916383;944.367273926471;960.8270511307892;5892.616979896293;5912.497437717842;5989.55078369124;6039.0035644118225;6104.32852386246;411.54935345796906;412.4617374326473;415.15723082143296;423.88131323229544;427.8737772357162;3837.76973381598;3942.250972315251;3985.8405663773706;4013.7792421417103;4061.2080644274083;10593.364398135982;10781.568737250578;10940.890591093987;10974.341539507655;10980.121590272758;10811.405372704578;10813.783724325469;10833.187510936712;10850.0777433919;10959.726006849733;7946.443748937519;8335.416520843604;8343.290820280345;8389.874151887849;8418.873716894252;6099.75600975956;6102.389761023862;6122.755089796358;6165.876682466236;6203.2556093269095;10024.997500249916;10249.436281004617;10268.64059757905;10269.84595231087;10278.434686092336;10008.39949603026;10353.378797272188;10476.63331783384;10477.790444190921;10529.684109476728;7046.541974771623;7431.591262480615;7595.886061708969;7662.54024758494;7773.572453515111;7100.680469378787;8439.071702112886;8481;8497.83004339922;8519.701810436605;7421.888671669822;7514.661840217313;7572.621368931642;7621.580813055335;7664.885026724715;7430.517016393917;7448.851022979618;7471.215522370073;7546.320831125225;7566.810829729301;7079.858402831812;7099.544593358246;7162.391196748335;7182.676779545038;7211.206767255493;4772.7136371817805;4802.543758342952;4932.704037757746;4942.55517003457;5101.9489805101475;4201.45381100455;4880.974995251037;5027.773750181177;5073.213651883964;5084.237364395371 13:20:38 INFO - PROCESS | 1660 | |4;prototype.html;315.70639305445934;317.8299609708806;321.30437602590393;321.80369974315664;327.90982479818064;4832.081904438154;4837.8790530236765;5218.869528261791;5836.970815145929;5854.121881717734;4528.7509186994785;4804.759762011903;5426.647267927582;5536.640118392302;5572.60991730579;3752.1745216052423;3819.720393668111;4116.115035267417;4249.215329561576;4381.780910954455;3387.0854869185305;3625.7461977661574;4269.893252668677;4302.816725400508;4310.672236992021;3414.8901606977834;3567.8929632111076;3872.5740168581433;4088.366303223005;4297.613214810682;3118.8635784487697;3563.483294922222;4156.334986402177;4239.025024244425;4330.696851220416;3324.9692595295537;3416.607090184611;3482.8432720527385;3643.8360273787744;3683.8580040187626;4464.508904020555;4547.6357092872;5026.547610715053;5495.450454954513;5534.75093620788;4823.421189457287;4935.284383764334;4990.90018199634;5012.749362531896;5070.8225212117395;4821.987382649648;5422.91865622016;5954.940450595525;6039.667818269993;6076.817695469143;4043.555208927016;4066.5323487798987;4066.6340029397497;4070.735402198877;4098.180363927227;5177.508136727005;5277.8416647500635;5416.610472579769;6129.693515324216;6245.625962288334;3281.868725250963;3326.284848757529;3404.3531728971416;4076.7553946763287;4097.152479027107;3551.401869158889;4169.708120431601;4174.72864263828;4181.665466762652;4184.28867092595;4834.912144767399;4903.754812259373;4925.310456536088;4935.284383764334;4978.452370239271;4945.975270123627;5433.646812957145;6145.053232148163;6145.385461453909;6156.445919867234;1306.9803952940713;1365.3105181883086;1428.1359777334562;1430.9125064950504;1435.5621535431585;740.1303468424579;788.625402933602;816.9183081691783;886.2732559301439;932.6176267730198;4785.521447855187;5602.467765562265;5625.2436306209665;5628.915566266509;5638.633488823295;6217.720202590895;6218.751249949952;6223.844403889849;6250.718717657716;6289.245290565162;4772.2364421693055;5083.872903177376;5375.81184658535;5612.214289999403;5623.381428042912;2880.5535142052595;3231.935361292761;3281.096229836564;3330.3672302262494;3340.398728228942;5528.9494995950645;5967.93762555438;6320.462760665395;6409.551331406757;6526.34736526353;6338.714757835908;6358.837995081694;6368.331325210879;6393.872122557523;6404.167458230493;5375.623706340597;6148.969255153875;6377.553571249968;6457.3865482779065;6476.481881449473;4763.452202996561;5116.15583428745;5498.615096943176;6025.614108754923;6109.938900611115;5918.378570250234;6066.090086487062;6119.755209791559;6131.785387511416;6131.908021379593;5359.758810853443;6222.346653601307;6444.938654139246;6465.64438955862;6565.353795735759;3474.7915125092577;3996.6611185253264;4007.0583412898527;4035.7376770510996;4052.5005747873474;5179.30079439286;6241.875162496815;6308.33762454954;6328.208973878265;6329.120250286017;5784.7107644618445;6092.299385570539;6102.694865256808;6104.298005729398;6104.328523862283;5730.885382292414;6047.758089676364;6066.908996365147;6109.969450152721;6163.3528479509005;5697.720911635379;5855.502282305882;5867.946218823377;5877.617954832922;6003.6998150093195;5607.607467477401;5619;5966.731497082555;6121.204243448323;6154.79981403622;5118;5180;5781.017227071492;6057.848553786191;6060.212172417558 13:20:38 INFO - PROCESS | 1660 | |5;yui.html;645.2901808011188;669.3239827773948;747.3684736397742;757.0914902117458;780.4536824223042;15052.623684407881;15527;15583.220838958063;15691.764623530657;15744.60638484037;14695.559133225994;14712.337944792484;14771;14775;14798.630034249149;697.4873468001015;903.1735961595166;908.3596064774351;926.3885835348672;934.028610245344;917.3807679816131;918.4122161816442;925.4817302310694;925.9860452804169;929.8233335666235;969.970900872975;985.8225519406504;989.9802003959917;990.8018396320729;991.167419367731;883.7304622090292;944.3955868244329;975.0152346130388;977.6431602465085;984.4200058905461;698.4063545985871;700.1528150937372;720.7009091227135;726.233823316401;731.0788406607712;15024.09855408679;15127.243637818065;15208.695826083527;15237.314320855477;15244.847551524454;10644.327706941092;12588.874111258861;12701.555445559454;12748;13002.934985325108;9903.306768526207;9949.104580587782;10190.184785217165;10525.000124988113;10641.893581064245;3499.667531584521;3535.9116022099397;3563.501109844624;3623.8550457981914;3633.291508155911;83744;84354;88561.11438885593;88595;90625;9484.193843523377;9514.666986655433;9568.138867501959;9758.46328451942;9799.97100304472;22541.872906354616;22731.204407845646;22779;22804.97377618011;23040.769592304197;6929;7333.853322933512;7472.103347598214;7529.774106776806;7613.467057306047;3843.6348546888003;3902.824372903226;3925.21495700861;3939.153082087365;3941.152652179795;3510.350585141757;3583.283343331344;3625.619309972468;3639.9090022749115;3653.47024681425;658.9176352955881;661.5468404143178;663.0650782396798;666.0708311904963;667.8946343801017;501.68895284922854;505.45158503023896;539.1992890557519;550.758040618411;560.9566206855246;7787.92212077872;7878.803029924183;7955.124936257007;8072.919270807334;8139.4709343892455;495.56637941800926;529.5736931769885;547.6276132230042;557.2059814763945;587.3554048663724;3649.5620525537106;3736.4208547675153;3769.227308401771;3879.321118804198;3931.3709806430834;89251;94455.52772236094;99330.50334748425;102549.48725256325;102788;86461;86843.56578217195;87039.56480217558;87928.12071879327;91422.54288728513;73485.53028939391;73872.63063684755;74002.62998685079;74388;76081.23918760741;8072.192780721881;8300.335973122257;8502.064772874855;8587.65649374018;8615.784605384915;27854;46581;47140;47483.81290467766;48277.75861120672;48770.75614622046;62700;65533;66168.66915665583;66840;11552.884471155181;11676.241044332091;11739.823902641296;11775.882241177822;11928;11974.64076077719;12030;12083.637490875288;12188.573399931314;12312.630621081382;11714.589989350332;11828.34944078084;11946.581869634521;12014.219075760047;12058.819117713067;9656.71029869105;10084.798304033731;11386.373749443861;11851.52593896269;11913.880861191277;9621.807563848544;10840.837387439036;11065.336079835235;11299.661010169708;11578.536858525564;9499.192568631745;9573.825669455087;9677.806443870943;9745.12293893553;9816.411015339103;9998.700038998843;10095.732823819713;10129.949350253202;10131.392116473035;10145.60221870071 13:20:38 INFO - PROCESS | 1660 | __end_tp_report 13:20:38 INFO - PROCESS | 1660 | __start_cc_report 13:20:38 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,429 13:20:38 INFO - PROCESS | 1660 | __end_cc_report 13:20:38 INFO - PROCESS | 1660 | __startTimestamp1463602838203__endTimestamp 13:20:38 INFO - PROCESS | 1660 | 13:20:38 INFO - PROCESS | 1660 | ------- Summary: start ------- 13:20:38 INFO - PROCESS | 1660 | Number of tests: 6 13:20:38 INFO - PROCESS | 1660 | 13:20:38 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5784.85 Median:6080.70 stddev:789.09 (13.0%) stddev-sans-first:758.99 13:20:38 INFO - PROCESS | 1660 | Values: 2926.8 3459.3 3486.2 3493.1 3506.0 5786.5 5819.4 5828.4 5874.0 5880.9 4449.5 5781.5 5865.6 5951.7 5983.1 5215.0 5292.7 5401.6 5416.9 5473.5 387.2 5266.7 5271.1 5384.0 5397.3 5288.9 5305.4 5313.6 5343.8 5361.6 5341.3 5351.3 5365.4 5379.5 5390.0 5174.5 5228.9 5293.2 5331.5 5359.5 5222.9 5566.4 5741.1 5755.8 5768.5 5964.2 6243.2 6304.2 6310.6 6312.8 6222.5 6266.8 6288.2 6337.1 6340.8 6058.4 6083.9 6095.8 6100.4 6110.7 6240.4 6288.3 6458.9 6481.5 6496.5 6205.5 6217.0 6263.0 6271.7 6288.1 6266.8 6277.0 6364.8 6368.6 6373.5 6216.1 6236.3 6276.6 6294.9 6304.5 6266.6 6283.8 6292.9 6297.8 6302.0 5076.0 5112.8 5114.2 5114.4 5119.9 3807.4 4422.2 4492.1 4556.9 4652.5 5505.9 5823.1 5851.1 5973.9 6009.1 5739.3 6324.0 6325.7 6339.3 6342.5 5825.4 5865.6 5992.6 6038.0 6080.7 5547.5 5580.7 5588.4 5595.4 5598.4 6131.8 6583.4 6586.1 6609.2 6613.9 6159.5 6230.4 6272.8 6276.0 6331.4 4964.0 5761.2 6038.9 6515.9 6561.7 4771.0 5690.9 6205.2 6307.1 6316.3 6129.4 6231.8 6232.8 6261.0 6329.8 6217.7 6273.5 6281.7 6283.7 6320.9 6105.4 6105.7 6269.4 6275.3 6344.3 5835.9 5891.1 6017.8 6065.9 6110.5 6166.2 6179.1 6190.2 6192.8 6193.8 6050.1 6087.3 6101.6 6111.6 6148.9 13:20:38 INFO - PROCESS | 1660 | 13:20:38 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:71794.45 Median:36487.54 stddev:157364.56 (431.3%) stddev-sans-first:157714.98 13:20:38 INFO - PROCESS | 1660 | Values: 504.6 528.3 529.4 530.0 561.7 36414.6 38488.0 39597.4 39727.8 39778.0 40626.6 41316.0 41359.6 41506.8 41582.6 8652.6 9369.0 11722.1 11764.5 11963.5 11627.2 11628.9 11637.6 11647.9 11712.2 11648.1 11840.8 11854.8 11869.0 11874.5 11739.0 11763.4 11828.0 11838.8 11861.9 10013.9 11727.8 11853.7 11876.2 12005.9 39689.6 39828.4 40009.8 40182.0 40222.0 3215.2 3325.7 3449.0 3791.4 3872.8 28714.0 29026.7 29264.9 29270.4 29783.7 2698.9 2713.9 2723.3 2843.6 2906.7 534456.0 541116.0 541118.0 541259.0 541750.0 59227.8 59369.1 59740.0 59837.4 59917.1 63217.7 63653.0 63816.0 63981.0 64043.0 14924.4 16105.8 16397.6 16592.2 16741.1 11166.7 12412.8 12434.5 12735.9 13002.8 15576.7 15631.4 15633.3 15670.2 15725.7 5891.6 6933.7 7004.5 7030.5 7034.1 920.2 981.7 1009.6 1084.3 1135.1 28378.6 29434.7 29632.9 29902.4 30321.2 883.7 1061.7 1114.3 1121.7 1226.8 12225.9 15312.4 15515.4 15520.9 15631.4 664851.0 677620.0 684253.0 684302.0 686617.0 59745.0 60241.4 60553.7 60719.1 60733.7 1428.0 1448.7 1480.9 1488.4 1490.8 25126.7 29401.0 29514.4 29732.3 30078.7 51186.7 51217.7 51257.7 51277.7 51490.0 499810.0 502047.0 504329.9 580219.0 581857.0 32499.4 38506.0 38875.6 39898.8 41859.0 44709.6 45135.3 45763.5 46437.5 47658.0 35683.8 37856.0 37971.6 38433.2 38744.0 36560.5 37583.6 37717.0 37951.6 38160.8 36807.0 37245.8 37991.8 38359.4 38810.4 32758.3 33066.8 33177.0 33613.8 33651.0 23615.1 24110.3 24734.5 24804.1 25265.9 13:20:38 INFO - PROCESS | 1660 | 13:20:38 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:33457.81 Median:2205.80 stddev:94157.33 (4268.6%) stddev-sans-first:94388.62 13:20:38 INFO - PROCESS | 1660 | Values: 331.5 391.5 393.2 407.6 411.9 16079.0 17587.9 19074.8 20910.8 21061.8 17925.6 20874.3 21382.1 21467.5 21702.6 5876.4 5976.4 6212.8 6240.3 6336.1 6092.6 6114.6 6149.2 6159.8 6164.7 5526.4 5858.8 5901.6 5907.8 6101.0 4890.2 5872.7 5879.1 5934.1 6082.1 4996.9 5755.9 5843.9 5851.0 5855.2 13374.8 13583.0 13600.6 13736.0 13830.8 2085.4 2155.2 2175.8 2235.8 2241.7 9344.8 9761.5 10047.5 10170.8 10272.6 1737.3 1780.1 1804.7 1806.9 1844.4 76909.0 106658.0 125377.0 126926.0 130761.0 9154.6 11766.3 11786.9 11906.0 11983.2 14729.0 15080.1 15082.8 15201.3 15244.7 8687.4 8818.1 8858.9 8945.4 8962.9 8701.8 8765.7 8791.8 8854.4 8877.8 4573.5 4587.4 4654.6 4709.8 4757.9 1973.6 1998.9 2018.1 2026.9 2075.9 939.6 939.9 969.6 990.7 1016.8 18652.7 20116.5 21109.7 21302.9 21411.0 1009.6 1014.7 1017.9 1022.6 1055.0 9089.1 9789.1 11300.0 11312.3 11321.4 518443.0 522198.6 536060.0 592679.0 629823.0 112671.9 113900.0 115430.0 143273.0 148332.0 848.8 931.3 1068.4 1086.2 1088.3 18672.3 18688.3 18925.5 18975.3 19228.3 52311.4 54762.5 55035.7 55364.0 56115.2 78954.6 79891.2 80070.0 80212.6 80228.6 7003.8 7746.4 7976.0 8103.0 8600.2 10961.6 13018.0 13191.1 13374.5 13441.5 5202.3 6687.9 11128.5 11277.0 11402.6 8453.2 10526.5 11181.0 11283.2 11300.0 10885.8 11122.3 11568.0 11589.0 11644.5 7415.7 7463.6 7491.8 7760.1 7796.0 8755.5 8914.5 10661.8 10702.3 10749.2 13:20:38 INFO - PROCESS | 1660 | 13:20:38 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5787.97 Median:4873.63 stddev:3252.36 (66.7%) stddev-sans-first:3245.35 13:20:38 INFO - PROCESS | 1660 | Values: 1477.3 1575.5 1862.7 1990.5 1996.4 8235.5 8554.5 8835.9 9266.8 9275.5 8723.3 8849.0 8872.0 8905.2 8953.1 2342.8 2411.1 2456.8 2469.9 2471.5 2202.3 2435.5 2477.9 2480.3 2482.3 2457.6 2460.7 2470.5 2471.8 2492.6 2396.4 2448.8 2456.9 2462.1 2462.3 2422.3 2434.1 2446.5 2458.8 2465.0 8734.8 8775.4 8779.8 8815.0 8879.0 5456.0 5500.2 5594.4 5600.3 5642.3 3321.9 3522.2 3589.7 3613.4 3631.0 840.1 961.5 995.6 998.8 998.9 10101.4 11871.5 11926.6 11979.7 12019.7 4470.5 4650.9 4926.2 4972.1 4987.4 8880.5 10176.0 10340.6 10390.3 10418.8 5194.0 5258.9 5274.3 5276.3 5576.4 4522.4 4866.3 4902.5 5038.1 5066.6 4129.6 4158.8 4190.0 4214.7 4270.0 1317.8 1345.9 1364.3 1368.4 1372.4 847.2 859.6 905.8 944.4 960.8 5892.6 5912.5 5989.6 6039.0 6104.3 411.5 412.5 415.2 423.9 427.9 3837.8 3942.3 3985.8 4013.8 4061.2 10593.4 10781.6 10940.9 10974.3 10980.1 10811.4 10813.8 10833.2 10850.1 10959.7 7946.4 8335.4 8343.3 8389.9 8418.9 6099.8 6102.4 6122.8 6165.9 6203.3 10025.0 10249.4 10268.6 10269.8 10278.4 10008.4 10353.4 10476.6 10477.8 10529.7 7046.5 7431.6 7595.9 7662.5 7773.6 7100.7 8439.1 8481.0 8497.8 8519.7 7421.9 7514.7 7572.6 7621.6 7664.9 7430.5 7448.9 7471.2 7546.3 7566.8 7079.9 7099.5 7162.4 7182.7 7211.2 4772.7 4802.5 4932.7 4942.6 5101.9 4201.5 4881.0 5027.8 5073.2 5084.2 13:20:38 INFO - PROCESS | 1660 | 13:20:38 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4782.79 Median:5367.69 stddev:1540.53 (28.7%) stddev-sans-first:1507.93 13:20:38 INFO - PROCESS | 1660 | Values: 315.7 317.8 321.3 321.8 327.9 4832.1 4837.9 5218.9 5837.0 5854.1 4528.8 4804.8 5426.6 5536.6 5572.6 3752.2 3819.7 4116.1 4249.2 4381.8 3387.1 3625.7 4269.9 4302.8 4310.7 3414.9 3567.9 3872.6 4088.4 4297.6 3118.9 3563.5 4156.3 4239.0 4330.7 3325.0 3416.6 3482.8 3643.8 3683.9 4464.5 4547.6 5026.5 5495.5 5534.8 4823.4 4935.3 4990.9 5012.7 5070.8 4822.0 5422.9 5954.9 6039.7 6076.8 4043.6 4066.5 4066.6 4070.7 4098.2 5177.5 5277.8 5416.6 6129.7 6245.6 3281.9 3326.3 3404.4 4076.8 4097.2 3551.4 4169.7 4174.7 4181.7 4184.3 4834.9 4903.8 4925.3 4935.3 4978.5 4946.0 5433.6 6145.1 6145.4 6156.4 1307.0 1365.3 1428.1 1430.9 1435.6 740.1 788.6 816.9 886.3 932.6 4785.5 5602.5 5625.2 5628.9 5638.6 6217.7 6218.8 6223.8 6250.7 6289.2 4772.2 5083.9 5375.8 5612.2 5623.4 2880.6 3231.9 3281.1 3330.4 3340.4 5528.9 5967.9 6320.5 6409.6 6526.3 6338.7 6358.8 6368.3 6393.9 6404.2 5375.6 6149.0 6377.6 6457.4 6476.5 4763.5 5116.2 5498.6 6025.6 6109.9 5918.4 6066.1 6119.8 6131.8 6131.9 5359.8 6222.3 6444.9 6465.6 6565.4 3474.8 3996.7 4007.1 4035.7 4052.5 5179.3 6241.9 6308.3 6328.2 6329.1 5784.7 6092.3 6102.7 6104.3 6104.3 5730.9 6047.8 6066.9 6110.0 6163.4 5697.7 5855.5 5867.9 5877.6 6003.7 5607.6 5619.0 5966.7 6121.2 6154.8 5118.0 5180.0 5781.0 6057.8 6060.2 13:20:38 INFO - PROCESS | 1660 | 13:20:38 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18777.24 Median:559.08 stddev:27083.20 (4844.2%) stddev-sans-first:27124.96 13:20:38 INFO - PROCESS | 1660 | Values: 645.3 669.3 747.4 757.1 780.5 15052.6 15527.0 15583.2 15691.8 15744.6 14695.6 14712.3 14771.0 14775.0 14798.6 697.5 903.2 908.4 926.4 934.0 917.4 918.4 925.5 926.0 929.8 970.0 985.8 990.0 990.8 991.2 883.7 944.4 975.0 977.6 984.4 698.4 700.2 720.7 726.2 731.1 15024.1 15127.2 15208.7 15237.3 15244.8 10644.3 12588.9 12701.6 12748.0 13002.9 9903.3 9949.1 10190.2 10525.0 10641.9 3499.7 3535.9 3563.5 3623.9 3633.3 83744.0 84354.0 88561.1 88595.0 90625.0 9484.2 9514.7 9568.1 9758.5 9800.0 22541.9 22731.2 22779.0 22805.0 23040.8 6929.0 7333.9 7472.1 7529.8 7613.5 3843.6 3902.8 3925.2 3939.2 3941.2 3510.4 3583.3 3625.6 3639.9 3653.5 658.9 661.5 663.1 666.1 667.9 501.7 505.5 539.2 550.8 561.0 7787.9 7878.8 7955.1 8072.9 8139.5 495.6 529.6 547.6 557.2 587.4 3649.6 3736.4 3769.2 3879.3 3931.4 89251.0 94455.5 99330.5 102549.5 102788.0 86461.0 86843.6 87039.6 87928.1 91422.5 73485.5 73872.6 74002.6 74388.0 76081.2 8072.2 8300.3 8502.1 8587.7 8615.8 27854.0 46581.0 47140.0 47483.8 48277.8 48770.8 62700.0 65533.0 66168.7 66840.0 11552.9 11676.2 11739.8 11775.9 11928.0 11974.6 12030.0 12083.6 12188.6 12312.6 11714.6 11828.3 11946.6 12014.2 12058.8 9656.7 10084.8 11386.4 11851.5 11913.9 9621.8 10840.8 11065.3 11299.7 11578.5 9499.2 9573.8 9677.8 9745.1 9816.4 9998.7 10095.7 10129.9 10131.4 10145.6 13:20:38 INFO - PROCESS | 1660 | -------- Summary: end -------- 13:20:38 INFO - PROCESS | 1660 | 13:20:38 INFO - TEST-INFO | 1660: exit 0 13:20:38 INFO - TEST-OK | dromaeo_css | took 1112942ms 13:20:38 INFO - TEST-START | kraken 13:20:38 INFO - operating with platform_type : mac_ 13:20:38 INFO - Initialising browser for kraken test... 13:20:38 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpPrvYwB/profile http://localhost:49278/getInfo.html) 13:20:41 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 13:20:41 INFO - PROCESS | 1671 | colorDepth:24 13:20:41 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 13:20:41 INFO - PROCESS | 1671 | __metrics 13:20:41 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:20:42 INFO - TEST-INFO | 1671: exit 0 13:20:42 INFO - Browser initialized. 13:20:42 INFO - Running cycle 1/1 for kraken test... 13:20:42 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpPrvYwB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:20:54 INFO - PROCESS | 1674 | RSS: Main: 249999360 13:20:54 INFO - PROCESS | 1674 | 13:20:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 13:20:57 INFO - PROCESS | 1674 | RSS: Main: 262000640 13:20:57 INFO - PROCESS | 1674 | 13:21:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 13:21:01 INFO - PROCESS | 1674 | RSS: Main: 266661888 13:21:01 INFO - PROCESS | 1674 | 13:21:04 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 13:21:05 INFO - PROCESS | 1674 | RSS: Main: 267100160 13:21:05 INFO - PROCESS | 1674 | 13:21:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 13:21:07 INFO - PROCESS | 1674 | RSS: Main: 267776000 13:21:07 INFO - PROCESS | 1674 | 13:21:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:21:10 INFO - PROCESS | 1674 | RSS: Main: 268435456 13:21:10 INFO - PROCESS | 1674 | 13:21:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 13:21:14 INFO - PROCESS | 1674 | RSS: Main: 268800000 13:21:14 INFO - PROCESS | 1674 | 13:21:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 13:21:17 INFO - PROCESS | 1674 | RSS: Main: 270049280 13:21:17 INFO - PROCESS | 1674 | 13:21:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 13:21:21 INFO - PROCESS | 1674 | RSS: Main: 270766080 13:21:21 INFO - PROCESS | 1674 | 13:21:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:21:23 INFO - PROCESS | 1674 | RSS: Main: 270589952 13:21:23 INFO - PROCESS | 1674 | 13:21:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:21:26 INFO - PROCESS | 1674 | RSS: Main: 270446592 13:21:26 INFO - PROCESS | 1674 | 13:21:28 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:21:29 INFO - PROCESS | 1674 | RSS: Main: 270725120 13:21:29 INFO - PROCESS | 1674 | 13:21:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:21:33 INFO - PROCESS | 1674 | RSS: Main: 271024128 13:21:33 INFO - PROCESS | 1674 | 13:21:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:21:36 INFO - PROCESS | 1674 | RSS: Main: 271298560 13:21:36 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 13:21:38 INFO - PROCESS | 1674 | RSS: Main: 272670720 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | __start_tp_report 13:21:38 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 13:21:38 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 13:21:38 INFO - PROCESS | 1674 | |i|pagename|runs| 13:21:38 INFO - PROCESS | 1674 | |0;ai-astar;163;167;124;115;117;115;107;118;116;113 13:21:38 INFO - PROCESS | 1674 | |1;audio-beat-detection;156;145;134;235;149;135;129;138;203;150 13:21:38 INFO - PROCESS | 1674 | |2;audio-dft;187;204;211;209;199;192;198;194;199;195 13:21:38 INFO - PROCESS | 1674 | |3;audio-fft;71;83;76;173;57;58;64;70;156;56 13:21:38 INFO - PROCESS | 1674 | |4;audio-oscillator;81;82;141;78;84;81;117;82;90;123 13:21:38 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;93;88;91;88;89;87;91;88;90;88 13:21:38 INFO - PROCESS | 1674 | |6;imaging-darkroom;95;95;97;96;95;96;96;95;95;97 13:21:38 INFO - PROCESS | 1674 | |7;imaging-desaturate;91;92;90;89;89;89;92;93;122;142 13:21:38 INFO - PROCESS | 1674 | |8;json-parse-financial;61;58;57;59;60;59;59;121;60;57 13:21:38 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;64;66;64;72;70;69;65;63;62;62 13:21:38 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;85;84;90;86;86;82;89;108;103;88 13:21:38 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;147;150;161;150;155;138;149;157;217;145 13:21:38 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;167;165;165;163;165;165;165;164;161;163 13:21:38 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;66;65;66;64;67;62;63;65;62;64 13:21:38 INFO - PROCESS | 1674 | __end_tp_report 13:21:38 INFO - PROCESS | 1674 | __start_cc_report 13:21:38 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1090 13:21:38 INFO - PROCESS | 1674 | __end_cc_report 13:21:38 INFO - PROCESS | 1674 | __startTimestamp1463602898385__endTimestamp 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | ------- Summary: start ------- 13:21:38 INFO - PROCESS | 1674 | Number of tests: 14 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:125.50 Median:117.50 stddev:21.26 (18.1%) stddev-sans-first:17.70 13:21:38 INFO - PROCESS | 1674 | Values: 163.0 167.0 124.0 115.0 117.0 115.0 107.0 118.0 116.0 113.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:157.40 Median:149.50 stddev:34.34 (23.0%) stddev-sans-first:36.42 13:21:38 INFO - PROCESS | 1674 | Values: 156.0 145.0 134.0 235.0 149.0 135.0 129.0 138.0 203.0 150.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:198.80 Median:199.00 stddev:7.48 (3.8%) stddev-sans-first:6.60 13:21:38 INFO - PROCESS | 1674 | Values: 187.0 204.0 211.0 209.0 199.0 192.0 198.0 194.0 199.0 195.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:86.40 Median:67.00 stddev:42.25 (63.1%) stddev-sans-first:44.45 13:21:38 INFO - PROCESS | 1674 | Values: 71.0 83.0 76.0 173.0 57.0 58.0 64.0 70.0 156.0 56.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:81.50 stddev:22.46 (27.6%) stddev-sans-first:23.17 13:21:38 INFO - PROCESS | 1674 | Values: 81.0 82.0 141.0 78.0 84.0 81.0 117.0 82.0 90.0 123.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:89.50 stddev:1.89 (2.1%) stddev-sans-first:1.45 13:21:38 INFO - PROCESS | 1674 | Values: 93.0 88.0 91.0 88.0 89.0 87.0 91.0 88.0 90.0 88.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 13:21:38 INFO - PROCESS | 1674 | Values: 95.0 95.0 97.0 96.0 95.0 96.0 96.0 95.0 95.0 97.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:98.90 Median:90.50 stddev:18.13 (20.0%) stddev-sans-first:19.00 13:21:38 INFO - PROCESS | 1674 | Values: 91.0 92.0 90.0 89.0 89.0 89.0 92.0 93.0 122.0 142.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:59.00 stddev:19.68 (33.4%) stddev-sans-first:20.82 13:21:38 INFO - PROCESS | 1674 | Values: 61.0 58.0 57.0 59.0 60.0 59.0 59.0 121.0 60.0 57.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.50 stddev:3.50 (5.3%) stddev-sans-first:3.66 13:21:38 INFO - PROCESS | 1674 | Values: 64.0 66.0 64.0 72.0 70.0 69.0 65.0 63.0 62.0 62.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:86.00 stddev:8.53 (9.9%) stddev-sans-first:8.85 13:21:38 INFO - PROCESS | 1674 | Values: 85.0 84.0 90.0 86.0 86.0 82.0 89.0 108.0 103.0 88.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:156.90 Median:152.50 stddev:22.08 (14.5%) stddev-sans-first:23.12 13:21:38 INFO - PROCESS | 1674 | Values: 147.0 150.0 161.0 150.0 155.0 138.0 149.0 157.0 217.0 145.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:165.00 stddev:1.64 (1.0%) stddev-sans-first:1.41 13:21:38 INFO - PROCESS | 1674 | Values: 167.0 165.0 165.0 163.0 165.0 165.0 165.0 164.0 161.0 163.0 13:21:38 INFO - PROCESS | 1674 | 13:21:38 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:1.71 (2.6%) stddev-sans-first:1.72 13:21:38 INFO - PROCESS | 1674 | Values: 66.0 65.0 66.0 64.0 67.0 62.0 63.0 65.0 62.0 64.0 13:21:38 INFO - PROCESS | 1674 | -------- Summary: end -------- 13:21:38 INFO - PROCESS | 1674 | 13:21:39 INFO - TEST-INFO | 1674: exit 0 13:21:39 INFO - TEST-OK | kraken | took 60367ms 13:21:39 INFO - SUITE-END | took 1173s 13:21:39 INFO - Completed test suite (00:19:33) 13:21:39 INFO - javascript benchmark 13:21:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7282.210054137355, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2926.7658587313017, 3459.273552553964, 3486.180747524333, 3493.12671832042, 3506.0, 5786.450287222712, 5819.418058194178, 5828.388019257975, 5874.0, 5880.882382352351, 4449.532799056093, 5781.508571771405, 5865.648061116336, 5951.732172052257, 5983.0726237433155, 5215.0, 5292.655977361478, 5401.567874570044, 5416.91874621881, 5473.534749546274, 387.1634406774351, 5266.683998960056, 5271.103912334893, 5383.977044361559, 5397.298351214336, 5288.947110528903, 5305.4164041955355, 5313.574914006909, 5343.839684809443, 5361.597880159004, 5341.332333458317, 5351.331083614548, 5365.436629153922, 5379.515843574097, 5389.973050134763, 5174.456682048369, 5228.8692782680355, 5293.206019097128, 5331.466853314676, 5359.490848369399, 5222.895542089175, 5566.443355664442, 5741.1388291756575, 5755.769769209227, 5768.538516918637, 5964.194833697438, 6243.188385509855, 6304.1804565406665, 6310.5898116622275, 6312.810615681537, 6222.502199824004, 6266.811995640138, 6288.151099601587, 6337.144485494446, 6340.841478963017, 6058.363871793443, 6083.908741368883, 6095.786647467317, 6100.359462256399, 6110.663913484756, 6240.438360547572, 6288.27684816252, 6458.869697802866, 6481.546291759531, 6496.512761542903, 6205.503559715212, 6217.0, 6262.968685156637, 6271.6550589718, 6288.056791481315, 6266.811995640138, 6277.0, 6364.840878978062, 6368.649724265118, 6373.521985850987, 6216.067589861466, 6236.345183755731, 6276.560640755103, 6294.866923953732, 6304.464120549713, 6266.561340706106, 6283.842903927347, 6292.937070629235, 6297.811065668037, 6302.0, 5076.035553244872, 5112.821051263262, 5114.232865070269, 5114.43741188469, 5119.948800512022, 3807.371783655721, 4422.226110430662, 4492.124035812985, 4556.886077848014, 4652.534746525321, 5505.898820235889, 5823.126531020381, 5851.1223316502865, 5973.910391344133, 6009.06859436788, 5739.253896993364, 6323.9683801580695, 6325.746970121236, 6339.2709838368255, 6342.5243106767175, 5825.38833422493, 5865.618734782311, 5992.610480318765, 6038.0, 6080.69596520181, 5547.472990065938, 5580.748866301026, 5588.385277619459, 5595.356533998643, 5598.356189038232, 6131.816045518642, 6583.407493325623, 6586.077949087132, 6609.239937407259, 6613.867722645617, 6159.538034647509, 6230.43926046658, 6272.843178920563, 6275.96862015687, 6331.366863313632, 4963.975180124076, 5761.164631128547, 6038.9396106038375, 6515.902261465987, 6561.704723287559, 4770.9980904010545, 5690.857728556819, 6205.22434695663, 6307.148534947723, 6316.305206427198, 6129.387061293835, 6231.781887633911, 6232.8130156095385, 6260.968695156495, 6329.778457754141, 6217.689115544114, 6273.466755325666, 6281.654509002049, 6283.71723272463, 6320.905186422116, 6105.399096011382, 6105.725242364016, 6269.404406581368, 6275.278342990432, 6344.302126766144, 5835.912461313169, 5891.1457838612305, 6017.789377371771, 6065.909011364922, 6110.511159107172, 6166.167567378347, 6179.104029915727, 6190.164327815687, 6192.845178870564, 6193.81418557444, 6050.092486127116, 6087.269527656709, 6101.60339577926, 6111.561351782648, 6148.923938310688], "value": 5737.503737254391, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.6089280807375, 528.3474908488016, 529.4044200274691, 530.0459173487724, 561.7444062951357, 36414.635853641455, 38488.0, 39597.406038909365, 39727.80136099315, 39778.0, 40626.59373406265, 41316.0, 41359.58640413595, 41506.79246603763, 41582.58417415825, 8652.61063252152, 9368.999999999984, 11722.062235021178, 11764.529418823236, 11963.461644225987, 11627.244229125079, 11628.941855290712, 11637.592684256053, 11647.941760291229, 11712.180147389687, 11648.126390520703, 11840.815918408176, 11854.822177667342, 11869.0, 11874.465649045771, 11739.0021848143, 11763.35301558414, 11828.0, 11838.763224735458, 11861.932426081694, 10013.949930250375, 11727.824082638766, 11853.703657408547, 11876.16866819323, 12005.939970300093, 39689.60310396888, 39828.402573961415, 40009.79970600887, 40181.99545011369, 40222.0, 3215.1962009497624, 3325.6840600142946, 3449.0, 3791.374423220165, 3872.806359682033, 28713.995010174753, 29026.709732902822, 29264.853675731698, 29270.41459170826, 29783.65973531196, 2698.892044318225, 2713.9050133245437, 2723.305557082931, 2843.644544431946, 2906.6999880994867, 534456.0, 541115.999999996, 541118.0, 541259.0, 541750.0, 59227.81544369089, 59369.10946335809, 59740.0, 59837.401625983184, 59917.10124348138, 63217.683911581065, 63653.0, 63816.0, 63981.0, 64043.0, 14924.403023878924, 16105.758413623806, 16397.59006024859, 16592.170391480377, 16741.079240641637, 11166.720831979266, 12412.751744965231, 12434.502619895104, 12735.917447016922, 13002.804957925639, 15576.706274380665, 15631.3747450103, 15633.296501657453, 15670.216489175496, 15725.685486290438, 5891.646501209855, 6933.722651094001, 7004.509684322151, 7030.543014704028, 7034.050403195605, 920.2270093121676, 981.66623348062, 1009.6415772400838, 1084.3277168155794, 1135.1259530161728, 28378.574321385197, 29434.705652943197, 29632.851835741116, 29902.401951960837, 30321.24196895095, 883.743714322852, 1061.7027232374946, 1114.2979922648678, 1121.7363919478917, 1226.8037114061708, 12225.93887030577, 15312.387504499695, 15515.37938482448, 15520.92239538795, 15631.3747450103, 664851.0, 677620.0, 684253.0, 684302.0, 686617.0, 59745.0, 60241.39758602445, 60553.69723151356, 60719.08921366183, 60733.69633151894, 1427.9500217492339, 1448.6740483391363, 1480.8671366404924, 1488.4492737686908, 1490.7987421697933, 25126.748732512442, 29401.0, 29514.409711805216, 29732.256693582833, 30078.699213008465, 51186.74406627943, 51217.7439112802, 51257.71855703637, 51277.7436112817, 51489.97020059503, 499810.0, 502047.0, 504329.9134017372, 580219.0, 581857.0, 32499.400047996576, 38506.0, 38875.6112438872, 39898.800505997286, 41859.0, 44709.55290447054, 45135.32297015482, 45763.54236457593, 46437.53562464332, 47658.04683906372, 35683.82158089193, 37856.0, 37971.62028379681, 38433.23133537258, 38744.0, 36560.45159322665, 37583.62416375911, 37717.0, 37951.62048379481, 38160.80919595384, 36807.0, 37245.81377093206, 37991.81004094962, 38359.42460863033, 38810.41784373293, 32758.34483310273, 33066.834665826515, 33177.0, 33613.83193084019, 33651.0, 23615.055397784585, 24110.276691699277, 24734.505309894063, 24804.131855384974, 25265.87367063153], "value": 20441.74274764167, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.47851362247286, 391.5418959817012, 393.1782574419463, 407.5761208343323, 411.9320312148496, 16078.954867933591, 17587.91206043968, 19074.809251907493, 20910.790892091074, 21061.789382106195, 17925.551861203476, 20874.26940057098, 21382.144714211452, 21467.46331341717, 21702.565948681055, 5876.353601103875, 5976.402359764011, 6212.78255261066, 6240.282367527747, 6336.112944187817, 6092.634441933476, 6114.571979961404, 6149.2313460817395, 6159.7536098556, 6164.691765411734, 5526.364468086182, 5858.7949421770245, 5901.645901245917, 5907.822765317026, 6100.969495152518, 4890.24201248807, 5872.677002764846, 5879.147523609074, 5934.109883517485, 6082.1180928765525, 4996.875078123039, 5755.913661295084, 5843.8539036524, 5851.005329094064, 5855.151003104568, 13374.780726961912, 13583.0, 13600.591982240549, 13736.0, 13830.792538111937, 2085.384811480617, 2155.2025750472267, 2175.8041776240057, 2235.787600177997, 2241.7197850268717, 9344.766380840465, 9761.511924403823, 10047.497625118714, 10170.796584068352, 10272.589096436133, 1737.287712038071, 1780.1455301455244, 1804.684180268461, 1806.9168858076373, 1844.3729132095152, 76909.0, 106658.0, 125377.0, 126926.0, 130761.0, 9154.588043538057, 11766.294022358687, 11786.94106529479, 11906.0, 11983.161178717406, 14729.04261223017, 15080.095194288158, 15082.773758393632, 15201.31594078247, 15244.695106097815, 8687.435316704394, 8818.074102219303, 8858.911410885938, 8945.37382383227, 8962.86555701665, 8701.825963480695, 8765.693200737944, 8791.780205494786, 8854.424462409923, 8877.845880035555, 4573.47405048417, 4587.42657167854, 4654.627629789608, 4709.811607535728, 4757.881052973634, 1973.58554703511, 1998.8500862435085, 2018.0515157875775, 2026.8682535635137, 2075.9169633214806, 939.6053657463882, 939.8815409662479, 969.5927710361524, 990.7424069741919, 1016.7559785651538, 18652.720209196603, 20116.497087572927, 21109.68335474969, 21302.893485532473, 21411.0, 1009.6163457885956, 1014.6854475112739, 1017.877854657431, 1022.6420752736483, 1055.0451841083832, 9089.136532029446, 9789.070038346346, 11300.039496642878, 11312.264702794291, 11321.433928303717, 518443.0, 522198.55602887727, 536060.0, 592679.0, 629823.0, 112671.87328126613, 113900.0, 115430.0, 143273.0, 148332.0, 848.758103940374, 931.2968708624945, 1068.3856782350024, 1086.1962148010416, 1088.3034857691084, 18672.25310987545, 18688.25246990105, 18925.526861828563, 18975.292223699933, 19228.327008554632, 52311.430657080346, 54762.452375477325, 55035.72482137564, 55364.0, 56115.15827262676, 78954.6052269735, 79891.20108798837, 80070.0, 80212.59893700494, 80228.59885700535, 7003.789886303419, 7746.419018573517, 7976.0, 8102.959485202537, 8600.22597966186, 10961.56153753841, 13018.023648226232, 13191.142575732547, 13374.531891383755, 13441.529546465827, 5202.3497062867145, 6687.899681504879, 11128.499217535069, 11277.041451476389, 11402.600908968147, 8453.239208471266, 10526.473676316, 11181.0, 11283.153763467608, 11300.0, 10885.836712449161, 11122.332660040423, 11568.0, 11589.01493373039, 11644.475998579916, 7415.740449084256, 7463.552186868806, 7491.775246742606, 7760.146383897654, 7796.0, 8755.474671519474, 8914.465132092095, 10661.786764264827, 10702.250842440955, 10749.19381046409], "value": 9342.350558594826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1477.3499660149535, 1575.5430925031737, 1862.7205919112132, 1990.4625751047208, 1996.3911007142824, 8235.505869647825, 8554.52950087745, 8835.867461988075, 9266.768330791725, 9275.489848058363, 8723.345749068814, 8849.008972981075, 8872.024077351502, 8905.198532132106, 8953.05992870749, 2342.781753488185, 2411.1440438644336, 2456.7666071723156, 2469.938251543708, 2471.530409222251, 2202.3392982105383, 2435.5250726108416, 2477.913273035444, 2480.255923223026, 2482.2925466242123, 2457.606782914734, 2460.692413448319, 2470.5429495543294, 2471.789897858684, 2492.5513407586627, 2396.35298469414, 2448.8285819992607, 2456.9385765355833, 2462.101333013455, 2462.347477918353, 2422.321749910028, 2434.0993832281993, 2446.5351583199135, 2458.754124587545, 2464.9753502465105, 8734.781630459225, 8775.385722999392, 8779.824403511893, 8815.0, 8878.955605221932, 5456.045192091368, 5500.1749737539285, 5594.412586678422, 5600.327960644708, 5642.294713160855, 3321.90034298969, 3522.2427178156563, 3589.7128229741556, 3613.403788374915, 3631.0377749896297, 840.1220724343076, 961.5096300886528, 995.5619527407918, 998.8401855703125, 998.8501724741275, 10101.393916365041, 11871.52513899443, 11926.58256961011, 11979.700507487296, 12019.699507512294, 4470.530594287618, 4650.907036846338, 4926.211806111005, 4972.129877271463, 4987.351644286219, 8880.51157186361, 10175.982401759913, 10340.586376545005, 10390.272680912414, 10418.791624167627, 5193.974030129825, 5258.947410525922, 5274.340707411574, 5276.34045744282, 5576.414476479992, 4522.412086428743, 4866.29438731389, 4902.534259245366, 5038.118329292359, 5066.569341605931, 4129.616578446194, 4158.812853421603, 4190.0, 4214.704970652087, 4269.978650106729, 1317.841858976929, 1345.9125156864773, 1364.3178410794603, 1368.4184221705734, 1372.3961456958907, 847.169773621854, 859.5659192107945, 905.791667916383, 944.367273926471, 960.8270511307892, 5892.616979896293, 5912.497437717842, 5989.55078369124, 6039.0035644118225, 6104.32852386246, 411.54935345796906, 412.4617374326473, 415.15723082143296, 423.88131323229544, 427.8737772357162, 3837.76973381598, 3942.250972315251, 3985.8405663773706, 4013.7792421417103, 4061.2080644274083, 10593.364398135982, 10781.568737250578, 10940.890591093987, 10974.341539507655, 10980.121590272758, 10811.405372704578, 10813.783724325469, 10833.187510936712, 10850.0777433919, 10959.726006849733, 7946.443748937519, 8335.416520843604, 8343.290820280345, 8389.874151887849, 8418.873716894252, 6099.75600975956, 6102.389761023862, 6122.755089796358, 6165.876682466236, 6203.2556093269095, 10024.997500249916, 10249.436281004617, 10268.64059757905, 10269.84595231087, 10278.434686092336, 10008.39949603026, 10353.378797272188, 10476.63331783384, 10477.790444190921, 10529.684109476728, 7046.541974771623, 7431.591262480615, 7595.886061708969, 7662.54024758494, 7773.572453515111, 7100.680469378787, 8439.071702112886, 8481.0, 8497.83004339922, 8519.701810436605, 7421.888671669822, 7514.661840217313, 7572.621368931642, 7621.580813055335, 7664.885026724715, 7430.517016393917, 7448.851022979618, 7471.215522370073, 7546.320831125225, 7566.810829729301, 7079.858402831812, 7099.544593358246, 7162.391196748335, 7182.676779545038, 7211.206767255493, 4772.7136371817805, 4802.543758342952, 4932.704037757746, 4942.55517003457, 5101.9489805101475, 4201.45381100455, 4880.974995251037, 5027.773750181177, 5073.213651883964, 5084.237364395371], "value": 4567.436692113647, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.70639305445934, 317.8299609708806, 321.30437602590393, 321.80369974315664, 327.90982479818064, 4832.081904438154, 4837.8790530236765, 5218.869528261791, 5836.970815145929, 5854.121881717734, 4528.7509186994785, 4804.759762011903, 5426.647267927582, 5536.640118392302, 5572.60991730579, 3752.1745216052423, 3819.720393668111, 4116.115035267417, 4249.215329561576, 4381.780910954455, 3387.0854869185305, 3625.7461977661574, 4269.893252668677, 4302.816725400508, 4310.672236992021, 3414.8901606977834, 3567.8929632111076, 3872.5740168581433, 4088.366303223005, 4297.613214810682, 3118.8635784487697, 3563.483294922222, 4156.334986402177, 4239.025024244425, 4330.696851220416, 3324.9692595295537, 3416.607090184611, 3482.8432720527385, 3643.8360273787744, 3683.8580040187626, 4464.508904020555, 4547.6357092872, 5026.547610715053, 5495.450454954513, 5534.75093620788, 4823.421189457287, 4935.284383764334, 4990.90018199634, 5012.749362531896, 5070.8225212117395, 4821.987382649648, 5422.91865622016, 5954.940450595525, 6039.667818269993, 6076.817695469143, 4043.555208927016, 4066.5323487798987, 4066.6340029397497, 4070.735402198877, 4098.180363927227, 5177.508136727005, 5277.8416647500635, 5416.610472579769, 6129.693515324216, 6245.625962288334, 3281.868725250963, 3326.284848757529, 3404.3531728971416, 4076.7553946763287, 4097.152479027107, 3551.401869158889, 4169.708120431601, 4174.72864263828, 4181.665466762652, 4184.28867092595, 4834.912144767399, 4903.754812259373, 4925.310456536088, 4935.284383764334, 4978.452370239271, 4945.975270123627, 5433.646812957145, 6145.053232148163, 6145.385461453909, 6156.445919867234, 1306.9803952940713, 1365.3105181883086, 1428.1359777334562, 1430.9125064950504, 1435.5621535431585, 740.1303468424579, 788.625402933602, 816.9183081691783, 886.2732559301439, 932.6176267730198, 4785.521447855187, 5602.467765562265, 5625.2436306209665, 5628.915566266509, 5638.633488823295, 6217.720202590895, 6218.751249949952, 6223.844403889849, 6250.718717657716, 6289.245290565162, 4772.2364421693055, 5083.872903177376, 5375.81184658535, 5612.214289999403, 5623.381428042912, 2880.5535142052595, 3231.935361292761, 3281.096229836564, 3330.3672302262494, 3340.398728228942, 5528.9494995950645, 5967.93762555438, 6320.462760665395, 6409.551331406757, 6526.34736526353, 6338.714757835908, 6358.837995081694, 6368.331325210879, 6393.872122557523, 6404.167458230493, 5375.623706340597, 6148.969255153875, 6377.553571249968, 6457.3865482779065, 6476.481881449473, 4763.452202996561, 5116.15583428745, 5498.615096943176, 6025.614108754923, 6109.938900611115, 5918.378570250234, 6066.090086487062, 6119.755209791559, 6131.785387511416, 6131.908021379593, 5359.758810853443, 6222.346653601307, 6444.938654139246, 6465.64438955862, 6565.353795735759, 3474.7915125092577, 3996.6611185253264, 4007.0583412898527, 4035.7376770510996, 4052.5005747873474, 5179.30079439286, 6241.875162496815, 6308.33762454954, 6328.208973878265, 6329.120250286017, 5784.7107644618445, 6092.299385570539, 6102.694865256808, 6104.298005729398, 6104.328523862283, 5730.885382292414, 6047.758089676364, 6066.908996365147, 6109.969450152721, 6163.3528479509005, 5697.720911635379, 5855.502282305882, 5867.946218823377, 5877.617954832922, 6003.6998150093195, 5607.607467477401, 5619.0, 5966.731497082555, 6121.204243448323, 6154.79981403622, 5118.0, 5180.0, 5781.017227071492, 6057.848553786191, 6060.212172417558], "value": 4288.172955227163, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.2901808011188, 669.3239827773948, 747.3684736397742, 757.0914902117458, 780.4536824223042, 15052.623684407881, 15527.0, 15583.220838958063, 15691.764623530657, 15744.60638484037, 14695.559133225994, 14712.337944792484, 14771.0, 14775.0, 14798.630034249149, 697.4873468001015, 903.1735961595166, 908.3596064774351, 926.3885835348672, 934.028610245344, 917.3807679816131, 918.4122161816442, 925.4817302310694, 925.9860452804169, 929.8233335666235, 969.970900872975, 985.8225519406504, 989.9802003959917, 990.8018396320729, 991.167419367731, 883.7304622090292, 944.3955868244329, 975.0152346130388, 977.6431602465085, 984.4200058905461, 698.4063545985871, 700.1528150937372, 720.7009091227135, 726.233823316401, 731.0788406607712, 15024.09855408679, 15127.243637818065, 15208.695826083527, 15237.314320855477, 15244.847551524454, 10644.327706941092, 12588.874111258861, 12701.555445559454, 12748.0, 13002.934985325108, 9903.306768526207, 9949.104580587782, 10190.184785217165, 10525.000124988113, 10641.893581064245, 3499.667531584521, 3535.9116022099397, 3563.501109844624, 3623.8550457981914, 3633.291508155911, 83744.0, 84354.0, 88561.11438885593, 88595.0, 90625.0, 9484.193843523377, 9514.666986655433, 9568.138867501959, 9758.46328451942, 9799.97100304472, 22541.872906354616, 22731.204407845646, 22779.0, 22804.97377618011, 23040.769592304197, 6929.0, 7333.853322933512, 7472.103347598214, 7529.774106776806, 7613.467057306047, 3843.6348546888003, 3902.824372903226, 3925.21495700861, 3939.153082087365, 3941.152652179795, 3510.350585141757, 3583.283343331344, 3625.619309972468, 3639.9090022749115, 3653.47024681425, 658.9176352955881, 661.5468404143178, 663.0650782396798, 666.0708311904963, 667.8946343801017, 501.68895284922854, 505.45158503023896, 539.1992890557519, 550.758040618411, 560.9566206855246, 7787.92212077872, 7878.803029924183, 7955.124936257007, 8072.919270807334, 8139.4709343892455, 495.56637941800926, 529.5736931769885, 547.6276132230042, 557.2059814763945, 587.3554048663724, 3649.5620525537106, 3736.4208547675153, 3769.227308401771, 3879.321118804198, 3931.3709806430834, 89251.0, 94455.52772236094, 99330.50334748425, 102549.48725256325, 102788.0, 86461.0, 86843.56578217195, 87039.56480217558, 87928.12071879327, 91422.54288728513, 73485.53028939391, 73872.63063684755, 74002.62998685079, 74388.0, 76081.23918760741, 8072.192780721881, 8300.335973122257, 8502.064772874855, 8587.65649374018, 8615.784605384915, 27854.0, 46581.0, 47140.0, 47483.81290467766, 48277.75861120672, 48770.75614622046, 62700.0, 65533.0, 66168.66915665583, 66840.0, 11552.884471155181, 11676.241044332091, 11739.823902641296, 11775.882241177822, 11928.0, 11974.64076077719, 12030.0, 12083.637490875288, 12188.573399931314, 12312.630621081382, 11714.589989350332, 11828.34944078084, 11946.581869634521, 12014.219075760047, 12058.819117713067, 9656.71029869105, 10084.798304033731, 11386.373749443861, 11851.52593896269, 11913.880861191277, 9621.807563848544, 10840.837387439036, 11065.336079835235, 11299.661010169708, 11578.536858525564, 9499.192568631745, 9573.825669455087, 9677.806443870943, 9745.12293893553, 9816.411015339103, 9998.700038998843, 10095.732823819713, 10129.949350253202, 10131.392116473035, 10145.60221870071], "value": 6948.528934760703, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 167.0, 124.0, 115.0, 117.0, 115.0, 107.0, 118.0, 116.0, 113.0], "value": 125.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 145.0, 134.0, 235.0, 149.0, 135.0, 129.0, 138.0, 203.0, 150.0], "value": 157.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 204.0, 211.0, 209.0, 199.0, 192.0, 198.0, 194.0, 199.0, 195.0], "value": 198.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 83.0, 76.0, 173.0, 57.0, 58.0, 64.0, 70.0, 156.0, 56.0], "value": 86.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 141.0, 78.0, 84.0, 81.0, 117.0, 82.0, 90.0, 123.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 88.0, 91.0, 88.0, 89.0, 87.0, 91.0, 88.0, 90.0, 88.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 97.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 90.0, 89.0, 89.0, 89.0, 92.0, 93.0, 122.0, 142.0], "value": 98.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 57.0, 59.0, 60.0, 59.0, 59.0, 121.0, 60.0, 57.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 64.0, 72.0, 70.0, 69.0, 65.0, 63.0, 62.0, 62.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 90.0, 86.0, 86.0, 82.0, 89.0, 108.0, 103.0, 88.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 161.0, 150.0, 155.0, 138.0, 149.0, 157.0, 217.0, 145.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 165.0, 163.0, 165.0, 165.0, 165.0, 164.0, 161.0, 163.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 64.0, 67.0, 62.0, 63.0, 65.0, 62.0, 64.0], "value": 64.4, "unit": "score"}]}]} 13:21:39 INFO - Return code: 0 13:21:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:21:39 ERROR - # TBPL SUCCESS # 13:21:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:21:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:21:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:21:39 INFO - 2016-05-18 13:21:39.699 screenresolution[1679:10305] starting screenresolution argv=screenresolution get 13:21:39 INFO - 2016-05-18 13:21:39.702 screenresolution[1679:10305] Display 0: 1600x1200x32@60 13:21:39 INFO - 2016-05-18 13:21:39.708 screenresolution[1680:10309] starting screenresolution argv=screenresolution list 13:21:39 INFO - Available Modes on Display 0 13:21:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:21:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:21:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:21:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:21:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:21:39 INFO - Intel Iris: 13:21:39 INFO - Chipset Model: Intel Iris 13:21:39 INFO - Type: GPU 13:21:39 INFO - Bus: Built-In 13:21:39 INFO - VRAM (Dynamic, Max): 1536 MB 13:21:39 INFO - Vendor: Intel (0x8086) 13:21:39 INFO - Device ID: 0x0a2e 13:21:39 INFO - Revision ID: 0x0009 13:21:39 INFO - Displays: 13:21:39 INFO - Display: 13:21:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:21:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:21:39 INFO - Display Serial Number: 1600x1200 60 13:21:39 INFO - Main Display: Yes 13:21:39 INFO - Mirror: Off 13:21:39 INFO - Online: Yes 13:21:39 INFO - Rotation: Supported 13:21:39 INFO - Return code: 0 13:21:39 INFO - Running post-action listener: _resource_record_post_action 13:21:39 INFO - ##### 13:21:39 INFO - ##### Finished run-tests step (success) 13:21:39 INFO - ##### 13:21:39 INFO - Running post-run listener: _resource_record_post_run 13:21:40 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 37676544; Write bytes: 695610368; Read time: 442; Write time: 2216 13:21:40 INFO - TinderboxPrint: CPU usage
54.4% 13:21:40 INFO - TinderboxPrint: I/O read bytes / time
37,676,544 / 442 13:21:40 INFO - TinderboxPrint: I/O write bytes / time
695,610,368 / 2,216 13:21:40 INFO - TinderboxPrint: CPU idle
2,204.4 (45.6%) 13:21:40 INFO - TinderboxPrint: CPU system
971.7 (20.1%) 13:21:40 INFO - TinderboxPrint: CPU user
1,661.6 (34.3%) 13:21:40 INFO - TinderboxPrint: Swap in / out
432,803,840 / 0 13:21:40 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 386867712; Write bytes: 380634624; Read time: 29616; Write time: 699 13:21:40 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35882496; Write bytes: 297657856; Read time: 375; Write time: 1473 13:21:40 INFO - Running post-run listener: _upload_blobber_files 13:21:40 INFO - Blob upload gear active. 13:21:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:21:40 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:40 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:40 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:40 INFO - (blobuploader) - INFO - Open directory for files ... 13:21:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:21:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:21:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:21:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:21:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:21:41 INFO - (blobuploader) - INFO - Done attempting. 13:21:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:21:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:21:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:21:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:21:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:21:43 INFO - (blobuploader) - INFO - Done attempting. 13:21:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:21:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:21:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:21:45 INFO - (blobuploader) - INFO - Done attempting. 13:21:45 INFO - (blobuploader) - INFO - Iteration through files over. 13:21:45 INFO - Return code: 0 13:21:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:21:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:21:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af688025ebcbc1cd6e6187add4270ebd7b47790e7a32a3e4ed7ce2478fd9af9121387f2deb1765b4062b0017747766231a5957cd156289b99d84b76d2c0dbd25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a978172c7b6e592c7c2ba2e631e78340d76bc17f4174c992fd208e8f8f9a0f76abeddca72fb027c695b1bd8f6d47c727348df5168aed53630531d9c48e3863f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13c927a3c6c9cb7806a3fadd4e802f04564aa18c010464c457d453e02e04d79ac77af49d9184529bb91bf92d594584fb5b861a81a276ba92fd119358bc211693"} 13:21:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:21:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:21:45 INFO - Contents: 13:21:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af688025ebcbc1cd6e6187add4270ebd7b47790e7a32a3e4ed7ce2478fd9af9121387f2deb1765b4062b0017747766231a5957cd156289b99d84b76d2c0dbd25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a978172c7b6e592c7c2ba2e631e78340d76bc17f4174c992fd208e8f8f9a0f76abeddca72fb027c695b1bd8f6d47c727348df5168aed53630531d9c48e3863f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13c927a3c6c9cb7806a3fadd4e802f04564aa18c010464c457d453e02e04d79ac77af49d9184529bb91bf92d594584fb5b861a81a276ba92fd119358bc211693"} 13:21:45 INFO - Running post-run listener: copy_logs_to_upload_dir 13:21:45 INFO - Copying logs to upload dir... 13:21:45 INFO - mkdir: /builds/slave/test/build/upload/logs 13:21:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.175035 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-18 13:21:45.736591) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:21:45.739908) ========= 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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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/af688025ebcbc1cd6e6187add4270ebd7b47790e7a32a3e4ed7ce2478fd9af9121387f2deb1765b4062b0017747766231a5957cd156289b99d84b76d2c0dbd25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a978172c7b6e592c7c2ba2e631e78340d76bc17f4174c992fd208e8f8f9a0f76abeddca72fb027c695b1bd8f6d47c727348df5168aed53630531d9c48e3863f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13c927a3c6c9cb7806a3fadd4e802f04564aa18c010464c457d453e02e04d79ac77af49d9184529bb91bf92d594584fb5b861a81a276ba92fd119358bc211693"} 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.011388 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/af688025ebcbc1cd6e6187add4270ebd7b47790e7a32a3e4ed7ce2478fd9af9121387f2deb1765b4062b0017747766231a5957cd156289b99d84b76d2c0dbd25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a978172c7b6e592c7c2ba2e631e78340d76bc17f4174c992fd208e8f8f9a0f76abeddca72fb027c695b1bd8f6d47c727348df5168aed53630531d9c48e3863f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13c927a3c6c9cb7806a3fadd4e802f04564aa18c010464c457d453e02e04d79ac77af49d9184529bb91bf92d594584fb5b861a81a276ba92fd119358bc211693"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 13:21:45.771790) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:21:45.772090) ========= 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.a9SMYp3C1l/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.YqFAw3hDhJ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.004992 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 13:21:45.820418) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:21:45.820736) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 13:21:45.821084) ========= ========= Total master_lag: 0.16 =========