builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-046 starttime: 1476389447.04 results: failure (2) buildid: 20161013102025 builduid: 1bbe533c04454b6d883d9602a678adb1 revision: 28cea4babaf878d6ea5bfd19be4ac0aeef38d154 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.043972) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.044446) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.044740) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.059124) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.059405) ========= 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=652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-13 13:10:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-10-13 13:10:47 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080150 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.153350) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.153696) ========= 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=652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015932 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.203381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.203746) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 28cea4babaf878d6ea5bfd19be4ac0aeef38d154 --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 28cea4babaf878d6ea5bfd19be4ac0aeef38d154 --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=652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-13 13:10:47,527 truncating revision to first 12 chars 2016-10-13 13:10:47,528 Setting DEBUG logging. 2016-10-13 13:10:47,528 attempt 1/10 2016-10-13 13:10:47,528 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/28cea4babaf8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 13:10:47,862 unpacking tar archive at: mozilla-aurora-28cea4babaf8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.730226 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.947299) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.947645) ========= script_repo_revision: 28cea4babaf878d6ea5bfd19be4ac0aeef38d154 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.948082) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.948353) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 13:10:47.958578) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 37 secs) (at 2016-10-13 13:10:47.958876) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:10:48 INFO - MultiFileLogger online at 20161013 13:10:48 in /builds/slave/test 13:10:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:10:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:10:48 INFO - {'append_to_log': False, 13:10:48 INFO - 'base_work_dir': '/builds/slave/test', 13:10:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:10:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:10:48 INFO - 'branch': 'Mozilla-Aurora', 13:10:48 INFO - 'buildbot_json_path': 'buildprops.json', 13:10:48 INFO - 'config_files': ('talos/linux_config.py',), 13:10:48 INFO - 'default_actions': ('clobber', 13:10:48 INFO - 'read-buildbot-config', 13:10:48 INFO - 'download-and-extract', 13:10:48 INFO - 'populate-webroot', 13:10:48 INFO - 'create-virtualenv', 13:10:48 INFO - 'install', 13:10:48 INFO - 'run-tests'), 13:10:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:10:48 INFO - 'download_minidump_stackwalk': True, 13:10:48 INFO - 'download_symbols': 'ondemand', 13:10:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:10:48 INFO - 'tooltool.py': '/tools/tooltool.py', 13:10:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:10:48 INFO - '/tools/misc-python/virtualenv.py')}, 13:10:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:10:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:10:48 INFO - 'installer_path': 'installer.exe', 13:10:48 INFO - 'log_level': 'info', 13:10:48 INFO - 'log_name': 'talos', 13:10:48 INFO - 'log_to_console': True, 13:10:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:10:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:10:48 INFO - 'opt_config_files': (), 13:10:48 INFO - 'pip_index': False, 13:10:48 INFO - 'sps_profile': False, 13:10:48 INFO - 'sps_profile_interval': 0, 13:10:48 INFO - 'suite': 'dromaeojs-e10s', 13:10:48 INFO - 'system_bits': '32', 13:10:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:10:48 INFO - 'title': 'talos-linux64-ix-046', 13:10:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:10:48 INFO - 'use_talos_json': True, 13:10:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:10:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:10:48 INFO - 'work_dir': 'build'} 13:10:48 INFO - [mozharness: 2016-10-13 20:10:48.382067Z] Running clobber step. 13:10:48 INFO - Running pre-action listener: _resource_record_pre_action 13:10:48 INFO - Running main action method: clobber 13:10:48 INFO - rmtree: /builds/slave/test/build 13:10:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:10:48 INFO - Running post-action listener: _resource_record_post_action 13:10:48 INFO - [mozharness: 2016-10-13 20:10:48.848711Z] Finished clobber step (success) 13:10:48 INFO - [mozharness: 2016-10-13 20:10:48.848811Z] Running read-buildbot-config step. 13:10:48 INFO - Running pre-action listener: _resource_record_pre_action 13:10:48 INFO - Running main action method: read_buildbot_config 13:10:48 INFO - Using buildbot properties: 13:10:48 INFO - { 13:10:48 INFO - "project": "", 13:10:48 INFO - "product": "firefox", 13:10:48 INFO - "script_repo_revision": "production", 13:10:48 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 13:10:48 INFO - "repository": "", 13:10:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 13:10:48 INFO - "buildid": "20161013102025", 13:10:48 INFO - "pgo_build": "True", 13:10:48 INFO - "basedir": "/builds/slave/test", 13:10:48 INFO - "buildnumber": 590, 13:10:48 INFO - "slavename": "talos-linux64-ix-046", 13:10:48 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:10:48 INFO - "platform": "ubuntu64_hw", 13:10:48 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:10:48 INFO - "branch": "mozilla-aurora", 13:10:48 INFO - "repo_path": "releases/mozilla-aurora", 13:10:48 INFO - "revision": "28cea4babaf878d6ea5bfd19be4ac0aeef38d154", 13:10:48 INFO - "stage_platform": "linux64-pgo", 13:10:48 INFO - "builduid": "1bbe533c04454b6d883d9602a678adb1", 13:10:48 INFO - "slavebuilddir": "test" 13:10:48 INFO - } 13:10:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 13:10:48 INFO - Running post-action listener: _resource_record_post_action 13:10:48 INFO - [mozharness: 2016-10-13 20:10:48.851261Z] Finished read-buildbot-config step (success) 13:10:48 INFO - [mozharness: 2016-10-13 20:10:48.851350Z] Running download-and-extract step. 13:10:48 INFO - Running pre-action listener: _resource_record_pre_action 13:10:48 INFO - Running main action method: download_and_extract 13:10:48 INFO - mkdir: /builds/slave/test/build/tests 13:10:48 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')]} 13:10:48 INFO - trying https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 13:10:48 INFO - Downloading https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/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 13:10:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/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 13:10:49 INFO - Downloaded 1361 bytes. 13:10:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 13:10:49 INFO - Using the following test package requirements: 13:10:49 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 13:10:49 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:10:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:10:49 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:10:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:10:49 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:10:49 INFO - u'jsshell-linux-x86_64.zip'], 13:10:49 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:10:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:10:49 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 13:10:49 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:10:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:10:49 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:10:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:10:49 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:10:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:10:49 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 13:10:49 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:10:49 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:10:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 13:10:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:10:49 INFO - Fetch https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 13:10:50 INFO - Expected file size: 21907344 13:10:54 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 13:10:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 13:10:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:10:54 INFO - Fetch https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 13:10:54 INFO - Expected file size: 21907344 13:10:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 13:10:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:10:59 INFO - Fetch https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 13:11:00 INFO - Expected file size: 14414059 13:11:00 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')]} 13:11:00 INFO - trying https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 13:11:00 INFO - Downloading https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/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 13:11:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/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 13:11:01 INFO - Downloaded 63968482 bytes. 13:11:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 13:11:01 INFO - mkdir: /builds/slave/test/properties 13:11:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:11:01 INFO - Writing to file /builds/slave/test/properties/build_url 13:11:01 INFO - Contents: 13:11:01 INFO - build_url:https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 13:11:02 INFO - Running post-action listener: _resource_record_post_action 13:11:02 INFO - Running post-action listener: set_extra_try_arguments 13:11:02 INFO - [mozharness: 2016-10-13 20:11:02.438086Z] Finished download-and-extract step (success) 13:11:02 INFO - [mozharness: 2016-10-13 20:11:02.438388Z] Running populate-webroot step. 13:11:02 INFO - Running pre-action listener: _resource_record_pre_action 13:11:02 INFO - Running main action method: populate_webroot 13:11:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:11:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:11:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:11:02 INFO - '--tppagecycles', 13:11:02 INFO - '7'], 13:11:02 INFO - 'tests': ['tsvgm']}}, 13:11:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:11:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:11:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:11:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:11:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['damp', 'tps']}, 13:11:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'tests': ['damp', 'tps']}, 13:11:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['dromaeo_dom']}, 13:11:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:11:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['basic_compositor_video']}, 13:11:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:11:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other-e10s': {'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['tsvgx', 13:11:02 INFO - 'tsvgr_opacity', 13:11:02 INFO - 'tart', 13:11:02 INFO - 'tscrollx', 13:11:02 INFO - 'cart']}, 13:11:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:11:02 INFO - 'tsvgr_opacity', 13:11:02 INFO - 'tart', 13:11:02 INFO - 'tscrollx', 13:11:02 INFO - 'cart']}, 13:11:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['tp5o']}, 13:11:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'tests': ['tp5o']}, 13:11:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--disable-e10s', 13:11:02 INFO - '--xperf_path', 13:11:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:02 INFO - 'tests': ['tp5n']}, 13:11:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--xperf_path', 13:11:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:02 INFO - 'tests': ['tp5n']}}, 13:11:02 INFO - 'talos.zip': {'path': '', 13:11:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:11:02 INFO - Running post-action listener: _resource_record_post_action 13:11:02 INFO - [mozharness: 2016-10-13 20:11:02.456983Z] Finished populate-webroot step (success) 13:11:02 INFO - [mozharness: 2016-10-13 20:11:02.457075Z] Running create-virtualenv step. 13:11:02 INFO - Running pre-action listener: _resource_record_pre_action 13:11:02 INFO - Running main action method: create_virtualenv 13:11:02 INFO - Creating virtualenv /builds/slave/test/build/venv 13:11:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:11:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:11:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:11:02 INFO - Using real prefix '/usr' 13:11:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:11:03 INFO - Installing distribute.............................................................................................................................................................................................done. 13:11:05 INFO - Installing pip.................done. 13:11:05 INFO - Return code: 0 13:11:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:11:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:05 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 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:11:05 INFO - Using env: {'DISPLAY': ':0', 13:11:05 INFO - 'HOME': '/home/cltbld', 13:11:05 INFO - 'LANG': 'en_US.UTF-8', 13:11:05 INFO - 'LANGUAGE': 'en_US:en', 13:11:05 INFO - 'LOGNAME': 'cltbld', 13:11:05 INFO - 'MAIL': '/var/mail/cltbld', 13:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:05 INFO - 'MOZ_NO_REMOTE': '1', 13:11:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:05 INFO - 'NO_EM_RESTART': '1', 13:11:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:05 INFO - 'PWD': '/builds/slave/test', 13:11:05 INFO - 'SHELL': '/bin/bash', 13:11:05 INFO - 'SHLVL': '1', 13:11:05 INFO - 'TERM': 'linux', 13:11:05 INFO - 'TMOUT': '86400', 13:11:05 INFO - 'USER': 'cltbld', 13:11:05 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:05 INFO - '_': '/tools/buildbot/bin/python'} 13:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:11:05 INFO - Cleaning up... 13:11:05 INFO - Return code: 0 13:11:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:11:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:05 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 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:11:05 INFO - Using env: {'DISPLAY': ':0', 13:11:05 INFO - 'HOME': '/home/cltbld', 13:11:05 INFO - 'LANG': 'en_US.UTF-8', 13:11:05 INFO - 'LANGUAGE': 'en_US:en', 13:11:05 INFO - 'LOGNAME': 'cltbld', 13:11:05 INFO - 'MAIL': '/var/mail/cltbld', 13:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:05 INFO - 'MOZ_NO_REMOTE': '1', 13:11:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:05 INFO - 'NO_EM_RESTART': '1', 13:11:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:05 INFO - 'PWD': '/builds/slave/test', 13:11:05 INFO - 'SHELL': '/bin/bash', 13:11:05 INFO - 'SHLVL': '1', 13:11:05 INFO - 'TERM': 'linux', 13:11:05 INFO - 'TMOUT': '86400', 13:11:05 INFO - 'USER': 'cltbld', 13:11:05 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:05 INFO - '_': '/tools/buildbot/bin/python'} 13:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:05 INFO - Downloading/unpacking psutil>=3.1.1 13:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:11:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:07 INFO - Installing collected packages: psutil 13:11:07 INFO - Running setup.py install for psutil 13:11:07 INFO - building 'psutil._psutil_linux' extension 13:11:07 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 13:11:07 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 13:11:07 INFO - building 'psutil._psutil_posix' extension 13:11:07 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 13:11:07 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 13:11:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:07 INFO - Successfully installed psutil 13:11:07 INFO - Cleaning up... 13:11:08 INFO - Return code: 0 13:11:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:08 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 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:11:08 INFO - Using env: {'DISPLAY': ':0', 13:11:08 INFO - 'HOME': '/home/cltbld', 13:11:08 INFO - 'LANG': 'en_US.UTF-8', 13:11:08 INFO - 'LANGUAGE': 'en_US:en', 13:11:08 INFO - 'LOGNAME': 'cltbld', 13:11:08 INFO - 'MAIL': '/var/mail/cltbld', 13:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:08 INFO - 'MOZ_NO_REMOTE': '1', 13:11:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:08 INFO - 'NO_EM_RESTART': '1', 13:11:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:08 INFO - 'PWD': '/builds/slave/test', 13:11:08 INFO - 'SHELL': '/bin/bash', 13:11:08 INFO - 'SHLVL': '1', 13:11:08 INFO - 'TERM': 'linux', 13:11:08 INFO - 'TMOUT': '86400', 13:11:08 INFO - 'USER': 'cltbld', 13:11:08 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:08 INFO - '_': '/tools/buildbot/bin/python'} 13:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:11:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:11:10 INFO - Installing collected packages: mozsystemmonitor 13:11:10 INFO - Running setup.py install for mozsystemmonitor 13:11:10 INFO - Successfully installed mozsystemmonitor 13:11:10 INFO - Cleaning up... 13:11:10 INFO - Return code: 0 13:11:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:10 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 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11:10 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 13:11:10 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 13:11:10 INFO - Using env: {'DISPLAY': ':0', 13:11:10 INFO - 'HOME': '/home/cltbld', 13:11:10 INFO - 'LANG': 'en_US.UTF-8', 13:11:10 INFO - 'LANGUAGE': 'en_US:en', 13:11:10 INFO - 'LOGNAME': 'cltbld', 13:11:10 INFO - 'MAIL': '/var/mail/cltbld', 13:11:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:10 INFO - 'MOZ_NO_REMOTE': '1', 13:11:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:10 INFO - 'NO_EM_RESTART': '1', 13:11:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:10 INFO - 'PWD': '/builds/slave/test', 13:11:10 INFO - 'SHELL': '/bin/bash', 13:11:10 INFO - 'SHLVL': '1', 13:11:10 INFO - 'TERM': 'linux', 13:11:10 INFO - 'TMOUT': '86400', 13:11:10 INFO - 'USER': 'cltbld', 13:11:10 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:10 INFO - '_': '/tools/buildbot/bin/python'} 13:11:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:10 INFO - Downloading/unpacking jsonschema==2.5.1 13:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:11:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:12 INFO - Downloading functools32-3.2.3-2.tar.gz 13:11:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:11:12 INFO - warning: no files found matching '*.txt' 13:11:12 INFO - no previously-included directories found matching 'build' 13:11:12 INFO - no previously-included directories found matching 'dist' 13:11:12 INFO - no previously-included directories found matching '.git*' 13:11:12 INFO - Installing collected packages: jsonschema, functools32 13:11:12 INFO - Running setup.py install for functools32 13:11:12 INFO - warning: no files found matching '*.txt' 13:11:12 INFO - no previously-included directories found matching 'build' 13:11:12 INFO - no previously-included directories found matching 'dist' 13:11:12 INFO - no previously-included directories found matching '.git*' 13:11:12 INFO - Successfully installed jsonschema functools32 13:11:12 INFO - Cleaning up... 13:11:12 INFO - Return code: 0 13:11:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:12 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 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11:12 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 13:11:12 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 13:11:12 INFO - Using env: {'DISPLAY': ':0', 13:11:12 INFO - 'HOME': '/home/cltbld', 13:11:12 INFO - 'LANG': 'en_US.UTF-8', 13:11:12 INFO - 'LANGUAGE': 'en_US:en', 13:11:12 INFO - 'LOGNAME': 'cltbld', 13:11:12 INFO - 'MAIL': '/var/mail/cltbld', 13:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:12 INFO - 'MOZ_NO_REMOTE': '1', 13:11:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:12 INFO - 'NO_EM_RESTART': '1', 13:11:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:12 INFO - 'PWD': '/builds/slave/test', 13:11:12 INFO - 'SHELL': '/bin/bash', 13:11:12 INFO - 'SHLVL': '1', 13:11:12 INFO - 'TERM': 'linux', 13:11:12 INFO - 'TMOUT': '86400', 13:11:12 INFO - 'USER': 'cltbld', 13:11:12 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:12 INFO - '_': '/tools/buildbot/bin/python'} 13:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:11:13 INFO - Cleaning up... 13:11:13 INFO - Return code: 0 13:11:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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', '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 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:11: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 blobuploader==1.2.4 13:11:13 INFO - Using env: {'DISPLAY': ':0', 13:11:13 INFO - 'HOME': '/home/cltbld', 13:11:13 INFO - 'LANG': 'en_US.UTF-8', 13:11:13 INFO - 'LANGUAGE': 'en_US:en', 13:11:13 INFO - 'LOGNAME': 'cltbld', 13:11:13 INFO - 'MAIL': '/var/mail/cltbld', 13:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:13 INFO - 'MOZ_NO_REMOTE': '1', 13:11:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:13 INFO - 'NO_EM_RESTART': '1', 13:11:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:13 INFO - 'PWD': '/builds/slave/test', 13:11:13 INFO - 'SHELL': '/bin/bash', 13:11:13 INFO - 'SHLVL': '1', 13:11:13 INFO - 'TERM': 'linux', 13:11:13 INFO - 'TMOUT': '86400', 13:11:13 INFO - 'USER': 'cltbld', 13:11:13 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:13 INFO - '_': '/tools/buildbot/bin/python'} 13:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:13 INFO - Downloading/unpacking blobuploader==1.2.4 13:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:15 INFO - Downloading blobuploader-1.2.4.tar.gz 13:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:11:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:11:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:16 INFO - Downloading docopt-0.6.1.tar.gz 13:11:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:11:16 INFO - Installing collected packages: blobuploader, requests, docopt 13:11:16 INFO - Running setup.py install for blobuploader 13:11:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:11:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:11:16 INFO - Running setup.py install for requests 13:11:16 INFO - Running setup.py install for docopt 13:11:16 INFO - Successfully installed blobuploader requests docopt 13:11:16 INFO - Cleaning up... 13:11:16 INFO - Return code: 0 13:11:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:16 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 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:11:16 INFO - Using env: {'DISPLAY': ':0', 13:11:16 INFO - 'HOME': '/home/cltbld', 13:11:16 INFO - 'LANG': 'en_US.UTF-8', 13:11:16 INFO - 'LANGUAGE': 'en_US:en', 13:11:16 INFO - 'LOGNAME': 'cltbld', 13:11:16 INFO - 'MAIL': '/var/mail/cltbld', 13:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:16 INFO - 'MOZ_NO_REMOTE': '1', 13:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:16 INFO - 'NO_EM_RESTART': '1', 13:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:16 INFO - 'PWD': '/builds/slave/test', 13:11:16 INFO - 'SHELL': '/bin/bash', 13:11:16 INFO - 'SHLVL': '1', 13:11:16 INFO - 'TERM': 'linux', 13:11:16 INFO - 'TMOUT': '86400', 13:11:16 INFO - 'USER': 'cltbld', 13:11:16 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:16 INFO - '_': '/tools/buildbot/bin/python'} 13:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:16 INFO - Running setup.py (path:/tmp/pip-WGyir2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:17 INFO - Running setup.py (path:/tmp/pip-uPIwVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:17 INFO - Running setup.py (path:/tmp/pip-cPldy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:17 INFO - Running setup.py (path:/tmp/pip-p1ox7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:17 INFO - Running setup.py (path:/tmp/pip-ZWwFbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:17 INFO - Running setup.py (path:/tmp/pip-2c8SFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:17 INFO - Running setup.py (path:/tmp/pip-IiAT3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:17 INFO - Running setup.py (path:/tmp/pip-Mxvbqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:17 INFO - Running setup.py (path:/tmp/pip-mSCXz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:17 INFO - Running setup.py (path:/tmp/pip-_he76G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:17 INFO - Running setup.py (path:/tmp/pip-q0Izpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:17 INFO - Running setup.py (path:/tmp/pip-rU1YPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:17 INFO - Running setup.py (path:/tmp/pip-mWaxTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:17 INFO - Running setup.py (path:/tmp/pip-EN7wJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:17 INFO - Running setup.py (path:/tmp/pip-fPjXYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:18 INFO - Running setup.py (path:/tmp/pip-xggRI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:18 INFO - Running setup.py (path:/tmp/pip-Nz1kiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:11:18 INFO - Running setup.py install for manifestparser 13:11:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:11:18 INFO - Running setup.py install for mozcrash 13:11:18 INFO - Running setup.py install for mozdebug 13:11:18 INFO - Running setup.py install for mozdevice 13:11:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:11:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:11:18 INFO - Running setup.py install for mozfile 13:11:18 INFO - Running setup.py install for mozhttpd 13:11:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:11:18 INFO - Running setup.py install for mozinfo 13:11:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:11:18 INFO - Running setup.py install for mozInstall 13:11:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:11:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:11:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:11:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:11:18 INFO - Running setup.py install for mozleak 13:11:19 INFO - Running setup.py install for mozlog 13:11:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:11:19 INFO - Running setup.py install for moznetwork 13:11:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:11:19 INFO - Running setup.py install for mozprocess 13:11:19 INFO - Running setup.py install for mozprofile 13:11:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:11:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:11:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:11:19 INFO - Running setup.py install for mozrunner 13:11:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:11:19 INFO - Running setup.py install for mozscreenshot 13:11:19 INFO - Running setup.py install for moztest 13:11:19 INFO - Running setup.py install for mozversion 13:11:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:11:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:11:19 INFO - Cleaning up... 13:11:19 INFO - Return code: 0 13:11:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:19 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 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:11:19 INFO - Using env: {'DISPLAY': ':0', 13:11:19 INFO - 'HOME': '/home/cltbld', 13:11:19 INFO - 'LANG': 'en_US.UTF-8', 13:11:19 INFO - 'LANGUAGE': 'en_US:en', 13:11:19 INFO - 'LOGNAME': 'cltbld', 13:11:19 INFO - 'MAIL': '/var/mail/cltbld', 13:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:19 INFO - 'MOZ_NO_REMOTE': '1', 13:11:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:19 INFO - 'NO_EM_RESTART': '1', 13:11:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:19 INFO - 'PWD': '/builds/slave/test', 13:11:19 INFO - 'SHELL': '/bin/bash', 13:11:19 INFO - 'SHLVL': '1', 13:11:19 INFO - 'TERM': 'linux', 13:11:19 INFO - 'TMOUT': '86400', 13:11:19 INFO - 'USER': 'cltbld', 13:11:19 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:19 INFO - '_': '/tools/buildbot/bin/python'} 13:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:20 INFO - Running setup.py (path:/tmp/pip-byllrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:20 INFO - Running setup.py (path:/tmp/pip-dH77c5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:20 INFO - Running setup.py (path:/tmp/pip-NEcw6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:20 INFO - Running setup.py (path:/tmp/pip-PjKSN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:20 INFO - Running setup.py (path:/tmp/pip-4k06cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:20 INFO - Running setup.py (path:/tmp/pip-Ot7Dzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:20 INFO - Running setup.py (path:/tmp/pip-lO5lSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:20 INFO - Running setup.py (path:/tmp/pip-3qL5sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:20 INFO - Running setup.py (path:/tmp/pip-iJhZRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:20 INFO - Running setup.py (path:/tmp/pip-hJZvZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:20 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)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:20 INFO - Running setup.py (path:/tmp/pip-_GZw7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:21 INFO - Running setup.py (path:/tmp/pip-OIrPCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:21 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)) 13:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:21 INFO - Running setup.py (path:/tmp/pip-oWbLnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:21 INFO - Running setup.py (path:/tmp/pip-pZferM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:21 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)) 13:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:21 INFO - Running setup.py (path:/tmp/pip-uBDScF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:21 INFO - Running setup.py (path:/tmp/pip-eShLcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:21 INFO - Running setup.py (path:/tmp/pip-TOnCt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:11:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:11: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 13:11: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 13:11: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 13:11: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 13:11: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 13:11: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 13:11:23 INFO - Downloading blessings-1.6.tar.gz 13:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:11:23 INFO - Installing collected packages: blessings 13:11:23 INFO - Running setup.py install for blessings 13:11:23 INFO - Successfully installed blessings 13:11:23 INFO - Cleaning up... 13:11:23 INFO - Return code: 0 13:11:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:11:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:23 INFO - Reading from file tmpfile_stdout 13:11:23 INFO - Current package versions: 13:11:23 INFO - argparse == 1.2.1 13:11:23 INFO - blessings == 1.6 13:11:23 INFO - blobuploader == 1.2.4 13:11:23 INFO - docopt == 0.6.1 13:11:23 INFO - functools32 == 3.2.3-2 13:11:23 INFO - jsonschema == 2.5.1 13:11:23 INFO - manifestparser == 1.1 13:11:23 INFO - mozInstall == 1.12 13:11:23 INFO - mozcrash == 0.17 13:11:23 INFO - mozdebug == 0.1 13:11:23 INFO - mozdevice == 0.48 13:11:23 INFO - mozfile == 1.2 13:11:23 INFO - mozhttpd == 0.7 13:11:23 INFO - mozinfo == 0.9 13:11:23 INFO - mozleak == 0.1 13:11:23 INFO - mozlog == 3.4 13:11:23 INFO - moznetwork == 0.27 13:11:23 INFO - mozprocess == 0.23 13:11:23 INFO - mozprofile == 0.28 13:11:23 INFO - mozrunner == 6.12 13:11:23 INFO - mozscreenshot == 0.1 13:11:23 INFO - mozsystemmonitor == 0.3 13:11:23 INFO - moztest == 0.7 13:11:23 INFO - mozversion == 1.4 13:11:23 INFO - psutil == 3.1.1 13:11:23 INFO - requests == 1.2.3 13:11:23 INFO - wsgiref == 0.1.2 13:11:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:23 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 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:11:23 INFO - Using env: {'DISPLAY': ':0', 13:11:23 INFO - 'HOME': '/home/cltbld', 13:11:23 INFO - 'LANG': 'en_US.UTF-8', 13:11:23 INFO - 'LANGUAGE': 'en_US:en', 13:11:23 INFO - 'LOGNAME': 'cltbld', 13:11:23 INFO - 'MAIL': '/var/mail/cltbld', 13:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:23 INFO - 'MOZ_NO_REMOTE': '1', 13:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:23 INFO - 'NO_EM_RESTART': '1', 13:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:23 INFO - 'PWD': '/builds/slave/test', 13:11:23 INFO - 'SHELL': '/bin/bash', 13:11:23 INFO - 'SHLVL': '1', 13:11:23 INFO - 'TERM': 'linux', 13:11:23 INFO - 'TMOUT': '86400', 13:11:23 INFO - 'USER': 'cltbld', 13:11:23 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:23 INFO - '_': '/tools/buildbot/bin/python'} 13:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:11:24 INFO - Cleaning up... 13:11:24 INFO - Return code: 0 13:11:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7df1faf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', '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 13:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:11:24 INFO - Using env: {'DISPLAY': ':0', 13:11:24 INFO - 'HOME': '/home/cltbld', 13:11:24 INFO - 'LANG': 'en_US.UTF-8', 13:11:24 INFO - 'LANGUAGE': 'en_US:en', 13:11:24 INFO - 'LOGNAME': 'cltbld', 13:11:24 INFO - 'MAIL': '/var/mail/cltbld', 13:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:24 INFO - 'MOZ_NO_REMOTE': '1', 13:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:24 INFO - 'NO_EM_RESTART': '1', 13:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:24 INFO - 'PWD': '/builds/slave/test', 13:11:24 INFO - 'SHELL': '/bin/bash', 13:11:24 INFO - 'SHLVL': '1', 13:11:24 INFO - 'TERM': 'linux', 13:11:24 INFO - 'TMOUT': '86400', 13:11:24 INFO - 'USER': 'cltbld', 13:11:24 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:24 INFO - '_': '/tools/buildbot/bin/python'} 13:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:11:24 INFO - Cleaning up... 13:11:24 INFO - Return code: 0 13:11:24 INFO - Running post-action listener: _resource_record_post_action 13:11:24 INFO - Running post-action listener: _start_resource_monitoring 13:11:24 INFO - Starting resource monitoring. 13:11:24 INFO - [mozharness: 2016-10-13 20:11:24.289023Z] Finished create-virtualenv step (success) 13:11:24 INFO - [mozharness: 2016-10-13 20:11:24.289521Z] Running install step. 13:11:24 INFO - Running pre-action listener: _resource_record_pre_action 13:11:24 INFO - Running main action method: install 13:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:24 INFO - Reading from file tmpfile_stdout 13:11:24 INFO - Detecting whether we're running mozinstall >=1.0... 13:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:11:24 INFO - Reading from file tmpfile_stdout 13:11:24 INFO - Output received: 13:11:24 INFO - Usage: mozinstall [options] installer 13:11:24 INFO - Options: 13:11:24 INFO - -h, --help show this help message and exit 13:11:24 INFO - -d DEST, --destination=DEST 13:11:24 INFO - Directory to install application into. [default: 13:11:24 INFO - "/builds/slave/test"] 13:11:24 INFO - --app=APP Application being installed. [default: firefox] 13:11:24 INFO - mkdir: /builds/slave/test/build/application 13:11:24 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'] 13:11:24 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 13:11:40 INFO - Reading from file tmpfile_stdout 13:11:40 INFO - Output received: 13:11:40 INFO - /builds/slave/test/build/application/firefox/firefox 13:11:40 INFO - Running post-action listener: _resource_record_post_action 13:11:40 INFO - [mozharness: 2016-10-13 20:11:40.554388Z] Finished install step (success) 13:11:40 INFO - [mozharness: 2016-10-13 20:11:40.554630Z] Running run-tests step. 13:11:40 INFO - Running pre-action listener: _resource_record_pre_action 13:11:40 INFO - Running main action method: run_tests 13:11:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:11:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:11:40 INFO - Python 2.7.3 13:11:40 INFO - Return code: 0 13:11:40 INFO - grabbing minidump binary from tooltool 13:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:40 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 0xfe6f60>, '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 0xfe7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe81d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:11:40 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 13:11:40 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 13:11:40 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 13:11:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:11:40 INFO - Return code: 0 13:11:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:11:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:11:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:11:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:11:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:11:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:11:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:11:40 INFO - Using env: {'DISPLAY': ':0', 13:11:40 INFO - 'HOME': '/home/cltbld', 13:11:40 INFO - 'LANG': 'en_US.UTF-8', 13:11:40 INFO - 'LANGUAGE': 'en_US:en', 13:11:40 INFO - 'LOGNAME': 'cltbld', 13:11:40 INFO - 'MAIL': '/var/mail/cltbld', 13:11:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:11:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:40 INFO - 'MOZ_NO_REMOTE': '1', 13:11:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:11:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:40 INFO - 'NO_EM_RESTART': '1', 13:11:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:40 INFO - 'PWD': '/builds/slave/test', 13:11:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:11:40 INFO - 'SHELL': '/bin/bash', 13:11:40 INFO - 'SHLVL': '1', 13:11:40 INFO - 'TERM': 'linux', 13:11:40 INFO - 'TMOUT': '86400', 13:11:40 INFO - 'USER': 'cltbld', 13:11:40 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892', 13:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:40 INFO - '_': '/tools/buildbot/bin/python'} 13:11:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:11:40 INFO - mozversion application_buildid: 20161013102025 13:11:40 INFO - mozversion application_changeset: 28cea4babaf878d6ea5bfd19be4ac0aeef38d154 13:11:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:11:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:11:40 INFO - mozversion application_name: Firefox 13:11:40 INFO - mozversion application_remotingname: firefox-dev 13:11:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:11:40 INFO - mozversion application_vendor: Mozilla 13:11:40 INFO - mozversion application_version: 51.0a2 13:11:40 INFO - mozversion platform_buildid: 20161013102025 13:11:40 INFO - mozversion platform_changeset: 28cea4babaf878d6ea5bfd19be4ac0aeef38d154 13:11:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:11:40 INFO - mozversion platform_version: 51.0a2 13:11:40 INFO - using testdate: 1476389500 13:11:40 INFO - actual date: 1476389500 13:11:40 INFO - starting webserver on 'localhost:56648' 13:11:40 INFO - SUITE-START | Running 2 tests 13:11:40 INFO - TEST-START | dromaeo_css 13:11:40 INFO - operating with platform_type : linux_ 13:11:40 INFO - Initialising browser for dromaeo_css test... 13:11:40 INFO - TEST-INFO | started process 6831 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJxPZ4v/profile http://localhost:56648/getInfo.html) 13:11:45 INFO - PROCESS | 6831 | __metrics Screen width/height:1600/1200 13:11:45 INFO - PROCESS | 6831 | colorDepth:24 13:11:45 INFO - PROCESS | 6831 | Browser inner width/height: 1024/697 13:11:45 INFO - PROCESS | 6831 | __metrics 13:11:45 ERROR - PROCESS | 6831 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3018: TypeError: this.tabs is undefined 13:11:46 INFO - TEST-INFO | 6831: exit 0 13:11:46 INFO - Browser initialized. 13:11:46 INFO - Running cycle 1/1 for dromaeo_css test... 13:11:46 INFO - TEST-INFO | started process 6958 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJxPZ4v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:11:58 INFO - PROCESS | 6958 | RSS: Main: 151461888 13:11:58 INFO - PROCESS | 6958 | 13:12:47 INFO - PROCESS | 6958 | 1476389567935 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:14:57 INFO - PROCESS | 6958 | Cycle 1(1): loaded http://localhost:56648/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56648/tests/dromaeo/cssquery-ext.html) 13:14:58 INFO - PROCESS | 6958 | RSS: Main: 155783168 13:14:58 INFO - PROCESS | 6958 | 13:18:00 INFO - PROCESS | 6958 | Cycle 1(1): loaded http://localhost:56648/tests/dromaeo/cssquery-ext.html (next: http://localhost:56648/tests/dromaeo/cssquery-jquery.html) 13:18:01 INFO - PROCESS | 6958 | RSS: Main: 152539136 13:18:01 INFO - PROCESS | 6958 | 13:21:03 INFO - PROCESS | 6958 | Cycle 1(1): loaded http://localhost:56648/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56648/tests/dromaeo/cssquery-mootools.html) 13:21:03 INFO - PROCESS | 6958 | RSS: Main: 152887296 13:21:03 INFO - PROCESS | 6958 | 13:24:05 INFO - PROCESS | 6958 | Cycle 1(1): loaded http://localhost:56648/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56648/tests/dromaeo/cssquery-prototype.html) 13:24:05 INFO - PROCESS | 6958 | RSS: Main: 151109632 13:24:05 INFO - PROCESS | 6958 | 13:27:08 INFO - PROCESS | 6958 | Cycle 1(1): loaded http://localhost:56648/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56648/tests/dromaeo/cssquery-yui.html) 13:27:09 INFO - PROCESS | 6958 | RSS: Main: 153972736 13:27:09 INFO - PROCESS | 6958 | 13:30:11 INFO - PROCESS | 6958 | Cycle 1(1): loaded http://localhost:56648/tests/dromaeo/cssquery-yui.html (next: http://localhost:56648/tests/dromaeo/cssquery-dojo.html) 13:30:11 INFO - PROCESS | 6958 | RSS: Main: 154603520 13:30:11 INFO - PROCESS | 6958 | 13:30:11 INFO - PROCESS | 6958 | __start_tp_report 13:30:11 INFO - PROCESS | 6958 | _x_x_mozilla_page_load 13:30:11 INFO - PROCESS | 6958 | _x_x_mozilla_page_load_details 13:30:11 INFO - PROCESS | 6958 | |i|pagename|runs| 13:30:11 INFO - PROCESS | 6958 | |0;dojo.html;3186.665400132986;3201.63181234158;3202.183443221978;3222.258880457494;3233.143217047484;5575.916361254584;5594.24477695511;5626.831195064149;5667.291588551431;5675.035244008513;5613.578981576377;5717.3139223293265;5724.484796368326;5726.3128424589;5768.163616275648;5138.66598671087;5192.376914770233;5229.2156176573435;5269.525742683158;5275.182346736243;5221.921671174935;5246.239295302198;5255.343082114736;5256.553192978582;5274.709890955995;5071.898562028758;5088.313077734496;5089.236614507817;5240.34495688039;5290.444503327153;5063.189889617662;5098.260752190911;5136.255242989783;5174.715390653512;5185.714785686786;5076.060928728197;5111.182210846247;5124.436312005676;5126.179811230185;5183.5982561536475;5637.069883469264;5662.37713851476;5728;5756.1653560233535;5780;5946.048632218867;5971.313298970631;5989.221401217857;6025.307089684698;6057.909131363033;6012.729427175787;6030.276366836008;6057.818265452043;6068.757249710006;6112.358202388729;5458.2904222450825;5486.067368547357;5588.580856435784;5643.6049476536655;5662.575306851962;6009.1587177795145;6088.4215999479975;6155.692215389302;6208.286047104641;6227.906581401192;5973.67045765624;5985.191999080069;5992.820215393545;5993.580449368503;5996.820095397145;5880;6057.4851137653795;6066.878662426727;6074;6084.847878802977;5839.883202335929;5908.497777688945;5919.556033297434;5930.406959304035;6037.516998640098;5918.221658172952;5940.584159108907;5981.6710080945095;5983.222181116427;6017.939820601738;4650.953490465052;4652.511486293891;4662.393888794435;4662.510436404129;4698.36572062774;4062.939055914164;4066.430699702044;4072.266991941479;4112.8149233283975;4134.751914885116;5760;5761.798337058183;5800.100984347433;5822.446867547661;5848.824535263949;5874.618149820248;6081.057436097412;6089.360617135226;6089.48239399656;6102.847428814315;5639.069553523705;5671.149327600893;5766.135079737989;5787.768489260382;5845.532357411397;5273.472652734773;5281.419043905167;5288.101022826128;5291.735413229323;5294.835136270014;6202.131701561785;6288.27684816252;6290.874182516232;6293.181886354744;6302.747890084345;5886.882262354815;5913.113033045078;5923.555733319932;5952.375000625046;6028.3971602839365;6163.137160797582;6181.752729890934;6205.782797602063;6224.004159334485;6224.875502490016;5929.258842644669;5950.821475355746;5969.104634304889;5974.581779275409;5986.191864098291;5797.826065218051;5845.152452894392;5985.820425387245;5996.5502587307;6033.456988871023;5974.044152935683;5987.341392446914;5994.70026498682;5994.850128746816;6002.609830361012;5928.466438020599;5958.433948774859;5961.970190149022;5966.701664916824;5976.402359763989;5587.944120558905;5729.4843464088435;5788.942110578841;5863.472287494146;5868.618539794899;5853.946289667901;5976.820695379146;5981.401859813984;6001.909971350346;6019.458248757633;5816.592838501433;5819.592628515963;5829.8251052468495;5840.386759390203;5856.0630299151935 13:30:11 INFO - PROCESS | 6958 | |1;ext.html;617.3425302053313;633.1705465839748;633.9302676705563;651.9413252807252;657.9407853293203;39044.21911561767;39496;39799.20401591966;39803.60196398035;39826.60173398265;46633;46942.53057469424;47198.292025619645;47248.52751472484;47380.815479613026;9102.772430689252;9170.954145229298;9217.354785165042;9256.629734810633;9269.351145419823;9204.769880752969;9224.03147669495;9277.814443711122;9285.675001374953;9289.674861379854;9017.77455563613;9020.188183063523;9053.094690530927;9055.547222638841;9081.727548173565;9279.814403711887;9318.254539636813;9331.25349972004;9336.813263734755;9348.205402540792;9227.584758685809;9350.111739384747;9351.672691455766;9353;9354.06459354066;44262.336064959054;44279;44350.55649443497;44470.11059778819;44598.33102503465;3796.0699628591174;3818.694504439638;3863.4784304118866;3903.1022864740985;4034.9596504034876;34873.65126348755;35071.824640876635;35185;35446.64553354459;35630.28739425197;4688.554587314234;4695.530446955311;4726.7400292983875;4787.40157480315;4869.610431165498;366919;371320;371781;376938;377628;39210.803945980086;65813.34186658073;65848;66232.33767662357;66402;39107.217855642724;41638.1672366551;42000;42039.15921681549;42210.36684449736;17055.48833534996;17117.486475405756;17119.22963466647;17138.314467421413;17252.13739313029;14287.928560357339;14297.142171469744;14358.56924292273;14388.208648524222;14410.639734006734;9406.670766523139;9439.24486041115;9465.66870159541;9566.856497152548;9572.664956726481;3588.4258518636893;3637.9818100909324;3705.3515634763808;3709.4621279914263;3715.4241092630687;1041.7031146123318;1043.3538360642754;1079.5897558927713;1123.1632433836844;1166.9183157179086;33380;34165.829170853984;34686.65313346834;34689.66234589621;34995.300093997976;1098.7527806243531;1119.1438549509633;1120.5853834081279;1200.9971673652599;1205.7166565857017;17125.22936467862;17762.37831675885;17848.286068557114;17899.552511187325;18071.186796594186;546958;550615;551497;552281;556787;64363;64973.67513162404;65004;65214.673926630065;65526.34473655298;1349.5546469664987;1394.2122700674088;1428.7999680044802;1438.3671184678708;1443.1846007006009;32211.838940805144;33517.66482335146;33615.83192084024;33713.66286337203;33908.88267240382;56835.43164568301;56982;57221.71389143028;57334.13998790105;57399.139012914005;467438;468110;468493;469822;470489;31664.525032125;31921.361572768878;32165.517517237728;32203.194920127185;32233.194170145933;52887.471125288255;53112.203316949504;53352.1997170035;53703.19445208403;53733;45146.77426612846;46413.30380044234;46554.53445465637;46714.29928551007;48182.75908620434;45843;45970;46629.53370466252;46851.765741171075;47232.7638361806;45054;45316.54683453123;45346;46295.76852115718;47289.29066063943;38879.222415552096;40016.59983400129;40131.39802903017;41112.38331424971;41201.587984119775;29840.25399365033;30933.226669333446;30947.0715878524;31011;31231.84384078065 13:30:11 INFO - PROCESS | 6958 | |2;jquery.html;406.4959450281652;409.62314670503133;430.22344667874484;431.58783361889397;435.35567360306743;19096.331628392993;19601.21595136193;20027.59944801103;20411.489712757153;20601;19404.126814293355;19531.121099550517;19640.21439142436;19818.504537386572;19857.3049943252;5855.824325270238;6065.8180254592435;6068.3021452533085;6082.908756368658;6091.604045737013;5710.743016564244;5907.14346419771;5937.4656280934705;6014.639121652693;6025.638461692312;5861.472467477926;5880.7059647017695;5896.321922978848;5897.616654917438;5914.792982245623;5703.914441283384;5879.0593505039205;5881.323647780496;5886.175935369051;5913.763449462016;5254.52709256165;5280.049591073644;5305.098133317345;5314.813981510667;5321.281626980385;22541.436464088365;23210.41973950648;23383.649245261335;23622.645660314938;23725.406864828517;2558.731333210005;2690.5291573974478;2693.1920423872957;3086.768492363082;3411.6076651185185;14255.429782808675;14809.851901481063;15244.39022439101;15301.540953771404;15460.381584736597;1918.6162767446567;1967.0951362373326;2006.8294194993443;2041.7039529268322;2075.616011037963;85721;87536.56231718864;87910.56044719799;91809;92370.53814730883;11247.887521124847;11392;11419.14356423255;11516.596919107791;11761.294322340687;16342.591435213977;16362.918185408997;16370.590735231715;16378.344866205456;16425.507234782977;8567.357448191411;8750.51872147038;8881.511516866634;8884.777880552909;8936.24041956441;8534.70128545498;8542.572871356408;8602.78493037679;8694.13058694138;8825.955870220609;4947.554720075211;4958.677685950402;5030.849074527697;5036.219385995177;5141.948580514222;2094.7800480949586;2144.753353364383;2190.386691726319;2213.557288542298;2232.5088480534255;959.9664011759555;962.6823148361024;999.1307562420595;1007.8790545134484;1064.3560645809173;19819.3984924997;20036.89981550083;20247.595048098956;20247.898760506105;20302.69545956812;1069.2141276162015;1075.3225467955137;1086.9619563315405;1105.9115270778318;1130.4912789244872;10527;11147;11297.604583839691;11332.376719280519;11434.599789007345;345692;350951;353414;451655;460228;78848.605756972;97225;97333;98940.50529747449;102183.48908255559;1125.7354521687396;1136.9602063927723;1161.5005547614608;1178.714326441927;1222.419350808359;17887.552811179823;18076.367327143485;18239.544011399823;18423.67576403977;18529.629407411507;35746.463803042454;41504.79247603743;42016;42429.363559546015;44354.55645443504;58378.7081064592;59187;59306;59435.40564594472;60496.09255861254;7779.92220077792;8603.870941935751;8705.651773928972;8740.91259087401;8762.956185219033;12030.819537706766;12579.748405031665;12619.684507887008;12646.620601381974;12737.93631031839;10481.842772358268;10715.571377144826;10764.085052770602;10832.67501974942;10992.28550144238;10591.4704264788;11027.83458248111;11034.282771619819;11296;11484.540618375506;10503.317284376491;10658.200634952254;10762.838557421801;11043.723906902393;11086.61196858106;8505.574721263789;8610.698625548075;8642.35182361338;8769.693060742844;8972;8603.52680602573;9274.768130796783;10626.946865265625;10648.946755266174;11407.828882566602 13:30:11 INFO - PROCESS | 6958 | |3;mootools.html;1564.5697433205871;1586.547833867348;1797.1553369916135;1875.0718394394776;1911.0444777611194;8522.57387130644;8801.899762529683;9145.908540914588;9979.700608981733;10012.199024078074;9251.722448326544;9501.334906556544;9513.763210782607;9743.269254805882;9754.414735115897;2534.5184414961145;2560.039985005623;2562;2563.7564431378987;2565.4869026194838;2510.3096648421647;2575.2016874768788;2580.5226033183826;2584.961225581618;2594.714581396045;2496.425822060927;2564.038485567912;2580.238829545289;2595.1306312385373;2597.6752905886765;2505.887235074426;2531.3545046013332;2532.5441420544294;2547.0703193334393;2555.3356127406823;2429.514097180571;2431.222008957134;2432.477017441259;2499.6750422445148;2540.301417110291;9773.609055637766;9854.162396196332;9865.60537578496;9885.555150018266;9912.405255684682;5747.885042299131;5753.079507278857;5851.795187168471;5854.912176317359;5876.29484461863;3745.4569087482437;3746.119661879456;3801.0877389426614;3940.9014774630577;3951.565327813938;1282.7691015617186;1331.1081575344542;1336.4587342126454;1351.3243378310844;1359.9796003059962;13252.46990120394;13430.127041742255;13440.932795336059;13495.392707328194;13534.729305413935;4984.451710311862;4984.700917944935;5177.068127736969;5177.223416487482;5211.5830733541225;11420.14348923834;11442;11450.656480305604;11464.140189485655;11525.135614828921;5830.883382332329;5854.531637468992;5861.3845546217035;5874.060150375919;5888.470037696542;4792.568668819823;4933.260010998307;5355.919661205085;5400.486953739389;5403.459654034565;4338.219120558267;4387.539308372575;4459.397981272552;4483.08096840147;4487.394201782783;1396.958091257264;1403.7894315852704;1408.1269612839899;1410.7108042851191;1428.435767871685;1051.1590727418188;1051.8527406163143;1060.39557452253;1061.766411389493;1079.071998081649;6290.370962903765;6449.419552240321;6700;6786.423154031864;6829.931700683029;1029.46467836726;1059.2003037706481;1079.5034284229184;1086.4296244471557;1098.9175811814146;4539.432570928634;4556.794944227518;4614.469336026333;4636.235021221461;4678.789454474557;11506.137039722054;12015.459304331325;12038.87961120395;12331.260124392566;12396.938015310046;12068.698282543006;12157.148999570121;12220.083493738006;12255.080868934865;12297.139200255897;8994.28045756338;9135.862962055575;9152.81694366109;9209.263258939269;9224.677136300197;6431.839204019937;6501.4798816095545;6636.203655561363;6658.500612453989;6667.966660166862;11009.275176907802;11022.889771102185;11180;11319.886801131883;11350.205485616258;11124.221304508606;11244.437778111225;11293.37886416255;11364.94317528407;11475.885241147482;8532.914670853212;8666.480011199348;8737.21365077146;8781.604827782638;8832.160944710242;9262.259019278306;9398.577064032272;9419.764505887408;9431.717048488556;9435;8436.11420800807;8486.533240671575;8506.106858779738;8546.914530854861;8673.479591224546;8307.626156823078;8318.126596707258;8469.322454203771;8496.830063398822;8574.528400938008;7779.377649788116;7813.570253636105;7816.10114836801;7823.84352312962;7853.450258481851;5601.691906945157;5613.129964855454;5633.0705433603;5696.629719068247;5773.3072031356505;5383;5596.020696378199;5601.60788744784;5606.215129881902;5657.773689052392 13:30:11 INFO - PROCESS | 6958 | |4;prototype.html;354.17123930003794;358.74708330626913;360.34777053533105;362.50156035451255;370.4739270236266;5789.279038851738;5826.15520749491;5866.618669786471;5873.970630146849;5889.7349619267125;5451.890962180764;5783.190353350535;5792.826215213539;5804.883902321951;5855.711743416452;4396.714213576115;4488.461384633849;4518.457785065797;4533.637309015271;4533.705309154894;4507.1211113832815;4508.6393088553;4512.819487220508;4516.14193303273;4547.113312903985;4415.801288942005;4424.867253982386;4524.886877828064;4539.387182730322;4541.43232095988;4470.463544374679;4480.372747815309;4518.774061296922;4522.6155776759015;4524.23088075028;4013.0769922917602;4032.052467670095;4038.1519880825063;4044.9393259100843;4047.2512585171544;5429.484199001128;5628.746706398222;5637.520810731093;5654.067078931971;5672.546196304285;5168.715720635363;5171.50870667286;5181.989512045153;5191.143461328877;5256.579473642099;6002.609830361012;6033.155358249848;6042.456178943916;6057.939420605781;6099.146119543268;4127.09203975125;4314.395984562164;4384.846530371422;4405.867823965286;4463.285874260136;6178.598391104564;6187.226596675416;6203.751849925998;6218.662987457673;6238.941149420681;4400.987772812207;4408.228560001987;4424;4434.623057040123;4434.84478043267;4393.956060439418;4395.560463942543;4435.2016637005;4493.326001099861;4509.526499717548;4964.751762411865;5011.974940125276;5037.244413337955;5055.469175736568;5089.770960306795;5991.7902873399225;5991.790287340009;6016.548758843104;6069;6069.332373438918;1516.4010215964852;1521.73369660309;1538.9612011891927;1553.7203303405495;1557.8675812555832;975.1613612293422;1000.2548101664306;1022.6727447216967;1030.3817709374316;1061.6814955513469;5728.255326807488;5747;5777.971110144423;5778.422157784188;5795.623284486495;6104.3590423005835;6115.4190351916495;6119.5104391648565;6178.293024185453;6221.875562488724;5619.4099619540175;5777.19119323295;5785.322256545639;5811.360750317462;5824.184614154022;3235.6602556731673;3263.689949454798;3278.164068162651;3284.6058472983395;3293.604767427876;6165.784197553064;6207.503399728011;6266.404691554295;6301;6303.558750887486;6241.687915604201;6243.157173781572;6259;6262.5929314594405;6267.561270711007;6138;6260.906086408799;6291.150694656254;6316.842078948063;6357.4914006879335;6020.668863212565;6086.969565152146;6097.878042439215;6117.785877494444;6136.140940268455;6013;6061.6666083365835;6077.696115194134;6084.817455476343;6102.633841969496;6030.39696030411;6255.311915689362;6272.874542509033;6292.968535157295;6301.464375528131;4383.408239887702;4385.79390667569;4395.7142785718825;4435.179491794029;4438.068005718836;5990.251218597676;6151.477124444294;6201.596896201732;6226.377362263737;6259.937400626118;5889.734961926809;5980.5215582754145;5989.880202396015;6035;6076.331603523698;6004.39956004396;6010.609310394811;6047.522674462114;6065.60573562717;6100.725467353891;5827.76688932438;5939.4357536034;5939.762409503572;5964.970175149097;5969.134475501115;6031.939680603138;6041.365656605993;6054.39456054391;6059.666718330358;6065.332813390435;5699.572532060195;5864.006237879142;5901.91147132802;5912.349641539341;5938.435848594377 13:30:11 INFO - PROCESS | 6958 | |5;yui.html;725.3109545931367;764.9808754781129;809.0897740042452;809.6356639512213;864.6022829498428;15704;17088.316467341323;17113.315467381304;17157.742633860504;17193.398231061914;15069.321880515376;15747.448839290628;15756.606084847885;15861.84138158618;15871.603209919758;868.2706526517724;885.1325700813206;897.3045889435707;903.1194585279366;906.6101576322179;894.6913314906346;898.3307435960219;899.8875140607424;902.9954850225739;907.7957459571577;952.5761036338832;954.312894715807;957.4159762544842;959.4147569982305;962.0283513651193;950.5152372290182;953.4803532075035;954.5847556312991;955.0211033690454;955.5556666150238;699.5837476701355;700.7232143303418;703.2440126863642;703.2826516952731;706.0221593093603;15949.840501594952;15960.122193279365;16092.839071609369;16117.677646447006;16156.59608509785;14399;14788.70422591553;14902.105873647615;15468.303926323342;15788.60528486797;12845.871541284561;12988.480460781651;13051.934740326333;13125.081244312902;13456.259905705183;3973.384125460558;3994.6005399459823;4026.838926442939;4063.6952228582973;4078.5921407858978;76167.23832761656;76408;76670.23329766742;93945;95308;11807.822882656767;12160.635180944586;12174.939125304316;12327.876721232673;12667.619971400873;21109.472263193544;21338;21432.678509822366;21452.89273553622;21479.78520214809;9328.486933218737;9349.298802589698;9461;9652.710418687451;9666.661666841626;5113.769880355393;5212.113940630102;5220.530152286313;5262.447443018428;5365.3293338332705;3749.081475038633;3909.139989202371;3919.2553414851086;3922.5292964844402;3926.70549708773;670.8624731929942;671.5601281160848;674.642439507062;676.1581830620909;691.2568988337557;559.5831105826186;597.0149253731291;604.2446941323345;663.9701213445406;668.2448832818981;9789.657361992297;10001;10592.523336449878;10927.235093543375;11400.429978501043;670.2325836916704;690.9516333856582;717.6053170756063;718.2996578336081;745.5005146551823;4664.790084446208;5122.564582010496;5249.160134378558;5262.736863156827;5317.574594032468;75458;76270;101977.49011254896;102739;106188.46905765422;60596.39403605908;74375.62812185904;100745.49627251817;101988.49005755072;106304.46847765712;57082;88826.55586722025;90736.54631726799;91921;93515.53242233745;9930.900690993287;10586.4706764663;10751.139908807432;10809.945950270197;10859.479672845966;53598.73200633972;54501;55281.17078243749;55436;55843;69172;79354;80132;82072.58963705382;82586.17413825785;12285.57000504978;12422.875771242534;12513.249205047727;12637.93681031589;12933.288669122912;13729.45082196701;14798.852011480178;15300.540983770505;15601.219939002778;16031.519054428385;13946.232957187452;14154.646133846736;14902;15238.466653667512;15316.693666126392;12646;13603.795943060664;14248.928755356157;14627.634309142357;14930;13461.326933653474;13930.164190148624;14021.158730476203;14687.192204428859;15047.849521504644;12044.457999389873;12436.875631243935;12460.56388026451;12828.935855320664;13064.804027939399;11863.110266729856;12074.818877717016;12292.925064290299;12319.938400307941;12323.137380383645 13:30:11 INFO - PROCESS | 6958 | __end_tp_report 13:30:11 INFO - PROCESS | 6958 | __start_cc_report 13:30:11 INFO - PROCESS | 6958 | _x_x_mozilla_cycle_collect,318 13:30:11 INFO - PROCESS | 6958 | __end_cc_report 13:30:11 INFO - PROCESS | 6958 | __startTimestamp1476390611602__endTimestamp 13:30:11 INFO - PROCESS | 6958 | 13:30:11 INFO - PROCESS | 6958 | ------- Summary: start ------- 13:30:11 INFO - PROCESS | 6958 | Number of tests: 6 13:30:11 INFO - PROCESS | 6958 | 13:30:11 INFO - PROCESS | 6958 | [#0] dojo.html Cycles:165 Average:5614.41 Median:5845.15 stddev:633.36 (10.8%) stddev-sans-first:605.99 13:30:11 INFO - PROCESS | 6958 | Values: 3186.7 3201.6 3202.2 3222.3 3233.1 5575.9 5594.2 5626.8 5667.3 5675.0 5613.6 5717.3 5724.5 5726.3 5768.2 5138.7 5192.4 5229.2 5269.5 5275.2 5221.9 5246.2 5255.3 5256.6 5274.7 5071.9 5088.3 5089.2 5240.3 5290.4 5063.2 5098.3 5136.3 5174.7 5185.7 5076.1 5111.2 5124.4 5126.2 5183.6 5637.1 5662.4 5728.0 5756.2 5780.0 5946.0 5971.3 5989.2 6025.3 6057.9 6012.7 6030.3 6057.8 6068.8 6112.4 5458.3 5486.1 5588.6 5643.6 5662.6 6009.2 6088.4 6155.7 6208.3 6227.9 5973.7 5985.2 5992.8 5993.6 5996.8 5880.0 6057.5 6066.9 6074.0 6084.8 5839.9 5908.5 5919.6 5930.4 6037.5 5918.2 5940.6 5981.7 5983.2 6017.9 4651.0 4652.5 4662.4 4662.5 4698.4 4062.9 4066.4 4072.3 4112.8 4134.8 5760.0 5761.8 5800.1 5822.4 5848.8 5874.6 6081.1 6089.4 6089.5 6102.8 5639.1 5671.1 5766.1 5787.8 5845.5 5273.5 5281.4 5288.1 5291.7 5294.8 6202.1 6288.3 6290.9 6293.2 6302.7 5886.9 5913.1 5923.6 5952.4 6028.4 6163.1 6181.8 6205.8 6224.0 6224.9 5929.3 5950.8 5969.1 5974.6 5986.2 5797.8 5845.2 5985.8 5996.6 6033.5 5974.0 5987.3 5994.7 5994.9 6002.6 5928.5 5958.4 5962.0 5966.7 5976.4 5587.9 5729.5 5788.9 5863.5 5868.6 5853.9 5976.8 5981.4 6001.9 6019.5 5816.6 5819.6 5829.8 5840.4 5856.1 13:30:11 INFO - PROCESS | 6958 | 13:30:11 INFO - PROCESS | 6958 | [#1] ext.html Cycles:180 Average:63018.49 Median:44826.17 stddev:124715.74 (278.2%) stddev-sans-first:124977.60 13:30:11 INFO - PROCESS | 6958 | Values: 617.3 633.2 633.9 651.9 657.9 39044.2 39496.0 39799.2 39803.6 39826.6 46633.0 46942.5 47198.3 47248.5 47380.8 9102.8 9171.0 9217.4 9256.6 9269.4 9204.8 9224.0 9277.8 9285.7 9289.7 9017.8 9020.2 9053.1 9055.5 9081.7 9279.8 9318.3 9331.3 9336.8 9348.2 9227.6 9350.1 9351.7 9353.0 9354.1 44262.3 44279.0 44350.6 44470.1 44598.3 3796.1 3818.7 3863.5 3903.1 4035.0 34873.7 35071.8 35185.0 35446.6 35630.3 4688.6 4695.5 4726.7 4787.4 4869.6 366919.0 371320.0 371781.0 376938.0 377628.0 39210.8 65813.3 65848.0 66232.3 66402.0 39107.2 41638.2 42000.0 42039.2 42210.4 17055.5 17117.5 17119.2 17138.3 17252.1 14287.9 14297.1 14358.6 14388.2 14410.6 9406.7 9439.2 9465.7 9566.9 9572.7 3588.4 3638.0 3705.4 3709.5 3715.4 1041.7 1043.4 1079.6 1123.2 1166.9 33380.0 34165.8 34686.7 34689.7 34995.3 1098.8 1119.1 1120.6 1201.0 1205.7 17125.2 17762.4 17848.3 17899.6 18071.2 546958.0 550615.0 551497.0 552281.0 556787.0 64363.0 64973.7 65004.0 65214.7 65526.3 1349.6 1394.2 1428.8 1438.4 1443.2 32211.8 33517.7 33615.8 33713.7 33908.9 56835.4 56982.0 57221.7 57334.1 57399.1 467438.0 468110.0 468493.0 469822.0 470489.0 31664.5 31921.4 32165.5 32203.2 32233.2 52887.5 53112.2 53352.2 53703.2 53733.0 45146.8 46413.3 46554.5 46714.3 48182.8 45843.0 45970.0 46629.5 46851.8 47232.8 45054.0 45316.5 45346.0 46295.8 47289.3 38879.2 40016.6 40131.4 41112.4 41201.6 29840.3 30933.2 30947.1 31011.0 31231.8 13:30:11 INFO - PROCESS | 6958 | 13:30:11 INFO - PROCESS | 6958 | [#2] jquery.html Cycles:180 Average:26858.57 Median:13142.07 stddev:66201.05 (503.7%) stddev-sans-first:66356.97 13:30:11 INFO - PROCESS | 6958 | Values: 406.5 409.6 430.2 431.6 435.4 19096.3 19601.2 20027.6 20411.5 20601.0 19404.1 19531.1 19640.2 19818.5 19857.3 5855.8 6065.8 6068.3 6082.9 6091.6 5710.7 5907.1 5937.5 6014.6 6025.6 5861.5 5880.7 5896.3 5897.6 5914.8 5703.9 5879.1 5881.3 5886.2 5913.8 5254.5 5280.0 5305.1 5314.8 5321.3 22541.4 23210.4 23383.6 23622.6 23725.4 2558.7 2690.5 2693.2 3086.8 3411.6 14255.4 14809.9 15244.4 15301.5 15460.4 1918.6 1967.1 2006.8 2041.7 2075.6 85721.0 87536.6 87910.6 91809.0 92370.5 11247.9 11392.0 11419.1 11516.6 11761.3 16342.6 16362.9 16370.6 16378.3 16425.5 8567.4 8750.5 8881.5 8884.8 8936.2 8534.7 8542.6 8602.8 8694.1 8826.0 4947.6 4958.7 5030.8 5036.2 5141.9 2094.8 2144.8 2190.4 2213.6 2232.5 960.0 962.7 999.1 1007.9 1064.4 19819.4 20036.9 20247.6 20247.9 20302.7 1069.2 1075.3 1087.0 1105.9 1130.5 10527.0 11147.0 11297.6 11332.4 11434.6 345692.0 350951.0 353414.0 451655.0 460228.0 78848.6 97225.0 97333.0 98940.5 102183.5 1125.7 1137.0 1161.5 1178.7 1222.4 17887.6 18076.4 18239.5 18423.7 18529.6 35746.5 41504.8 42016.0 42429.4 44354.6 58378.7 59187.0 59306.0 59435.4 60496.1 7779.9 8603.9 8705.7 8740.9 8763.0 12030.8 12579.7 12619.7 12646.6 12737.9 10481.8 10715.6 10764.1 10832.7 10992.3 10591.5 11027.8 11034.3 11296.0 11484.5 10503.3 10658.2 10762.8 11043.7 11086.6 8505.6 8610.7 8642.4 8769.7 8972.0 8603.5 9274.8 10626.9 10648.9 11407.8 13:30:11 INFO - PROCESS | 6958 | 13:30:11 INFO - PROCESS | 6958 | [#3] mootools.html Cycles:180 Average:6414.61 Median:4513.41 stddev:3636.82 (80.6%) stddev-sans-first:3628.75 13:30:11 INFO - PROCESS | 6958 | Values: 1564.6 1586.5 1797.2 1875.1 1911.0 8522.6 8801.9 9145.9 9979.7 10012.2 9251.7 9501.3 9513.8 9743.3 9754.4 2534.5 2560.0 2562.0 2563.8 2565.5 2510.3 2575.2 2580.5 2585.0 2594.7 2496.4 2564.0 2580.2 2595.1 2597.7 2505.9 2531.4 2532.5 2547.1 2555.3 2429.5 2431.2 2432.5 2499.7 2540.3 9773.6 9854.2 9865.6 9885.6 9912.4 5747.9 5753.1 5851.8 5854.9 5876.3 3745.5 3746.1 3801.1 3940.9 3951.6 1282.8 1331.1 1336.5 1351.3 1360.0 13252.5 13430.1 13440.9 13495.4 13534.7 4984.5 4984.7 5177.1 5177.2 5211.6 11420.1 11442.0 11450.7 11464.1 11525.1 5830.9 5854.5 5861.4 5874.1 5888.5 4792.6 4933.3 5355.9 5400.5 5403.5 4338.2 4387.5 4459.4 4483.1 4487.4 1397.0 1403.8 1408.1 1410.7 1428.4 1051.2 1051.9 1060.4 1061.8 1079.1 6290.4 6449.4 6700.0 6786.4 6829.9 1029.5 1059.2 1079.5 1086.4 1098.9 4539.4 4556.8 4614.5 4636.2 4678.8 11506.1 12015.5 12038.9 12331.3 12396.9 12068.7 12157.1 12220.1 12255.1 12297.1 8994.3 9135.9 9152.8 9209.3 9224.7 6431.8 6501.5 6636.2 6658.5 6668.0 11009.3 11022.9 11180.0 11319.9 11350.2 11124.2 11244.4 11293.4 11364.9 11475.9 8532.9 8666.5 8737.2 8781.6 8832.2 9262.3 9398.6 9419.8 9431.7 9435.0 8436.1 8486.5 8506.1 8546.9 8673.5 8307.6 8318.1 8469.3 8496.8 8574.5 7779.4 7813.6 7816.1 7823.8 7853.5 5601.7 5613.1 5633.1 5696.6 5773.3 5383.0 5596.0 5601.6 5606.2 5657.8 13:30:11 INFO - PROCESS | 6958 | 13:30:11 INFO - PROCESS | 6958 | [#4] prototype.html Cycles:180 Average:5067.51 Median:5794.22 stddev:1477.70 (25.5%) stddev-sans-first:1438.88 13:30:11 INFO - PROCESS | 6958 | Values: 354.2 358.7 360.3 362.5 370.5 5789.3 5826.2 5866.6 5874.0 5889.7 5451.9 5783.2 5792.8 5804.9 5855.7 4396.7 4488.5 4518.5 4533.6 4533.7 4507.1 4508.6 4512.8 4516.1 4547.1 4415.8 4424.9 4524.9 4539.4 4541.4 4470.5 4480.4 4518.8 4522.6 4524.2 4013.1 4032.1 4038.2 4044.9 4047.3 5429.5 5628.7 5637.5 5654.1 5672.5 5168.7 5171.5 5182.0 5191.1 5256.6 6002.6 6033.2 6042.5 6057.9 6099.1 4127.1 4314.4 4384.8 4405.9 4463.3 6178.6 6187.2 6203.8 6218.7 6238.9 4401.0 4408.2 4424.0 4434.6 4434.8 4394.0 4395.6 4435.2 4493.3 4509.5 4964.8 5012.0 5037.2 5055.5 5089.8 5991.8 5991.8 6016.5 6069.0 6069.3 1516.4 1521.7 1539.0 1553.7 1557.9 975.2 1000.3 1022.7 1030.4 1061.7 5728.3 5747.0 5778.0 5778.4 5795.6 6104.4 6115.4 6119.5 6178.3 6221.9 5619.4 5777.2 5785.3 5811.4 5824.2 3235.7 3263.7 3278.2 3284.6 3293.6 6165.8 6207.5 6266.4 6301.0 6303.6 6241.7 6243.2 6259.0 6262.6 6267.6 6138.0 6260.9 6291.2 6316.8 6357.5 6020.7 6087.0 6097.9 6117.8 6136.1 6013.0 6061.7 6077.7 6084.8 6102.6 6030.4 6255.3 6272.9 6293.0 6301.5 4383.4 4385.8 4395.7 4435.2 4438.1 5990.3 6151.5 6201.6 6226.4 6259.9 5889.7 5980.5 5989.9 6035.0 6076.3 6004.4 6010.6 6047.5 6065.6 6100.7 5827.8 5939.4 5939.8 5965.0 5969.1 6031.9 6041.4 6054.4 6059.7 6065.3 5699.6 5864.0 5901.9 5912.3 5938.4 13:30:11 INFO - PROCESS | 6958 | 13:30:11 INFO - PROCESS | 6958 | [#5] yui.html Cycles:180 Average:20689.00 Median:4010.72 stddev:28307.44 (705.8%) stddev-sans-first:28347.16 13:30:11 INFO - PROCESS | 6958 | Values: 725.3 765.0 809.1 809.6 864.6 15704.0 17088.3 17113.3 17157.7 17193.4 15069.3 15747.4 15756.6 15861.8 15871.6 868.3 885.1 897.3 903.1 906.6 894.7 898.3 899.9 903.0 907.8 952.6 954.3 957.4 959.4 962.0 950.5 953.5 954.6 955.0 955.6 699.6 700.7 703.2 703.3 706.0 15949.8 15960.1 16092.8 16117.7 16156.6 14399.0 14788.7 14902.1 15468.3 15788.6 12845.9 12988.5 13051.9 13125.1 13456.3 3973.4 3994.6 4026.8 4063.7 4078.6 76167.2 76408.0 76670.2 93945.0 95308.0 11807.8 12160.6 12174.9 12327.9 12667.6 21109.5 21338.0 21432.7 21452.9 21479.8 9328.5 9349.3 9461.0 9652.7 9666.7 5113.8 5212.1 5220.5 5262.4 5365.3 3749.1 3909.1 3919.3 3922.5 3926.7 670.9 671.6 674.6 676.2 691.3 559.6 597.0 604.2 664.0 668.2 9789.7 10001.0 10592.5 10927.2 11400.4 670.2 691.0 717.6 718.3 745.5 4664.8 5122.6 5249.2 5262.7 5317.6 75458.0 76270.0 101977.5 102739.0 106188.5 60596.4 74375.6 100745.5 101988.5 106304.5 57082.0 88826.6 90736.5 91921.0 93515.5 9930.9 10586.5 10751.1 10809.9 10859.5 53598.7 54501.0 55281.2 55436.0 55843.0 69172.0 79354.0 80132.0 82072.6 82586.2 12285.6 12422.9 12513.2 12637.9 12933.3 13729.5 14798.9 15300.5 15601.2 16031.5 13946.2 14154.6 14902.0 15238.5 15316.7 12646.0 13603.8 14248.9 14627.6 14930.0 13461.3 13930.2 14021.2 14687.2 15047.8 12044.5 12436.9 12460.6 12828.9 13064.8 11863.1 12074.8 12292.9 12319.9 12323.1 13:30:11 INFO - PROCESS | 6958 | -------- Summary: end -------- 13:30:11 INFO - PROCESS | 6958 | 13:30:12 INFO - TEST-INFO | 6958: exit 0 13:30:12 INFO - TEST-OK | dromaeo_css | took 1111429ms 13:30:12 INFO - TEST-START | kraken 13:30:12 INFO - operating with platform_type : linux_ 13:30:12 INFO - Initialising browser for kraken test... 13:30:12 INFO - TEST-INFO | started process 7331 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFSGG1t/profile http://localhost:56648/getInfo.html) 13:30:16 INFO - PROCESS | 7331 | __metrics Screen width/height:1600/1200 13:30:16 INFO - PROCESS | 7331 | colorDepth:24 13:30:16 INFO - PROCESS | 7331 | Browser inner width/height: 1024/697 13:30:16 INFO - PROCESS | 7331 | __metrics 13:30:17 ERROR - PROCESS | 7331 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3018: TypeError: this.tabs is undefined 13:30:17 INFO - TEST-INFO | 7331: exit 0 13:30:17 INFO - Browser initialized. 13:30:17 INFO - Running cycle 1/1 for kraken test... 13:30:17 INFO - TEST-INFO | started process 7465 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFSGG1t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:30:18 INFO - PROCESS | 7465 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 13:30:18 INFO - Terminating psutil.Process(pid=7465, name='firefox') 13:30:18 INFO - TEST-INFO | 7465: exit 127 13:30:18 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 13:30:18 ERROR - Traceback (most recent call last): 13:30:18 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 13:30:18 INFO - talos_results.add(mytest.runTest(browser_config, test)) 13:30:18 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 13:30:18 INFO - return self._runTest(browser_config, test_config, setup) 13:30:18 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 232, in _runTest 13:30:18 INFO - else None) 13:30:18 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 13:30:18 INFO - global_counters=self.global_counters 13:30:18 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 13:30:18 INFO - self.parse() 13:30:18 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 13:30:18 INFO - % self.report_tokens) 13:30:18 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 13:30:18 INFO - raise utils.TalosError(message) 13:30:18 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 13:30:18 INFO - TEST-INFO took 5890ms 13:30:18 INFO - SUITE-END | took 1117s 13:30:18 ERROR - Return code: 2 13:30:18 WARNING - setting return code to 2 13:30:18 ERROR - # TBPL FAILURE # 13:30:18 INFO - Running post-action listener: _resource_record_post_action 13:30:18 INFO - [mozharness: 2016-10-13 20:30:18.377006Z] Finished run-tests step (success) 13:30:18 INFO - Running post-run listener: _resource_record_post_run 13:30:18 INFO - Total resource usage - Wall time: 1134s; CPU: 13.0%; Read bytes: 0; Write bytes: 305991680; Read time: 0; Write time: 253796 13:30:18 INFO - TinderboxPrint: CPU usage
13.3% 13:30:18 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 13:30:18 INFO - TinderboxPrint: I/O write bytes / time
305,991,680 / 253,796 13:30:18 INFO - TinderboxPrint: CPU idle
7,840.6 (86.7%) 13:30:18 INFO - TinderboxPrint: CPU user
1,160.1 (12.8%) 13:30:18 INFO - TinderboxPrint: Swap in / out
0 / 0 13:30:18 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107327488; Read time: 0; Write time: 82140 13:30:18 INFO - run-tests - Wall time: 1118s; CPU: 13.0%; Read bytes: 0; Write bytes: 198664192; Read time: 0; Write time: 171656 13:30:19 INFO - Running post-run listener: _upload_blobber_files 13:30:19 INFO - Blob upload gear active. 13:30:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:30:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:30:19 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'] 13:30:19 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 13:30:19 INFO - (blobuploader) - INFO - Open directory for files ... 13:30:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:30:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:30:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:30:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:30:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:30:20 INFO - (blobuploader) - INFO - Done attempting. 13:30:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:30:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:30:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:30:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:30:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:30:21 INFO - (blobuploader) - INFO - Done attempting. 13:30:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:30:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:30:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:30:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:30:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:30:25 INFO - (blobuploader) - INFO - Done attempting. 13:30:25 INFO - (blobuploader) - INFO - Iteration through files over. 13:30:25 INFO - Return code: 0 13:30:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:30:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:30:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86575f313f019e2ca353b9eeb4c1538bfd54ad1d36de9345b4a6bc3d916c528024e65c8b519234bfd7c13cb326f856df0ba78b2ec3145e0d609fac3f090273b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/577e3b9c052e3484eb86fb567f3b9a51f694643d3c05de17836ee80d65a863a0f095e408c87e1d94d14bdd419dfa86e70a8e2e82b1a30ad6bf93a6a5c690b2da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb40f95d0b9f0c37a49e4b03f748b8966017506c8cb7e78f11f06ea7772ed767d229500b188771ad377dcdd225615093e9b4a8f7ab8927005b1b3df0cab92d7e"} 13:30:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:30:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:30:25 INFO - Contents: 13:30:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86575f313f019e2ca353b9eeb4c1538bfd54ad1d36de9345b4a6bc3d916c528024e65c8b519234bfd7c13cb326f856df0ba78b2ec3145e0d609fac3f090273b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/577e3b9c052e3484eb86fb567f3b9a51f694643d3c05de17836ee80d65a863a0f095e408c87e1d94d14bdd419dfa86e70a8e2e82b1a30ad6bf93a6a5c690b2da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb40f95d0b9f0c37a49e4b03f748b8966017506c8cb7e78f11f06ea7772ed767d229500b188771ad377dcdd225615093e9b4a8f7ab8927005b1b3df0cab92d7e"} 13:30:25 INFO - Running post-run listener: copy_logs_to_upload_dir 13:30:25 INFO - Copying logs to upload dir... 13:30:25 INFO - mkdir: /builds/slave/test/build/upload/logs 13:30:25 INFO - Copying logs to upload dir... 13:30:25 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1177.589941 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 37 secs) (at 2016-10-13 13:30:25.565386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 13:30:25.569055) ========= 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=652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86575f313f019e2ca353b9eeb4c1538bfd54ad1d36de9345b4a6bc3d916c528024e65c8b519234bfd7c13cb326f856df0ba78b2ec3145e0d609fac3f090273b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/577e3b9c052e3484eb86fb567f3b9a51f694643d3c05de17836ee80d65a863a0f095e408c87e1d94d14bdd419dfa86e70a8e2e82b1a30ad6bf93a6a5c690b2da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb40f95d0b9f0c37a49e4b03f748b8966017506c8cb7e78f11f06ea7772ed767d229500b188771ad377dcdd225615093e9b4a8f7ab8927005b1b3df0cab92d7e"} build_url:https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/YPlxKQaZQLG4BQn2uOCo1A/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/86575f313f019e2ca353b9eeb4c1538bfd54ad1d36de9345b4a6bc3d916c528024e65c8b519234bfd7c13cb326f856df0ba78b2ec3145e0d609fac3f090273b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/577e3b9c052e3484eb86fb567f3b9a51f694643d3c05de17836ee80d65a863a0f095e408c87e1d94d14bdd419dfa86e70a8e2e82b1a30ad6bf93a6a5c690b2da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb40f95d0b9f0c37a49e4b03f748b8966017506c8cb7e78f11f06ea7772ed767d229500b188771ad377dcdd225615093e9b4a8f7ab8927005b1b3df0cab92d7e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 13:30:25.593515) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:30:25.593808) ========= 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=652eb03db935cfc1c15ebda0000001ad-1476389446.363811-1409315892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:30:25.676244) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 13:30:25.676527) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 13:30:25.676796) ========= ========= Total master_lag: 0.17 =========