builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-034 starttime: 1474125163.92 results: failure (2) buildid: 20160917075422 builduid: bd482b72f0fe4292b514bbd7fe657440 revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:43.917867) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:43.918854) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:43.919500) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:43.935332) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:43.935666) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 08:12:44-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-09-17 08:12:44 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121510 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:44.075702) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:44.076515) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017786 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:44.132236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:44.133092) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --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=d6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 08:12:44,984 truncating revision to first 12 chars 2016-09-17 08:12:44,984 Setting DEBUG logging. 2016-09-17 08:12:44,984 attempt 1/10 2016-09-17 08:12:44,984 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b401cb17167b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 08:12:45,286 unpacking tar archive at: mozilla-central-b401cb17167b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.646536 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:44.795602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:44.796041) ========= script_repo_revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:44.796493) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:44.796831) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:12:44.806607) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 39 secs) (at 2016-09-17 08:12:44.806966) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=d6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:12:45 INFO - MultiFileLogger online at 20160917 08:12:45 in /builds/slave/test 08:12:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:12:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:12:45 INFO - {'append_to_log': False, 08:12:45 INFO - 'base_work_dir': '/builds/slave/test', 08:12:45 INFO - 'blob_upload_branch': 'Firefox', 08:12:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:12:45 INFO - 'branch': 'Firefox', 08:12:45 INFO - 'buildbot_json_path': 'buildprops.json', 08:12:45 INFO - 'config_files': ('talos/linux_config.py',), 08:12:45 INFO - 'default_actions': ('clobber', 08:12:45 INFO - 'read-buildbot-config', 08:12:45 INFO - 'download-and-extract', 08:12:45 INFO - 'populate-webroot', 08:12:45 INFO - 'create-virtualenv', 08:12:45 INFO - 'install', 08:12:45 INFO - 'run-tests'), 08:12:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:12:45 INFO - 'download_minidump_stackwalk': True, 08:12:45 INFO - 'download_symbols': 'ondemand', 08:12:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:12:45 INFO - 'tooltool.py': '/tools/tooltool.py', 08:12:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:12:45 INFO - '/tools/misc-python/virtualenv.py')}, 08:12:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:12:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:12:45 INFO - 'installer_path': 'installer.exe', 08:12:45 INFO - 'log_level': 'info', 08:12:45 INFO - 'log_name': 'talos', 08:12:45 INFO - 'log_to_console': True, 08:12:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:12:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:12:45 INFO - 'opt_config_files': (), 08:12:45 INFO - 'pip_index': False, 08:12:45 INFO - 'sps_profile': False, 08:12:45 INFO - 'sps_profile_interval': 0, 08:12:45 INFO - 'suite': 'dromaeojs-e10s', 08:12:45 INFO - 'system_bits': '32', 08:12:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:12:45 INFO - 'title': 'talos-linux64-ix-034', 08:12:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:12:45 INFO - 'use_talos_json': True, 08:12:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:12:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:12:45 INFO - 'work_dir': 'build'} 08:12:45 INFO - [mozharness: 2016-09-17 15:12:45.754616Z] Running clobber step. 08:12:45 INFO - Running pre-action listener: _resource_record_pre_action 08:12:45 INFO - Running main action method: clobber 08:12:45 INFO - rmtree: /builds/slave/test/build 08:12:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:12:46 INFO - Running post-action listener: _resource_record_post_action 08:12:46 INFO - [mozharness: 2016-09-17 15:12:46.754075Z] Finished clobber step (success) 08:12:46 INFO - [mozharness: 2016-09-17 15:12:46.754176Z] Running read-buildbot-config step. 08:12:46 INFO - Running pre-action listener: _resource_record_pre_action 08:12:46 INFO - Running main action method: read_buildbot_config 08:12:46 INFO - Using buildbot properties: 08:12:46 INFO - { 08:12:46 INFO - "project": "", 08:12:46 INFO - "product": "firefox", 08:12:46 INFO - "script_repo_revision": "production", 08:12:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:12:46 INFO - "repository": "", 08:12:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 08:12:46 INFO - "buildid": "20160917075422", 08:12:46 INFO - "stage_platform": "linux64-pgo", 08:12:46 INFO - "basedir": "/builds/slave/test", 08:12:46 INFO - "buildnumber": 74, 08:12:46 INFO - "platform": "ubuntu64_hw", 08:12:46 INFO - "mozci_request": { 08:12:46 INFO - "priority": -1, 08:12:46 INFO - "type": "trigger_all_talos_jobs", 08:12:46 INFO - "times": 6 08:12:46 INFO - }, 08:12:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:12:46 INFO - "slavebuilddir": "test", 08:12:46 INFO - "branch": "mozilla-central", 08:12:46 INFO - "revision": "b401cb17167b34c362eb819259effbb3c0979f59", 08:12:46 INFO - "slavename": "talos-linux64-ix-034", 08:12:46 INFO - "builduid": "bd482b72f0fe4292b514bbd7fe657440", 08:12:46 INFO - "repo_path": "mozilla-central" 08:12:46 INFO - } 08:12:46 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:12:46 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 08:12:46 INFO - Running post-action listener: _resource_record_post_action 08:12:46 INFO - [mozharness: 2016-09-17 15:12:46.756947Z] Finished read-buildbot-config step (success) 08:12:46 INFO - [mozharness: 2016-09-17 15:12:46.757038Z] Running download-and-extract step. 08:12:46 INFO - Running pre-action listener: _resource_record_pre_action 08:12:46 INFO - Running main action method: download_and_extract 08:12:46 INFO - mkdir: /builds/slave/test/build/tests 08:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:12:46 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:12:46 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:12:46 INFO - Downloaded 1361 bytes. 08:12:46 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:12:46 INFO - Using the following test package requirements: 08:12:46 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:12:46 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:12:46 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:12:46 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:46 INFO - u'jsshell-linux-x86_64.zip'], 08:12:46 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:12:46 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:12:46 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:12:46 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:12:46 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:12:46 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:12:46 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:12:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:12:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:12:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:12:46 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:12:46 INFO - Expected file size: 21936347 08:12:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:12:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:12:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:12:51 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:12:51 INFO - Expected file size: 21936347 08:12:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:12:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:12:55 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:12:55 INFO - Expected file size: 14414048 08:12:56 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')]} 08:12:56 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:12:56 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:12:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:12:56 INFO - Downloaded 59535581 bytes. 08:12:56 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:12:56 INFO - mkdir: /builds/slave/test/properties 08:12:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:12:56 INFO - Writing to file /builds/slave/test/properties/build_url 08:12:56 INFO - Contents: 08:12:56 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:12:57 INFO - Running post-action listener: _resource_record_post_action 08:12:57 INFO - Running post-action listener: set_extra_try_arguments 08:12:57 INFO - [mozharness: 2016-09-17 15:12:57.427083Z] Finished download-and-extract step (success) 08:12:57 INFO - [mozharness: 2016-09-17 15:12:57.427311Z] Running populate-webroot step. 08:12:57 INFO - Running pre-action listener: _resource_record_pre_action 08:12:57 INFO - Running main action method: populate_webroot 08:12:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:12:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:12:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:12:57 INFO - '--tppagecycles', 08:12:57 INFO - '7'], 08:12:57 INFO - 'tests': ['tsvgm']}}, 08:12:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:12:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:12:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:12:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:12:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:57 INFO - 'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:12:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:12:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:57 INFO - 'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['damp', 'tps']}, 08:12:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:57 INFO - 'tests': ['damp', 'tps']}, 08:12:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['dromaeo_dom']}, 08:12:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:12:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['basic_compositor_video']}, 08:12:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:12:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['a11yr', 08:12:57 INFO - 'ts_paint', 08:12:57 INFO - 'tpaint', 08:12:57 INFO - 'sessionrestore', 08:12:57 INFO - 'sessionrestore_no_auto_restore', 08:12:57 INFO - 'tabpaint']}, 08:12:57 INFO - 'other-e10s': {'tests': ['a11yr', 08:12:57 INFO - 'ts_paint', 08:12:57 INFO - 'tpaint', 08:12:57 INFO - 'sessionrestore', 08:12:57 INFO - 'sessionrestore_no_auto_restore', 08:12:57 INFO - 'tabpaint']}, 08:12:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:12:57 INFO - 'ts_paint', 08:12:57 INFO - 'tpaint', 08:12:57 INFO - 'sessionrestore', 08:12:57 INFO - 'sessionrestore_no_auto_restore', 08:12:57 INFO - 'tabpaint']}, 08:12:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:12:57 INFO - 'ts_paint', 08:12:57 INFO - 'tpaint', 08:12:57 INFO - 'sessionrestore', 08:12:57 INFO - 'sessionrestore_no_auto_restore', 08:12:57 INFO - 'tabpaint']}, 08:12:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['a11yr', 08:12:57 INFO - 'ts_paint', 08:12:57 INFO - 'tpaint', 08:12:57 INFO - 'sessionrestore', 08:12:57 INFO - 'sessionrestore_no_auto_restore', 08:12:57 INFO - 'tabpaint']}, 08:12:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['a11yr', 08:12:57 INFO - 'ts_paint', 08:12:57 INFO - 'tpaint', 08:12:57 INFO - 'sessionrestore', 08:12:57 INFO - 'sessionrestore_no_auto_restore', 08:12:57 INFO - 'tabpaint']}, 08:12:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['tsvgx', 08:12:57 INFO - 'tsvgr_opacity', 08:12:57 INFO - 'tart', 08:12:57 INFO - 'tscrollx', 08:12:57 INFO - 'cart']}, 08:12:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:12:57 INFO - 'tsvgr_opacity', 08:12:57 INFO - 'tart', 08:12:57 INFO - 'tscrollx', 08:12:57 INFO - 'cart']}, 08:12:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:57 INFO - 'talos_options': ['--disable-e10s'], 08:12:57 INFO - 'tests': ['tp5o']}, 08:12:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:57 INFO - 'tests': ['tp5o']}, 08:12:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:57 INFO - 'talos_options': ['--disable-e10s', 08:12:57 INFO - '--xperf_path', 08:12:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:12:57 INFO - 'tests': ['tp5n']}, 08:12:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:12:57 INFO - 'talos_options': ['--xperf_path', 08:12:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:12:57 INFO - 'tests': ['tp5n']}}, 08:12:57 INFO - 'talos.zip': {'path': '', 08:12:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:12:57 INFO - Running post-action listener: _resource_record_post_action 08:12:57 INFO - [mozharness: 2016-09-17 15:12:57.449517Z] Finished populate-webroot step (success) 08:12:57 INFO - [mozharness: 2016-09-17 15:12:57.449605Z] Running create-virtualenv step. 08:12:57 INFO - Running pre-action listener: _resource_record_pre_action 08:12:57 INFO - Running main action method: create_virtualenv 08:12:57 INFO - Creating virtualenv /builds/slave/test/build/venv 08:12:57 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 08:12:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:12:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:12:57 INFO - Using real prefix '/usr' 08:12:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:12:58 INFO - Installing distribute.............................................................................................................................................................................................done. 08:13:00 INFO - Installing pip.................done. 08:13:00 INFO - Return code: 0 08:13:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:13: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')]} 08:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13: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')]} 08:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:00 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 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13:00 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 08:13:00 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 08:13:00 INFO - Using env: {'DISPLAY': ':0', 08:13:00 INFO - 'HOME': '/home/cltbld', 08:13:00 INFO - 'LANG': 'en_US.UTF-8', 08:13:00 INFO - 'LANGUAGE': 'en_US:en', 08:13:00 INFO - 'LOGNAME': 'cltbld', 08:13:00 INFO - 'MAIL': '/var/mail/cltbld', 08:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:00 INFO - 'MOZ_NO_REMOTE': '1', 08:13:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:00 INFO - 'NO_EM_RESTART': '1', 08:13:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:00 INFO - 'PWD': '/builds/slave/test', 08:13:00 INFO - 'SHELL': '/bin/bash', 08:13:00 INFO - 'SHLVL': '1', 08:13:00 INFO - 'TERM': 'linux', 08:13:00 INFO - 'TMOUT': '86400', 08:13:00 INFO - 'USER': 'cltbld', 08:13:00 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:00 INFO - '_': '/tools/buildbot/bin/python'} 08:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:00 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 08:13:00 INFO - Cleaning up... 08:13:00 INFO - Return code: 0 08:13:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:13: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')]} 08:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13: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')]} 08:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:00 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 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13:00 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 08:13:00 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 08:13:00 INFO - Using env: {'DISPLAY': ':0', 08:13:00 INFO - 'HOME': '/home/cltbld', 08:13:00 INFO - 'LANG': 'en_US.UTF-8', 08:13:00 INFO - 'LANGUAGE': 'en_US:en', 08:13:00 INFO - 'LOGNAME': 'cltbld', 08:13:00 INFO - 'MAIL': '/var/mail/cltbld', 08:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:00 INFO - 'MOZ_NO_REMOTE': '1', 08:13:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:00 INFO - 'NO_EM_RESTART': '1', 08:13:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:00 INFO - 'PWD': '/builds/slave/test', 08:13:00 INFO - 'SHELL': '/bin/bash', 08:13:00 INFO - 'SHLVL': '1', 08:13:00 INFO - 'TERM': 'linux', 08:13:00 INFO - 'TMOUT': '86400', 08:13:00 INFO - 'USER': 'cltbld', 08:13:00 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:00 INFO - '_': '/tools/buildbot/bin/python'} 08:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:00 INFO - Downloading/unpacking psutil>=3.1.1 08:13:00 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 08:13:00 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 08:13:00 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 08:13:00 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 08:13:00 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 08:13:00 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 08:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:13:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:13:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:13:02 INFO - Installing collected packages: psutil 08:13:02 INFO - Running setup.py install for psutil 08:13:02 INFO - building 'psutil._psutil_linux' extension 08:13:02 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 08:13:02 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 08:13:02 INFO - building 'psutil._psutil_posix' extension 08:13:02 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 08:13:02 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 08:13:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:13:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:13:02 INFO - Successfully installed psutil 08:13:02 INFO - Cleaning up... 08:13:03 INFO - Return code: 0 08:13:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:13:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:13:03 INFO - Using env: {'DISPLAY': ':0', 08:13:03 INFO - 'HOME': '/home/cltbld', 08:13:03 INFO - 'LANG': 'en_US.UTF-8', 08:13:03 INFO - 'LANGUAGE': 'en_US:en', 08:13:03 INFO - 'LOGNAME': 'cltbld', 08:13:03 INFO - 'MAIL': '/var/mail/cltbld', 08:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:03 INFO - 'MOZ_NO_REMOTE': '1', 08:13:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:03 INFO - 'NO_EM_RESTART': '1', 08:13:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:03 INFO - 'PWD': '/builds/slave/test', 08:13:03 INFO - 'SHELL': '/bin/bash', 08:13:03 INFO - 'SHLVL': '1', 08:13:03 INFO - 'TERM': 'linux', 08:13:03 INFO - 'TMOUT': '86400', 08:13:03 INFO - 'USER': 'cltbld', 08:13:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:03 INFO - '_': '/tools/buildbot/bin/python'} 08:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:13:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:13:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:13:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:13:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:13:05 INFO - Installing collected packages: mozsystemmonitor 08:13:05 INFO - Running setup.py install for mozsystemmonitor 08:13:05 INFO - Successfully installed mozsystemmonitor 08:13:05 INFO - Cleaning up... 08:13:05 INFO - Return code: 0 08:13:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:13: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')]} 08:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13: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')]} 08:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13: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', '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 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:13: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 jsonschema==2.5.1 08:13:05 INFO - Using env: {'DISPLAY': ':0', 08:13:05 INFO - 'HOME': '/home/cltbld', 08:13:05 INFO - 'LANG': 'en_US.UTF-8', 08:13:05 INFO - 'LANGUAGE': 'en_US:en', 08:13:05 INFO - 'LOGNAME': 'cltbld', 08:13:05 INFO - 'MAIL': '/var/mail/cltbld', 08:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:05 INFO - 'MOZ_NO_REMOTE': '1', 08:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:05 INFO - 'NO_EM_RESTART': '1', 08:13:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:05 INFO - 'PWD': '/builds/slave/test', 08:13:05 INFO - 'SHELL': '/bin/bash', 08:13:05 INFO - 'SHLVL': '1', 08:13:05 INFO - 'TERM': 'linux', 08:13:05 INFO - 'TMOUT': '86400', 08:13:05 INFO - 'USER': 'cltbld', 08:13:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:05 INFO - '_': '/tools/buildbot/bin/python'} 08:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:05 INFO - Downloading/unpacking jsonschema==2.5.1 08:13: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 08:13: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 08:13: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 08:13: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 08:13: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 08:13: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 08:13:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:13:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:13:07 INFO - Downloading functools32-3.2.3-2.tar.gz 08:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:13:07 INFO - warning: no files found matching '*.txt' 08:13:07 INFO - no previously-included directories found matching 'build' 08:13:07 INFO - no previously-included directories found matching 'dist' 08:13:07 INFO - no previously-included directories found matching '.git*' 08:13:07 INFO - Installing collected packages: jsonschema, functools32 08:13:07 INFO - Running setup.py install for functools32 08:13:07 INFO - warning: no files found matching '*.txt' 08:13:07 INFO - no previously-included directories found matching 'build' 08:13:07 INFO - no previously-included directories found matching 'dist' 08:13:07 INFO - no previously-included directories found matching '.git*' 08:13:07 INFO - Successfully installed jsonschema functools32 08:13:07 INFO - Cleaning up... 08:13:07 INFO - Return code: 0 08:13:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:13:07 INFO - Using env: {'DISPLAY': ':0', 08:13:07 INFO - 'HOME': '/home/cltbld', 08:13:07 INFO - 'LANG': 'en_US.UTF-8', 08:13:07 INFO - 'LANGUAGE': 'en_US:en', 08:13:07 INFO - 'LOGNAME': 'cltbld', 08:13:07 INFO - 'MAIL': '/var/mail/cltbld', 08:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:07 INFO - 'MOZ_NO_REMOTE': '1', 08:13:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:07 INFO - 'NO_EM_RESTART': '1', 08:13:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:07 INFO - 'PWD': '/builds/slave/test', 08:13:07 INFO - 'SHELL': '/bin/bash', 08:13:07 INFO - 'SHLVL': '1', 08:13:07 INFO - 'TERM': 'linux', 08:13:07 INFO - 'TMOUT': '86400', 08:13:07 INFO - 'USER': 'cltbld', 08:13:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:07 INFO - '_': '/tools/buildbot/bin/python'} 08:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:13:08 INFO - Cleaning up... 08:13:08 INFO - Return code: 0 08:13:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:13: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')]} 08:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13: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')]} 08:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13: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', '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 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:13: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 blobuploader==1.2.4 08:13:08 INFO - Using env: {'DISPLAY': ':0', 08:13:08 INFO - 'HOME': '/home/cltbld', 08:13:08 INFO - 'LANG': 'en_US.UTF-8', 08:13:08 INFO - 'LANGUAGE': 'en_US:en', 08:13:08 INFO - 'LOGNAME': 'cltbld', 08:13:08 INFO - 'MAIL': '/var/mail/cltbld', 08:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:08 INFO - 'MOZ_NO_REMOTE': '1', 08:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:08 INFO - 'NO_EM_RESTART': '1', 08:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:08 INFO - 'PWD': '/builds/slave/test', 08:13:08 INFO - 'SHELL': '/bin/bash', 08:13:08 INFO - 'SHLVL': '1', 08:13:08 INFO - 'TERM': 'linux', 08:13:08 INFO - 'TMOUT': '86400', 08:13:08 INFO - 'USER': 'cltbld', 08:13:08 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:08 INFO - '_': '/tools/buildbot/bin/python'} 08:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:08 INFO - Downloading/unpacking blobuploader==1.2.4 08:13: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 08:13: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 08:13: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 08:13: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 08:13: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 08:13: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 08:13:10 INFO - Downloading blobuploader-1.2.4.tar.gz 08:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:13:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:13: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 08:13: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 08:13: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 08:13: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 08:13: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 08:13: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 08:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:13:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:13: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 08:13: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 08:13: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 08:13: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 08:13: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 08:13: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 08:13:11 INFO - Downloading docopt-0.6.1.tar.gz 08:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:13:11 INFO - Installing collected packages: blobuploader, requests, docopt 08:13:11 INFO - Running setup.py install for blobuploader 08:13:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:13:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:13:11 INFO - Running setup.py install for requests 08:13:11 INFO - Running setup.py install for docopt 08:13:11 INFO - Successfully installed blobuploader requests docopt 08:13:11 INFO - Cleaning up... 08:13:11 INFO - Return code: 0 08:13:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:13:11 INFO - Using env: {'DISPLAY': ':0', 08:13:11 INFO - 'HOME': '/home/cltbld', 08:13:11 INFO - 'LANG': 'en_US.UTF-8', 08:13:11 INFO - 'LANGUAGE': 'en_US:en', 08:13:11 INFO - 'LOGNAME': 'cltbld', 08:13:11 INFO - 'MAIL': '/var/mail/cltbld', 08:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:11 INFO - 'MOZ_NO_REMOTE': '1', 08:13:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:11 INFO - 'NO_EM_RESTART': '1', 08:13:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:11 INFO - 'PWD': '/builds/slave/test', 08:13:11 INFO - 'SHELL': '/bin/bash', 08:13:11 INFO - 'SHLVL': '1', 08:13:11 INFO - 'TERM': 'linux', 08:13:11 INFO - 'TMOUT': '86400', 08:13:11 INFO - 'USER': 'cltbld', 08:13:11 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:11 INFO - '_': '/tools/buildbot/bin/python'} 08:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:13:11 INFO - Running setup.py (path:/tmp/pip-sJW9EU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:13:12 INFO - Running setup.py (path:/tmp/pip-xNZVc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:13:12 INFO - Running setup.py (path:/tmp/pip-xCtYiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:13:12 INFO - Running setup.py (path:/tmp/pip-46J76S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:13:12 INFO - Running setup.py (path:/tmp/pip-7ccQSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:13:12 INFO - Running setup.py (path:/tmp/pip-ElNLQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:13:12 INFO - Running setup.py (path:/tmp/pip-7ctU17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:13:12 INFO - Running setup.py (path:/tmp/pip-MhPbAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:13:12 INFO - Running setup.py (path:/tmp/pip-Q8SvIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:13:12 INFO - Running setup.py (path:/tmp/pip-enua2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:13:12 INFO - Running setup.py (path:/tmp/pip-a5f8I0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:13:12 INFO - Running setup.py (path:/tmp/pip-Migt2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:13:12 INFO - Running setup.py (path:/tmp/pip-Uq1Of3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:13:12 INFO - Running setup.py (path:/tmp/pip-3qPoBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:13:12 INFO - Running setup.py (path:/tmp/pip-uV_rSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:13:13 INFO - Running setup.py (path:/tmp/pip-F_OlyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:13:13 INFO - Running setup.py (path:/tmp/pip-NxIwSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:13:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:13:13 INFO - Running setup.py install for manifestparser 08:13:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:13:13 INFO - Running setup.py install for mozcrash 08:13:13 INFO - Running setup.py install for mozdebug 08:13:13 INFO - Running setup.py install for mozdevice 08:13:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:13:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:13:13 INFO - Running setup.py install for mozfile 08:13:13 INFO - Running setup.py install for mozhttpd 08:13:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:13:13 INFO - Running setup.py install for mozinfo 08:13:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:13:13 INFO - Running setup.py install for mozInstall 08:13:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:13:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:13:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:13:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:13:14 INFO - Running setup.py install for mozleak 08:13:14 INFO - Running setup.py install for mozlog 08:13:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:13:14 INFO - Running setup.py install for moznetwork 08:13:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:13:14 INFO - Running setup.py install for mozprocess 08:13:14 INFO - Running setup.py install for mozprofile 08:13:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:13:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:13:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:13:14 INFO - Running setup.py install for mozrunner 08:13:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:13:14 INFO - Running setup.py install for mozscreenshot 08:13:14 INFO - Running setup.py install for moztest 08:13:14 INFO - Running setup.py install for mozversion 08:13:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:13:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:13:14 INFO - Cleaning up... 08:13:15 INFO - Return code: 0 08:13:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:15 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 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13:15 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 08:13:15 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 08:13:15 INFO - Using env: {'DISPLAY': ':0', 08:13:15 INFO - 'HOME': '/home/cltbld', 08:13:15 INFO - 'LANG': 'en_US.UTF-8', 08:13:15 INFO - 'LANGUAGE': 'en_US:en', 08:13:15 INFO - 'LOGNAME': 'cltbld', 08:13:15 INFO - 'MAIL': '/var/mail/cltbld', 08:13:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:15 INFO - 'MOZ_NO_REMOTE': '1', 08:13:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:15 INFO - 'NO_EM_RESTART': '1', 08:13:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:15 INFO - 'PWD': '/builds/slave/test', 08:13:15 INFO - 'SHELL': '/bin/bash', 08:13:15 INFO - 'SHLVL': '1', 08:13:15 INFO - 'TERM': 'linux', 08:13:15 INFO - 'TMOUT': '86400', 08:13:15 INFO - 'USER': 'cltbld', 08:13:15 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:15 INFO - '_': '/tools/buildbot/bin/python'} 08:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:13:15 INFO - Running setup.py (path:/tmp/pip-k3HcLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:13:15 INFO - Running setup.py (path:/tmp/pip-aqeX4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:13:15 INFO - Running setup.py (path:/tmp/pip-9Jd4I7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:13:15 INFO - Running setup.py (path:/tmp/pip-jq8ZRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:13:15 INFO - Running setup.py (path:/tmp/pip-V9ST3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:13:15 INFO - Running setup.py (path:/tmp/pip-BCzszj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:13:15 INFO - Running setup.py (path:/tmp/pip-vp1IIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:13:15 INFO - Running setup.py (path:/tmp/pip-jCRz3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:13:15 INFO - Running setup.py (path:/tmp/pip-S_nBTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:13:15 INFO - Running setup.py (path:/tmp/pip-3MYWgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:13:15 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)) 08:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:13:15 INFO - Running setup.py (path:/tmp/pip-0hF65D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:13:16 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)) 08:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:13:16 INFO - Running setup.py (path:/tmp/pip-tGVlGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:13:16 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)) 08:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:13:16 INFO - Running setup.py (path:/tmp/pip-CruRFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:13:16 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)) 08:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:13:16 INFO - Running setup.py (path:/tmp/pip-imG1vP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:13:16 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)) 08:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:13:16 INFO - Running setup.py (path:/tmp/pip-BlJewz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:13:16 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)) 08:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:13:16 INFO - Running setup.py (path:/tmp/pip-P7NQ_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:13:16 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)) 08:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:13:16 INFO - Running setup.py (path:/tmp/pip-IlXebh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:13:16 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)) 08:13:16 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)) 08:13:16 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)) 08:13:16 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)) 08:13:16 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)) 08:13:16 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)) 08:13:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:13:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:13:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:13:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:13:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:13:18 INFO - Downloading blessings-1.6.tar.gz 08:13:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:13:18 INFO - Installing collected packages: blessings 08:13:18 INFO - Running setup.py install for blessings 08:13:18 INFO - Successfully installed blessings 08:13:18 INFO - Cleaning up... 08:13:18 INFO - Return code: 0 08:13:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:13:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:13:18 INFO - Reading from file tmpfile_stdout 08:13:18 INFO - Current package versions: 08:13:18 INFO - argparse == 1.2.1 08:13:18 INFO - blessings == 1.6 08:13:18 INFO - blobuploader == 1.2.4 08:13:18 INFO - docopt == 0.6.1 08:13:18 INFO - functools32 == 3.2.3-2 08:13:18 INFO - jsonschema == 2.5.1 08:13:18 INFO - manifestparser == 1.1 08:13:18 INFO - mozInstall == 1.12 08:13:18 INFO - mozcrash == 0.17 08:13:18 INFO - mozdebug == 0.1 08:13:18 INFO - mozdevice == 0.48 08:13:18 INFO - mozfile == 1.2 08:13:18 INFO - mozhttpd == 0.7 08:13:18 INFO - mozinfo == 0.9 08:13:18 INFO - mozleak == 0.1 08:13:18 INFO - mozlog == 3.4 08:13:18 INFO - moznetwork == 0.27 08:13:18 INFO - mozprocess == 0.23 08:13:18 INFO - mozprofile == 0.28 08:13:18 INFO - mozrunner == 6.12 08:13:18 INFO - mozscreenshot == 0.1 08:13:18 INFO - mozsystemmonitor == 0.3 08:13:18 INFO - moztest == 0.7 08:13:18 INFO - mozversion == 1.4 08:13:18 INFO - psutil == 3.1.1 08:13:18 INFO - requests == 1.2.3 08:13:18 INFO - wsgiref == 0.1.2 08:13:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:13:18 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')]} 08:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:18 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')]} 08:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:18 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 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13:18 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 08:13:18 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 08:13:18 INFO - Using env: {'DISPLAY': ':0', 08:13:18 INFO - 'HOME': '/home/cltbld', 08:13:18 INFO - 'LANG': 'en_US.UTF-8', 08:13:18 INFO - 'LANGUAGE': 'en_US:en', 08:13:18 INFO - 'LOGNAME': 'cltbld', 08:13:18 INFO - 'MAIL': '/var/mail/cltbld', 08:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:18 INFO - 'MOZ_NO_REMOTE': '1', 08:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:18 INFO - 'NO_EM_RESTART': '1', 08:13:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:18 INFO - 'PWD': '/builds/slave/test', 08:13:18 INFO - 'SHELL': '/bin/bash', 08:13:18 INFO - 'SHLVL': '1', 08:13:18 INFO - 'TERM': 'linux', 08:13:18 INFO - 'TMOUT': '86400', 08:13:18 INFO - 'USER': 'cltbld', 08:13:18 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:18 INFO - '_': '/tools/buildbot/bin/python'} 08:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:19 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)) 08:13:19 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)) 08:13:19 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)) 08:13:19 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)) 08:13:19 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)) 08:13:19 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)) 08:13:19 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)) 08:13:19 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)) 08:13:19 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)) 08:13:19 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)) 08:13:19 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)) 08:13:19 INFO - Cleaning up... 08:13:19 INFO - Return code: 0 08:13:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:13: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')]} 08:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13: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')]} 08:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2818a8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x209a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', '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 08:13:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:13:19 INFO - Using env: {'DISPLAY': ':0', 08:13:19 INFO - 'HOME': '/home/cltbld', 08:13:19 INFO - 'LANG': 'en_US.UTF-8', 08:13:19 INFO - 'LANGUAGE': 'en_US:en', 08:13:19 INFO - 'LOGNAME': 'cltbld', 08:13:19 INFO - 'MAIL': '/var/mail/cltbld', 08:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:19 INFO - 'MOZ_NO_REMOTE': '1', 08:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:19 INFO - 'NO_EM_RESTART': '1', 08:13:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:19 INFO - 'PWD': '/builds/slave/test', 08:13:19 INFO - 'SHELL': '/bin/bash', 08:13:19 INFO - 'SHLVL': '1', 08:13:19 INFO - 'TERM': 'linux', 08:13:19 INFO - 'TMOUT': '86400', 08:13:19 INFO - 'USER': 'cltbld', 08:13:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:19 INFO - '_': '/tools/buildbot/bin/python'} 08:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:13:19 INFO - Cleaning up... 08:13:19 INFO - Return code: 0 08:13:19 INFO - Running post-action listener: _resource_record_post_action 08:13:19 INFO - Running post-action listener: _start_resource_monitoring 08:13:19 INFO - Starting resource monitoring. 08:13:19 INFO - [mozharness: 2016-09-17 15:13:19.320844Z] Finished create-virtualenv step (success) 08:13:19 INFO - [mozharness: 2016-09-17 15:13:19.321364Z] Running install step. 08:13:19 INFO - Running pre-action listener: _resource_record_pre_action 08:13:19 INFO - Running main action method: install 08:13:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:13:19 INFO - Reading from file tmpfile_stdout 08:13:19 INFO - Detecting whether we're running mozinstall >=1.0... 08:13:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:13:19 INFO - Reading from file tmpfile_stdout 08:13:19 INFO - Output received: 08:13:19 INFO - Usage: mozinstall [options] installer 08:13:19 INFO - Options: 08:13:19 INFO - -h, --help show this help message and exit 08:13:19 INFO - -d DEST, --destination=DEST 08:13:19 INFO - Directory to install application into. [default: 08:13:19 INFO - "/builds/slave/test"] 08:13:19 INFO - --app=APP Application being installed. [default: firefox] 08:13:19 INFO - mkdir: /builds/slave/test/build/application 08:13:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:13:36 INFO - Reading from file tmpfile_stdout 08:13:36 INFO - Output received: 08:13:36 INFO - /builds/slave/test/build/application/firefox/firefox 08:13:36 INFO - Running post-action listener: _resource_record_post_action 08:13:36 INFO - [mozharness: 2016-09-17 15:13:36.551138Z] Finished install step (success) 08:13:36 INFO - [mozharness: 2016-09-17 15:13:36.551388Z] Running run-tests step. 08:13:36 INFO - Running pre-action listener: _resource_record_pre_action 08:13:36 INFO - Running main action method: run_tests 08:13:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:13:36 INFO - Python 2.7.3 08:13:36 INFO - Return code: 0 08:13:36 INFO - grabbing minidump binary from tooltool 08:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:36 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 0x2097930>, '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 0x2098830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:13:36 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 08:13:36 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 08:13:36 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 08:13:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:13:36 INFO - Return code: 0 08:13:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:13:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:13:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:13:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:13:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:13:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 08:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 08:13:36 INFO - Using env: {'DISPLAY': ':0', 08:13:36 INFO - 'HOME': '/home/cltbld', 08:13:36 INFO - 'LANG': 'en_US.UTF-8', 08:13:36 INFO - 'LANGUAGE': 'en_US:en', 08:13:36 INFO - 'LOGNAME': 'cltbld', 08:13:36 INFO - 'MAIL': '/var/mail/cltbld', 08:13:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:13:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:36 INFO - 'MOZ_NO_REMOTE': '1', 08:13:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:13:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:13:36 INFO - 'NO_EM_RESTART': '1', 08:13:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:36 INFO - 'PWD': '/builds/slave/test', 08:13:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:13:36 INFO - 'SHELL': '/bin/bash', 08:13:36 INFO - 'SHLVL': '1', 08:13:36 INFO - 'TERM': 'linux', 08:13:36 INFO - 'TMOUT': '86400', 08:13:36 INFO - 'USER': 'cltbld', 08:13:36 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617', 08:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:36 INFO - '_': '/tools/buildbot/bin/python'} 08:13:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 08:13:36 INFO - mozversion application_buildid: 20160916163001 08:13:36 INFO - mozversion application_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:13:36 INFO - mozversion application_display_name: Nightly 08:13:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:13:36 INFO - mozversion application_name: Firefox 08:13:36 INFO - mozversion application_remotingname: firefox 08:13:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:13:36 INFO - mozversion application_vendor: Mozilla 08:13:36 INFO - mozversion application_version: 51.0a1 08:13:36 INFO - mozversion platform_buildid: 20160916163001 08:13:36 INFO - mozversion platform_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:13:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:13:36 INFO - mozversion platform_version: 51.0a1 08:13:36 INFO - using testdate: 1474125216 08:13:36 INFO - actual date: 1474125216 08:13:36 INFO - starting webserver on 'localhost:41589' 08:13:36 INFO - SUITE-START | Running 2 tests 08:13:36 INFO - TEST-START | dromaeo_css 08:13:36 INFO - operating with platform_type : linux_ 08:13:36 INFO - Initialising browser for dromaeo_css test... 08:13:36 INFO - TEST-INFO | started process 29377 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHmuRRx/profile http://localhost:41589/getInfo.html) 08:13:41 INFO - PROCESS | 29377 | __metrics Screen width/height:1600/1200 08:13:41 INFO - PROCESS | 29377 | colorDepth:24 08:13:41 INFO - PROCESS | 29377 | Browser inner width/height: 1024/697 08:13:41 INFO - PROCESS | 29377 | __metrics 08:13:42 ERROR - PROCESS | 29377 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3016: TypeError: this.tabs is undefined 08:13:43 INFO - TEST-INFO | 29377: exit 0 08:13:43 INFO - Browser initialized. 08:13:43 INFO - Running cycle 1/1 for dromaeo_css test... 08:13:43 INFO - TEST-INFO | started process 29499 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHmuRRx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:13:55 INFO - PROCESS | 29499 | RSS: Main: 184360960 08:13:55 INFO - PROCESS | 29499 | 08:14:45 INFO - PROCESS | 29499 | 1474125285048 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] 08:16:55 INFO - PROCESS | 29499 | Cycle 1(1): loaded http://localhost:41589/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41589/tests/dromaeo/cssquery-ext.html) 08:16:56 INFO - PROCESS | 29499 | RSS: Main: 201433088 08:16:56 INFO - PROCESS | 29499 | 08:19:58 INFO - PROCESS | 29499 | Cycle 1(1): loaded http://localhost:41589/tests/dromaeo/cssquery-ext.html (next: http://localhost:41589/tests/dromaeo/cssquery-jquery.html) 08:19:59 INFO - PROCESS | 29499 | RSS: Main: 201531392 08:19:59 INFO - PROCESS | 29499 | 08:23:01 INFO - PROCESS | 29499 | Cycle 1(1): loaded http://localhost:41589/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41589/tests/dromaeo/cssquery-mootools.html) 08:23:01 INFO - PROCESS | 29499 | RSS: Main: 197525504 08:23:01 INFO - PROCESS | 29499 | 08:26:03 INFO - PROCESS | 29499 | Cycle 1(1): loaded http://localhost:41589/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41589/tests/dromaeo/cssquery-prototype.html) 08:26:04 INFO - PROCESS | 29499 | RSS: Main: 194551808 08:26:04 INFO - PROCESS | 29499 | 08:29:06 INFO - PROCESS | 29499 | Cycle 1(1): loaded http://localhost:41589/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41589/tests/dromaeo/cssquery-yui.html) 08:29:07 INFO - PROCESS | 29499 | RSS: Main: 198299648 08:29:07 INFO - PROCESS | 29499 | 08:32:10 INFO - PROCESS | 29499 | Cycle 1(1): loaded http://localhost:41589/tests/dromaeo/cssquery-yui.html (next: http://localhost:41589/tests/dromaeo/cssquery-dojo.html) 08:32:10 INFO - PROCESS | 29499 | RSS: Main: 198524928 08:32:10 INFO - PROCESS | 29499 | 08:32:10 INFO - PROCESS | 29499 | __start_tp_report 08:32:10 INFO - PROCESS | 29499 | _x_x_mozilla_page_load 08:32:10 INFO - PROCESS | 29499 | _x_x_mozilla_page_load_details 08:32:10 INFO - PROCESS | 29499 | |i|pagename|runs| 08:32:10 INFO - PROCESS | 29499 | |0;dojo.html;3047.2991212021234;3066.4173806976673;3084.8611812468434;3101.131683128724;3105.9844700776507;5292.364916210051;5298.89402211956;5355.089634762095;5372.57019438445;5442.1292593185;5336.546393556543;5342.465753424655;5361.410244873061;5373.301470808789;5395.649282796616;4795.42454905411;4867.123917694814;4876.902461950759;4883.780229889646;4954.653174277784;4916.164252077156;4941.258811178334;4945.208766597345;4978.452370239271;5015.899682006358;4633.907321853544;4665.790039448199;4779.761011949388;4853.320535125068;4870.293807397925;4839.564439200489;4848.442429120626;4922.187839006559;4941.851744447672;4960.429550601655;4806.879828004293;4860.8055677772845;4873.439554451213;4880.121578115938;4914.213725803854;5175.844724658266;5207.244949482304;5331.306930099101;5350.30446042016;5424.349078110613;5660.264165658479;5668.178114173463;5680.715964201773;5699.8005069822775;5720.48515633595;5791.623544469596;5802;5804.332501762309;5805.954928112938;5814.778896431754;5278.917821846512;5347.438518955492;5348.866278343073;5386.1920711893135;5413.593980451482;5777.306723193244;5881.5000724938;5889.939810834091;5897.056470964625;5959.28488581373;5603.131514615241;5667.3765885753355;5670.461306175906;5670.631408958487;5683.801066962719;5513.558915286768;5630.296212973379;5634.774609015593;5744.942550574524;5754.568407369464;5670.432956704297;5674.0637794764225;5675.914861277084;5696.943030569641;5723.942760572341;5671.858203544862;5678.261825962681;5684.37471878093;5696.174054762119;5704.486596206361;4298.2263192625005;4360.302351623725;4399.560043995639;4404.691671582958;4468.195724769573;3954.5056867891512;3979.92040159201;4020.155767288902;4032.8185231664647;4048.2308361411237;5415.431379705153;5417.26866872975;5423.484768946943;5445.047116754552;5480.068388373986;5510.8071217507995;5538;5715.142728590745;5721;5724.141378793214;5383.838484845461;5386.272853164773;5402.324709411324;5457.345118585795;5519.475649813261;4918.6556941014505;4928.408590969035;4965.751712414365;4975.228839529878;5018.071656743514;5739.253896993364;5881.647101173858;5914.8817023659285;5928.110783382441;5929.970350148308;5658.7736490541565;5693.316801983623;5710.600257982068;5759.539236860957;5813.3605303417435;5904.350521442552;5908.113782932594;5909.645421274738;5912.822615321547;5914.58597898143;5398;5405.405405405481;5663.405342438985;5698.1737648041635;5709.314882214161;5672.829815105553;5701.543876489954;5715.428457154251;5720.313562372542;5816.82549523515;5573.804916827891;5576.470235327637;5578.107502799533;5586.720663966867;5586.748596313257;5421.7560209791445;5599.412061733459;5609.1305847593685;5617.32592088952;5624.550035997028;5398.676079435247;5507;5580.302462192226;5592.105263157875;5653.745581448927;5668.064769313017;5696.8006119785605;5697;5699.1451282306325;5704.600677952503;5543.417941116125;5562.860928476821;5588.245586845724;5589.636673616202;5596.496315331639 08:32:10 INFO - PROCESS | 29499 | |1;ext.html;577.1429427300459;578.2684903596951;584.3834754334177;587.8971180043496;593.222878029781;41282.38076428852;43224.351634725426;43505.564944350546;47335.526644733545;47388;44595;44683.329750053694;45052.774736126354;45118.54881451184;45171.54828451723;8926.821463570725;9062.818743625123;9075.364724469257;9075.773105672379;9094.818103637923;8819.426737262089;8916.643334266622;8918.197362237397;8931.464112153259;8935.95532022339;8876.955615221916;8900.51047192404;8942.821143577125;8953.597088131017;8959.059298773633;8871.645134194625;8881.978572464184;8900.287976961827;8911.465312081296;8916.420432671855;8856.380053396264;8865.645374185026;8931.106889311082;8939.73180804577;8960.1935825776;41405;41756.74729758082;41808.37287440691;41822.37266441006;41853.79073104646;3390.931856465206;3410.761246712731;3457.4468085106514;3458.723302135823;3466.116140384186;30970.38059238825;31422.37155256905;31500.842495787605;31850.681493185002;32693.836530817192;4046.979765101185;4071.7149799514045;4122.255806554239;4174.20690068889;4309.396684464177;329913;330083;331034;331255.3437232823;331381;34437.31125377528;35434.822825885705;54432.45567544353;55147;55171;36616.45075323872;36616.63383366186;36832.631673683456;36884.81557592248;37248;14094.85905140956;14147.080439771382;14209.573712788633;14281.78577321341;14287.285635718172;11622.94188529052;11688.883111168949;11715.941420292844;11723.062155027577;11753;8491.405601607828;8492.660293588311;8510.829783404297;8525.95737021311;8583.484990900566;3348.715359194447;3415.4535274355985;3438.82805859711;3457.3949558827608;3475.826208689555;986.063239922077;989.6981420666622;1031.4481752262504;1057.3233130796295;1101.942085826062;31038;31465.8426707865;31487;31565.21086972798;32544.511832322532;1014.4927536231884;1039.184240371305;1040.8542804007445;1134.7900638381925;1141.3209140561353;15515;16428.917855410884;16532.917335413487;16590;16658;532340;538978;544391;777911;780341;55196;55496;55887.44112558904;56173.71913140408;56240.43759562352;1342.7583035053783;1346.3335648853881;1376.1880490510646;1385.1827421821174;1391.8816900563565;29819.676146958136;31609.52585711262;31708.84145579257;31816.840915796198;32294.83852580816;47692.52307477019;48118.278225827344;48436.273455898896;48519.272210916155;48620.756896215295;440329;442536;442841;443752;444707;27312.317192070357;27741.583876242275;27952.860235698692;28093.719062809112;28178.85910570434;45579.54420455753;45864;45909.77045114753;46061;46795.29807052829;38713;39215.607843922335;39724.40413393744;40931.1813763729;41188.38217426801;38993;39756.403653945796;39801.80099099602;39829.60170398259;41211.1757764837;39007.41488877726;39364.60635393724;39423.408648869714;39631.80184099061;39989.800051000726;33953.49069763906;34956;35009.64990350064;35652.82173589116;36212.4568131473;27105.86447067752;27112.457750844478;27458.725412745618;27560.17319480419;27614.987838422723 08:32:10 INFO - PROCESS | 29499 | |2;jquery.html;396.8134976561015;401.27369461275083;405.07238424009023;406.30724614532227;428.68277474668736;15700.293486793093;15971.361145554163;18058.81941180588;19721.112549935253;19746.703799442985;17696.11519424027;18199.81800181998;18610.720839187423;18854.52863678409;19093.140808663607;5054.241863720435;5090.160123579605;5246.842594722164;5400.675959442427;5450.591205659572;5348.197770334462;5357.464253574651;5426.728663566825;5433.831726178871;5434.157705555625;5430.728463576826;5617.747201375927;5867.794627188049;5885.117232415129;5973.193618861442;5780.53755699543;5937.940620593804;5953.333066741276;5990.371011043843;5990.640561566298;5803.767849286066;5830.650160990354;5852.502537284336;5873.412658734135;5878.177055212273;13135.540256091086;13464.461421543127;13645.454181832714;13773.242471664054;13873.930630346884;2086.5201003769066;2156.428546435196;2164.891755412223;2257.582347265761;2262.490939538607;9914.55384507699;10212.489375531193;10499.475026248658;10871.29336593119;13841.861581384159;1862.8230318119756;1916.8179022992933;1923.7883832778384;1957.4812674641232;2003.0685731134947;78743;78960.60519697423;79501.60249198717;80237.59881200615;83411;10763.892361076445;11111.611093611846;11234;11269.267497612629;11280.435978201058;15198.620034499005;15249.6187595311;15419.768703469457;15470.922645386701;15477.148756818484;7568.508046976929;7689.653965571562;7736.110347310018;8049.919500804917;8145.6741730331305;7284.38082762971;7314.158871729819;7462.589557574277;7557.584332861636;7581.734639287599;4644.16405047088;4749.525047495292;4811.254255590389;4823.686460380064;4853.835079580946;1977.4957385866512;1996.600679864033;1998.4004798560663;2041.203930467119;2050.4258807533915;866.8743032260287;896.7309807057857;897.8339007283673;962.9277804164716;995.6216637677636;17914.820851791315;19422.70865937012;19456.8054319455;19708;19780.20879164846;930.3998920696113;933.7572231219932;935.7239614313658;947.990520094804;972.9610815567439;10426;10757.946210269056;10823;10849.132069434427;10911.236213464981;443874;445794;448597;449446;449558;90523;92371;92801;93268.53365733263;93555.53222233846;982.0866594067443;1016.11090295991;1046.586598293685;1055.0609957138154;1057.6721216422934;16550.751738723688;16935;17246.223919923883;17758.644827103126;17761.289548417917;39983.800081000576;40261.798691006356;40391.1921761569;40922.386164208154;40983.38524922188;56854.431455686565;56972.57568560819;58210.4178958222;59185;59301;8120.75637730869;8158.143394943446;8349.54077525742;8374.623141958748;8578.699745508877;10509.737256568647;11448.42757862087;12557.183783054072;12792.168509046882;12863.29251891155;10217.284790064921;10804.40575768309;10944.124470042218;11158.721031974266;11167.329960202414;9653.372530785477;10393.480325983823;10647.254692171473;10730.89269107299;10745.194110441591;6236.656983865931;7007.754728584475;10657.467126643793;11257.813782094343;11459.48432320531;8269.83460330802;8401.53791541471;8427.494350338755;8452.830943380975;8566.400351975302;9669.758256043655;10554.366737995744;10575.735606609896;10710.518026688662;10851.131909447384 08:32:10 INFO - PROCESS | 29499 | |3;mootools.html;1466.3181620546443;1473.5432016075015;1636.967260654787;1765.1174412793603;1770.9114544272784;8212.794680133;8531.146885311466;9089.636414543418;9571.569279382436;9583.616655333786;8896.955515222415;9130.360874738772;9187.310951678634;9358.519992202055;9437.292203084779;2422.982347414086;2456.361346050031;2457.2259738182415;2459.827812053157;2463.5688754467988;2345.4370950971816;2378.643203519477;2422.9394265143337;2432.4770174412497;2433.9391515212174;2392.4258178037235;2409.5662780699467;2411.67442395277;2418.1899063813644;2420.673209116764;2350.5063936573342;2361.598528250193;2361.8700971446565;2371.7983971362455;2406.38637147528;2303.5853546361814;2312.9653055204185;2324.0936034946385;2324.7675232476786;2327.639215921518;9254.167124958785;9362.25101991839;9373.718788436357;9405.153536181777;9463.858042129374;5342.893142137135;5450.5367043801325;5457.918131228034;5475.151351540518;5477.315335583052;3302.752293577967;3319.9502007469905;3341.7159541438764;3352.1452029732504;3380.053584996205;1186.2467333243367;1193.2959553863175;1208.8307636930838;1230.9815352769715;2072.38864534961;12497.437615307334;12567.18313309641;12645.367731613474;12670.936645316806;12700.745985080339;4506.414166158378;4731.195696731564;4741.715497070187;4833.081714474309;4863.197572400514;10201.234907381977;10206.744831379126;10211.795764084676;10220.335678180894;10226.181905447545;5488.56091512686;5518.089515230023;5549.278593782782;5552.666839989613;5554.389017208104;4934.432540257844;5078.568321692697;5107.974460127675;5128.512791284822;5169.06956747789;3840.5007349044445;3847.153626202231;3931.567527571965;3933.272344616255;3999.1601763629965;1210.84864391951;1212.4059210986552;1212.9696757580955;1216.087934049463;1223.443333283354;787.4291138924234;939.4738946190155;946.4983558713893;982.4350998176077;1009.5154325923597;5923.881522369528;6145.262568491809;6250.468710159596;6325.683715814283;6424.903626445607;925.8611208318671;946.5220063867703;946.8721722567503;999.415342024909;1029.8300780371305;4040.4141399496907;4056.8985775355263;4129.710920235615;4216.936745948813;4269.48766148058;11475.942620287013;11700.35648039349;11725.824112638316;11807.88192118085;11851.644450666494;11722.472488738027;11744.29534227932;11748.353840538852;11769.470373833197;11778.823317650242;8072.273495385443;8490.27832634233;8563.357748168912;8665.480071195749;8810.515421651871;5867.9413205869105;6074.362191969956;6101.176341193882;6137.84655383619;6395.360463953567;10674.306170098918;10748.570057197625;10844.945775271073;10848.945755271488;10857.728556786144;10653.201009924132;10686.412247326472;10717.196210284415;10828.079613232963;10855.511501982588;7302.123745150403;7603.46775725716;7833.177516360928;8113.066997295151;8200.671973121247;8590.312774978112;8956;8962.372633915564;8980.371374003757;8992.730218093468;7896.644650990834;7967.28294453502;8009.91990080115;8191.344692424711;8222;7712.305892469704;7929.762107136795;8005.07941586725;8053.31546818527;8102.513849168833;7268.454865885185;7467.925320746723;7484.70061197546;7528.058992625922;7531.322181003736;5279.920801187908;5352.090144675337;5367.463253674601;5464.480874316933;5488.259085023471;5314.601404894571;5386.111291636993;5409.918851217156;5422.918656220081;5501.312335958005 08:32:10 INFO - PROCESS | 29499 | |4;prototype.html;320.76263564961926;330.9387763263796;338.83058470764604;345.76660753991064;349.3205714884551;5310.230016647583;5447.428020057896;5460.208269800882;5539.972300138499;5558.138488534273;5245.239440281157;5352.91970620441;5429.728513574325;5430.185472179165;5432.402435732066;4243.766592837385;4269.594388533084;4271.202362452583;4302.075053863435;4325.978370108145;4172.287913657654;4257.40396344512;4273.166732487166;4311.849085282016;4315.352697095445;4242.9151416971645;4244.745315281093;4252.8511502097435;4259.16946195492;4263.403123562688;4234.640055595278;4235.682323825726;4235.788210589458;4246.278132717445;4259.978700106495;3581.767185132958;3667.743257971943;3677.2093999790172;3691.3724666806706;3755.1738617504107;5319.494648008433;5336.17289320156;5345.412004679482;5370.5435038021815;5389.380221274525;4810.807567697288;4832.178529649968;4836.927446088312;4840.273958906157;4870.537298956593;5471.179323101527;5647.322321321427;5697.6866272355;5707.286589176353;5711.428857114297;4086.8978275543054;4095.467589213413;4109.137081212949;4117.814698338582;4125.752454852688;5894.263217097863;5939.495142912858;5970.104484327386;5973.133895585159;5995.430434108753;4092.8976775580254;4171.958280417218;4173.165366926627;4176.624103830609;4206.495220573551;4208.936865947013;4248.129133527681;4258.829646814154;4261.893452663646;4267.594578515036;4851.56335929768;4868.41579010521;4870.391201099863;4878.170710979142;4930.605551555867;5622.465865742748;5695.430456954355;5707.944030354398;5767.250257466502;5821.970890145607;1423.7793142062997;1437.8921580881267;1465.340596731475;1470.647044709262;1482.081109711964;897.537768049455;922.4142669404843;927.3786563002805;934.1639232886529;947.990520094804;4807.663463557587;4829.927551086666;4846.636502262274;4850.223964165716;4903.53416425439;5024.447310795809;5100.5409513143995;5141.665791723601;5158.819441319611;5197.882455272134;4648.116857797076;4872.366592342973;4876.829310974099;4888.462269150391;4906.484819094015;2734.4531093781325;2745.7391547802927;2756.6967633560294;2758.7517123458583;2777.652793400825;5171.198464238049;5212.869678257998;5299.747607266896;5310.751973286275;5330.227117068081;4867.9269810952865;5234.618248696834;5256.6057545684225;5279.841604751864;5340.25236466895;5279.548124265858;5314.335708036496;5354.303940487712;5365.436629153961;5388.946110538845;5117.338983728366;5220.9738951305;5233.691577105724;5275.657082289639;5276.762545685377;4702.542211914318;4926.58124059459;5053.519915608012;5086.634714857609;5121.948780512147;5263.9210411844615;5333.759980800795;5335.2263921730355;5342.100266805332;5370.543503802221;3850.0567360996733;3873.670737987303;3882.5535063468064;3900.707446941434;3907.0818357685353;4815.719513032582;5096.388433388017;5100.770465328995;5264.341732354296;5753;5764.711764411846;5768.567357448124;5793.406813126424;5811.883762324645;5817;5816.680082595498;5839.416058394127;5856.443637854397;5870.677112758668;5878.236529041185;5537.640053396516;5663.830085097454;5707.200991861226;5711.657300561979;5716.628419152742;5780.514378816981;5787.36339002701;5798.391168927371;5866.560007999503;5901.373888619695;5426.267453893832;5669.376368599534;5675;5737.799177028784;5751.9424805751405 08:32:10 INFO - PROCESS | 29499 | |5;yui.html;692.9688164032618;747.3946103656039;754.9207333230011;767.5624893810531;854.2525290370927;15546.53360399187;15600.765988510182;16193;17052.658946821073;17078.487645370642;13865.237411942338;14098.859011409882;14102.929485352584;14123.364448599812;14168;800.2437696376911;802.4823988527396;822.2065706593137;823.0206054794805;828.056016141596;822.8601137806558;824.5176571705559;830.0661755525034;830.8338332333558;831;859.9398042137053;863.7192912303495;868.2576397180414;870.1037930931151;870.959203751514;864.8962124545031;866.4757821517954;866.8959724833029;873.4279047224078;873.4802792338548;634.9936500635027;635.0062152730961;635.1837888313495;638.6359774928293;640.3116649601697;14291.85708142926;14518.274086295749;14651.340689668883;15026.398944042225;15143.469978550807;14845.851541484555;14861.182634955074;14885.627859303495;14899.851001490064;15054.096754194674;12124.272543647321;12141.878581214163;12199.207051541622;12208.511659533608;12359.814602780965;3817.312883680964;3889.8249578769023;3908.7263891527605;3927.273454410943;3998.9200215995515;81076;81683.59158204231;82163.5891820537;83537.58231208866;83957.16042839554;10897.291676041032;10933.343999360064;11087.113030957504;11203.775924481464;11221.046211071987;21175.68236476454;21245.68131478029;21249.57500850005;21252.149914003578;21332.466688332606;7322.670479828421;7946.960265198637;8017.879731804028;8022.638981245858;8051.5571643559;4408.162449134654;4523.050159466483;4604.8388306409115;4609.976950115228;4797.472278049412;3393.932121357559;3580.9104772380897;3587.51568538244;3593.317269718745;3596.478510615946;598.2581598817522;604.1059232336167;606.754264522869;614.5912967876402;632.0645444741716;582.0570675505624;595.693217992734;651.2543138106842;668.4552090046597;676.6216748130325;8419.579021049045;9355.766105847408;9449.811003779885;9485.241180705527;9677.06458708247;587.5387820560925;652.6116960408549;659.3538332434146;679.2935347238816;682.3926705232348;4354.194474022316;4405.581469760367;4627.8611641650805;4667.789949452283;4762.190427627312;69972.3002769976;79700.60149699214;106605.93394066115;109595.45202273938;123266;102957;103276;104152;108228.4588577052;108248.45875770571;89009;90187.54906225427;90188.5490572556;91303;91554.54222728843;8829.69096081634;8903.332250081141;9032.232260257952;9036;9091.409058411182;49706.00587988148;50989;51544.22683659823;51635.48364516307;53541.732291338296;75233;76686;79491.60254198693;79630.20369796228;82245;10890.549626334328;11159.832602511131;11364.374959377314;11421.257618254787;11668.241564298294;12335.629931102081;12466;12569.183003104768;12756.808647870475;13044.478220871059;12095.697607559881;12101.213421127599;12469.376531173586;12512.95543330975;12910;11285.887141128484;11474.483648235682;12128.514859405524;12213.877861221274;12255.75488490243;11217.831732523855;11222.775544489228;13262;13690.657733556342;14190.574282771533;11027.448627568428;11196.608118715805;11378.43107844621;11443.370614616277;11624.5350185995;10042.799144016933;10399.792004160026;10903;11573.88426115728;11603.245789023686 08:32:10 INFO - PROCESS | 29499 | __end_tp_report 08:32:10 INFO - PROCESS | 29499 | __start_cc_report 08:32:10 INFO - PROCESS | 29499 | _x_x_mozilla_cycle_collect,295 08:32:10 INFO - PROCESS | 29499 | __end_cc_report 08:32:10 INFO - PROCESS | 29499 | __startTimestamp1474126330533__endTimestamp 08:32:10 INFO - PROCESS | 29499 | 08:32:10 INFO - PROCESS | 29499 | ------- Summary: start ------- 08:32:10 INFO - PROCESS | 29499 | Number of tests: 6 08:32:10 INFO - PROCESS | 29499 | 08:32:10 INFO - PROCESS | 29499 | [#0] dojo.html Cycles:165 Average:5318.87 Median:5538.00 stddev:595.26 (10.7%) stddev-sans-first:569.79 08:32:10 INFO - PROCESS | 29499 | Values: 3047.3 3066.4 3084.9 3101.1 3106.0 5292.4 5298.9 5355.1 5372.6 5442.1 5336.5 5342.5 5361.4 5373.3 5395.6 4795.4 4867.1 4876.9 4883.8 4954.7 4916.2 4941.3 4945.2 4978.5 5015.9 4633.9 4665.8 4779.8 4853.3 4870.3 4839.6 4848.4 4922.2 4941.9 4960.4 4806.9 4860.8 4873.4 4880.1 4914.2 5175.8 5207.2 5331.3 5350.3 5424.3 5660.3 5668.2 5680.7 5699.8 5720.5 5791.6 5802.0 5804.3 5806.0 5814.8 5278.9 5347.4 5348.9 5386.2 5413.6 5777.3 5881.5 5889.9 5897.1 5959.3 5603.1 5667.4 5670.5 5670.6 5683.8 5513.6 5630.3 5634.8 5744.9 5754.6 5670.4 5674.1 5675.9 5696.9 5723.9 5671.9 5678.3 5684.4 5696.2 5704.5 4298.2 4360.3 4399.6 4404.7 4468.2 3954.5 3979.9 4020.2 4032.8 4048.2 5415.4 5417.3 5423.5 5445.0 5480.1 5510.8 5538.0 5715.1 5721.0 5724.1 5383.8 5386.3 5402.3 5457.3 5519.5 4918.7 4928.4 4965.8 4975.2 5018.1 5739.3 5881.6 5914.9 5928.1 5930.0 5658.8 5693.3 5710.6 5759.5 5813.4 5904.4 5908.1 5909.6 5912.8 5914.6 5398.0 5405.4 5663.4 5698.2 5709.3 5672.8 5701.5 5715.4 5720.3 5816.8 5573.8 5576.5 5578.1 5586.7 5586.7 5421.8 5599.4 5609.1 5617.3 5624.6 5398.7 5507.0 5580.3 5592.1 5653.7 5668.1 5696.8 5697.0 5699.1 5704.6 5543.4 5562.9 5588.2 5589.6 5596.5 08:32:10 INFO - PROCESS | 29499 | 08:32:10 INFO - PROCESS | 29499 | [#1] ext.html Cycles:180 Average:60790.65 Median:39909.70 stddev:131377.92 (329.2%) stddev-sans-first:131668.68 08:32:10 INFO - PROCESS | 29499 | Values: 577.1 578.3 584.4 587.9 593.2 41282.4 43224.4 43505.6 47335.5 47388.0 44595.0 44683.3 45052.8 45118.5 45171.5 8926.8 9062.8 9075.4 9075.8 9094.8 8819.4 8916.6 8918.2 8931.5 8936.0 8877.0 8900.5 8942.8 8953.6 8959.1 8871.6 8882.0 8900.3 8911.5 8916.4 8856.4 8865.6 8931.1 8939.7 8960.2 41405.0 41756.7 41808.4 41822.4 41853.8 3390.9 3410.8 3457.4 3458.7 3466.1 30970.4 31422.4 31500.8 31850.7 32693.8 4047.0 4071.7 4122.3 4174.2 4309.4 329913.0 330083.0 331034.0 331255.3 331381.0 34437.3 35434.8 54432.5 55147.0 55171.0 36616.5 36616.6 36832.6 36884.8 37248.0 14094.9 14147.1 14209.6 14281.8 14287.3 11622.9 11688.9 11715.9 11723.1 11753.0 8491.4 8492.7 8510.8 8526.0 8583.5 3348.7 3415.5 3438.8 3457.4 3475.8 986.1 989.7 1031.4 1057.3 1101.9 31038.0 31465.8 31487.0 31565.2 32544.5 1014.5 1039.2 1040.9 1134.8 1141.3 15515.0 16428.9 16532.9 16590.0 16658.0 532340.0 538978.0 544391.0 777911.0 780341.0 55196.0 55496.0 55887.4 56173.7 56240.4 1342.8 1346.3 1376.2 1385.2 1391.9 29819.7 31609.5 31708.8 31816.8 32294.8 47692.5 48118.3 48436.3 48519.3 48620.8 440329.0 442536.0 442841.0 443752.0 444707.0 27312.3 27741.6 27952.9 28093.7 28178.9 45579.5 45864.0 45909.8 46061.0 46795.3 38713.0 39215.6 39724.4 40931.2 41188.4 38993.0 39756.4 39801.8 39829.6 41211.2 39007.4 39364.6 39423.4 39631.8 39989.8 33953.5 34956.0 35009.6 35652.8 36212.5 27105.9 27112.5 27458.7 27560.2 27615.0 08:32:10 INFO - PROCESS | 29499 | 08:32:10 INFO - PROCESS | 29499 | [#2] jquery.html Cycles:180 Average:27250.32 Median:20664.35 stddev:74113.02 (358.7%) stddev-sans-first:74293.50 08:32:10 INFO - PROCESS | 29499 | Values: 396.8 401.3 405.1 406.3 428.7 15700.3 15971.4 18058.8 19721.1 19746.7 17696.1 18199.8 18610.7 18854.5 19093.1 5054.2 5090.2 5246.8 5400.7 5450.6 5348.2 5357.5 5426.7 5433.8 5434.2 5430.7 5617.7 5867.8 5885.1 5973.2 5780.5 5937.9 5953.3 5990.4 5990.6 5803.8 5830.7 5852.5 5873.4 5878.2 13135.5 13464.5 13645.5 13773.2 13873.9 2086.5 2156.4 2164.9 2257.6 2262.5 9914.6 10212.5 10499.5 10871.3 13841.9 1862.8 1916.8 1923.8 1957.5 2003.1 78743.0 78960.6 79501.6 80237.6 83411.0 10763.9 11111.6 11234.0 11269.3 11280.4 15198.6 15249.6 15419.8 15470.9 15477.1 7568.5 7689.7 7736.1 8049.9 8145.7 7284.4 7314.2 7462.6 7557.6 7581.7 4644.2 4749.5 4811.3 4823.7 4853.8 1977.5 1996.6 1998.4 2041.2 2050.4 866.9 896.7 897.8 962.9 995.6 17914.8 19422.7 19456.8 19708.0 19780.2 930.4 933.8 935.7 948.0 973.0 10426.0 10757.9 10823.0 10849.1 10911.2 443874.0 445794.0 448597.0 449446.0 449558.0 90523.0 92371.0 92801.0 93268.5 93555.5 982.1 1016.1 1046.6 1055.1 1057.7 16550.8 16935.0 17246.2 17758.6 17761.3 39983.8 40261.8 40391.2 40922.4 40983.4 56854.4 56972.6 58210.4 59185.0 59301.0 8120.8 8158.1 8349.5 8374.6 8578.7 10509.7 11448.4 12557.2 12792.2 12863.3 10217.3 10804.4 10944.1 11158.7 11167.3 9653.4 10393.5 10647.3 10730.9 10745.2 6236.7 7007.8 10657.5 11257.8 11459.5 8269.8 8401.5 8427.5 8452.8 8566.4 9669.8 10554.4 10575.7 10710.5 10851.1 08:32:10 INFO - PROCESS | 29499 | 08:32:10 INFO - PROCESS | 29499 | [#3] mootools.html Cycles:180 Average:6041.53 Median:4848.14 stddev:3468.14 (71.5%) stddev-sans-first:3460.82 08:32:10 INFO - PROCESS | 29499 | Values: 1466.3 1473.5 1637.0 1765.1 1770.9 8212.8 8531.1 9089.6 9571.6 9583.6 8897.0 9130.4 9187.3 9358.5 9437.3 2423.0 2456.4 2457.2 2459.8 2463.6 2345.4 2378.6 2422.9 2432.5 2433.9 2392.4 2409.6 2411.7 2418.2 2420.7 2350.5 2361.6 2361.9 2371.8 2406.4 2303.6 2313.0 2324.1 2324.8 2327.6 9254.2 9362.3 9373.7 9405.2 9463.9 5342.9 5450.5 5457.9 5475.2 5477.3 3302.8 3320.0 3341.7 3352.1 3380.1 1186.2 1193.3 1208.8 1231.0 2072.4 12497.4 12567.2 12645.4 12670.9 12700.7 4506.4 4731.2 4741.7 4833.1 4863.2 10201.2 10206.7 10211.8 10220.3 10226.2 5488.6 5518.1 5549.3 5552.7 5554.4 4934.4 5078.6 5108.0 5128.5 5169.1 3840.5 3847.2 3931.6 3933.3 3999.2 1210.8 1212.4 1213.0 1216.1 1223.4 787.4 939.5 946.5 982.4 1009.5 5923.9 6145.3 6250.5 6325.7 6424.9 925.9 946.5 946.9 999.4 1029.8 4040.4 4056.9 4129.7 4216.9 4269.5 11475.9 11700.4 11725.8 11807.9 11851.6 11722.5 11744.3 11748.4 11769.5 11778.8 8072.3 8490.3 8563.4 8665.5 8810.5 5867.9 6074.4 6101.2 6137.8 6395.4 10674.3 10748.6 10844.9 10848.9 10857.7 10653.2 10686.4 10717.2 10828.1 10855.5 7302.1 7603.5 7833.2 8113.1 8200.7 8590.3 8956.0 8962.4 8980.4 8992.7 7896.6 7967.3 8009.9 8191.3 8222.0 7712.3 7929.8 8005.1 8053.3 8102.5 7268.5 7467.9 7484.7 7528.1 7531.3 5279.9 5352.1 5367.5 5464.5 5488.3 5314.6 5386.1 5409.9 5422.9 5501.3 08:32:10 INFO - PROCESS | 29499 | 08:32:10 INFO - PROCESS | 29499 | [#4] prototype.html Cycles:180 Average:4610.31 Median:5119.64 stddev:1334.23 (26.1%) stddev-sans-first:1298.54 08:32:10 INFO - PROCESS | 29499 | Values: 320.8 330.9 338.8 345.8 349.3 5310.2 5447.4 5460.2 5540.0 5558.1 5245.2 5352.9 5429.7 5430.2 5432.4 4243.8 4269.6 4271.2 4302.1 4326.0 4172.3 4257.4 4273.2 4311.8 4315.4 4242.9 4244.7 4252.9 4259.2 4263.4 4234.6 4235.7 4235.8 4246.3 4260.0 3581.8 3667.7 3677.2 3691.4 3755.2 5319.5 5336.2 5345.4 5370.5 5389.4 4810.8 4832.2 4836.9 4840.3 4870.5 5471.2 5647.3 5697.7 5707.3 5711.4 4086.9 4095.5 4109.1 4117.8 4125.8 5894.3 5939.5 5970.1 5973.1 5995.4 4092.9 4172.0 4173.2 4176.6 4206.5 4208.9 4248.1 4258.8 4261.9 4267.6 4851.6 4868.4 4870.4 4878.2 4930.6 5622.5 5695.4 5707.9 5767.3 5822.0 1423.8 1437.9 1465.3 1470.6 1482.1 897.5 922.4 927.4 934.2 948.0 4807.7 4829.9 4846.6 4850.2 4903.5 5024.4 5100.5 5141.7 5158.8 5197.9 4648.1 4872.4 4876.8 4888.5 4906.5 2734.5 2745.7 2756.7 2758.8 2777.7 5171.2 5212.9 5299.7 5310.8 5330.2 4867.9 5234.6 5256.6 5279.8 5340.3 5279.5 5314.3 5354.3 5365.4 5388.9 5117.3 5221.0 5233.7 5275.7 5276.8 4702.5 4926.6 5053.5 5086.6 5121.9 5263.9 5333.8 5335.2 5342.1 5370.5 3850.1 3873.7 3882.6 3900.7 3907.1 4815.7 5096.4 5100.8 5264.3 5753.0 5764.7 5768.6 5793.4 5811.9 5817.0 5816.7 5839.4 5856.4 5870.7 5878.2 5537.6 5663.8 5707.2 5711.7 5716.6 5780.5 5787.4 5798.4 5866.6 5901.4 5426.3 5669.4 5675.0 5737.8 5751.9 08:32:10 INFO - PROCESS | 29499 | 08:32:10 INFO - PROCESS | 29499 | [#5] yui.html Cycles:180 Average:20649.94 Median:4465.61 stddev:30119.55 (674.5%) stddev-sans-first:30166.77 08:32:10 INFO - PROCESS | 29499 | Values: 693.0 747.4 754.9 767.6 854.3 15546.5 15600.8 16193.0 17052.7 17078.5 13865.2 14098.9 14102.9 14123.4 14168.0 800.2 802.5 822.2 823.0 828.1 822.9 824.5 830.1 830.8 831.0 859.9 863.7 868.3 870.1 871.0 864.9 866.5 866.9 873.4 873.5 635.0 635.0 635.2 638.6 640.3 14291.9 14518.3 14651.3 15026.4 15143.5 14845.9 14861.2 14885.6 14899.9 15054.1 12124.3 12141.9 12199.2 12208.5 12359.8 3817.3 3889.8 3908.7 3927.3 3998.9 81076.0 81683.6 82163.6 83537.6 83957.2 10897.3 10933.3 11087.1 11203.8 11221.0 21175.7 21245.7 21249.6 21252.1 21332.5 7322.7 7947.0 8017.9 8022.6 8051.6 4408.2 4523.1 4604.8 4610.0 4797.5 3393.9 3580.9 3587.5 3593.3 3596.5 598.3 604.1 606.8 614.6 632.1 582.1 595.7 651.3 668.5 676.6 8419.6 9355.8 9449.8 9485.2 9677.1 587.5 652.6 659.4 679.3 682.4 4354.2 4405.6 4627.9 4667.8 4762.2 69972.3 79700.6 106605.9 109595.5 123266.0 102957.0 103276.0 104152.0 108228.5 108248.5 89009.0 90187.5 90188.5 91303.0 91554.5 8829.7 8903.3 9032.2 9036.0 9091.4 49706.0 50989.0 51544.2 51635.5 53541.7 75233.0 76686.0 79491.6 79630.2 82245.0 10890.5 11159.8 11364.4 11421.3 11668.2 12335.6 12466.0 12569.2 12756.8 13044.5 12095.7 12101.2 12469.4 12513.0 12910.0 11285.9 11474.5 12128.5 12213.9 12255.8 11217.8 11222.8 13262.0 13690.7 14190.6 11027.4 11196.6 11378.4 11443.4 11624.5 10042.8 10399.8 10903.0 11573.9 11603.2 08:32:10 INFO - PROCESS | 29499 | -------- Summary: end -------- 08:32:10 INFO - PROCESS | 29499 | 08:32:11 INFO - TEST-INFO | 29499: exit 0 08:32:11 INFO - TEST-OK | dromaeo_css | took 1114692ms 08:32:11 INFO - TEST-START | kraken 08:32:11 INFO - operating with platform_type : linux_ 08:32:11 INFO - Initialising browser for kraken test... 08:32:11 INFO - TEST-INFO | started process 29871 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLA9QlN/profile http://localhost:41589/getInfo.html) 08:32:16 INFO - PROCESS | 29871 | __metrics Screen width/height:1600/1200 08:32:16 INFO - PROCESS | 29871 | colorDepth:24 08:32:16 INFO - PROCESS | 29871 | Browser inner width/height: 1024/697 08:32:16 INFO - PROCESS | 29871 | __metrics 08:32:16 ERROR - PROCESS | 29871 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3016: TypeError: this.tabs is undefined 08:32:17 INFO - TEST-INFO | 29871: exit 0 08:32:17 INFO - Browser initialized. 08:32:17 INFO - Running cycle 1/1 for kraken test... 08:32:17 INFO - TEST-INFO | started process 29990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLA9QlN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:32:17 INFO - PROCESS | 29990 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 08:32:17 INFO - Terminating psutil.Process(pid=29990, name='firefox') 08:32:17 INFO - TEST-INFO | 29990: exit 127 08:32:17 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 08:32:17 ERROR - Traceback (most recent call last): 08:32:17 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 08:32:17 INFO - talos_results.add(mytest.runTest(browser_config, test)) 08:32:17 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 08:32:17 INFO - return self._runTest(browser_config, test_config, setup) 08:32:17 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 232, in _runTest 08:32:17 INFO - else None) 08:32:17 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 08:32:17 INFO - global_counters=self.global_counters 08:32:17 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 08:32:17 INFO - self.parse() 08:32:17 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 08:32:17 INFO - % self.report_tokens) 08:32:17 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 08:32:17 INFO - raise utils.TalosError(message) 08:32:17 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 08:32:17 INFO - TEST-INFO took 6316ms 08:32:17 INFO - SUITE-END | took 1121s 08:32:17 ERROR - Return code: 2 08:32:17 WARNING - setting return code to 2 08:32:17 ERROR - # TBPL FAILURE # 08:32:17 INFO - Running post-action listener: _resource_record_post_action 08:32:17 INFO - [mozharness: 2016-09-17 15:32:17.874090Z] Finished run-tests step (success) 08:32:17 INFO - Running post-run listener: _resource_record_post_run 08:32:18 INFO - Total resource usage - Wall time: 1138s; CPU: 13.0%; Read bytes: 0; Write bytes: 361345024; Read time: 0; Write time: 321656 08:32:18 INFO - TinderboxPrint: CPU usage
13.5% 08:32:18 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:32:18 INFO - TinderboxPrint: I/O write bytes / time
361,345,024 / 321,656 08:32:18 INFO - TinderboxPrint: CPU idle
7,851.9 (86.5%) 08:32:18 INFO - TinderboxPrint: CPU user
1,172.7 (12.9%) 08:32:18 INFO - TinderboxPrint: Swap in / out
0 / 0 08:32:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 121356288; Read time: 0; Write time: 116364 08:32:18 INFO - run-tests - Wall time: 1121s; CPU: 13.0%; Read bytes: 0; Write bytes: 239640576; Read time: 0; Write time: 205240 08:32:18 INFO - Running post-run listener: _upload_blobber_files 08:32:18 INFO - Blob upload gear active. 08:32:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:32:18 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 08:32:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:32:19 INFO - (blobuploader) - INFO - Open directory for files ... 08:32:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:32:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:32:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:19 INFO - (blobuploader) - INFO - Done attempting. 08:32:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:32:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:32:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:21 INFO - (blobuploader) - INFO - Done attempting. 08:32:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:32:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:32:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:25 INFO - (blobuploader) - INFO - Done attempting. 08:32:25 INFO - (blobuploader) - INFO - Iteration through files over. 08:32:25 INFO - Return code: 0 08:32:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:32:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:32:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b18bd9d485f558f25f6626ade2e12e0e38ee0d35accdfbc385cea4353ac96496cd4d5586dc2cbd11dce6a0f63045b92fc9401747b80776a91f9cffe3a5b2dae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7296fa4d1c1eb319c4a4794bd922455aacf6a96d44f810c87030642802d98764dce3607a761b8c71c9686c545a4c76bd6ed65fd671a16b7f921c2e3908dba39a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24edad58f739cea9ca49ca38e9b468c7e34efb04c18edd66748b6cc74b416ee8dc87408d484c1c17a29e338808614f0a1ad67d1dc10b3497ca1023debdb9b414"} 08:32:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:32:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:32:25 INFO - Contents: 08:32:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b18bd9d485f558f25f6626ade2e12e0e38ee0d35accdfbc385cea4353ac96496cd4d5586dc2cbd11dce6a0f63045b92fc9401747b80776a91f9cffe3a5b2dae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7296fa4d1c1eb319c4a4794bd922455aacf6a96d44f810c87030642802d98764dce3607a761b8c71c9686c545a4c76bd6ed65fd671a16b7f921c2e3908dba39a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24edad58f739cea9ca49ca38e9b468c7e34efb04c18edd66748b6cc74b416ee8dc87408d484c1c17a29e338808614f0a1ad67d1dc10b3497ca1023debdb9b414"} 08:32:25 INFO - Running post-run listener: copy_logs_to_upload_dir 08:32:25 INFO - Copying logs to upload dir... 08:32:25 INFO - mkdir: /builds/slave/test/build/upload/logs 08:32:25 INFO - Copying logs to upload dir... 08:32:25 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1179.814849 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 39 secs) (at 2016-09-17 08:32:24.639484) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:24.643332) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617 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/Firefox/sha512/b18bd9d485f558f25f6626ade2e12e0e38ee0d35accdfbc385cea4353ac96496cd4d5586dc2cbd11dce6a0f63045b92fc9401747b80776a91f9cffe3a5b2dae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7296fa4d1c1eb319c4a4794bd922455aacf6a96d44f810c87030642802d98764dce3607a761b8c71c9686c545a4c76bd6ed65fd671a16b7f921c2e3908dba39a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24edad58f739cea9ca49ca38e9b468c7e34efb04c18edd66748b6cc74b416ee8dc87408d484c1c17a29e338808614f0a1ad67d1dc10b3497ca1023debdb9b414"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011409 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b18bd9d485f558f25f6626ade2e12e0e38ee0d35accdfbc385cea4353ac96496cd4d5586dc2cbd11dce6a0f63045b92fc9401747b80776a91f9cffe3a5b2dae0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7296fa4d1c1eb319c4a4794bd922455aacf6a96d44f810c87030642802d98764dce3607a761b8c71c9686c545a4c76bd6ed65fd671a16b7f921c2e3908dba39a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24edad58f739cea9ca49ca38e9b468c7e34efb04c18edd66748b6cc74b416ee8dc87408d484c1c17a29e338808614f0a1ad67d1dc10b3497ca1023debdb9b414"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:24.674136) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:24.674681) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474125163.948749-260812617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004589 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:32:24.759056) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:32:24.759423) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:32:24.759798) ========= ========= Total master_lag: 0.19 =========