builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1479135054.79 results: retry (5) buildid: 20161114021920 builduid: 34a00fa3aee34299865356c4ee83715c revision: 2d9b6132e7d75327e063a15d8e5e279077adf987 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:54.790503) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:54.790882) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:54.791279) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:54.820815) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:54.821109) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-14 06:50:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.8M=0s 2016-11-14 06:50:55 (40.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174312 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:55.010494) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:55.010816) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014653 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:55.060181) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:55.060550) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2d9b6132e7d75327e063a15d8e5e279077adf987 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2d9b6132e7d75327e063a15d8e5e279077adf987 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-14 06:50:55,829 truncating revision to first 12 chars 2016-11-14 06:50:55,829 Setting DEBUG logging. 2016-11-14 06:50:55,829 attempt 1/10 2016-11-14 06:50:55,829 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2d9b6132e7d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 06:50:56,313 unpacking tar archive at: mozilla-aurora-2d9b6132e7d7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.837060 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:55.911253) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:55.911577) ========= script_repo_revision: 2d9b6132e7d75327e063a15d8e5e279077adf987 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:55.911970) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:55.912249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 06:50:55.921201) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 38 secs) (at 2016-11-14 06:50:55.921620) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:50:56 INFO - MultiFileLogger online at 20161114 06:50:56 in /builds/slave/test 06:50:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:50:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:50:56 INFO - {'append_to_log': False, 06:50:56 INFO - 'base_work_dir': '/builds/slave/test', 06:50:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:50:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:50:56 INFO - 'branch': 'Mozilla-Aurora', 06:50:56 INFO - 'buildbot_json_path': 'buildprops.json', 06:50:56 INFO - 'config_files': ('talos/linux_config.py',), 06:50:56 INFO - 'default_actions': ('clobber', 06:50:56 INFO - 'read-buildbot-config', 06:50:56 INFO - 'download-and-extract', 06:50:56 INFO - 'populate-webroot', 06:50:56 INFO - 'create-virtualenv', 06:50:56 INFO - 'install', 06:50:56 INFO - 'run-tests'), 06:50:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:50:56 INFO - 'download_minidump_stackwalk': True, 06:50:56 INFO - 'download_symbols': 'ondemand', 06:50:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:50:56 INFO - 'tooltool.py': '/tools/tooltool.py', 06:50:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:50:56 INFO - '/tools/misc-python/virtualenv.py')}, 06:50:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:50:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:50:56 INFO - 'installer_path': 'installer.exe', 06:50:56 INFO - 'log_level': 'info', 06:50:56 INFO - 'log_name': 'talos', 06:50:56 INFO - 'log_to_console': True, 06:50:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:50:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:50:56 INFO - 'opt_config_files': (), 06:50:56 INFO - 'pip_index': False, 06:50:56 INFO - 'sps_profile': False, 06:50:56 INFO - 'sps_profile_interval': 0, 06:50:56 INFO - 'suite': 'dromaeojs', 06:50:56 INFO - 'system_bits': '32', 06:50:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:50:56 INFO - 'title': 'talos-linux64-ix-004', 06:50:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:50:56 INFO - 'use_talos_json': True, 06:50:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:50:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:50:56 INFO - 'work_dir': 'build'} 06:50:56 INFO - [mozharness: 2016-11-14 14:50:56.782053Z] Running clobber step. 06:50:56 INFO - Running pre-action listener: _resource_record_pre_action 06:50:56 INFO - Running main action method: clobber 06:50:56 INFO - rmtree: /builds/slave/test/build 06:50:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:50:57 INFO - Running post-action listener: _resource_record_post_action 06:50:57 INFO - [mozharness: 2016-11-14 14:50:57.262793Z] Finished clobber step (success) 06:50:57 INFO - [mozharness: 2016-11-14 14:50:57.262894Z] Running read-buildbot-config step. 06:50:57 INFO - Running pre-action listener: _resource_record_pre_action 06:50:57 INFO - Running main action method: read_buildbot_config 06:50:57 INFO - Using buildbot properties: 06:50:57 INFO - { 06:50:57 INFO - "project": "", 06:50:57 INFO - "product": "firefox", 06:50:57 INFO - "script_repo_revision": "production", 06:50:57 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:50:57 INFO - "repository": "", 06:50:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:50:57 INFO - "buildid": "20161114021920", 06:50:57 INFO - "pgo_build": "True", 06:50:57 INFO - "basedir": "/builds/slave/test", 06:50:57 INFO - "buildnumber": 585, 06:50:57 INFO - "slavename": "talos-linux64-ix-004", 06:50:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 06:50:57 INFO - "platform": "ubuntu64_hw", 06:50:57 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:50:57 INFO - "branch": "mozilla-aurora", 06:50:57 INFO - "repo_path": "releases/mozilla-aurora", 06:50:57 INFO - "revision": "2d9b6132e7d75327e063a15d8e5e279077adf987", 06:50:57 INFO - "stage_platform": "linux64-pgo", 06:50:57 INFO - "builduid": "34a00fa3aee34299865356c4ee83715c", 06:50:57 INFO - "slavebuilddir": "test" 06:50:57 INFO - } 06:50:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 06:50:57 INFO - Running post-action listener: _resource_record_post_action 06:50:57 INFO - [mozharness: 2016-11-14 14:50:57.265349Z] Finished read-buildbot-config step (success) 06:50:57 INFO - [mozharness: 2016-11-14 14:50:57.265438Z] Running download-and-extract step. 06:50:57 INFO - Running pre-action listener: _resource_record_pre_action 06:50:57 INFO - Running main action method: download_and_extract 06:50:57 INFO - mkdir: /builds/slave/test/build/tests 06:50:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:50:57 INFO - trying https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 06:50:57 INFO - Downloading https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 06:50:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:50:57 INFO - Downloaded 1361 bytes. 06:50:57 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 06:50:57 INFO - Using the following test package requirements: 06:50:57 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 06:50:57 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 06:50:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:50:57 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 06:50:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:50:57 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 06:50:57 INFO - u'jsshell-linux-x86_64.zip'], 06:50:57 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 06:50:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:50:57 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 06:50:57 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 06:50:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:50:57 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 06:50:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:50:57 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 06:50:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:50:57 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 06:50:57 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:50:57 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:50:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 06:50:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:50:57 INFO - Fetch https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 06:50:58 INFO - Expected file size: 21962209 06:51:02 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:51:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 06:51:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:51:02 INFO - Fetch https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 06:51:03 INFO - Expected file size: 21962209 06:51:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 06:51:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:51:08 INFO - Fetch https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 06:51:08 INFO - Expected file size: 14414089 06:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:09 INFO - trying https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 06:51:09 INFO - Downloading https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 06:51:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:51:10 INFO - Downloaded 64088036 bytes. 06:51:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 06:51:10 INFO - mkdir: /builds/slave/test/properties 06:51:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:51:10 INFO - Writing to file /builds/slave/test/properties/build_url 06:51:10 INFO - Contents: 06:51:10 INFO - build_url:https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 06:51:11 INFO - Running post-action listener: _resource_record_post_action 06:51:11 INFO - Running post-action listener: set_extra_try_arguments 06:51:11 INFO - [mozharness: 2016-11-14 14:51:11.068463Z] Finished download-and-extract step (success) 06:51:11 INFO - [mozharness: 2016-11-14 14:51:11.068766Z] Running populate-webroot step. 06:51:11 INFO - Running pre-action listener: _resource_record_pre_action 06:51:11 INFO - Running main action method: populate_webroot 06:51:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:51:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:51:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:51:11 INFO - '--tppagecycles', 06:51:11 INFO - '7'], 06:51:11 INFO - 'tests': ['tsvgm']}}, 06:51:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:51:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:51:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:51:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:51:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:51:11 INFO - 'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:51:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:51:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:51:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:51:11 INFO - 'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['damp', 'tps']}, 06:51:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:51:11 INFO - 'tests': ['damp', 'tps']}, 06:51:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['dromaeo_dom']}, 06:51:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:51:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['basic_compositor_video']}, 06:51:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:51:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['a11yr', 06:51:11 INFO - 'ts_paint', 06:51:11 INFO - 'tpaint', 06:51:11 INFO - 'sessionrestore', 06:51:11 INFO - 'sessionrestore_no_auto_restore', 06:51:11 INFO - 'tabpaint']}, 06:51:11 INFO - 'other-e10s': {'tests': ['a11yr', 06:51:11 INFO - 'ts_paint', 06:51:11 INFO - 'tpaint', 06:51:11 INFO - 'sessionrestore', 06:51:11 INFO - 'sessionrestore_no_auto_restore', 06:51:11 INFO - 'tabpaint']}, 06:51:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:51:11 INFO - 'ts_paint', 06:51:11 INFO - 'tpaint', 06:51:11 INFO - 'sessionrestore', 06:51:11 INFO - 'sessionrestore_no_auto_restore', 06:51:11 INFO - 'tabpaint']}, 06:51:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:51:11 INFO - 'ts_paint', 06:51:11 INFO - 'tpaint', 06:51:11 INFO - 'sessionrestore', 06:51:11 INFO - 'sessionrestore_no_auto_restore', 06:51:11 INFO - 'tabpaint']}, 06:51:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['a11yr', 06:51:11 INFO - 'ts_paint', 06:51:11 INFO - 'tpaint', 06:51:11 INFO - 'sessionrestore', 06:51:11 INFO - 'sessionrestore_no_auto_restore', 06:51:11 INFO - 'tabpaint']}, 06:51:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['a11yr', 06:51:11 INFO - 'ts_paint', 06:51:11 INFO - 'tpaint', 06:51:11 INFO - 'sessionrestore', 06:51:11 INFO - 'sessionrestore_no_auto_restore', 06:51:11 INFO - 'tabpaint']}, 06:51:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['tsvgx', 06:51:11 INFO - 'tsvgr_opacity', 06:51:11 INFO - 'tart', 06:51:11 INFO - 'tscrollx', 06:51:11 INFO - 'cart']}, 06:51:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:51:11 INFO - 'tsvgr_opacity', 06:51:11 INFO - 'tart', 06:51:11 INFO - 'tscrollx', 06:51:11 INFO - 'cart']}, 06:51:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:51:11 INFO - 'talos_options': ['--disable-e10s'], 06:51:11 INFO - 'tests': ['tp5o']}, 06:51:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:51:11 INFO - 'tests': ['tp5o']}, 06:51:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:51:11 INFO - 'talos_options': ['--disable-e10s', 06:51:11 INFO - '--xperf_path', 06:51:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:51:11 INFO - 'tests': ['tp5n']}, 06:51:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:51:11 INFO - 'talos_options': ['--xperf_path', 06:51:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:51:11 INFO - 'tests': ['tp5n']}}, 06:51:11 INFO - 'talos.zip': {'path': '', 06:51:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:51:11 INFO - Running post-action listener: _resource_record_post_action 06:51:11 INFO - [mozharness: 2016-11-14 14:51:11.085878Z] Finished populate-webroot step (success) 06:51:11 INFO - [mozharness: 2016-11-14 14:51:11.085972Z] Running create-virtualenv step. 06:51:11 INFO - Running pre-action listener: _resource_record_pre_action 06:51:11 INFO - Running main action method: create_virtualenv 06:51:11 INFO - Creating virtualenv /builds/slave/test/build/venv 06:51:11 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 06:51:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:51:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:51:11 INFO - Using real prefix '/usr' 06:51:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:51:11 INFO - Installing distribute.............................................................................................................................................................................................done. 06:51:13 INFO - Installing pip.................done. 06:51:13 INFO - Return code: 0 06:51:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:51:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:13 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 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51:13 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 06:51:13 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 06:51:13 INFO - Using env: {'DISPLAY': ':0', 06:51:13 INFO - 'HOME': '/home/cltbld', 06:51:13 INFO - 'LANG': 'en_US.UTF-8', 06:51:13 INFO - 'LANGUAGE': 'en_US:en', 06:51:13 INFO - 'LOGNAME': 'cltbld', 06:51:13 INFO - 'MAIL': '/var/mail/cltbld', 06:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:13 INFO - 'MOZ_NO_REMOTE': '1', 06:51:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:13 INFO - 'NO_EM_RESTART': '1', 06:51:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:13 INFO - 'PWD': '/builds/slave/test', 06:51:13 INFO - 'SHELL': '/bin/bash', 06:51:13 INFO - 'SHLVL': '1', 06:51:13 INFO - 'TERM': 'linux', 06:51:13 INFO - 'TMOUT': '86400', 06:51:13 INFO - 'USER': 'cltbld', 06:51:13 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:13 INFO - '_': '/tools/buildbot/bin/python'} 06:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:13 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 06:51:13 INFO - Cleaning up... 06:51:13 INFO - Return code: 0 06:51:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:51:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:13 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 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51:13 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 06:51:13 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 06:51:13 INFO - Using env: {'DISPLAY': ':0', 06:51:13 INFO - 'HOME': '/home/cltbld', 06:51:13 INFO - 'LANG': 'en_US.UTF-8', 06:51:13 INFO - 'LANGUAGE': 'en_US:en', 06:51:13 INFO - 'LOGNAME': 'cltbld', 06:51:13 INFO - 'MAIL': '/var/mail/cltbld', 06:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:13 INFO - 'MOZ_NO_REMOTE': '1', 06:51:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:13 INFO - 'NO_EM_RESTART': '1', 06:51:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:13 INFO - 'PWD': '/builds/slave/test', 06:51:13 INFO - 'SHELL': '/bin/bash', 06:51:13 INFO - 'SHLVL': '1', 06:51:13 INFO - 'TERM': 'linux', 06:51:13 INFO - 'TMOUT': '86400', 06:51:13 INFO - 'USER': 'cltbld', 06:51:13 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:13 INFO - '_': '/tools/buildbot/bin/python'} 06:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:14 INFO - Downloading/unpacking psutil>=3.1.1 06:51:14 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 06:51:14 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 06:51:14 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 06:51:14 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 06:51:14 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 06:51:14 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 06:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:51:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:51:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:51:16 INFO - Installing collected packages: psutil 06:51:16 INFO - Running setup.py install for psutil 06:51:16 INFO - building 'psutil._psutil_linux' extension 06:51:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 06:51:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 06:51:16 INFO - building 'psutil._psutil_posix' extension 06:51:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 06:51:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 06:51:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:51:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:51:16 INFO - Successfully installed psutil 06:51:16 INFO - Cleaning up... 06:51:16 INFO - Return code: 0 06:51:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:16 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 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51:16 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 06:51:16 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 06:51:16 INFO - Using env: {'DISPLAY': ':0', 06:51:16 INFO - 'HOME': '/home/cltbld', 06:51:16 INFO - 'LANG': 'en_US.UTF-8', 06:51:16 INFO - 'LANGUAGE': 'en_US:en', 06:51:16 INFO - 'LOGNAME': 'cltbld', 06:51:16 INFO - 'MAIL': '/var/mail/cltbld', 06:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:16 INFO - 'MOZ_NO_REMOTE': '1', 06:51:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:16 INFO - 'NO_EM_RESTART': '1', 06:51:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:16 INFO - 'PWD': '/builds/slave/test', 06:51:16 INFO - 'SHELL': '/bin/bash', 06:51:16 INFO - 'SHLVL': '1', 06:51:16 INFO - 'TERM': 'linux', 06:51:16 INFO - 'TMOUT': '86400', 06:51:16 INFO - 'USER': 'cltbld', 06:51:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:16 INFO - '_': '/tools/buildbot/bin/python'} 06:51:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:51:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:51:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:51:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:51:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:51:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:51:18 INFO - Installing collected packages: mozsystemmonitor 06:51:18 INFO - Running setup.py install for mozsystemmonitor 06:51:18 INFO - Successfully installed mozsystemmonitor 06:51:18 INFO - Cleaning up... 06:51:19 INFO - Return code: 0 06:51:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:51:19 INFO - Using env: {'DISPLAY': ':0', 06:51:19 INFO - 'HOME': '/home/cltbld', 06:51:19 INFO - 'LANG': 'en_US.UTF-8', 06:51:19 INFO - 'LANGUAGE': 'en_US:en', 06:51:19 INFO - 'LOGNAME': 'cltbld', 06:51:19 INFO - 'MAIL': '/var/mail/cltbld', 06:51:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:19 INFO - 'MOZ_NO_REMOTE': '1', 06:51:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:19 INFO - 'NO_EM_RESTART': '1', 06:51:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:19 INFO - 'PWD': '/builds/slave/test', 06:51:19 INFO - 'SHELL': '/bin/bash', 06:51:19 INFO - 'SHLVL': '1', 06:51:19 INFO - 'TERM': 'linux', 06:51:19 INFO - 'TMOUT': '86400', 06:51:19 INFO - 'USER': 'cltbld', 06:51:19 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:19 INFO - '_': '/tools/buildbot/bin/python'} 06:51:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:19 INFO - Downloading/unpacking jsonschema==2.5.1 06:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:51:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:51:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:51:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:51:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:51:21 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 06:51:21 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 06:51:21 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 06:51:21 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 06:51:21 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 06:51:21 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 06:51:21 INFO - Downloading functools32-3.2.3-2.tar.gz 06:51:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:51:21 INFO - warning: no files found matching '*.txt' 06:51:21 INFO - no previously-included directories found matching 'build' 06:51:21 INFO - no previously-included directories found matching 'dist' 06:51:21 INFO - no previously-included directories found matching '.git*' 06:51:21 INFO - Installing collected packages: jsonschema, functools32 06:51:21 INFO - Running setup.py install for functools32 06:51:21 INFO - warning: no files found matching '*.txt' 06:51:21 INFO - no previously-included directories found matching 'build' 06:51:21 INFO - no previously-included directories found matching 'dist' 06:51:21 INFO - no previously-included directories found matching '.git*' 06:51:21 INFO - Successfully installed jsonschema functools32 06:51:21 INFO - Cleaning up... 06:51:21 INFO - Return code: 0 06:51:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:51: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 functools32==3.2.3-2 06:51:21 INFO - Using env: {'DISPLAY': ':0', 06:51:21 INFO - 'HOME': '/home/cltbld', 06:51:21 INFO - 'LANG': 'en_US.UTF-8', 06:51:21 INFO - 'LANGUAGE': 'en_US:en', 06:51:21 INFO - 'LOGNAME': 'cltbld', 06:51:21 INFO - 'MAIL': '/var/mail/cltbld', 06:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:21 INFO - 'MOZ_NO_REMOTE': '1', 06:51:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:21 INFO - 'NO_EM_RESTART': '1', 06:51:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:21 INFO - 'PWD': '/builds/slave/test', 06:51:21 INFO - 'SHELL': '/bin/bash', 06:51:21 INFO - 'SHLVL': '1', 06:51:21 INFO - 'TERM': 'linux', 06:51:21 INFO - 'TMOUT': '86400', 06:51:21 INFO - 'USER': 'cltbld', 06:51:21 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:21 INFO - '_': '/tools/buildbot/bin/python'} 06:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:51:21 INFO - Cleaning up... 06:51:21 INFO - Return code: 0 06:51:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51: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', '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 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:51: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 blobuploader==1.2.4 06:51:21 INFO - Using env: {'DISPLAY': ':0', 06:51:21 INFO - 'HOME': '/home/cltbld', 06:51:21 INFO - 'LANG': 'en_US.UTF-8', 06:51:21 INFO - 'LANGUAGE': 'en_US:en', 06:51:21 INFO - 'LOGNAME': 'cltbld', 06:51:21 INFO - 'MAIL': '/var/mail/cltbld', 06:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:21 INFO - 'MOZ_NO_REMOTE': '1', 06:51:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:21 INFO - 'NO_EM_RESTART': '1', 06:51:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:21 INFO - 'PWD': '/builds/slave/test', 06:51:21 INFO - 'SHELL': '/bin/bash', 06:51:21 INFO - 'SHLVL': '1', 06:51:21 INFO - 'TERM': 'linux', 06:51:21 INFO - 'TMOUT': '86400', 06:51:21 INFO - 'USER': 'cltbld', 06:51:21 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:21 INFO - '_': '/tools/buildbot/bin/python'} 06:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:22 INFO - Downloading/unpacking blobuploader==1.2.4 06:51: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 06:51: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 06:51: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 06:51: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 06:51: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 06:51: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 06:51:23 INFO - Downloading blobuploader-1.2.4.tar.gz 06:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:51:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:51: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 06:51: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 06:51: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 06:51: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 06:51: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 06:51: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 06:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:51:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:51: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 06:51: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 06:51: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 06:51: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 06:51: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 06:51: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 06:51:24 INFO - Downloading docopt-0.6.1.tar.gz 06:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:51:24 INFO - Installing collected packages: blobuploader, requests, docopt 06:51:24 INFO - Running setup.py install for blobuploader 06:51:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:51:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:51:24 INFO - Running setup.py install for requests 06:51:25 INFO - Running setup.py install for docopt 06:51:25 INFO - Successfully installed blobuploader requests docopt 06:51:25 INFO - Cleaning up... 06:51:25 INFO - Return code: 0 06:51:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:25 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 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51:25 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 06:51:25 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 06:51:25 INFO - Using env: {'DISPLAY': ':0', 06:51:25 INFO - 'HOME': '/home/cltbld', 06:51:25 INFO - 'LANG': 'en_US.UTF-8', 06:51:25 INFO - 'LANGUAGE': 'en_US:en', 06:51:25 INFO - 'LOGNAME': 'cltbld', 06:51:25 INFO - 'MAIL': '/var/mail/cltbld', 06:51:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:25 INFO - 'MOZ_NO_REMOTE': '1', 06:51:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:25 INFO - 'NO_EM_RESTART': '1', 06:51:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:25 INFO - 'PWD': '/builds/slave/test', 06:51:25 INFO - 'SHELL': '/bin/bash', 06:51:25 INFO - 'SHLVL': '1', 06:51:25 INFO - 'TERM': 'linux', 06:51:25 INFO - 'TMOUT': '86400', 06:51:25 INFO - 'USER': 'cltbld', 06:51:25 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:25 INFO - '_': '/tools/buildbot/bin/python'} 06:51:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:51:25 INFO - Running setup.py (path:/tmp/pip-vNrMga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:51:25 INFO - Running setup.py (path:/tmp/pip-x_QC4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:51:25 INFO - Running setup.py (path:/tmp/pip-ONtx0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:51:25 INFO - Running setup.py (path:/tmp/pip-L19nqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:51:25 INFO - Running setup.py (path:/tmp/pip-GiCF41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:51:25 INFO - Running setup.py (path:/tmp/pip-uB58sU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:51:26 INFO - Running setup.py (path:/tmp/pip-3BTt3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:51:26 INFO - Running setup.py (path:/tmp/pip-XGLDWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:51:26 INFO - Running setup.py (path:/tmp/pip-Vod0zP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:51:26 INFO - Running setup.py (path:/tmp/pip-Xtu5HD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:51:26 INFO - Running setup.py (path:/tmp/pip-SnmXb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:51:26 INFO - Running setup.py (path:/tmp/pip-IaVyae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:51:26 INFO - Running setup.py (path:/tmp/pip-sATZ4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:51:26 INFO - Running setup.py (path:/tmp/pip-F2ZXKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:51:26 INFO - Running setup.py (path:/tmp/pip-511gRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:51:26 INFO - Running setup.py (path:/tmp/pip-_26O6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:51:26 INFO - Running setup.py (path:/tmp/pip-zdbTJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:51:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:51:26 INFO - Running setup.py install for manifestparser 06:51:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:51:26 INFO - Running setup.py install for mozcrash 06:51:27 INFO - Running setup.py install for mozdebug 06:51:27 INFO - Running setup.py install for mozdevice 06:51:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Running setup.py install for mozfile 06:51:27 INFO - Running setup.py install for mozhttpd 06:51:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Running setup.py install for mozinfo 06:51:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Running setup.py install for mozInstall 06:51:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Running setup.py install for mozleak 06:51:27 INFO - Running setup.py install for mozlog 06:51:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Running setup.py install for moznetwork 06:51:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:51:27 INFO - Running setup.py install for mozprocess 06:51:28 INFO - Running setup.py install for mozprofile 06:51:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:51:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:51:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:51:28 INFO - Running setup.py install for mozrunner 06:51:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:51:28 INFO - Running setup.py install for mozscreenshot 06:51:28 INFO - Running setup.py install for moztest 06:51:28 INFO - Running setup.py install for mozversion 06:51:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:51:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:51:28 INFO - Cleaning up... 06:51:28 INFO - Return code: 0 06:51:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:28 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 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51:28 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 06:51:28 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 06:51:28 INFO - Using env: {'DISPLAY': ':0', 06:51:28 INFO - 'HOME': '/home/cltbld', 06:51:28 INFO - 'LANG': 'en_US.UTF-8', 06:51:28 INFO - 'LANGUAGE': 'en_US:en', 06:51:28 INFO - 'LOGNAME': 'cltbld', 06:51:28 INFO - 'MAIL': '/var/mail/cltbld', 06:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:28 INFO - 'MOZ_NO_REMOTE': '1', 06:51:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:28 INFO - 'NO_EM_RESTART': '1', 06:51:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:28 INFO - 'PWD': '/builds/slave/test', 06:51:28 INFO - 'SHELL': '/bin/bash', 06:51:28 INFO - 'SHLVL': '1', 06:51:28 INFO - 'TERM': 'linux', 06:51:28 INFO - 'TMOUT': '86400', 06:51:28 INFO - 'USER': 'cltbld', 06:51:28 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:28 INFO - '_': '/tools/buildbot/bin/python'} 06:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:51:28 INFO - Running setup.py (path:/tmp/pip-eTe21b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:51:28 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)) 06:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:51:28 INFO - Running setup.py (path:/tmp/pip-fMS9UO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:51:29 INFO - Running setup.py (path:/tmp/pip-eDOhLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:51:29 INFO - Running setup.py (path:/tmp/pip-OjpvD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:51:29 INFO - Running setup.py (path:/tmp/pip-kNfcfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:51:29 INFO - Running setup.py (path:/tmp/pip-dEs1ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:51:29 INFO - Running setup.py (path:/tmp/pip-YC8lSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:51:29 INFO - Running setup.py (path:/tmp/pip-EjJd_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:51:29 INFO - Running setup.py (path:/tmp/pip-hZSzfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:51:29 INFO - Running setup.py (path:/tmp/pip-gQvTi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:51:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:51:29 INFO - Running setup.py (path:/tmp/pip-VgxofQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:51:29 INFO - Running setup.py (path:/tmp/pip-EAk1Gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:51:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:51:29 INFO - Running setup.py (path:/tmp/pip-tm_vUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:51:29 INFO - Running setup.py (path:/tmp/pip-k8n4PT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:51:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:51:29 INFO - Running setup.py (path:/tmp/pip-6tM8FF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:51:29 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)) 06:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:51:29 INFO - Running setup.py (path:/tmp/pip-3WdARE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:51:30 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)) 06:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:51:30 INFO - Running setup.py (path:/tmp/pip-EBbgQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:51:30 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)) 06:51:30 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)) 06:51:30 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)) 06:51:30 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)) 06:51:30 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)) 06:51:30 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)) 06:51:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:51:30 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 06:51:30 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 06:51:30 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 06:51:30 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 06:51:30 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 06:51:30 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 06:51:32 INFO - Downloading blessings-1.6.tar.gz 06:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:51:32 INFO - Installing collected packages: blessings 06:51:32 INFO - Running setup.py install for blessings 06:51:32 INFO - Successfully installed blessings 06:51:32 INFO - Cleaning up... 06:51:32 INFO - Return code: 0 06:51:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:51:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:51:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:51:32 INFO - Reading from file tmpfile_stdout 06:51:32 INFO - Current package versions: 06:51:32 INFO - argparse == 1.2.1 06:51:32 INFO - blessings == 1.6 06:51:32 INFO - blobuploader == 1.2.4 06:51:32 INFO - docopt == 0.6.1 06:51:32 INFO - functools32 == 3.2.3-2 06:51:32 INFO - jsonschema == 2.5.1 06:51:32 INFO - manifestparser == 1.1 06:51:32 INFO - mozInstall == 1.12 06:51:32 INFO - mozcrash == 0.17 06:51:32 INFO - mozdebug == 0.1 06:51:32 INFO - mozdevice == 0.48 06:51:32 INFO - mozfile == 1.2 06:51:32 INFO - mozhttpd == 0.7 06:51:32 INFO - mozinfo == 0.9 06:51:32 INFO - mozleak == 0.1 06:51:32 INFO - mozlog == 3.4 06:51:32 INFO - moznetwork == 0.27 06:51:32 INFO - mozprocess == 0.23 06:51:32 INFO - mozprofile == 0.28 06:51:32 INFO - mozrunner == 6.12 06:51:32 INFO - mozscreenshot == 0.1 06:51:32 INFO - mozsystemmonitor == 0.3 06:51:32 INFO - moztest == 0.7 06:51:32 INFO - mozversion == 1.4 06:51:32 INFO - psutil == 3.1.1 06:51:32 INFO - requests == 1.2.3 06:51:32 INFO - wsgiref == 0.1.2 06:51:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:32 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 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51:32 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 06:51:32 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 06:51:32 INFO - Using env: {'DISPLAY': ':0', 06:51:32 INFO - 'HOME': '/home/cltbld', 06:51:32 INFO - 'LANG': 'en_US.UTF-8', 06:51:32 INFO - 'LANGUAGE': 'en_US:en', 06:51:32 INFO - 'LOGNAME': 'cltbld', 06:51:32 INFO - 'MAIL': '/var/mail/cltbld', 06:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:32 INFO - 'MOZ_NO_REMOTE': '1', 06:51:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:32 INFO - 'NO_EM_RESTART': '1', 06:51:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:32 INFO - 'PWD': '/builds/slave/test', 06:51:32 INFO - 'SHELL': '/bin/bash', 06:51:32 INFO - 'SHLVL': '1', 06:51:32 INFO - 'TERM': 'linux', 06:51:32 INFO - 'TMOUT': '86400', 06:51:32 INFO - 'USER': 'cltbld', 06:51:32 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:32 INFO - '_': '/tools/buildbot/bin/python'} 06:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:32 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)) 06:51:32 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)) 06:51:32 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)) 06:51:32 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)) 06:51:32 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)) 06:51:32 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)) 06:51:32 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)) 06:51:32 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)) 06:51:32 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)) 06:51:32 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)) 06:51:32 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)) 06:51:32 INFO - Cleaning up... 06:51:32 INFO - Return code: 0 06:51:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:51:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5ff4573f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17848a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1851ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:51:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:51:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:51:32 INFO - Using env: {'DISPLAY': ':0', 06:51:32 INFO - 'HOME': '/home/cltbld', 06:51:32 INFO - 'LANG': 'en_US.UTF-8', 06:51:32 INFO - 'LANGUAGE': 'en_US:en', 06:51:32 INFO - 'LOGNAME': 'cltbld', 06:51:32 INFO - 'MAIL': '/var/mail/cltbld', 06:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:32 INFO - 'MOZ_NO_REMOTE': '1', 06:51:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:32 INFO - 'NO_EM_RESTART': '1', 06:51:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:32 INFO - 'PWD': '/builds/slave/test', 06:51:32 INFO - 'SHELL': '/bin/bash', 06:51:32 INFO - 'SHLVL': '1', 06:51:32 INFO - 'TERM': 'linux', 06:51:32 INFO - 'TMOUT': '86400', 06:51:32 INFO - 'USER': 'cltbld', 06:51:32 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:32 INFO - '_': '/tools/buildbot/bin/python'} 06:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:51:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:51:32 INFO - Cleaning up... 06:51:32 INFO - Return code: 0 06:51:32 INFO - Running post-action listener: _resource_record_post_action 06:51:32 INFO - Running post-action listener: _start_resource_monitoring 06:51:32 INFO - Starting resource monitoring. 06:51:32 INFO - [mozharness: 2016-11-14 14:51:32.968608Z] Finished create-virtualenv step (success) 06:51:32 INFO - [mozharness: 2016-11-14 14:51:32.969068Z] Running install step. 06:51:32 INFO - Running pre-action listener: _resource_record_pre_action 06:51:32 INFO - Running main action method: install 06:51:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:51:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:51:33 INFO - Reading from file tmpfile_stdout 06:51:33 INFO - Detecting whether we're running mozinstall >=1.0... 06:51:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:51:33 INFO - Reading from file tmpfile_stdout 06:51:33 INFO - Output received: 06:51:33 INFO - Usage: mozinstall [options] installer 06:51:33 INFO - Options: 06:51:33 INFO - -h, --help show this help message and exit 06:51:33 INFO - -d DEST, --destination=DEST 06:51:33 INFO - Directory to install application into. [default: 06:51:33 INFO - "/builds/slave/test"] 06:51:33 INFO - --app=APP Application being installed. [default: firefox] 06:51:33 INFO - mkdir: /builds/slave/test/build/application 06:51:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 06:51:49 INFO - Reading from file tmpfile_stdout 06:51:49 INFO - Output received: 06:51:49 INFO - /builds/slave/test/build/application/firefox/firefox 06:51:49 INFO - Running post-action listener: _resource_record_post_action 06:51:49 INFO - [mozharness: 2016-11-14 14:51:49.240543Z] Finished install step (success) 06:51:49 INFO - [mozharness: 2016-11-14 14:51:49.240789Z] Running run-tests step. 06:51:49 INFO - Running pre-action listener: _resource_record_pre_action 06:51:49 INFO - Running main action method: run_tests 06:51:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:51:49 INFO - Python 2.7.3 06:51:49 INFO - Return code: 0 06:51:49 INFO - grabbing minidump binary from tooltool 06:51:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:51:49 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/linux64/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 0x184ef60>, '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 0x184fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18501d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:51:49 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:51:49 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/linux64/releng.manifest -o -c /builds/tooltool_cache 06:51:49 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:51:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:51:49 INFO - Return code: 0 06:51:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:51:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:51:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:51:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:51:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:51:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:51:49 INFO - Using env: {'DISPLAY': ':0', 06:51:49 INFO - 'HOME': '/home/cltbld', 06:51:49 INFO - 'LANG': 'en_US.UTF-8', 06:51:49 INFO - 'LANGUAGE': 'en_US:en', 06:51:49 INFO - 'LOGNAME': 'cltbld', 06:51:49 INFO - 'MAIL': '/var/mail/cltbld', 06:51:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:51:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:51:49 INFO - 'MOZ_NO_REMOTE': '1', 06:51:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:51:49 INFO - 'NO_EM_RESTART': '1', 06:51:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:51:49 INFO - 'PWD': '/builds/slave/test', 06:51:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:51:49 INFO - 'SHELL': '/bin/bash', 06:51:49 INFO - 'SHLVL': '1', 06:51:49 INFO - 'TERM': 'linux', 06:51:49 INFO - 'TMOUT': '86400', 06:51:49 INFO - 'USER': 'cltbld', 06:51:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438', 06:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:51:49 INFO - '_': '/tools/buildbot/bin/python'} 06:51:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:51:49 INFO - mozversion application_buildid: 20161114021920 06:51:49 INFO - mozversion application_changeset: 2d9b6132e7d75327e063a15d8e5e279077adf987 06:51:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:51:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:51:49 INFO - mozversion application_name: Firefox 06:51:49 INFO - mozversion application_remotingname: firefox-dev 06:51:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:51:49 INFO - mozversion application_vendor: Mozilla 06:51:49 INFO - mozversion application_version: 51.0a2 06:51:49 INFO - mozversion platform_buildid: 20161114021920 06:51:49 INFO - mozversion platform_changeset: 2d9b6132e7d75327e063a15d8e5e279077adf987 06:51:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:51:49 INFO - mozversion platform_version: 51.0a2 06:51:49 INFO - using testdate: 1479135109 06:51:49 INFO - actual date: 1479135109 06:51:49 INFO - starting webserver on 'localhost:35622' 06:51:49 INFO - SUITE-START | Running 2 tests 06:51:49 INFO - TEST-START | dromaeo_css 06:51:49 INFO - operating with platform_type : linux_ 06:51:49 INFO - Initialising browser for dromaeo_css test... 06:51:49 INFO - TEST-INFO | started process 7412 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU6k8aa/profile http://localhost:35622/getInfo.html) 06:51:53 INFO - PROCESS | 7412 | __metrics Screen width/height:1600/1200 06:51:53 INFO - PROCESS | 7412 | colorDepth:24 06:51:53 INFO - PROCESS | 7412 | Browser inner width/height: 1024/697 06:51:53 INFO - PROCESS | 7412 | __metrics 06:51:54 INFO - PROCESS | 7412 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:51:54 INFO - PROCESS | 7412 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:51:54 INFO - PROCESS | 7412 | console.error: PushService: 06:51:54 INFO - PROCESS | 7412 | stateChangeProcessEnqueue: Error transitioning state 06:51:54 INFO - PROCESS | 7412 | UnknownError 06:51:55 INFO - TEST-INFO | 7412: exit 0 06:51:55 INFO - Browser initialized. 06:51:55 INFO - Running cycle 1/1 for dromaeo_css test... 06:51:55 INFO - TEST-INFO | started process 7502 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU6k8aa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:52:06 INFO - PROCESS | 7502 | RSS: Main: 144715776 06:52:06 INFO - PROCESS | 7502 | 06:52:57 INFO - PROCESS | 7502 | 1479135177897 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] 06:55:06 INFO - PROCESS | 7502 | Cycle 1(1): loaded http://localhost:35622/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35622/tests/dromaeo/cssquery-ext.html) 06:55:07 INFO - PROCESS | 7502 | RSS: Main: 234078208 06:55:07 INFO - PROCESS | 7502 | 06:58:10 INFO - PROCESS | 7502 | Cycle 1(1): loaded http://localhost:35622/tests/dromaeo/cssquery-ext.html (next: http://localhost:35622/tests/dromaeo/cssquery-jquery.html) 06:58:10 INFO - PROCESS | 7502 | RSS: Main: 233111552 06:58:10 INFO - PROCESS | 7502 | 07:01:12 INFO - PROCESS | 7502 | Cycle 1(1): loaded http://localhost:35622/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35622/tests/dromaeo/cssquery-mootools.html) 07:01:13 INFO - PROCESS | 7502 | RSS: Main: 174706688 07:01:13 INFO - PROCESS | 7502 | 07:04:15 INFO - PROCESS | 7502 | Cycle 1(1): loaded http://localhost:35622/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35622/tests/dromaeo/cssquery-prototype.html) 07:04:15 INFO - PROCESS | 7502 | RSS: Main: 217739264 07:04:15 INFO - PROCESS | 7502 | 07:07:18 INFO - PROCESS | 7502 | Cycle 1(1): loaded http://localhost:35622/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35622/tests/dromaeo/cssquery-yui.html) 07:07:19 INFO - PROCESS | 7502 | RSS: Main: 177106944 07:07:19 INFO - PROCESS | 7502 | 07:10:21 INFO - PROCESS | 7502 | Cycle 1(1): loaded http://localhost:35622/tests/dromaeo/cssquery-yui.html (next: http://localhost:35622/tests/dromaeo/cssquery-dojo.html) 07:10:21 INFO - PROCESS | 7502 | RSS: Main: 266846208 07:10:21 INFO - PROCESS | 7502 | 07:10:21 INFO - PROCESS | 7502 | __start_tp_report 07:10:21 INFO - PROCESS | 7502 | _x_x_mozilla_page_load 07:10:21 INFO - PROCESS | 7502 | _x_x_mozilla_page_load_details 07:10:21 INFO - PROCESS | 7502 | |i|pagename|runs| 07:10:21 INFO - PROCESS | 7502 | |0;dojo.html;3092.829894355811;3150.369926014796;3162.65210826809;3185.888493902714;3200.8239546824916;5489.588280878935;5570.721463926798;5588.273524441827;5601.523870471005;5602.831915042545;5478.726063696819;5504.889902201963;5534.4742249486335;5536.031194540949;5552.3892371839065;4973.4031916170015;4991.126552853254;5005.049040682277;5061.367329083864;5079.034983353151;4918.877028074309;5001.3998320201445;5056.266841308008;5062.519060689228;5063.670861393997;4898.338724272231;5004.12427825131;5039.924401133986;5073.949260507385;5076.213186956028;5028.8239911603105;5114.616403769732;5132.615053870974;5134.897302053975;5160.303359046556;4966.950330496721;5038.790690234354;5084.567811736007;5094.61790365724;5107.770150343244;5336.653117547347;5355.946440535623;5392.164214546751;5405.324334458192;5415.891682166374;5794.710264486801;5807.680577568232;5825.38833422493;5826.184334193216;5836.737346819404;5864.618799778001;5868.501177399926;5877.441643043904;5888.175655408247;5891.293044834604;5354.973225133888;5380.2736630555155;5516.531094856942;5568.164775283659;5574.275344205227;6153.353897840662;6154.753809847645;6159.168512250879;6173.47525460332;6175.44421002112;5901.76392944286;5913.822585322448;5926.911096333559;5928.970355148283;5934.109883517421;5653.293338332708;5755.510781583613;5804.216430781875;5969.164316995624;5982.670953097534;5828.475437210672;5841.153032810219;5866.032104702762;5879.853003674943;5895.911561326584;5863.442972917566;5871.119332100219;5874.1776151338845;5879.147523609053;5906.675132867647;4429.667774916894;4448.466184057869;4534.682572219979;4540.137373898949;4555.794989225493;4011.7592944423427;4065.329220678555;4067.4102255173425;4076.7961601919787;4101.85643502476;5694.060480020833;5695.515881150066;5697.259356283739;5697.800576979786;5725.914111288334;5747.109198074305;5948.315943666534;5989.1914591529585;6003.669798161143;6006.069059295816;5449.373322067933;5496.0107180707055;5517.144842549331;5523.530499907552;5584.274044374205;4931;4954.504549545017;4955.950440495549;4963.553280204726;4975.129352363322;6149.508039356751;6162.106494558354;6165.290991535941;6165.445109940038;6180.196574445294;5787.7395517200985;5814.970925145347;5916.526677865846;5920.555958303058;5952.958232309242;6163.414475624809;6163.506919446523;6189.195404597373;6202.7829025985675;6208.441240288395;5852.502537284378;5889.94110058894;5890.057590785454;5905.291365036223;5949.910751338647;5678.079323615566;5759.65442073477;5961.821145365646;5987.850303742442;5996.430339117776;5863.970680146572;5872.500837428866;5882.735276912464;5909.970450147722;5913.940860591339;5835.79574714883;5847.06446968483;5848.385919478564;5854.180414741854;5872.588918775645;5578.916316255179;5611.410801865745;5800.564957628279;5803.82588522345;5817.970910145422;5817.069268916953;5850.678212698343;5850.795222167204;5861.7655293787775;5940.227770389822;5745.339285981999;5751.712414379181;5755.625884317506;5759.2512973313205;5762.250907382181 07:10:21 INFO - PROCESS | 7502 | |1;ext.html;589.5313225985341;615.0497481391252;643.391994565136;652.1847690387019;665.703761825987;34076.4888526672;45671.54328456719;46389.76805115978;46542.534574654244;46637.30044049342;47577.7621111894;48049.51950480494;48145.7592712036;48279.51720482794;48286;8606.182412670787;9322.533873306342;9484.383515071499;9486.573104210327;9493.240540756757;8386.203310685476;11024.834627480594;11297.039751621121;11305.26515776476;11621.941890290578;11323.830142547868;11379.544818207261;11386.829197562043;11394.487248073856;11394.715132121722;11191.608293709722;11228.214025018253;11266.943665281704;11275.605353812618;11282.605108821152;11088.667339979813;11115.610953616584;11172;11176.776464470666;11179.105671546256;43954.780226098985;44437.11125777466;44453.77773111146;44492.11015779666;44570;3862.8068596570342;3867.7872717000555;3997.700172487075;4271.252530807096;4578.908421831578;33089.00732978014;33259.16852078693;33331.500027499605;33588.49617255743;35372.64627353719;4224.070704445048;4277.679174061957;4299.720518166309;4315.05068884848;4317.676174286909;245328;245912.99999999822;245993;246549;246636;62010.37989620136;62431.37568624347;62436;62457;62467.6876615614;40566;41363.586364135976;41474.1705165901;43051.56948430538;43076.78461607672;17964.730529042074;18051.097445127954;18058.638827223644;18062.729059064124;18063.638727225643;13556.93221533886;13792.793108103386;14655.267236638125;14657.926710366593;14668;19918;20590.38228853107;20726.58546829055;20730.274440394514;20781;7514.549127052285;7602.581857997752;7611.543307401463;7745.922540774633;7866.7639970800965;1052.489542571852;1159.976800463986;1164.2956011612853;1177.2289150606366;1190.565443613075;34151.82924085413;34360.82819585886;34457.483137752955;34558.82720586381;34580.65419345825;1148.1561052626314;1170.7190274334268;1184.1059999700171;1193.4232219438377;1266.3098611256887;18208.271669133086;18218.635627287644;18238;18259.90870045668;18338.541536461427;561693;568060;568576;568895;570298;62029.069563956575;62668.05997910035;62767.372326277065;62791.686041570414;62928.37071629317;1356.1185229600642;1416.122004357305;1422.5503154849655;1465.8387577366482;1498.88008959283;30574.388512230074;35321.29357412889;35498.29003419969;35673.52766448198;35760.46359304665;52069;52629.473705262455;52688.73655631697;52711;52823.47176528186;387030;390208;390563;391111;391291;29923;31169;31170;31172.688273117885;31231.687683122876;45520.31719524276;45636.543634563226;46372.768136160455;46910.29634555553;46948.76525617487;43178.568214317456;43588.34617480804;43624.345634816134;44759.55240447554;46189.07621847611;43635.34546981861;44530.3320450187;44566;45283.54716452793;45530;42912.78543607262;43010.13979720326;43170;43252;43362.13275734531;37703.81148094242;39117.2176556473;39223;39426.80286598549;39842.60157398389;29161.334186530054;29396.706032939397;29712.702872970993;29728.108156755334;29940.850295748383 07:10:21 INFO - PROCESS | 7502 | |2;jquery.html;387.2796598327113;422.18939635899073;430.08669540369067;442.25700822618;443.7226733291695;16108.35566577337;19405.41783746486;20480.38558843237;21123.577528449423;21303.36089917301;20222.494437639067;20897.059632316545;20978.475538111554;20995.160193592277;21163.894180529114;5758.078707406816;5790.536757059425;5986.790462333819;5990.9101363479585;5991.730372133243;5901.380355062721;6030.698465076751;6033.42682445167;6035.366286539916;6038.124471951564;5737.770489180428;5924.970375148118;5988.550858685594;5998.070299103646;6000.8499787505225;5909.143174239733;5939.524838012949;5940.940590594082;5943.673097979609;5948.851278718045;5257.080010998098;5277.182036784305;5338.546223571001;5344.438833922421;5378.327709036371;22202.222922197645;22895.656565151538;22978.08087676491;24371.390715232083;24441.388965275833;2297.173017713622;2564.8461092334333;2732.5627899536175;2847.3166440054238;3028.757699384044;10502.842457363146;15040.473583424637;15116.244187790677;15361.462348817739;15724.921375393164;1877.1458986161265;1956.8825870447818;2006.0872303102053;2023.5244717491378;2033.8474614403979;84391;87732.56133719355;88962.55518722365;89110.55444722735;91101.54449227778;10435.32170408921;11592.942035289769;11788.764224715458;11790.410479475991;11985.940070299766;13997.30013499321;14597.708045839025;14607.853921460863;14625.122492650475;15811.604709882346;7084.681189346492;8456.830863382698;8862.86705699415;9084.001519665662;9092.408993415407;8697.478151310816;8758.386912916161;8770.597463851296;8811;9026.819463610691;5080;5106.387233532;5114.948850511521;5194.702157517062;5315.229291752751;2077.542940553076;2088.5509615432757;2096.088201632295;2097.234509404087;2164.1343462614764;925.8194652042787;983.9114479696862;985.3299756165733;1033.106362996003;1034.7930413917247;19648.11583478747;20237.59524809525;20396.49008774763;21351.67972480383;21651.242206522697;1007.2395341517108;1025.661531694539;1037.1702637889657;1038.6416686243233;1080.6541906589816;10754.40850753216;11600.88399116015;11673.591424300108;11715.297082175097;11822;455439;468910;472527;473340;549381;96432.51783741177;96464.51767741116;100996.49501752444;102988.48505757423;103220.48389758004;497.7486369383438;518.9974050129725;522.1853907903683;523.7459831981486;526.9631125821232;11224.270422422516;11479.196456247982;11737.88262117368;11931.821022684493;12430.81353779676;33925.66074339225;39887.20507577108;42483;44677.329840051774;44742.328865066396;59170.40829591821;59327.70336148292;59340;60862;61218.693906530185;8529.232369086812;8846.248068914214;9221.769455763659;9228.40015398997;9303.488308143116;10925.672229833117;12872.935635321763;13169.802452963022;13237.338133093113;13860.168389896235;5936.8812623748145;7690.500117492345;11607.607087149596;12225.327606981702;12477;11420;11667.6499705009;11927.761444770882;12014.098942579167;12077.879221207675;11865.228760130563;11976.981956533444;12182.512699492276;12314.50741970347;12704.618861434172;8903.510306933182;9031.322650801347;9198.080191980749;9288.210502107126;9494.382865113745;9932.354396964174;11433.771324573629;11906.880931190924;11996.160268781101;12003.519859205533 07:10:21 INFO - PROCESS | 7502 | |3;mootools.html;1490.6347944753536;1530.4107918451396;1673.1634182908547;1782.4385318624625;1808.095952023988;8055.677772889085;8609.225169734722;8629.87055194171;8638.956805215981;8655.956720216398;7408.851822963537;7648.541087534752;8042.839143217147;8324.91675083249;8331;2457.655928170062;2479.2562231330617;2480.218731099707;2481.7146028206807;2482.2429159106464;2436.183878400729;2465.716442609096;2487.1046423287603;2487.589547724623;2490.5018996200743;2452.5953217719057;2454.1287842815723;2455.1529722245887;2477.083479112731;2478.677771889661;2414;2431.6474111253856;2434.403571125068;2435.330284171849;2442.072012635205;2421.3583400398734;2427.1383658801046;2439;2446.678793451552;2468.2348471973746;8517.48895066298;8582.957085214533;8585.828283434297;8601.268892144175;8610.483370997761;5032;5221.921671174935;5233.633645644806;5234.581233501311;5245.029669511115;3322.7009569138895;3428.9314213715834;3429.9314013719836;3457.8271086445575;3503.1067077895227;1133.982990255147;1163.4357336691698;1173.5188572685242;1192.415716299016;1212.563477148226;11331.886681133166;11346.092312615054;11355.261907976037;11362.772744545064;11409.20135590509;4504.549545045469;4690.202665546865;4817.7350245736825;4854.029194161182;4855.878603034952;9841.753956151153;9869.062439068277;9879.259055570861;9890.604375824887;9893.406395616286;5417.8103766367985;5419.729013549307;5419.756110975016;5427.131658934631;5429.837104886859;4443.733375997451;4578.839740609063;4597.402337696145;4892.046051020017;4998.6500944933505;4045.696572757055;4149.564295748964;4193.874183774492;4215.199112168678;4244.236037513278;1379.862013798612;1383.619504636213;1394.2819447984295;1398.1960372785688;1412.6750847305177;958.6596758150896;1000.9199264058858;1046.0742243114898;1050.191352658464;1059.3802625464036;5977.731002104916;6004.189434426384;6031;6123.203983482119;6327.3988971047675;952.6475204174361;982.9557669904872;994.0722736179059;1035.4563853976572;1052.2791887556896;4153.335466325373;4160.958390416057;4195.999860100043;4248.851290204828;4399.472063352418;10276;10567.682969510926;10568.207384446197;10582.894171058344;10593.152547796159;10439.37363758177;10467.528961196764;10469.895301047045;10472.947635261775;10549.208809339178;7681.423893208032;7944.205579442009;8004.279614834576;8059.798505037304;8090.150534193825;6146.815595532141;6200.3179650239335;6205.193324867739;6230.968845155745;6257.43683068526;9606.759831004281;9648.614055437703;9657.372270802376;9753.366031207948;9761.365511241747;9539;10037.397756134655;10049.095581397709;10059.39643621385;10074.647387341407;6805.795826125224;6990.720371185095;6997.685104170223;7676.193999629959;7795.688172473037;7740.458167928191;7776.066871975399;7871.409644276817;7977.082635496992;8106.635201416068;7369.963150184215;7547.849043018999;7593.620318984139;7635.618219088912;7717.768466946;7228.421726261781;7366;7393.297636724503;7408.222136675571;7572.318491335806;7018.613976231356;7108.395786358217;7135.892961605477;7136.85726285461;7138.250483699345;5062.594992400525;5177.8187763428095;5231.581473482189;5317.388500322512;5328.57371410294;5133.178691409356;5215.634905556726;5217.843464696065;5286.418493965583;5289.841304760864 07:10:21 INFO - PROCESS | 7502 | |4;prototype.html;332.683950247265;347.3781930344683;351.6957831475773;355.2486491071385;358.7506682855415;5436.782528698852;5465.371482279539;5483.725813709309;5486.204500347452;5493.697846618435;5108.872278193037;5257.211418287259;5274.789008439668;5291.68249905006;5420.539254163401;4140.881961870293;4203.3274676051915;4280.657547396217;4284.807183676727;4333.891652708676;4318.5033721122;4318.611324980751;4319.82720691172;4322.675799315064;4323.524412314643;4154.5222299435645;4281.12236991416;4285.164392943378;4291.313389857623;4292.61366477017;4253.510846252659;4258.6380157686635;4266.957330426718;4271.658267338621;4282.700210985232;3844.1350696093436;3845.6346647068485;3846.7114966377353;3848.8652897148463;3865.864694735699;5144.61415393847;5301.257823904656;5314.707691076989;5321.973390133063;5331.466853314638;4886.804527818883;4887.951120488785;4896.143174944406;4897.681650692693;4901.387326584177;5697.4587414195585;5711.742971566289;5713.228714123582;5722;5728.8854222915725;4034;4124.319487284594;4129.91740165195;4161.209370219679;4163.333866581363;5872.5889187756875;5876.853078673024;5896.764129434818;5910.34986151523;5935.821925342247;4148.398482220061;4153.958460415418;4171.937420938689;4177.2455568660625;4179.373094035919;4019.41718450823;4191.245575796386;4195.475565554306;4197.580241975778;4207.474065741782;4818.445878723992;4833.32333473314;4837.443693975168;4846.200376937836;4847.75761211938;5728.398518155617;5743.2246646703;5751.482366587027;5767.740451679684;5775.364709881909;1446.797448357231;1453.7062014806831;1484.2430360515998;1488.1666266890577;1490.754025585788;904.3217586809892;917.4632839788665;928.2342067794096;929.8794952082724;938.8920274168559;4834.661573689808;4836.3712717347225;4838.371011768518;4853.6602437829015;4855.5387238212315;5114.616403769732;5148.382194136728;5164.8192313268855;5168.664036837668;5171.922421163686;4579.862604121881;4853.344798452164;4864.221724524059;4897.310427902368;4906.288588154697;2735.2478068531314;2736.6031925371108;2738.342797728531;2739.6849362323187;2745.4783591117625;5224.753356056932;5232.319798426181;5241.9213711794355;5263.34208223972;5265.736713164327;5205.947940520622;5236.188390799432;5246.554042906396;5551.500364967173;5970.223870896755;6049;6067.908981365283;6071.423214794588;6073;6074.210352654127;5874.796951143321;5902.498287645598;5907.616004959664;5916;5930.555208359477;5548.8624831910165;5820.883582328245;5861.1501332305725;5861.472467477947;5862.091375836752;6026.939730602638;6048.60684055535;6048.697565121638;6069.302030266399;6084.90872636902;4050.6151915567975;4192.685548583808;4263.253930562201;4275.845521709183;4280.550542193025;5606.635568688022;5727.914081288701;5789.623674461147;5905.557083218861;5920.141579471038;5765.827025189251;5768.971155144198;5777.508911742549;5779.68211748341;5796.275465566804;5627.465390787868;5702.429757024264;5759.625624334405;5762.94237057624;5768.365479797135;5671.120976248688;5688.146777983336;5702.714864256687;5717.656940583578;5727.570432217683;5752.252207213036;5759.942400576108;5786.594938354275;5799.188113664175;5802.912956305574;5429.375621803385;5556.249906262603;5643.774249030157;5646.689432081111;5662.405447427961 07:10:21 INFO - PROCESS | 7502 | |5;yui.html;798.5767543202103;843.2789964580284;843.7004863273538;844.1558441558442;923.1213039088419;15667.843321566781;16631.251593678284;16761;16854.57863553412;16934.83065169348;15101.697966040672;15122.621934451618;15226.847731522681;15313.846861531381;15322.846771532282;885.6811547842788;898.7932775461647;898.8382091223577;900.4732231644496;901.1709227510706;736.3152268390395;761.6877080397038;867.6659486097834;881.418263945796;888.9733308000771;933.733885842535;939.0421769794805;939.3283802081511;939.6945992552413;943.1087622197026;935.3592788939599;936.2042264075516;936.2463217110223;936.6066252174103;936.9953150234239;566.1875209074991;582.5980073749099;671.9764808231688;682.147315855181;686.2828344380134;14245.358958846773;14255.215963122024;14269.215193164371;14270.143791372551;14273.143611383246;14441.205733684643;14898.553043408716;15388.923055384652;15606.141662208574;15711.450099246586;13874;13899.930500347535;13973.650658733512;14058;14327;4256.446661933929;4369.257226271509;4383;4383.123375324916;4601.7699115044115;86695.99999999937;86947.56526217329;87126.56436717839;87175;87400.56299718525;10845.728856778485;11567.652970410902;11763.353015584054;11793.646190614296;11926;21451.67822482664;21561.892190538947;21587.13651453924;21595.352139435843;21600.67598985985;8693.391462597683;8728.781780455412;8903.910960890438;8906.510141942126;8956.68651597191;4810.63920205986;4952.603791696655;4953.504649535018;5053.5957123430035;5224.399194092728;3539.1682954505673;3693.1874987502706;3701.8149092545264;3707.7775333480076;3711.2391959648207;595.1548800703013;606.75123199488;610.9725062372204;619.3094699410216;622.6699849079995;664.8769977554168;698.598305974067;727.1928159742681;731.9597422141727;763.6029264782283;8876.955615221883;9427.481488518197;9594.760130996781;9622;9682.854757178648;537.8010136249535;552.7871769368742;563.2677519224991;641.6310621392718;647.4852492268658;4705.341252224691;4952.028782730405;5022.673870422247;5043.372408913005;5108.361454818148;122314;123690;125531;125946;130017.34991324983;110069;111871.4406427979;112152;114729.4263528677;117758;99211.50394248127;99644;100821.49589252153;101538.49230753946;103296;9570.47362395075;9871.457069861226;9879.802403952024;9945.204383649147;10049.296549241482;55342;56067;56441.435585643616;56684.43315566792;56882.7155864218;81831.18168818236;83298;84746;84999.57500212459;85074.14925850663;12471.5011399543;12501.312427816558;12848.229106253655;12898.290594017419;13060.151090179108;14033.578992630237;14132.929335353258;14273.14361138335;14492.275386230436;14508.564743057725;13163.210207387587;13514;13709.383077761326;13753.518626848012;14412.279386030867;13142.34288285601;13211.66970825737;13563.118397304144;13743.931280343535;13863.584092477242;12827.743445131231;13010.349482526026;13067.34663266852;13126.540571079966;13997.230152341719;10684.358938463716;11079.113670906147;11088;11179.217454778413;11326.660200194008;10695.839562406414;11230.101591872794;11506.76986460283;11508.597199098327;11516.481758321062 07:10:21 INFO - PROCESS | 7502 | __end_tp_report 07:10:21 INFO - PROCESS | 7502 | __start_cc_report 07:10:21 INFO - PROCESS | 7502 | _x_x_mozilla_cycle_collect,1047 07:10:21 INFO - PROCESS | 7502 | __end_cc_report 07:10:21 INFO - PROCESS | 7502 | __startTimestamp1479136221861__endTimestamp 07:10:21 INFO - PROCESS | 7502 | 07:10:21 INFO - PROCESS | 7502 | ------- Summary: start ------- 07:10:21 INFO - PROCESS | 7502 | Number of tests: 6 07:10:21 INFO - PROCESS | 7502 | 07:10:21 INFO - PROCESS | 7502 | [#0] dojo.html Cycles:165 Average:5498.82 Median:5755.51 stddev:637.80 (11.1%) stddev-sans-first:611.19 07:10:21 INFO - PROCESS | 7502 | Values: 3092.8 3150.4 3162.7 3185.9 3200.8 5489.6 5570.7 5588.3 5601.5 5602.8 5478.7 5504.9 5534.5 5536.0 5552.4 4973.4 4991.1 5005.0 5061.4 5079.0 4918.9 5001.4 5056.3 5062.5 5063.7 4898.3 5004.1 5039.9 5073.9 5076.2 5028.8 5114.6 5132.6 5134.9 5160.3 4967.0 5038.8 5084.6 5094.6 5107.8 5336.7 5355.9 5392.2 5405.3 5415.9 5794.7 5807.7 5825.4 5826.2 5836.7 5864.6 5868.5 5877.4 5888.2 5891.3 5355.0 5380.3 5516.5 5568.2 5574.3 6153.4 6154.8 6159.2 6173.5 6175.4 5901.8 5913.8 5926.9 5929.0 5934.1 5653.3 5755.5 5804.2 5969.2 5982.7 5828.5 5841.2 5866.0 5879.9 5895.9 5863.4 5871.1 5874.2 5879.1 5906.7 4429.7 4448.5 4534.7 4540.1 4555.8 4011.8 4065.3 4067.4 4076.8 4101.9 5694.1 5695.5 5697.3 5697.8 5725.9 5747.1 5948.3 5989.2 6003.7 6006.1 5449.4 5496.0 5517.1 5523.5 5584.3 4931.0 4954.5 4956.0 4963.6 4975.1 6149.5 6162.1 6165.3 6165.4 6180.2 5787.7 5815.0 5916.5 5920.6 5953.0 6163.4 6163.5 6189.2 6202.8 6208.4 5852.5 5889.9 5890.1 5905.3 5949.9 5678.1 5759.7 5961.8 5987.9 5996.4 5864.0 5872.5 5882.7 5910.0 5913.9 5835.8 5847.1 5848.4 5854.2 5872.6 5578.9 5611.4 5800.6 5803.8 5818.0 5817.1 5850.7 5850.8 5861.8 5940.2 5745.3 5751.7 5755.6 5759.3 5762.3 07:10:21 INFO - PROCESS | 7502 | 07:10:21 INFO - PROCESS | 7502 | [#1] ext.html Cycles:180 Average:57930.22 Median:390837.00 stddev:112030.96 (28.7%) stddev-sans-first:112262.52 07:10:21 INFO - PROCESS | 7502 | Values: 589.5 615.0 643.4 652.2 665.7 34076.5 45671.5 46389.8 46542.5 46637.3 47577.8 48049.5 48145.8 48279.5 48286.0 8606.2 9322.5 9484.4 9486.6 9493.2 8386.2 11024.8 11297.0 11305.3 11621.9 11323.8 11379.5 11386.8 11394.5 11394.7 11191.6 11228.2 11266.9 11275.6 11282.6 11088.7 11115.6 11172.0 11176.8 11179.1 43954.8 44437.1 44453.8 44492.1 44570.0 3862.8 3867.8 3997.7 4271.3 4578.9 33089.0 33259.2 33331.5 33588.5 35372.6 4224.1 4277.7 4299.7 4315.1 4317.7 245328.0 245913.0 245993.0 246549.0 246636.0 62010.4 62431.4 62436.0 62457.0 62467.7 40566.0 41363.6 41474.2 43051.6 43076.8 17964.7 18051.1 18058.6 18062.7 18063.6 13556.9 13792.8 14655.3 14657.9 14668.0 19918.0 20590.4 20726.6 20730.3 20781.0 7514.5 7602.6 7611.5 7745.9 7866.8 1052.5 1160.0 1164.3 1177.2 1190.6 34151.8 34360.8 34457.5 34558.8 34580.7 1148.2 1170.7 1184.1 1193.4 1266.3 18208.3 18218.6 18238.0 18259.9 18338.5 561693.0 568060.0 568576.0 568895.0 570298.0 62029.1 62668.1 62767.4 62791.7 62928.4 1356.1 1416.1 1422.6 1465.8 1498.9 30574.4 35321.3 35498.3 35673.5 35760.5 52069.0 52629.5 52688.7 52711.0 52823.5 387030.0 390208.0 390563.0 391111.0 391291.0 29923.0 31169.0 31170.0 31172.7 31231.7 45520.3 45636.5 46372.8 46910.3 46948.8 43178.6 43588.3 43624.3 44759.6 46189.1 43635.3 44530.3 44566.0 45283.5 45530.0 42912.8 43010.1 43170.0 43252.0 43362.1 37703.8 39117.2 39223.0 39426.8 39842.6 29161.3 29396.7 29712.7 29728.1 29940.9 07:10:21 INFO - PROCESS | 7502 | 07:10:21 INFO - PROCESS | 7502 | [#2] jquery.html Cycles:180 Average:29433.36 Median:2938.04 stddev:80340.46 (2734.5%) stddev-sans-first:80536.24 07:10:21 INFO - PROCESS | 7502 | Values: 387.3 422.2 430.1 442.3 443.7 16108.4 19405.4 20480.4 21123.6 21303.4 20222.5 20897.1 20978.5 20995.2 21163.9 5758.1 5790.5 5986.8 5990.9 5991.7 5901.4 6030.7 6033.4 6035.4 6038.1 5737.8 5925.0 5988.6 5998.1 6000.8 5909.1 5939.5 5940.9 5943.7 5948.9 5257.1 5277.2 5338.5 5344.4 5378.3 22202.2 22895.7 22978.1 24371.4 24441.4 2297.2 2564.8 2732.6 2847.3 3028.8 10502.8 15040.5 15116.2 15361.5 15724.9 1877.1 1956.9 2006.1 2023.5 2033.8 84391.0 87732.6 88962.6 89110.6 91101.5 10435.3 11592.9 11788.8 11790.4 11985.9 13997.3 14597.7 14607.9 14625.1 15811.6 7084.7 8456.8 8862.9 9084.0 9092.4 8697.5 8758.4 8770.6 8811.0 9026.8 5080.0 5106.4 5114.9 5194.7 5315.2 2077.5 2088.6 2096.1 2097.2 2164.1 925.8 983.9 985.3 1033.1 1034.8 19648.1 20237.6 20396.5 21351.7 21651.2 1007.2 1025.7 1037.2 1038.6 1080.7 10754.4 11600.9 11673.6 11715.3 11822.0 455439.0 468910.0 472527.0 473340.0 549381.0 96432.5 96464.5 100996.5 102988.5 103220.5 497.7 519.0 522.2 523.7 527.0 11224.3 11479.2 11737.9 11931.8 12430.8 33925.7 39887.2 42483.0 44677.3 44742.3 59170.4 59327.7 59340.0 60862.0 61218.7 8529.2 8846.2 9221.8 9228.4 9303.5 10925.7 12872.9 13169.8 13237.3 13860.2 5936.9 7690.5 11607.6 12225.3 12477.0 11420.0 11667.6 11927.8 12014.1 12077.9 11865.2 11977.0 12182.5 12314.5 12704.6 8903.5 9031.3 9198.1 9288.2 9494.4 9932.4 11433.8 11906.9 11996.2 12003.5 07:10:21 INFO - PROCESS | 7502 | 07:10:21 INFO - PROCESS | 7502 | [#3] mootools.html Cycles:180 Average:5702.24 Median:5047.30 stddev:3066.90 (60.8%) stddev-sans-first:3059.17 07:10:21 INFO - PROCESS | 7502 | Values: 1490.6 1530.4 1673.2 1782.4 1808.1 8055.7 8609.2 8629.9 8639.0 8656.0 7408.9 7648.5 8042.8 8324.9 8331.0 2457.7 2479.3 2480.2 2481.7 2482.2 2436.2 2465.7 2487.1 2487.6 2490.5 2452.6 2454.1 2455.2 2477.1 2478.7 2414.0 2431.6 2434.4 2435.3 2442.1 2421.4 2427.1 2439.0 2446.7 2468.2 8517.5 8583.0 8585.8 8601.3 8610.5 5032.0 5221.9 5233.6 5234.6 5245.0 3322.7 3428.9 3429.9 3457.8 3503.1 1134.0 1163.4 1173.5 1192.4 1212.6 11331.9 11346.1 11355.3 11362.8 11409.2 4504.5 4690.2 4817.7 4854.0 4855.9 9841.8 9869.1 9879.3 9890.6 9893.4 5417.8 5419.7 5419.8 5427.1 5429.8 4443.7 4578.8 4597.4 4892.0 4998.7 4045.7 4149.6 4193.9 4215.2 4244.2 1379.9 1383.6 1394.3 1398.2 1412.7 958.7 1000.9 1046.1 1050.2 1059.4 5977.7 6004.2 6031.0 6123.2 6327.4 952.6 983.0 994.1 1035.5 1052.3 4153.3 4161.0 4196.0 4248.9 4399.5 10276.0 10567.7 10568.2 10582.9 10593.2 10439.4 10467.5 10469.9 10472.9 10549.2 7681.4 7944.2 8004.3 8059.8 8090.2 6146.8 6200.3 6205.2 6231.0 6257.4 9606.8 9648.6 9657.4 9753.4 9761.4 9539.0 10037.4 10049.1 10059.4 10074.6 6805.8 6990.7 6997.7 7676.2 7795.7 7740.5 7776.1 7871.4 7977.1 8106.6 7370.0 7547.8 7593.6 7635.6 7717.8 7228.4 7366.0 7393.3 7408.2 7572.3 7018.6 7108.4 7135.9 7136.9 7138.3 5062.6 5177.8 5231.6 5317.4 5328.6 5133.2 5215.6 5217.8 5286.4 5289.8 07:10:21 INFO - PROCESS | 7502 | 07:10:21 INFO - PROCESS | 7502 | [#4] prototype.html Cycles:180 Average:4719.60 Median:5239.05 stddev:1387.35 (26.5%) stddev-sans-first:1351.60 07:10:21 INFO - PROCESS | 7502 | Values: 332.7 347.4 351.7 355.2 358.8 5436.8 5465.4 5483.7 5486.2 5493.7 5108.9 5257.2 5274.8 5291.7 5420.5 4140.9 4203.3 4280.7 4284.8 4333.9 4318.5 4318.6 4319.8 4322.7 4323.5 4154.5 4281.1 4285.2 4291.3 4292.6 4253.5 4258.6 4267.0 4271.7 4282.7 3844.1 3845.6 3846.7 3848.9 3865.9 5144.6 5301.3 5314.7 5322.0 5331.5 4886.8 4888.0 4896.1 4897.7 4901.4 5697.5 5711.7 5713.2 5722.0 5728.9 4034.0 4124.3 4129.9 4161.2 4163.3 5872.6 5876.9 5896.8 5910.3 5935.8 4148.4 4154.0 4171.9 4177.2 4179.4 4019.4 4191.2 4195.5 4197.6 4207.5 4818.4 4833.3 4837.4 4846.2 4847.8 5728.4 5743.2 5751.5 5767.7 5775.4 1446.8 1453.7 1484.2 1488.2 1490.8 904.3 917.5 928.2 929.9 938.9 4834.7 4836.4 4838.4 4853.7 4855.5 5114.6 5148.4 5164.8 5168.7 5171.9 4579.9 4853.3 4864.2 4897.3 4906.3 2735.2 2736.6 2738.3 2739.7 2745.5 5224.8 5232.3 5241.9 5263.3 5265.7 5205.9 5236.2 5246.6 5551.5 5970.2 6049.0 6067.9 6071.4 6073.0 6074.2 5874.8 5902.5 5907.6 5916.0 5930.6 5548.9 5820.9 5861.2 5861.5 5862.1 6026.9 6048.6 6048.7 6069.3 6084.9 4050.6 4192.7 4263.3 4275.8 4280.6 5606.6 5727.9 5789.6 5905.6 5920.1 5765.8 5769.0 5777.5 5779.7 5796.3 5627.5 5702.4 5759.6 5762.9 5768.4 5671.1 5688.1 5702.7 5717.7 5727.6 5752.3 5759.9 5786.6 5799.2 5802.9 5429.4 5556.2 5643.8 5646.7 5662.4 07:10:21 INFO - PROCESS | 7502 | 07:10:21 INFO - PROCESS | 7502 | [#5] yui.html Cycles:180 Average:22735.96 Median:4376.13 stddev:33938.87 (775.5%) stddev-sans-first:33994.11 07:10:21 INFO - PROCESS | 7502 | Values: 798.6 843.3 843.7 844.2 923.1 15667.8 16631.3 16761.0 16854.6 16934.8 15101.7 15122.6 15226.8 15313.8 15322.8 885.7 898.8 898.8 900.5 901.2 736.3 761.7 867.7 881.4 889.0 933.7 939.0 939.3 939.7 943.1 935.4 936.2 936.2 936.6 937.0 566.2 582.6 672.0 682.1 686.3 14245.4 14255.2 14269.2 14270.1 14273.1 14441.2 14898.6 15388.9 15606.1 15711.5 13874.0 13899.9 13973.7 14058.0 14327.0 4256.4 4369.3 4383.0 4383.1 4601.8 86696.0 86947.6 87126.6 87175.0 87400.6 10845.7 11567.7 11763.4 11793.6 11926.0 21451.7 21561.9 21587.1 21595.4 21600.7 8693.4 8728.8 8903.9 8906.5 8956.7 4810.6 4952.6 4953.5 5053.6 5224.4 3539.2 3693.2 3701.8 3707.8 3711.2 595.2 606.8 611.0 619.3 622.7 664.9 698.6 727.2 732.0 763.6 8877.0 9427.5 9594.8 9622.0 9682.9 537.8 552.8 563.3 641.6 647.5 4705.3 4952.0 5022.7 5043.4 5108.4 122314.0 123690.0 125531.0 125946.0 130017.3 110069.0 111871.4 112152.0 114729.4 117758.0 99211.5 99644.0 100821.5 101538.5 103296.0 9570.5 9871.5 9879.8 9945.2 10049.3 55342.0 56067.0 56441.4 56684.4 56882.7 81831.2 83298.0 84746.0 84999.6 85074.1 12471.5 12501.3 12848.2 12898.3 13060.2 14033.6 14132.9 14273.1 14492.3 14508.6 13163.2 13514.0 13709.4 13753.5 14412.3 13142.3 13211.7 13563.1 13743.9 13863.6 12827.7 13010.3 13067.3 13126.5 13997.2 10684.4 11079.1 11088.0 11179.2 11326.7 10695.8 11230.1 11506.8 11508.6 11516.5 07:10:21 INFO - PROCESS | 7502 | -------- Summary: end -------- 07:10:21 INFO - PROCESS | 7502 | 07:10:22 INFO - TEST-INFO | 7502: exit 0 07:10:22 INFO - TEST-OK | dromaeo_css | took 1113153ms 07:10:22 INFO - TEST-START | kraken 07:10:22 INFO - operating with platform_type : linux_ 07:10:22 INFO - Initialising browser for kraken test... 07:10:22 INFO - TEST-INFO | started process 7825 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoAKewa/profile http://localhost:35622/getInfo.html) 07:10:26 INFO - PROCESS | 7825 | __metrics Screen width/height:1600/1200 07:10:26 INFO - PROCESS | 7825 | colorDepth:24 07:10:26 INFO - PROCESS | 7825 | Browser inner width/height: 1024/697 07:10:26 INFO - PROCESS | 7825 | __metrics 07:10:27 INFO - PROCESS | 7825 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:10:27 INFO - PROCESS | 7825 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:10:27 INFO - PROCESS | 7825 | console.error: PushService: 07:10:27 INFO - PROCESS | 7825 | stateChangeProcessEnqueue: Error transitioning state 07:10:27 INFO - PROCESS | 7825 | UnknownError 07:10:28 INFO - TEST-INFO | 7825: exit 0 07:10:28 INFO - Browser initialized. 07:10:28 INFO - Running cycle 1/1 for kraken test... 07:10:28 INFO - TEST-INFO | started process 7915 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoAKewa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:10:28 CRITICAL - PROCESS | 7915 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 07:10:28 INFO - Terminating psutil.Process(pid=7915, name='firefox') 07:10:28 INFO - TEST-INFO | 7915: exit 127 07:10:28 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 07:10:28 ERROR - Traceback (most recent call last): 07:10:28 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 07:10:28 INFO - talos_results.add(mytest.runTest(browser_config, test)) 07:10:28 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 07:10:28 INFO - return self._runTest(browser_config, test_config, setup) 07:10:28 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 232, in _runTest 07:10:28 INFO - else None) 07:10:28 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 07:10:28 INFO - global_counters=self.global_counters 07:10:28 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 07:10:28 INFO - self.parse() 07:10:28 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 07:10:28 INFO - % self.report_tokens) 07:10:28 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 07:10:28 INFO - raise utils.TalosError(message) 07:10:28 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 07:10:28 INFO - TEST-INFO took 5648ms 07:10:28 INFO - SUITE-END | took 1118s 07:10:28 ERROR - Return code: 2 07:10:28 WARNING - setting return code to 2 07:10:28 CRITICAL - # TBPL RETRY # 07:10:28 WARNING - setting return code to 4 07:10:28 INFO - Running post-action listener: _resource_record_post_action 07:10:28 INFO - [mozharness: 2016-11-14 15:10:28.761697Z] Finished run-tests step (success) 07:10:28 INFO - Running post-run listener: _resource_record_post_run 07:10:28 INFO - Total resource usage - Wall time: 1135s; CPU: 13.0%; Read bytes: 8192; Write bytes: 316678144; Read time: 356; Write time: 283772 07:10:28 INFO - TinderboxPrint: CPU usage
13.1% 07:10:28 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 356 07:10:28 INFO - TinderboxPrint: I/O write bytes / time
316,678,144 / 283,772 07:10:28 INFO - TinderboxPrint: CPU idle
7,870.8 (86.9%) 07:10:28 INFO - TinderboxPrint: CPU user
1,150.2 (12.7%) 07:10:28 INFO - TinderboxPrint: Swap in / out
0 / 0 07:10:28 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118939648; Read time: 340; Write time: 108864 07:10:28 INFO - run-tests - Wall time: 1120s; CPU: 13.0%; Read bytes: 0; Write bytes: 197738496; Read time: 0; Write time: 174908 07:10:29 INFO - Running post-run listener: _upload_blobber_files 07:10:29 INFO - Blob upload gear active. 07:10:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:10:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:10:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:10:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:10:29 INFO - (blobuploader) - INFO - Open directory for files ... 07:10:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:10:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:10:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:10:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:10:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:10:30 INFO - (blobuploader) - INFO - Done attempting. 07:10:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:10:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:10:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:10:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:10:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:10:31 INFO - (blobuploader) - INFO - Done attempting. 07:10:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:10:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:10:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:10:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:10:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:10:34 INFO - (blobuploader) - INFO - Done attempting. 07:10:34 INFO - (blobuploader) - INFO - Iteration through files over. 07:10:34 INFO - Return code: 0 07:10:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:10:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:10:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f579e98390afa4609fc863069bf612afb4bf42bee343b9d80a7ec124502dfcfb7d90292bcbf4ab6ab086ab3160c86097190674610b356a346cd7efd3b8ad00ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59084adf4c8f11b7c8a821e2f98ff18f996562082652cd11c124d84f15f4ba4f3aef5c4fc173a6a0d7010f6867f63d759326666a1596fc03153cd98bae9125dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1240d2384c4d0d8ef815acb25693bb102a529c52de7aaa0e690cd0a1f72a187d80caa5e3cf70303b68b8c4cebbba104a94f2060dab3765cc8418e61724a7034d"} 07:10:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:10:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:10:34 INFO - Contents: 07:10:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f579e98390afa4609fc863069bf612afb4bf42bee343b9d80a7ec124502dfcfb7d90292bcbf4ab6ab086ab3160c86097190674610b356a346cd7efd3b8ad00ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59084adf4c8f11b7c8a821e2f98ff18f996562082652cd11c124d84f15f4ba4f3aef5c4fc173a6a0d7010f6867f63d759326666a1596fc03153cd98bae9125dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1240d2384c4d0d8ef815acb25693bb102a529c52de7aaa0e690cd0a1f72a187d80caa5e3cf70303b68b8c4cebbba104a94f2060dab3765cc8418e61724a7034d"} 07:10:34 INFO - Running post-run listener: copy_logs_to_upload_dir 07:10:34 INFO - Copying logs to upload dir... 07:10:34 INFO - mkdir: /builds/slave/test/build/upload/logs 07:10:34 INFO - Copying logs to upload dir... 07:10:34 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1178.090733 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 38 secs) (at 2016-11-14 07:10:34.027494) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:10:34.031626) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f579e98390afa4609fc863069bf612afb4bf42bee343b9d80a7ec124502dfcfb7d90292bcbf4ab6ab086ab3160c86097190674610b356a346cd7efd3b8ad00ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59084adf4c8f11b7c8a821e2f98ff18f996562082652cd11c124d84f15f4ba4f3aef5c4fc173a6a0d7010f6867f63d759326666a1596fc03153cd98bae9125dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1240d2384c4d0d8ef815acb25693bb102a529c52de7aaa0e690cd0a1f72a187d80caa5e3cf70303b68b8c4cebbba104a94f2060dab3765cc8418e61724a7034d"} build_url:https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/fUTohLACRWiVwkChiTzxtA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f579e98390afa4609fc863069bf612afb4bf42bee343b9d80a7ec124502dfcfb7d90292bcbf4ab6ab086ab3160c86097190674610b356a346cd7efd3b8ad00ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59084adf4c8f11b7c8a821e2f98ff18f996562082652cd11c124d84f15f4ba4f3aef5c4fc173a6a0d7010f6867f63d759326666a1596fc03153cd98bae9125dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1240d2384c4d0d8ef815acb25693bb102a529c52de7aaa0e690cd0a1f72a187d80caa5e3cf70303b68b8c4cebbba104a94f2060dab3765cc8418e61724a7034d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:10:34.062117) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:10:34.062847) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1479134729.307598-974532438 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004502 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:10:34.146774) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:10:34.147584) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:10:34.148187) ========= ========= Total master_lag: 0.18 =========