builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-015 starttime: 1479406831.43 results: retry (5) buildid: 20161117073328 builduid: 3252d071457d4ca59e43e1ba427b0e8a revision: bcafa3ea224b818b4ffb165da4fa7a51f3d80280 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:31.428726) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:31.429647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:31.430296) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:31.446243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:31.446647) ========= 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=20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-17 10:20:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 63.3M=0s 2016-11-17 10:20:31 (63.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111443 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:31.572347) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:31.572877) ========= 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=20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018975 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:31.623470) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:31.623920) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bcafa3ea224b818b4ffb165da4fa7a51f3d80280 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bcafa3ea224b818b4ffb165da4fa7a51f3d80280 --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=20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-17 10:20:31,744 truncating revision to first 12 chars 2016-11-17 10:20:31,744 Setting DEBUG logging. 2016-11-17 10:20:31,744 attempt 1/10 2016-11-17 10:20:31,744 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bcafa3ea224b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-17 10:20:32,057 unpacking tar archive at: mozilla-beta-bcafa3ea224b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.657094 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:32.295070) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:32.295441) ========= script_repo_revision: bcafa3ea224b818b4ffb165da4fa7a51f3d80280 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:32.295903) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:32.296227) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 10:20:32.306015) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 32 secs) (at 2016-11-17 10:20:32.306414) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:20:32 INFO - MultiFileLogger online at 20161117 10:20:32 in /builds/slave/test 10:20:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:20:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:20:32 INFO - {'append_to_log': False, 10:20:32 INFO - 'base_work_dir': '/builds/slave/test', 10:20:32 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:20:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:20:32 INFO - 'branch': 'Mozilla-Beta', 10:20:32 INFO - 'buildbot_json_path': 'buildprops.json', 10:20:32 INFO - 'config_files': ('talos/linux_config.py',), 10:20:32 INFO - 'default_actions': ('clobber', 10:20:32 INFO - 'read-buildbot-config', 10:20:32 INFO - 'download-and-extract', 10:20:32 INFO - 'populate-webroot', 10:20:32 INFO - 'create-virtualenv', 10:20:32 INFO - 'install', 10:20:32 INFO - 'run-tests'), 10:20:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:20:32 INFO - 'download_minidump_stackwalk': True, 10:20:32 INFO - 'download_symbols': 'ondemand', 10:20:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:20:32 INFO - 'tooltool.py': '/tools/tooltool.py', 10:20:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:20:32 INFO - '/tools/misc-python/virtualenv.py')}, 10:20:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:20:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:20:32 INFO - 'installer_path': 'installer.exe', 10:20:32 INFO - 'log_level': 'info', 10:20:32 INFO - 'log_name': 'talos', 10:20:32 INFO - 'log_to_console': True, 10:20:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:20:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:20:32 INFO - 'opt_config_files': (), 10:20:32 INFO - 'pip_index': False, 10:20:32 INFO - 'sps_profile': False, 10:20:32 INFO - 'sps_profile_interval': 0, 10:20:32 INFO - 'suite': 'dromaeojs-e10s', 10:20:32 INFO - 'system_bits': '32', 10:20:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:20:32 INFO - 'title': 'talos-linux64-ix-015', 10:20:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:20:32 INFO - 'use_talos_json': True, 10:20:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:20:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:20:32 INFO - 'work_dir': 'build'} 10:20:32 INFO - [mozharness: 2016-11-17 18:20:32.520064Z] Running clobber step. 10:20:32 INFO - Running pre-action listener: _resource_record_pre_action 10:20:32 INFO - Running main action method: clobber 10:20:32 INFO - rmtree: /builds/slave/test/build 10:20:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:20:32 INFO - Running post-action listener: _resource_record_post_action 10:20:32 INFO - [mozharness: 2016-11-17 18:20:32.979277Z] Finished clobber step (success) 10:20:32 INFO - [mozharness: 2016-11-17 18:20:32.979376Z] Running read-buildbot-config step. 10:20:32 INFO - Running pre-action listener: _resource_record_pre_action 10:20:32 INFO - Running main action method: read_buildbot_config 10:20:32 INFO - Using buildbot properties: 10:20:32 INFO - { 10:20:32 INFO - "project": "", 10:20:32 INFO - "product": "firefox", 10:20:32 INFO - "script_repo_revision": "production", 10:20:32 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 10:20:32 INFO - "repository": "", 10:20:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 10:20:32 INFO - "buildid": "20161117073328", 10:20:32 INFO - "pgo_build": "True", 10:20:32 INFO - "basedir": "/builds/slave/test", 10:20:32 INFO - "buildnumber": 221, 10:20:32 INFO - "slavename": "talos-linux64-ix-015", 10:20:32 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:20:32 INFO - "platform": "ubuntu64_hw", 10:20:32 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:20:32 INFO - "branch": "mozilla-beta", 10:20:32 INFO - "repo_path": "releases/mozilla-beta", 10:20:32 INFO - "revision": "bcafa3ea224b818b4ffb165da4fa7a51f3d80280", 10:20:32 INFO - "stage_platform": "linux64-pgo", 10:20:32 INFO - "builduid": "3252d071457d4ca59e43e1ba427b0e8a", 10:20:32 INFO - "slavebuilddir": "test" 10:20:32 INFO - } 10:20:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 10:20:32 INFO - Running post-action listener: _resource_record_post_action 10:20:32 INFO - [mozharness: 2016-11-17 18:20:32.981788Z] Finished read-buildbot-config step (success) 10:20:32 INFO - [mozharness: 2016-11-17 18:20:32.981877Z] Running download-and-extract step. 10:20:32 INFO - Running pre-action listener: _resource_record_pre_action 10:20:32 INFO - Running main action method: download_and_extract 10:20:32 INFO - mkdir: /builds/slave/test/build/tests 10:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:32 INFO - trying https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 10:20:32 INFO - Downloading https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 10:20:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:20:33 INFO - Downloaded 1327 bytes. 10:20:33 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 10:20:33 INFO - Using the following test package requirements: 10:20:33 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 10:20:33 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 10:20:33 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:20:33 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 10:20:33 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 10:20:33 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 10:20:33 INFO - u'jsshell-linux-x86_64.zip'], 10:20:33 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 10:20:33 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:20:33 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 10:20:33 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 10:20:33 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 10:20:33 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 10:20:33 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 10:20:33 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 10:20:33 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:20:33 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 10:20:33 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:20:33 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:20:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 10:20:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:20:33 INFO - Fetch https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 10:20:34 INFO - Expected file size: 21962123 10:20:38 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:20:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 10:20:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:20:38 INFO - Fetch https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 10:20:38 INFO - Expected file size: 21962123 10:20:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 10:20:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:20:43 INFO - Fetch https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 10:20:44 INFO - Expected file size: 14414089 10:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:44 INFO - trying https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 10:20:44 INFO - Downloading https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 10:20:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:20:45 INFO - Downloaded 56959953 bytes. 10:20:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 10:20:45 INFO - mkdir: /builds/slave/test/properties 10:20:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:20:45 INFO - Writing to file /builds/slave/test/properties/build_url 10:20:45 INFO - Contents: 10:20:45 INFO - build_url:https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 10:20:46 INFO - Running post-action listener: _resource_record_post_action 10:20:46 INFO - Running post-action listener: set_extra_try_arguments 10:20:46 INFO - [mozharness: 2016-11-17 18:20:46.495715Z] Finished download-and-extract step (success) 10:20:46 INFO - [mozharness: 2016-11-17 18:20:46.495942Z] Running populate-webroot step. 10:20:46 INFO - Running pre-action listener: _resource_record_pre_action 10:20:46 INFO - Running main action method: populate_webroot 10:20:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:20:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:20:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:20:46 INFO - '--tppagecycles', 10:20:46 INFO - '7'], 10:20:46 INFO - 'tests': ['tsvgm']}}, 10:20:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:20:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:20:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:20:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:20:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:46 INFO - 'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:20:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:20:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:46 INFO - 'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['damp', 'tps']}, 10:20:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:46 INFO - 'tests': ['damp', 'tps']}, 10:20:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['dromaeo_dom']}, 10:20:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:20:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['basic_compositor_video']}, 10:20:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:20:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['a11yr', 10:20:46 INFO - 'ts_paint', 10:20:46 INFO - 'tpaint', 10:20:46 INFO - 'sessionrestore', 10:20:46 INFO - 'sessionrestore_no_auto_restore', 10:20:46 INFO - 'tabpaint']}, 10:20:46 INFO - 'other-e10s': {'tests': ['a11yr', 10:20:46 INFO - 'ts_paint', 10:20:46 INFO - 'tpaint', 10:20:46 INFO - 'sessionrestore', 10:20:46 INFO - 'sessionrestore_no_auto_restore', 10:20:46 INFO - 'tabpaint']}, 10:20:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:20:46 INFO - 'ts_paint', 10:20:46 INFO - 'tpaint', 10:20:46 INFO - 'sessionrestore', 10:20:46 INFO - 'sessionrestore_no_auto_restore', 10:20:46 INFO - 'tabpaint']}, 10:20:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:20:46 INFO - 'ts_paint', 10:20:46 INFO - 'tpaint', 10:20:46 INFO - 'sessionrestore', 10:20:46 INFO - 'sessionrestore_no_auto_restore', 10:20:46 INFO - 'tabpaint']}, 10:20:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['a11yr', 10:20:46 INFO - 'ts_paint', 10:20:46 INFO - 'tpaint', 10:20:46 INFO - 'sessionrestore', 10:20:46 INFO - 'sessionrestore_no_auto_restore', 10:20:46 INFO - 'tabpaint']}, 10:20:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['a11yr', 10:20:46 INFO - 'ts_paint', 10:20:46 INFO - 'tpaint', 10:20:46 INFO - 'sessionrestore', 10:20:46 INFO - 'sessionrestore_no_auto_restore', 10:20:46 INFO - 'tabpaint']}, 10:20:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['tsvgx', 10:20:46 INFO - 'tsvgr_opacity', 10:20:46 INFO - 'tart', 10:20:46 INFO - 'tscrollx', 10:20:46 INFO - 'cart']}, 10:20:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:20:46 INFO - 'tsvgr_opacity', 10:20:46 INFO - 'tart', 10:20:46 INFO - 'tscrollx', 10:20:46 INFO - 'cart']}, 10:20:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:46 INFO - 'talos_options': ['--disable-e10s'], 10:20:46 INFO - 'tests': ['tp5o']}, 10:20:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:46 INFO - 'tests': ['tp5o']}, 10:20:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:46 INFO - 'talos_options': ['--disable-e10s', 10:20:46 INFO - '--xperf_path', 10:20:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:46 INFO - 'tests': ['tp5n']}, 10:20:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:46 INFO - 'talos_options': ['--xperf_path', 10:20:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:46 INFO - 'tests': ['tp5n']}}, 10:20:46 INFO - 'talos.zip': {'path': '', 10:20:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:20:46 INFO - Running post-action listener: _resource_record_post_action 10:20:46 INFO - [mozharness: 2016-11-17 18:20:46.518236Z] Finished populate-webroot step (success) 10:20:46 INFO - [mozharness: 2016-11-17 18:20:46.518340Z] Running create-virtualenv step. 10:20:46 INFO - Running pre-action listener: _resource_record_pre_action 10:20:46 INFO - Running main action method: create_virtualenv 10:20:46 INFO - Creating virtualenv /builds/slave/test/build/venv 10:20:46 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 10:20:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:20:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:20:46 INFO - Using real prefix '/usr' 10:20:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:20:47 INFO - Installing distribute.............................................................................................................................................................................................done. 10:20:49 INFO - Installing pip.................done. 10:20:49 INFO - Return code: 0 10:20:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:49 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 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:20:49 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 10:20:49 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 10:20:49 INFO - Using env: {'DISPLAY': ':0', 10:20:49 INFO - 'HOME': '/home/cltbld', 10:20:49 INFO - 'LANG': 'en_US.UTF-8', 10:20:49 INFO - 'LANGUAGE': 'en_US:en', 10:20:49 INFO - 'LOGNAME': 'cltbld', 10:20:49 INFO - 'MAIL': '/var/mail/cltbld', 10:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:49 INFO - 'MOZ_NO_REMOTE': '1', 10:20:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:49 INFO - 'NO_EM_RESTART': '1', 10:20:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:49 INFO - 'PWD': '/builds/slave/test', 10:20:49 INFO - 'SHELL': '/bin/bash', 10:20:49 INFO - 'SHLVL': '1', 10:20:49 INFO - 'TERM': 'linux', 10:20:49 INFO - 'TMOUT': '86400', 10:20:49 INFO - 'USER': 'cltbld', 10:20:49 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:49 INFO - '_': '/tools/buildbot/bin/python'} 10:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:49 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 10:20:49 INFO - Cleaning up... 10:20:49 INFO - Return code: 0 10:20:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:49 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 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:20:49 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 10:20:49 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 10:20:49 INFO - Using env: {'DISPLAY': ':0', 10:20:49 INFO - 'HOME': '/home/cltbld', 10:20:49 INFO - 'LANG': 'en_US.UTF-8', 10:20:49 INFO - 'LANGUAGE': 'en_US:en', 10:20:49 INFO - 'LOGNAME': 'cltbld', 10:20:49 INFO - 'MAIL': '/var/mail/cltbld', 10:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:49 INFO - 'MOZ_NO_REMOTE': '1', 10:20:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:49 INFO - 'NO_EM_RESTART': '1', 10:20:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:49 INFO - 'PWD': '/builds/slave/test', 10:20:49 INFO - 'SHELL': '/bin/bash', 10:20:49 INFO - 'SHLVL': '1', 10:20:49 INFO - 'TERM': 'linux', 10:20:49 INFO - 'TMOUT': '86400', 10:20:49 INFO - 'USER': 'cltbld', 10:20:49 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:49 INFO - '_': '/tools/buildbot/bin/python'} 10:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:49 INFO - Downloading/unpacking psutil>=3.1.1 10:20:49 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 10:20:49 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 10:20:49 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 10:20:49 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 10:20:49 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 10:20:49 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 10:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:20:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:20:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:20:51 INFO - Installing collected packages: psutil 10:20:51 INFO - Running setup.py install for psutil 10:20:51 INFO - building 'psutil._psutil_linux' extension 10:20:51 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 10:20:51 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 10:20:51 INFO - building 'psutil._psutil_posix' extension 10:20:51 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 10:20:51 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 10:20:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:20:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:20:52 INFO - Successfully installed psutil 10:20:52 INFO - Cleaning up... 10:20:52 INFO - Return code: 0 10:20:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:20:52 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')]} 10:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:52 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')]} 10:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:52 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 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:20:52 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 10:20:52 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 10:20:52 INFO - Using env: {'DISPLAY': ':0', 10:20:52 INFO - 'HOME': '/home/cltbld', 10:20:52 INFO - 'LANG': 'en_US.UTF-8', 10:20:52 INFO - 'LANGUAGE': 'en_US:en', 10:20:52 INFO - 'LOGNAME': 'cltbld', 10:20:52 INFO - 'MAIL': '/var/mail/cltbld', 10:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:52 INFO - 'MOZ_NO_REMOTE': '1', 10:20:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:52 INFO - 'NO_EM_RESTART': '1', 10:20:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:52 INFO - 'PWD': '/builds/slave/test', 10:20:52 INFO - 'SHELL': '/bin/bash', 10:20:52 INFO - 'SHLVL': '1', 10:20:52 INFO - 'TERM': 'linux', 10:20:52 INFO - 'TMOUT': '86400', 10:20:52 INFO - 'USER': 'cltbld', 10:20:52 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:52 INFO - '_': '/tools/buildbot/bin/python'} 10:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:20:52 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 10:20:52 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 10:20:52 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 10:20:52 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 10:20:52 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 10:20:52 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 10:20:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:20:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:20:54 INFO - Installing collected packages: mozsystemmonitor 10:20:54 INFO - Running setup.py install for mozsystemmonitor 10:20:54 INFO - Successfully installed mozsystemmonitor 10:20:54 INFO - Cleaning up... 10:20:54 INFO - Return code: 0 10:20:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:20:54 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')]} 10:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:54 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')]} 10:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:54 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 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:20:54 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 10:20:54 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 10:20:54 INFO - Using env: {'DISPLAY': ':0', 10:20:54 INFO - 'HOME': '/home/cltbld', 10:20:54 INFO - 'LANG': 'en_US.UTF-8', 10:20:54 INFO - 'LANGUAGE': 'en_US:en', 10:20:54 INFO - 'LOGNAME': 'cltbld', 10:20:54 INFO - 'MAIL': '/var/mail/cltbld', 10:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:54 INFO - 'MOZ_NO_REMOTE': '1', 10:20:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:54 INFO - 'NO_EM_RESTART': '1', 10:20:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:54 INFO - 'PWD': '/builds/slave/test', 10:20:54 INFO - 'SHELL': '/bin/bash', 10:20:54 INFO - 'SHLVL': '1', 10:20:54 INFO - 'TERM': 'linux', 10:20:54 INFO - 'TMOUT': '86400', 10:20:54 INFO - 'USER': 'cltbld', 10:20:54 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:54 INFO - '_': '/tools/buildbot/bin/python'} 10:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:54 INFO - Downloading/unpacking jsonschema==2.5.1 10:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:20:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:20:56 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 10:20:56 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 10:20:56 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 10:20:56 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 10:20:56 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 10:20:56 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 10:20:56 INFO - Downloading functools32-3.2.3-2.tar.gz 10:20:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:20:56 INFO - warning: no files found matching '*.txt' 10:20:56 INFO - no previously-included directories found matching 'build' 10:20:56 INFO - no previously-included directories found matching 'dist' 10:20:56 INFO - no previously-included directories found matching '.git*' 10:20:56 INFO - Installing collected packages: jsonschema, functools32 10:20:56 INFO - Running setup.py install for functools32 10:20:56 INFO - warning: no files found matching '*.txt' 10:20:56 INFO - no previously-included directories found matching 'build' 10:20:56 INFO - no previously-included directories found matching 'dist' 10:20:56 INFO - no previously-included directories found matching '.git*' 10:20:57 INFO - Successfully installed jsonschema functools32 10:20:57 INFO - Cleaning up... 10:20:57 INFO - Return code: 0 10:20:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:57 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 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:20:57 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 10:20:57 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 10:20:57 INFO - Using env: {'DISPLAY': ':0', 10:20:57 INFO - 'HOME': '/home/cltbld', 10:20:57 INFO - 'LANG': 'en_US.UTF-8', 10:20:57 INFO - 'LANGUAGE': 'en_US:en', 10:20:57 INFO - 'LOGNAME': 'cltbld', 10:20:57 INFO - 'MAIL': '/var/mail/cltbld', 10:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:57 INFO - 'MOZ_NO_REMOTE': '1', 10:20:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:57 INFO - 'NO_EM_RESTART': '1', 10:20:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:57 INFO - 'PWD': '/builds/slave/test', 10:20:57 INFO - 'SHELL': '/bin/bash', 10:20:57 INFO - 'SHLVL': '1', 10:20:57 INFO - 'TERM': 'linux', 10:20:57 INFO - 'TMOUT': '86400', 10:20:57 INFO - 'USER': 'cltbld', 10:20:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:57 INFO - '_': '/tools/buildbot/bin/python'} 10:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:20:57 INFO - Cleaning up... 10:20:57 INFO - Return code: 0 10:20:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:57 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 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:20:57 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 10:20:57 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 10:20:57 INFO - Using env: {'DISPLAY': ':0', 10:20:57 INFO - 'HOME': '/home/cltbld', 10:20:57 INFO - 'LANG': 'en_US.UTF-8', 10:20:57 INFO - 'LANGUAGE': 'en_US:en', 10:20:57 INFO - 'LOGNAME': 'cltbld', 10:20:57 INFO - 'MAIL': '/var/mail/cltbld', 10:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:57 INFO - 'MOZ_NO_REMOTE': '1', 10:20:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:57 INFO - 'NO_EM_RESTART': '1', 10:20:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:57 INFO - 'PWD': '/builds/slave/test', 10:20:57 INFO - 'SHELL': '/bin/bash', 10:20:57 INFO - 'SHLVL': '1', 10:20:57 INFO - 'TERM': 'linux', 10:20:57 INFO - 'TMOUT': '86400', 10:20:57 INFO - 'USER': 'cltbld', 10:20:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:57 INFO - '_': '/tools/buildbot/bin/python'} 10:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:57 INFO - Downloading/unpacking blobuploader==1.2.4 10:20:57 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 10:20:57 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 10:20:57 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 10:20:57 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 10:20:57 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 10:20:57 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 10:20:59 INFO - Downloading blobuploader-1.2.4.tar.gz 10:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:20:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:20:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:00 INFO - Downloading docopt-0.6.1.tar.gz 10:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:21:00 INFO - Installing collected packages: blobuploader, requests, docopt 10:21:00 INFO - Running setup.py install for blobuploader 10:21:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:21:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:21:00 INFO - Running setup.py install for requests 10:21:00 INFO - Running setup.py install for docopt 10:21:00 INFO - Successfully installed blobuploader requests docopt 10:21:00 INFO - Cleaning up... 10:21:00 INFO - Return code: 0 10:21:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21: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')]} 10:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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')]} 10:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:00 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 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:21:00 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 10:21:00 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 10:21:00 INFO - Using env: {'DISPLAY': ':0', 10:21:00 INFO - 'HOME': '/home/cltbld', 10:21:00 INFO - 'LANG': 'en_US.UTF-8', 10:21:00 INFO - 'LANGUAGE': 'en_US:en', 10:21:00 INFO - 'LOGNAME': 'cltbld', 10:21:00 INFO - 'MAIL': '/var/mail/cltbld', 10:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:00 INFO - 'MOZ_NO_REMOTE': '1', 10:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:00 INFO - 'NO_EM_RESTART': '1', 10:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:00 INFO - 'PWD': '/builds/slave/test', 10:21:00 INFO - 'SHELL': '/bin/bash', 10:21:00 INFO - 'SHLVL': '1', 10:21:00 INFO - 'TERM': 'linux', 10:21:00 INFO - 'TMOUT': '86400', 10:21:00 INFO - 'USER': 'cltbld', 10:21:00 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:00 INFO - '_': '/tools/buildbot/bin/python'} 10:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:21:01 INFO - Running setup.py (path:/tmp/pip-JeoFe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:21:01 INFO - Running setup.py (path:/tmp/pip-WZNZ4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:21:01 INFO - Running setup.py (path:/tmp/pip-Uvx7Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:21:01 INFO - Running setup.py (path:/tmp/pip-EQxZL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:21:01 INFO - Running setup.py (path:/tmp/pip-WQUEUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:21:01 INFO - Running setup.py (path:/tmp/pip-cUEKCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:21:01 INFO - Running setup.py (path:/tmp/pip-rDc7qO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:21:01 INFO - Running setup.py (path:/tmp/pip-rM5jIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:21:01 INFO - Running setup.py (path:/tmp/pip-v1XJx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:21:01 INFO - Running setup.py (path:/tmp/pip-fqd6Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:21:01 INFO - Running setup.py (path:/tmp/pip-fl8JyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:21:01 INFO - Running setup.py (path:/tmp/pip-d1XoeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:21:01 INFO - Running setup.py (path:/tmp/pip-fKHLT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:21:01 INFO - Running setup.py (path:/tmp/pip-8kSS8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:02 INFO - Running setup.py (path:/tmp/pip-Ba4fFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:21:02 INFO - Running setup.py (path:/tmp/pip-od5wbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:21:02 INFO - Running setup.py (path:/tmp/pip-NtcHTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:21:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:21:02 INFO - Running setup.py install for manifestparser 10:21:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:21:02 INFO - Running setup.py install for mozcrash 10:21:02 INFO - Running setup.py install for mozdebug 10:21:02 INFO - Running setup.py install for mozdevice 10:21:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:21:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:21:02 INFO - Running setup.py install for mozfile 10:21:02 INFO - Running setup.py install for mozhttpd 10:21:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:21:02 INFO - Running setup.py install for mozinfo 10:21:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:21:02 INFO - Running setup.py install for mozInstall 10:21:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Running setup.py install for mozleak 10:21:03 INFO - Running setup.py install for mozlog 10:21:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Running setup.py install for moznetwork 10:21:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Running setup.py install for mozprocess 10:21:03 INFO - Running setup.py install for mozprofile 10:21:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Running setup.py install for mozrunner 10:21:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:21:03 INFO - Running setup.py install for mozscreenshot 10:21:03 INFO - Running setup.py install for moztest 10:21:03 INFO - Running setup.py install for mozversion 10:21:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:21:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:21:04 INFO - Cleaning up... 10:21:04 INFO - Return code: 0 10:21:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:04 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 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:21:04 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 10:21:04 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 10:21:04 INFO - Using env: {'DISPLAY': ':0', 10:21:04 INFO - 'HOME': '/home/cltbld', 10:21:04 INFO - 'LANG': 'en_US.UTF-8', 10:21:04 INFO - 'LANGUAGE': 'en_US:en', 10:21:04 INFO - 'LOGNAME': 'cltbld', 10:21:04 INFO - 'MAIL': '/var/mail/cltbld', 10:21:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:04 INFO - 'MOZ_NO_REMOTE': '1', 10:21:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:04 INFO - 'NO_EM_RESTART': '1', 10:21:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:04 INFO - 'PWD': '/builds/slave/test', 10:21:04 INFO - 'SHELL': '/bin/bash', 10:21:04 INFO - 'SHLVL': '1', 10:21:04 INFO - 'TERM': 'linux', 10:21:04 INFO - 'TMOUT': '86400', 10:21:04 INFO - 'USER': 'cltbld', 10:21:04 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:21:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:04 INFO - '_': '/tools/buildbot/bin/python'} 10:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:21:04 INFO - Running setup.py (path:/tmp/pip-JOfkMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:21:04 INFO - Running setup.py (path:/tmp/pip-A_hwYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:21:04 INFO - Running setup.py (path:/tmp/pip-Uoa5aZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:21:04 INFO - Running setup.py (path:/tmp/pip-V7hTlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:21:04 INFO - Running setup.py (path:/tmp/pip-XStVEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:21:04 INFO - Running setup.py (path:/tmp/pip-Wl4BWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:21:04 INFO - Running setup.py (path:/tmp/pip-d7gock-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:21:04 INFO - Running setup.py (path:/tmp/pip-UOEWg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:21:04 INFO - Running setup.py (path:/tmp/pip-6h2UZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:21:04 INFO - Running setup.py (path:/tmp/pip-PI1A70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:21:04 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)) 10:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:21:04 INFO - Running setup.py (path:/tmp/pip-JeYOip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:21:05 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)) 10:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:21:05 INFO - Running setup.py (path:/tmp/pip-Pk9FS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:21:05 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)) 10:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:21:05 INFO - Running setup.py (path:/tmp/pip-Tw7GI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:21:05 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)) 10:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:21:05 INFO - Running setup.py (path:/tmp/pip-kkyCUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:21:05 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)) 10:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:05 INFO - Running setup.py (path:/tmp/pip-S3RRjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:21:05 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)) 10:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:21:05 INFO - Running setup.py (path:/tmp/pip-stjOKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:21:05 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)) 10:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:21:05 INFO - Running setup.py (path:/tmp/pip-RPj9p3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:21:05 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)) 10:21:05 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)) 10:21:05 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)) 10:21:05 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)) 10:21:05 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)) 10:21:05 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)) 10:21:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:21: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 10:21: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 10:21: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 10:21: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 10:21: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 10:21: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 10:21:07 INFO - Downloading blessings-1.6.tar.gz 10:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:21:07 INFO - Installing collected packages: blessings 10:21:07 INFO - Running setup.py install for blessings 10:21:07 INFO - Successfully installed blessings 10:21:07 INFO - Cleaning up... 10:21:07 INFO - Return code: 0 10:21:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:21:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:07 INFO - Reading from file tmpfile_stdout 10:21:07 INFO - Current package versions: 10:21:07 INFO - argparse == 1.2.1 10:21:07 INFO - blessings == 1.6 10:21:07 INFO - blobuploader == 1.2.4 10:21:07 INFO - docopt == 0.6.1 10:21:07 INFO - functools32 == 3.2.3-2 10:21:07 INFO - jsonschema == 2.5.1 10:21:07 INFO - manifestparser == 1.1 10:21:07 INFO - mozInstall == 1.12 10:21:07 INFO - mozcrash == 0.17 10:21:07 INFO - mozdebug == 0.1 10:21:07 INFO - mozdevice == 0.48 10:21:07 INFO - mozfile == 1.2 10:21:07 INFO - mozhttpd == 0.7 10:21:07 INFO - mozinfo == 0.9 10:21:07 INFO - mozleak == 0.1 10:21:07 INFO - mozlog == 3.4 10:21:07 INFO - moznetwork == 0.27 10:21:07 INFO - mozprocess == 0.23 10:21:07 INFO - mozprofile == 0.28 10:21:07 INFO - mozrunner == 6.12 10:21:07 INFO - mozscreenshot == 0.1 10:21:07 INFO - mozsystemmonitor == 0.3 10:21:07 INFO - moztest == 0.7 10:21:07 INFO - mozversion == 1.4 10:21:07 INFO - psutil == 3.1.1 10:21:07 INFO - requests == 1.2.3 10:21:07 INFO - wsgiref == 0.1.2 10:21:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21: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')]} 10:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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')]} 10:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:07 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 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:21:07 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 10:21:07 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 10:21:07 INFO - Using env: {'DISPLAY': ':0', 10:21:07 INFO - 'HOME': '/home/cltbld', 10:21:07 INFO - 'LANG': 'en_US.UTF-8', 10:21:07 INFO - 'LANGUAGE': 'en_US:en', 10:21:07 INFO - 'LOGNAME': 'cltbld', 10:21:07 INFO - 'MAIL': '/var/mail/cltbld', 10:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:07 INFO - 'MOZ_NO_REMOTE': '1', 10:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:07 INFO - 'NO_EM_RESTART': '1', 10:21:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:07 INFO - 'PWD': '/builds/slave/test', 10:21:07 INFO - 'SHELL': '/bin/bash', 10:21:07 INFO - 'SHLVL': '1', 10:21:07 INFO - 'TERM': 'linux', 10:21:07 INFO - 'TMOUT': '86400', 10:21:07 INFO - 'USER': 'cltbld', 10:21:07 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:07 INFO - '_': '/tools/buildbot/bin/python'} 10:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:08 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)) 10:21:08 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)) 10:21:08 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)) 10:21:08 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)) 10:21:08 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)) 10:21:08 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)) 10:21:08 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)) 10:21:08 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)) 10:21:08 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)) 10:21:08 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)) 10:21:08 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)) 10:21:08 INFO - Cleaning up... 10:21:08 INFO - Return code: 0 10:21:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:21: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')]} 10:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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')]} 10:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90335b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b9a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c67ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, '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': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', '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 10:21: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', 'jsonschema'] in /builds/slave/test/build 10:21: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 jsonschema 10:21:08 INFO - Using env: {'DISPLAY': ':0', 10:21:08 INFO - 'HOME': '/home/cltbld', 10:21:08 INFO - 'LANG': 'en_US.UTF-8', 10:21:08 INFO - 'LANGUAGE': 'en_US:en', 10:21:08 INFO - 'LOGNAME': 'cltbld', 10:21:08 INFO - 'MAIL': '/var/mail/cltbld', 10:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:08 INFO - 'MOZ_NO_REMOTE': '1', 10:21:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:08 INFO - 'NO_EM_RESTART': '1', 10:21:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:08 INFO - 'PWD': '/builds/slave/test', 10:21:08 INFO - 'SHELL': '/bin/bash', 10:21:08 INFO - 'SHLVL': '1', 10:21:08 INFO - 'TERM': 'linux', 10:21:08 INFO - 'TMOUT': '86400', 10:21:08 INFO - 'USER': 'cltbld', 10:21:08 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:08 INFO - '_': '/tools/buildbot/bin/python'} 10:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:21:08 INFO - Cleaning up... 10:21:08 INFO - Return code: 0 10:21:08 INFO - Running post-action listener: _resource_record_post_action 10:21:08 INFO - Running post-action listener: _start_resource_monitoring 10:21:08 INFO - Starting resource monitoring. 10:21:08 INFO - [mozharness: 2016-11-17 18:21:08.369934Z] Finished create-virtualenv step (success) 10:21:08 INFO - [mozharness: 2016-11-17 18:21:08.370502Z] Running install step. 10:21:08 INFO - Running pre-action listener: _resource_record_pre_action 10:21:08 INFO - Running main action method: install 10:21:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:08 INFO - Reading from file tmpfile_stdout 10:21:08 INFO - Detecting whether we're running mozinstall >=1.0... 10:21:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:21:08 INFO - Reading from file tmpfile_stdout 10:21:08 INFO - Output received: 10:21:08 INFO - Usage: mozinstall [options] installer 10:21:08 INFO - Options: 10:21:08 INFO - -h, --help show this help message and exit 10:21:08 INFO - -d DEST, --destination=DEST 10:21:08 INFO - Directory to install application into. [default: 10:21:08 INFO - "/builds/slave/test"] 10:21:08 INFO - --app=APP Application being installed. [default: firefox] 10:21:08 INFO - mkdir: /builds/slave/test/build/application 10:21:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:21:22 INFO - Reading from file tmpfile_stdout 10:21:22 INFO - Output received: 10:21:22 INFO - /builds/slave/test/build/application/firefox/firefox 10:21:22 INFO - Running post-action listener: _resource_record_post_action 10:21:22 INFO - [mozharness: 2016-11-17 18:21:22.149413Z] Finished install step (success) 10:21:22 INFO - [mozharness: 2016-11-17 18:21:22.149657Z] Running run-tests step. 10:21:22 INFO - Running pre-action listener: _resource_record_pre_action 10:21:22 INFO - Running main action method: run_tests 10:21:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:21:22 INFO - Python 2.7.3 10:21:22 INFO - Return code: 0 10:21:22 INFO - grabbing minidump binary from tooltool 10:21:22 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')]} 10:21:22 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 0x2c64f60>, '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 0x2c65d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c661d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:21:22 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 10:21:22 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 10:21:22 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 10:21:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:21:22 INFO - Return code: 0 10:21:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:21:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:21:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:21:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:21:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:21:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 10:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 10:21:22 INFO - Using env: {'DISPLAY': ':0', 10:21:22 INFO - 'HOME': '/home/cltbld', 10:21:22 INFO - 'LANG': 'en_US.UTF-8', 10:21:22 INFO - 'LANGUAGE': 'en_US:en', 10:21:22 INFO - 'LOGNAME': 'cltbld', 10:21:22 INFO - 'MAIL': '/var/mail/cltbld', 10:21:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:21:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:22 INFO - 'MOZ_NO_REMOTE': '1', 10:21:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:21:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:22 INFO - 'NO_EM_RESTART': '1', 10:21:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:22 INFO - 'PWD': '/builds/slave/test', 10:21:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:21:22 INFO - 'SHELL': '/bin/bash', 10:21:22 INFO - 'SHLVL': '1', 10:21:22 INFO - 'TERM': 'linux', 10:21:22 INFO - 'TMOUT': '86400', 10:21:22 INFO - 'USER': 'cltbld', 10:21:22 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763', 10:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:22 INFO - '_': '/tools/buildbot/bin/python'} 10:21:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 10:21:22 INFO - mozversion application_buildid: 20161117073328 10:21:22 INFO - mozversion application_changeset: bcafa3ea224b818b4ffb165da4fa7a51f3d80280 10:21:22 INFO - mozversion application_display_name: Firefox 10:21:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:21:22 INFO - mozversion application_name: Firefox 10:21:22 INFO - mozversion application_remotingname: firefox 10:21:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:21:22 INFO - mozversion application_vendor: Mozilla 10:21:22 INFO - mozversion application_version: 51.0 10:21:22 INFO - mozversion platform_buildid: 20161117073328 10:21:22 INFO - mozversion platform_changeset: bcafa3ea224b818b4ffb165da4fa7a51f3d80280 10:21:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:21:22 INFO - mozversion platform_version: 51.0 10:21:22 INFO - using testdate: 1479406882 10:21:22 INFO - actual date: 1479406882 10:21:22 INFO - starting webserver on 'localhost:49315' 10:21:22 INFO - SUITE-START | Running 2 tests 10:21:22 INFO - TEST-START | dromaeo_css 10:21:22 INFO - operating with platform_type : linux_ 10:21:22 INFO - Initialising browser for dromaeo_css test... 10:21:22 INFO - TEST-INFO | started process 4188 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0UalU_/profile http://localhost:49315/getInfo.html) 10:21:26 INFO - PROCESS | 4188 | __metrics Screen width/height:1600/1200 10:21:26 INFO - PROCESS | 4188 | colorDepth:24 10:21:26 INFO - PROCESS | 4188 | Browser inner width/height: 1024/697 10:21:26 INFO - PROCESS | 4188 | __metrics 10:21:27 ERROR - PROCESS | 4188 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3018: TypeError: this.tabs is undefined 10:21:27 INFO - TEST-INFO | 4188: exit 0 10:21:27 INFO - Browser initialized. 10:21:27 INFO - Running cycle 1/1 for dromaeo_css test... 10:21:27 INFO - TEST-INFO | started process 4319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0UalU_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:21:39 INFO - PROCESS | 4319 | RSS: Main: 147865600 10:21:39 INFO - PROCESS | 4319 | 10:22:29 INFO - PROCESS | 4319 | 1479406949338 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] 10:24:38 INFO - PROCESS | 4319 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49315/tests/dromaeo/cssquery-ext.html) 10:24:39 INFO - PROCESS | 4319 | RSS: Main: 154476544 10:24:39 INFO - PROCESS | 4319 | 10:27:41 INFO - PROCESS | 4319 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-ext.html (next: http://localhost:49315/tests/dromaeo/cssquery-jquery.html) 10:27:41 INFO - PROCESS | 4319 | RSS: Main: 151392256 10:27:41 INFO - PROCESS | 4319 | 10:30:43 INFO - PROCESS | 4319 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49315/tests/dromaeo/cssquery-mootools.html) 10:30:44 INFO - PROCESS | 4319 | RSS: Main: 150831104 10:30:44 INFO - PROCESS | 4319 | 10:33:46 INFO - PROCESS | 4319 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49315/tests/dromaeo/cssquery-prototype.html) 10:33:46 INFO - PROCESS | 4319 | RSS: Main: 153231360 10:33:46 INFO - PROCESS | 4319 | 10:36:49 INFO - PROCESS | 4319 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49315/tests/dromaeo/cssquery-yui.html) 10:36:49 INFO - PROCESS | 4319 | RSS: Main: 154578944 10:36:49 INFO - PROCESS | 4319 | 10:39:52 INFO - PROCESS | 4319 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-yui.html (next: http://localhost:49315/tests/dromaeo/cssquery-dojo.html) 10:39:52 INFO - PROCESS | 4319 | RSS: Main: 153743360 10:39:52 INFO - PROCESS | 4319 | 10:39:52 INFO - PROCESS | 4319 | __start_tp_report 10:39:52 INFO - PROCESS | 4319 | _x_x_mozilla_page_load 10:39:52 INFO - PROCESS | 4319 | _x_x_mozilla_page_load_details 10:39:52 INFO - PROCESS | 4319 | |i|pagename|runs| 10:39:52 INFO - PROCESS | 4319 | |0;dojo.html;3196.1210667066553;3217.7747557670973;3224.6130464344283;3232.6928941750534;3243.9189020274507;5664.49019588238;5672.149177623359;5696.715164241787;5719.342275638302;5730.541556675461;5782.24830771999;5857.209276747638;5869.677167755772;5885.823425297237;5890.145928840327;5239.240310155034;5295.867603309909;5298;5306.814261500848;5335.813246536372;5298.1522956327;5342.759575819078;5342.893142137155;5354.89793067666;5380.192971054354;5073.467285934979;5080.517350851663;5342.278792363039;5346.759395827177;5350.598705097113;5182.585393168556;5211.42674305826;5239.397469291042;5265.763040663141;5338.839834804923;5113.079645663779;5258.55302299305;5273.841784746463;5274.525292723673;5280.683159010472;5438.7280635968045;5678;5680.8863822723315;5786.566007549409;5786.594938354317;5889.411058894119;5919.881602367972;5952.553558483088;5979.133025711248;6010.068439391901;6004.519638428916;6105.938940610581;6109.358517355702;6115.755369785204;6119.755209791603;5713.6000479966415;5717.571182161271;5728.484436400702;5730.656160630376;5738.799142030009;6124.387561243929;6168.228971378578;6223.315435302113;6311.621302721759;6312.116303717483;5995.100734889801;6057.969710151421;6062.514998800174;6068.271807383142;6099.420555047263;5907.645541267538;5910.202122713378;6152.2044817648075;6155.538334624921;6162.075688646739;5946.8810623787285;5955.612885162451;5993.190919225936;6064.757409703562;6108.908366374508;6051.394860514001;6055.273367195965;6056.485198758067;6082.665453400105;6091.969540152271;4509.368688383629;4669.112868554964;4676.906461870743;4685.086408150446;4704.223803072523;4054.71616986814;4091.734037287567;4111.814968326433;4136.669066474675;4196.979015104905;5788.450097240755;5792.073268277056;5817.331006934172;5825.126231065374;5829.912551311733;6053.606515576474;6104.633721976696;6111.052786818051;6111.663858487737;6118.449339559461;5628.2683251177905;5737.741801618937;5752.511036561856;5754.913676294859;5787.102999035156;5196.32447781786;5216.869578260498;5234.97382513085;5238.1618940969265;5251;6234;6240.781572644935;6258.217722784652;6264.404881536247;6280.874382512416;5944.6730429826775;6012.158297838394;6019.1573179754;6051.152838602688;6106.72519736604;6262.46769024638;6308.400701933309;6309.1167236586;6311.990081586924;6314.905276420949;5970.283565972111;5990.371011043778;6013.248343957005;6023.728932198149;6037.154798328325;5810.825675229581;5881.911771323519;6041.124037014521;6061.696915154136;6117.632942023492;6013.6692481913915;6019.398060193946;6020.036794112921;6023.156758053964;6076.240469941257;5978.461938425563;5995.370486099071;5998.310194327533;6006.699665016644;6009.2188015557695;5679.233303503974;5740.253767010262;5800.854978625568;5981.910271346021;5985.461308482258;5801.477866991822;5858.121281807763;5905.498032667271;5909.55678324136;5938.287405511366;5723.313202415737;5737.713114344349;5760.596758226884;5761.366249712612;5772.480476757112 10:39:52 INFO - PROCESS | 4319 | |1;ext.html;638.8818068657297;650.473116775412;663.1644128398223;675.7297081167533;680.1837794646423;42956.570434295645;46471.53528464714;46511.534884651104;46554.30168547475;46606.300905486445;46110.30834537485;47093;47114.52885471153;47200.52799472004;47276;9102.317326200528;9204.309676774235;9216;9277.536123193848;9329.906700933007;9038.909610903906;9208.4474931504;9217.493037882914;9219.95390023049;9266.351355405124;9281;9338.393004454689;9351.345405821561;9358.204552613002;9362.438253704766;9217.585208665627;9280.953595232048;9285.25717942563;9299.209567186797;9321.86017209739;9191.954040229755;9262.490563019031;9265.351425400224;9269.397489163182;9279.582418791171;44228.336574951405;44302.55697443017;44416;44502.55497445016;44509.10981780346;3770.1894092769912;3985.5815139410524;4045.5752146024834;4180.435641188431;4463.33050042493;34805.47791783125;35088.29823403543;35201.47197792035;35292.82353588241;35312.29375412503;4748.852976270725;4773.140834649762;4785.258284965801;4813.30510319173;4856.805727770885;368376;369459;372159;372932;373138;62890;63477.36522634807;63485;63493.36506634874;63688.04467932985;40748.38877416841;41327.380089298684;41638.1672366557;43262.567374326485;57655.71172144113;19532.121054552295;19617.509562260773;19678.311259106147;19699.803001970082;19785.703214451794;14374.568762937128;14391.42434302637;14417.062890912057;14439.783403248959;14449.494267700791;21206.25778097759;21492.57014859694;21501.13995440165;21539.56920861574;21616.13535458564;7513.962430187814;7602.1257555381835;7668.309852113337;7771.222877712184;7874.763757087297;1029.4749677664445;1134.8184290513477;1165.854268216473;1167.643868620062;1194.970125746846;32175;32402.83798581039;32416.513752293733;33026.504602430985;33293.50059749106;1086.8750093739163;1119.8768135505088;1150.7871043856912;1151.366748288438;1155.4511606986614;16636.33454661824;16968.49094527166;17645.558861028578;17682.027488488;17790.288388464316;424658;429672;430524;637920;643404;63097.053544196875;63453.36546634567;63511;63539.36460635427;63608.681956590844;1420.3821337718125;1424.750668632985;1445.5880074178808;1476.6825132596327;1478.3051965576376;34070.65929340774;34928.47607285842;34974.47538286979;35215.11962200965;35390.82304588461;54597.72701136469;55573;55596.444035560744;55611;55705;398186;399872;401827;402195;402829;31182.37635247328;31325;31591.526127107652;31734.523982139825;32043.35913281768;50965.745171273906;51239;51323;51837.48162518477;51894.74052629713;43351.34972975471;44090;44625;45055;45753;43346.133077338905;44479.11041779211;44621.77689111534;45030.77484612556;45556.31665524953;43937.12125757531;45323.546764533254;46100;46379.30431043605;48594.514054859;38476.80761596174;39218.41172382359;39327;40382.39426408665;40399.59600403958;28525.572116417854;28752.42495150127;28756.42487150287;28778.71221287844;29094.7090529092 10:39:52 INFO - PROCESS | 4319 | |2;jquery.html;426.711969420641;426.8655373557331;430.81272628486533;434.84128293172984;445.74815229395386;18983.145758440885;19333.613327733456;20002.19991200352;20760.27339043134;20961.790382096173;19424.708629370536;19790.505237369074;20029.1988320467;20161.899190504064;20237.291694790685;5407.053765591026;5630.774769009235;5630.887382252353;5633.0987042073275;5633.211350410946;5479.917801232985;5511.3937466878615;5570.359408668014;5854.29748430189;6071.544634152434;6168.1364608954555;6173.845653858644;6190.380961903818;6201.782937597185;6207.5344349173765;6218.409251121137;6266.968665156668;6615.669216539178;6628.403443690091;6629.602223866584;5894.705264736789;5957;5994.910076348859;6012.248468941382;6015.729292181863;14964.850351496454;15178.772318415233;15347.232638368148;15358.769618455732;15358.923205384013;2376.7860892519584;2591.5464793661035;2609.1520255916903;2968.569557414163;3187.96812031879;10713.410762408064;10950.233483656148;11031.117510599133;15455.768163477556;15690.76463853043;2032.481717162129;2082.500199952016;2092.1317653174065;2115.5557332960097;2143.689165071072;87374.56312718395;89497.99999999935;90009.54995225047;90185;92935.53532232363;11940.820887686692;11985.28088314704;12101.394930253453;12234.143609947396;12262.386880655931;17351;17352.305907763548;17363;17390.739138912926;17403.825961740222;8805.251553618014;8887.15572020657;8903.465792052497;8963;9001.684941027033;8189.7133600323705;8266.380021498291;8275.95862020686;8533.104024077507;8566.22903938636;4994.70031798086;5051.217061355495;5091.66904151229;5105.591552675777;5238.397584277781;2096.748390193156;2102.7161333220124;2117.8835164065817;2134.487722946482;2205.1730601024615;1007.5163921317662;1023.6283380270473;1057.2440704896037;1076.0907033556632;1081.6214324986192;19547.51131221731;19701.832798392166;19750.901245493682;20156.89921550383;20337.593248134956;1001.8046480936293;1038.3977293169944;1040.1314902056715;1052.6894566103017;1064.3826580583243;10409.323393979366;11180.899504713187;11213.495392707347;11214.495347709373;11234.662960111209;495545;517789;530204;531956;541140;91553;93638.51492433384;103071.48464257631;103651;105777;1085.7394225386008;1088.2491081153978;1150.2868221702597;1156.005944603481;1162.436218434059;18495.352662656744;18534;18662.81337186665;18841.811581884554;18905.527361815515;43792.12415751731;44179.77910110429;46096.539034609224;46226.76886615546;46619.766901165276;55155;57761.71119144378;57780.42219577919;62902.596818222286;64927;9516.33385662997;9527.38072025316;9532.28507861927;9575.23398128134;9595.664151754934;11831.585894493652;14377.712445751236;14458.060226085692;14600.853991460375;14670.339834707676;5858.970705146618;9045.638174472948;10173.593056277667;12740.490380384681;12775.23348599087;12334.81497777516;12465.127441079037;12467.93766031164;12685.8097128545;12726.300053497147;6543.803685889431;12451.688707782378;12723.80914286305;13258.138221015603;13330.133541319783;9060.229880460234;9081.68214112503;9239.538023098952;9563.426194428357;9780.510974451392;9267.841519810023;12338.938305308417;12364.505419783109;12395.75208495843;13077.738445231233 10:39:52 INFO - PROCESS | 4319 | |3;mootools.html;1488.799012133362;1514.3412615512252;1678.4293340264308;1746.1269365317341;1803.332766876256;8321.375896807733;8521.020082690493;9052.683156089539;9689.418634881904;9757.75605609861;9171.724848254564;9563.474008929506;9567.234621230302;9776.902230977688;9870.605175792978;2463.224084413404;2467.543504451682;2497.6378425128346;2503.2240005598233;2503.7871780898645;2456.6806315179;2481.6525686403916;2489.4025433895827;2494.950100997979;2495.4011037350997;2477.541654793869;2496.051500429843;2504.6243063540433;2511.12110761233;2523.684539432571;2429.7813196812194;2434.233216536785;2437.025189924027;2443.132687895798;2465.2480993297036;2420.1771397724683;2435.866027368494;2452.509498100387;2464.4208610976407;2468.358226861029;9296.814063718755;9370.67202647911;9640.662576809847;9755.268354873342;9779.070988256104;5669.688167150805;5754;5761.452661997104;5802.854928626818;5808.128780682931;3627.1113577173496;3732.5520937487404;3784.0918179636965;3795.070207799079;3821.044738815296;1163.6567212672282;1212.1333246728632;1223.455562274788;1232.3160645841554;1237.5668516019412;13715.177089374669;13777.448902043907;13850.861491385058;13881.861181388258;13901.374438150307;4856.17445034345;5000.774965126578;5034.521720436553;5062.7468626568525;5160.458151894072;10680.305780124423;10696.037356637784;10710.518026688818;10711.892881071246;10738;5652.971735141298;5677.09166533361;5696.174054762119;5712.257406537124;5735.598508104393;5096.745162741848;5284.550813180846;5303.575713942875;5325.520703136736;5347.572194224453;4393.1872603568445;4399;4495.887602809956;4517.435320584927;4536.228841097021;1384.7922811578142;1417.4755340524073;1431.5490620454732;1431.7136572685297;1452.0634190946992;857.6740838481337;859.9269062129664;946.9479178645103;953.3564843730454;974.498133461268;5991.850203744854;6318.273398559225;6354.777582784581;6507.414332710079;6692.698828552727;944.244604316544;978.9021097890154;1006.3006210683504;1032.5353590883983;1041.729150420896;3924.8822535323984;4101;4114.300568903293;4149.564295748903;4257.914841703148;11415.258008229604;11710.121740869468;11775.293482391084;11809.468573914195;11836.112291578165;11646.767064658829;11775.52897884092;11871.76256474883;11876.168668193317;11887.346195959306;8248.13394593559;8667.4366166199;8669.783255418664;8852.29181665478;9019.143181397756;6302.432781049728;6341.682915854097;6379.904301435575;6522.869542609216;6614.371634694697;10595.417252051211;11021.779564408827;11050.281731687415;11097.500612472297;11195.10439164881;10998.560057597606;11240.044596209415;11276.32342059479;11298.09615230796;11351.829722554003;8189.7133600323705;8397.286230670461;8547.230749232598;8578.699745508877;8660.523671198145;9514.952425237829;9588.280878933969;9597.664081757104;9599.232061435208;9605.567749451151;8548.658053677784;8565.186307300797;8587.699430520152;8681.045085040767;8749.256313213193;8222.794430139294;8243.216894395024;8445.78885527842;8487.448315859448;8588.141185881363;7776;7807.726729564438;7839.9216007839195;7861.2531809478005;7875.8818617719635;5343.4389389113585;5359.383670877743;5444.700541470257;5520.365158006881;5546.168074788814;5424.457554244702;5452.754626041917;5467.234587157881;5510.889782204414;5725.8568535786935 10:39:52 INFO - PROCESS | 4319 | |4;prototype.html;347.4787818272591;350.2749308930513;356.5222601713703;371.014955293695;372.5231703419622;5732.627379220358;5735.655860648359;5790.30516338039;5804.303483581971;5875.941240587593;5725.312962444513;5739.799107031255;5762.279715035621;5762.711864406773;5770.3364113127;4498.370228168059;4498.640108791297;4500.97749511252;4515.2324104902245;4525.117602067598;4259.76571288579;4442.2004039272915;4487.932681009787;4488.573585509372;4534.478534968472;4454.754988475633;4455.108978204372;4461.219286624828;4467;4467.620252278561;4460.197164510387;4460.643148548108;4466.905608126013;4469.284914413711;4486.596206341413;3848.1726428817938;3960.4653371794725;3963.1875465529497;3963.6432721055244;3972.8609498667406;5589.239389592265;5589.580781441367;5594.552435805126;5595.586692117805;5603.551715862721;5187.507186817246;5199.532042116228;5209.958008398336;5229.712365819878;5232.2413250078525;5764.365919748824;5977.252843394575;5987.760489580412;6018.789342373039;6042.214512113441;4452.287633978548;4453.821847126111;4455.1535208310315;4457.309117086825;4463.464384273908;6255.343188965138;6274.843128921768;6279.717412716439;6282.403171698681;6297.89786787315;4470.240059189946;4559.179347717376;4566.566176213254;4573.794179261941;4587.472440669366;4623.214053610895;4626.7223966562115;4642.86071417858;4646.930296045562;4653.788885668878;5190.688558686491;5218.66078704883;5221.6083793715625;5236.188390799432;5240.947590524046;5861;5893.263342082239;6047.606905551125;6088.969555152196;6155.415235552616;1569.0899278418717;1591.2282542966652;1596.4013494939397;1597.1375457252952;1610.4363472784432;1019.4138370437029;1020.642775028734;1049.6326285800062;1052.3370276725766;1054.583439541377;5166.664166829144;5167.4832516748775;5199.48005199477;5201.167813149954;5286.779552587922;5398.352197736218;5411.589554513059;5465.07093794056;5466.337553838937;5494.478024587658;5002.624803139779;5164.1995490699;5166.948330516722;5173.586113110942;5219.747260657415;2923.0707732896185;2926.356201635637;2934.5891575179494;2935.66239882412;2938.503181214348;5577.832665020056;5578.804741834017;5598.648567601617;5601.7759289628775;5605.607607467438;5275.894482110336;5540.473655002768;5543.972280138573;5603.3836278009385;5639.746211420496;5616.129499927518;5644.871025794858;5645.673266782385;5656.717164141776;5661.46216109469;5231.782453790967;5433.5924805640525;5441.700706461024;5462.453754624506;5498.340199175965;5310.911263190959;5371.301730774974;5377.763114483612;5384.630762464503;5404.675719456688;5562.499375056264;5588.944110558842;5597.188407680782;5605.747741351731;5611.5230205433;3916.2754890345377;4150.190712810973;4164.293209413905;4169.061961058737;4173.10278290169;5419.729013549228;5446.673199608036;5453.836384908459;6032.336442991355;6267.373262673696;6148.47737942262;6148.600340977822;6152.815415537541;6153.72308246139;6160.661163636043;6059.394060594082;6064;6076.453119219292;6118.724657390517;6156.6921653918025;5817.5345972321265;6044.455998960115;6075.574709770273;6108.297545782292;6127.754889804358;6084.269887613515;6095.969520152371;6120.234970628671;6136.355682653257;6140.846478838065;5680.2047713321;5923.259592550931;5981.671008094597;5992.0712289595185;5997.280326360865 10:39:52 INFO - PROCESS | 4319 | |5;yui.html;766.3945483068375;774.4203017260637;807.2089352434614;820.2330820682661;860.9655613775449;16786;17692;17726.202320895558;17737.290508379683;17821.82178178218;15615.609609759762;15971.440999565017;16039.198040097977;16052.277647505862;16054.83945160551;708.1608294171398;720.74413583178;818.9344852411792;831.4512421801612;849.4096602860983;858.5707146426787;859.3468963587687;859.6088779605296;859.669027424443;860.8880845490078;897.6813231302891;898.0704970355683;901.7430032440756;902.9458232506038;903.503073309677;891.6567121658144;892.3173772064376;894.93735438519;896.4845214001942;896.9910300897005;657.3754932813846;663.6117871045444;663.920329560451;665.1220389086408;667.8029981155571;15589.298481568356;15659;15670.21648917561;15689.215539223107;15723.606909827231;14654.633634159125;14894.851051489562;16242.209434300421;16772;17273.481795546155;14042.227677477735;14265.286735663176;14536.563903082924;14699.485518006819;14730.705385892223;4746.501617330199;4798;4860.7326597037145;4868.294097355899;5034.773435195389;90198.54900725455;90493.54753226192;90993.09006909913;91300.54349728275;92055.53972230096;12372.19580727268;12419.627411177678;12441;12567.87432125667;12706.9364653178;21683.67474487884;22243.555128897333;22644.433889152573;23055.53888922212;23477.41306467352;9262.16640502358;9439.811203775886;9711.514424278757;9729.513524323896;9919.206463482904;4964.950350496449;5039.84880453587;5116.74416279192;5117.923231151536;5147.0992576298995;3888.3972984187494;4018.2365350583295;4041.0503531670033;4048.0891799344913;4053.6757059435176;658.0589756647944;660.3000819131736;666.6500087454125;669.4042302350912;690.0856365315976;767.2902565127235;788.7160622176012;808.8624933761274;809.5587904592012;825.5253229393123;10075.496225188712;10080.546375413123;10132.645357412455;10153.847692284622;10275.229357798195;669.3908543225641;741.2802298667041;775.1514278189886;805.46839086204;822.4119754375531;5090.739786820958;5257.211418287287;5275.129603615437;5277.973610131925;5301.014779948028;129359;131984;132346;133755;137148;104496;117049;119863.801361987;122036;122242;95010;100148.49925750325;102236.48881755544;104231.47884260681;104465;9540.427574345562;10017.649382271586;10134.341267817568;10171.694849154537;10250.38497690141;59763.40236597578;61444;61529;61906.380936190064;62175;48176;49312.26031609457;66386.6680666613;85603.57198214218;89958.55020724854;13289.933550332187;13293.667658308234;13536.932315338361;13620.795688064489;13725.588232353046;14827.110373377633;15303.387864485296;15396.615084622974;15403.691926161639;15648.92175539115;13938.860611393757;14025.719485610434;14343.641408964859;14354.282285885873;14370.568882933529;13562.254076025913;13832.308384580932;14063.859361406256;14457.783133252799;14690.338934747748;12792.232466051695;13546.458141674617;13567.932160339134;13816.447342106605;14904.701905962036;10865.348079114961;11314.547418103184;11453.713657158638;11510.654680359603;11639;10914.345139291669;11579.826302605636;11698.883011169779;11730.765384692088;11821.468033938321 10:39:52 INFO - PROCESS | 4319 | __end_tp_report 10:39:52 INFO - PROCESS | 4319 | __start_cc_report 10:39:52 INFO - PROCESS | 4319 | _x_x_mozilla_cycle_collect,291 10:39:52 INFO - PROCESS | 4319 | __end_cc_report 10:39:52 INFO - PROCESS | 4319 | __startTimestamp1479407992562__endTimestamp 10:39:52 INFO - PROCESS | 4319 | 10:39:52 INFO - PROCESS | 4319 | ------- Summary: start ------- 10:39:52 INFO - PROCESS | 4319 | Number of tests: 6 10:39:52 INFO - PROCESS | 4319 | 10:39:52 INFO - PROCESS | 4319 | [#0] dojo.html Cycles:165 Average:5658.09 Median:5858.12 stddev:637.62 (10.9%) stddev-sans-first:609.62 10:39:52 INFO - PROCESS | 4319 | Values: 3196.1 3217.8 3224.6 3232.7 3243.9 5664.5 5672.1 5696.7 5719.3 5730.5 5782.2 5857.2 5869.7 5885.8 5890.1 5239.2 5295.9 5298.0 5306.8 5335.8 5298.2 5342.8 5342.9 5354.9 5380.2 5073.5 5080.5 5342.3 5346.8 5350.6 5182.6 5211.4 5239.4 5265.8 5338.8 5113.1 5258.6 5273.8 5274.5 5280.7 5438.7 5678.0 5680.9 5786.6 5786.6 5889.4 5919.9 5952.6 5979.1 6010.1 6004.5 6105.9 6109.4 6115.8 6119.8 5713.6 5717.6 5728.5 5730.7 5738.8 6124.4 6168.2 6223.3 6311.6 6312.1 5995.1 6058.0 6062.5 6068.3 6099.4 5907.6 5910.2 6152.2 6155.5 6162.1 5946.9 5955.6 5993.2 6064.8 6108.9 6051.4 6055.3 6056.5 6082.7 6092.0 4509.4 4669.1 4676.9 4685.1 4704.2 4054.7 4091.7 4111.8 4136.7 4197.0 5788.5 5792.1 5817.3 5825.1 5829.9 6053.6 6104.6 6111.1 6111.7 6118.4 5628.3 5737.7 5752.5 5754.9 5787.1 5196.3 5216.9 5235.0 5238.2 5251.0 6234.0 6240.8 6258.2 6264.4 6280.9 5944.7 6012.2 6019.2 6051.2 6106.7 6262.5 6308.4 6309.1 6312.0 6314.9 5970.3 5990.4 6013.2 6023.7 6037.2 5810.8 5881.9 6041.1 6061.7 6117.6 6013.7 6019.4 6020.0 6023.2 6076.2 5978.5 5995.4 5998.3 6006.7 6009.2 5679.2 5740.3 5800.9 5981.9 5985.5 5801.5 5858.1 5905.5 5909.6 5938.3 5723.3 5737.7 5760.6 5761.4 5772.5 10:39:52 INFO - PROCESS | 4319 | 10:39:52 INFO - PROCESS | 4319 | [#1] ext.html Cycles:180 Average:60535.13 Median:44159.17 stddev:115540.70 (261.6%) stddev-sans-first:115777.30 10:39:52 INFO - PROCESS | 4319 | Values: 638.9 650.5 663.2 675.7 680.2 42956.6 46471.5 46511.5 46554.3 46606.3 46110.3 47093.0 47114.5 47200.5 47276.0 9102.3 9204.3 9216.0 9277.5 9329.9 9038.9 9208.4 9217.5 9220.0 9266.4 9281.0 9338.4 9351.3 9358.2 9362.4 9217.6 9281.0 9285.3 9299.2 9321.9 9192.0 9262.5 9265.4 9269.4 9279.6 44228.3 44302.6 44416.0 44502.6 44509.1 3770.2 3985.6 4045.6 4180.4 4463.3 34805.5 35088.3 35201.5 35292.8 35312.3 4748.9 4773.1 4785.3 4813.3 4856.8 368376.0 369459.0 372159.0 372932.0 373138.0 62890.0 63477.4 63485.0 63493.4 63688.0 40748.4 41327.4 41638.2 43262.6 57655.7 19532.1 19617.5 19678.3 19699.8 19785.7 14374.6 14391.4 14417.1 14439.8 14449.5 21206.3 21492.6 21501.1 21539.6 21616.1 7514.0 7602.1 7668.3 7771.2 7874.8 1029.5 1134.8 1165.9 1167.6 1195.0 32175.0 32402.8 32416.5 33026.5 33293.5 1086.9 1119.9 1150.8 1151.4 1155.5 16636.3 16968.5 17645.6 17682.0 17790.3 424658.0 429672.0 430524.0 637920.0 643404.0 63097.1 63453.4 63511.0 63539.4 63608.7 1420.4 1424.8 1445.6 1476.7 1478.3 34070.7 34928.5 34974.5 35215.1 35390.8 54597.7 55573.0 55596.4 55611.0 55705.0 398186.0 399872.0 401827.0 402195.0 402829.0 31182.4 31325.0 31591.5 31734.5 32043.4 50965.7 51239.0 51323.0 51837.5 51894.7 43351.3 44090.0 44625.0 45055.0 45753.0 43346.1 44479.1 44621.8 45030.8 45556.3 43937.1 45323.5 46100.0 46379.3 48594.5 38476.8 39218.4 39327.0 40382.4 40399.6 28525.6 28752.4 28756.4 28778.7 29094.7 10:39:52 INFO - PROCESS | 4319 | 10:39:52 INFO - PROCESS | 4319 | [#2] jquery.html Cycles:180 Average:30750.52 Median:2126.19 stddev:86453.81 (4066.1%) stddev-sans-first:86666.35 10:39:52 INFO - PROCESS | 4319 | Values: 426.7 426.9 430.8 434.8 445.7 18983.1 19333.6 20002.2 20760.3 20961.8 19424.7 19790.5 20029.2 20161.9 20237.3 5407.1 5630.8 5630.9 5633.1 5633.2 5479.9 5511.4 5570.4 5854.3 6071.5 6168.1 6173.8 6190.4 6201.8 6207.5 6218.4 6267.0 6615.7 6628.4 6629.6 5894.7 5957.0 5994.9 6012.2 6015.7 14964.9 15178.8 15347.2 15358.8 15358.9 2376.8 2591.5 2609.2 2968.6 3188.0 10713.4 10950.2 11031.1 15455.8 15690.8 2032.5 2082.5 2092.1 2115.6 2143.7 87374.6 89498.0 90009.5 90185.0 92935.5 11940.8 11985.3 12101.4 12234.1 12262.4 17351.0 17352.3 17363.0 17390.7 17403.8 8805.3 8887.2 8903.5 8963.0 9001.7 8189.7 8266.4 8276.0 8533.1 8566.2 4994.7 5051.2 5091.7 5105.6 5238.4 2096.7 2102.7 2117.9 2134.5 2205.2 1007.5 1023.6 1057.2 1076.1 1081.6 19547.5 19701.8 19750.9 20156.9 20337.6 1001.8 1038.4 1040.1 1052.7 1064.4 10409.3 11180.9 11213.5 11214.5 11234.7 495545.0 517789.0 530204.0 531956.0 541140.0 91553.0 93638.5 103071.5 103651.0 105777.0 1085.7 1088.2 1150.3 1156.0 1162.4 18495.4 18534.0 18662.8 18841.8 18905.5 43792.1 44179.8 46096.5 46226.8 46619.8 55155.0 57761.7 57780.4 62902.6 64927.0 9516.3 9527.4 9532.3 9575.2 9595.7 11831.6 14377.7 14458.1 14600.9 14670.3 5859.0 9045.6 10173.6 12740.5 12775.2 12334.8 12465.1 12467.9 12685.8 12726.3 6543.8 12451.7 12723.8 13258.1 13330.1 9060.2 9081.7 9239.5 9563.4 9780.5 9267.8 12338.9 12364.5 12395.8 13077.7 10:39:52 INFO - PROCESS | 4319 | 10:39:52 INFO - PROCESS | 4319 | [#3] mootools.html Cycles:180 Average:6292.15 Median:5048.63 stddev:3609.23 (71.5%) stddev-sans-first:3601.30 10:39:52 INFO - PROCESS | 4319 | Values: 1488.8 1514.3 1678.4 1746.1 1803.3 8321.4 8521.0 9052.7 9689.4 9757.8 9171.7 9563.5 9567.2 9776.9 9870.6 2463.2 2467.5 2497.6 2503.2 2503.8 2456.7 2481.7 2489.4 2495.0 2495.4 2477.5 2496.1 2504.6 2511.1 2523.7 2429.8 2434.2 2437.0 2443.1 2465.2 2420.2 2435.9 2452.5 2464.4 2468.4 9296.8 9370.7 9640.7 9755.3 9779.1 5669.7 5754.0 5761.5 5802.9 5808.1 3627.1 3732.6 3784.1 3795.1 3821.0 1163.7 1212.1 1223.5 1232.3 1237.6 13715.2 13777.4 13850.9 13881.9 13901.4 4856.2 5000.8 5034.5 5062.7 5160.5 10680.3 10696.0 10710.5 10711.9 10738.0 5653.0 5677.1 5696.2 5712.3 5735.6 5096.7 5284.6 5303.6 5325.5 5347.6 4393.2 4399.0 4495.9 4517.4 4536.2 1384.8 1417.5 1431.5 1431.7 1452.1 857.7 859.9 946.9 953.4 974.5 5991.9 6318.3 6354.8 6507.4 6692.7 944.2 978.9 1006.3 1032.5 1041.7 3924.9 4101.0 4114.3 4149.6 4257.9 11415.3 11710.1 11775.3 11809.5 11836.1 11646.8 11775.5 11871.8 11876.2 11887.3 8248.1 8667.4 8669.8 8852.3 9019.1 6302.4 6341.7 6379.9 6522.9 6614.4 10595.4 11021.8 11050.3 11097.5 11195.1 10998.6 11240.0 11276.3 11298.1 11351.8 8189.7 8397.3 8547.2 8578.7 8660.5 9515.0 9588.3 9597.7 9599.2 9605.6 8548.7 8565.2 8587.7 8681.0 8749.3 8222.8 8243.2 8445.8 8487.4 8588.1 7776.0 7807.7 7839.9 7861.3 7875.9 5343.4 5359.4 5444.7 5520.4 5546.2 5424.5 5452.8 5467.2 5510.9 5725.9 10:39:52 INFO - PROCESS | 4319 | 10:39:52 INFO - PROCESS | 4319 | [#4] prototype.html Cycles:180 Average:4896.89 Median:5391.49 stddev:1398.24 (25.9%) stddev-sans-first:1359.83 10:39:52 INFO - PROCESS | 4319 | Values: 347.5 350.3 356.5 371.0 372.5 5732.6 5735.7 5790.3 5804.3 5875.9 5725.3 5739.8 5762.3 5762.7 5770.3 4498.4 4498.6 4501.0 4515.2 4525.1 4259.8 4442.2 4487.9 4488.6 4534.5 4454.8 4455.1 4461.2 4467.0 4467.6 4460.2 4460.6 4466.9 4469.3 4486.6 3848.2 3960.5 3963.2 3963.6 3972.9 5589.2 5589.6 5594.6 5595.6 5603.6 5187.5 5199.5 5210.0 5229.7 5232.2 5764.4 5977.3 5987.8 6018.8 6042.2 4452.3 4453.8 4455.2 4457.3 4463.5 6255.3 6274.8 6279.7 6282.4 6297.9 4470.2 4559.2 4566.6 4573.8 4587.5 4623.2 4626.7 4642.9 4646.9 4653.8 5190.7 5218.7 5221.6 5236.2 5240.9 5861.0 5893.3 6047.6 6089.0 6155.4 1569.1 1591.2 1596.4 1597.1 1610.4 1019.4 1020.6 1049.6 1052.3 1054.6 5166.7 5167.5 5199.5 5201.2 5286.8 5398.4 5411.6 5465.1 5466.3 5494.5 5002.6 5164.2 5166.9 5173.6 5219.7 2923.1 2926.4 2934.6 2935.7 2938.5 5577.8 5578.8 5598.6 5601.8 5605.6 5275.9 5540.5 5544.0 5603.4 5639.7 5616.1 5644.9 5645.7 5656.7 5661.5 5231.8 5433.6 5441.7 5462.5 5498.3 5310.9 5371.3 5377.8 5384.6 5404.7 5562.5 5588.9 5597.2 5605.7 5611.5 3916.3 4150.2 4164.3 4169.1 4173.1 5419.7 5446.7 5453.8 6032.3 6267.4 6148.5 6148.6 6152.8 6153.7 6160.7 6059.4 6064.0 6076.5 6118.7 6156.7 5817.5 6044.5 6075.6 6108.3 6127.8 6084.3 6096.0 6120.2 6136.4 6140.8 5680.2 5923.3 5981.7 5992.1 5997.3 10:39:52 INFO - PROCESS | 4319 | 10:39:52 INFO - PROCESS | 4319 | [#5] yui.html Cycles:180 Average:23225.61 Median:22849.99 stddev:34595.41 (151.4%) stddev-sans-first:34651.36 10:39:52 INFO - PROCESS | 4319 | Values: 766.4 774.4 807.2 820.2 861.0 16786.0 17692.0 17726.2 17737.3 17821.8 15615.6 15971.4 16039.2 16052.3 16054.8 708.2 720.7 818.9 831.5 849.4 858.6 859.3 859.6 859.7 860.9 897.7 898.1 901.7 902.9 903.5 891.7 892.3 894.9 896.5 897.0 657.4 663.6 663.9 665.1 667.8 15589.3 15659.0 15670.2 15689.2 15723.6 14654.6 14894.9 16242.2 16772.0 17273.5 14042.2 14265.3 14536.6 14699.5 14730.7 4746.5 4798.0 4860.7 4868.3 5034.8 90198.5 90493.5 90993.1 91300.5 92055.5 12372.2 12419.6 12441.0 12567.9 12706.9 21683.7 22243.6 22644.4 23055.5 23477.4 9262.2 9439.8 9711.5 9729.5 9919.2 4965.0 5039.8 5116.7 5117.9 5147.1 3888.4 4018.2 4041.1 4048.1 4053.7 658.1 660.3 666.7 669.4 690.1 767.3 788.7 808.9 809.6 825.5 10075.5 10080.5 10132.6 10153.8 10275.2 669.4 741.3 775.2 805.5 822.4 5090.7 5257.2 5275.1 5278.0 5301.0 129359.0 131984.0 132346.0 133755.0 137148.0 104496.0 117049.0 119863.8 122036.0 122242.0 95010.0 100148.5 102236.5 104231.5 104465.0 9540.4 10017.6 10134.3 10171.7 10250.4 59763.4 61444.0 61529.0 61906.4 62175.0 48176.0 49312.3 66386.7 85603.6 89958.6 13289.9 13293.7 13536.9 13620.8 13725.6 14827.1 15303.4 15396.6 15403.7 15648.9 13938.9 14025.7 14343.6 14354.3 14370.6 13562.3 13832.3 14063.9 14457.8 14690.3 12792.2 13546.5 13567.9 13816.4 14904.7 10865.3 11314.5 11453.7 11510.7 11639.0 10914.3 11579.8 11698.9 11730.8 11821.5 10:39:52 INFO - PROCESS | 4319 | -------- Summary: end -------- 10:39:52 INFO - PROCESS | 4319 | 10:39:53 INFO - TEST-INFO | 4319: exit 0 10:39:53 INFO - TEST-OK | dromaeo_css | took 1110785ms 10:39:53 INFO - TEST-START | kraken 10:39:53 INFO - operating with platform_type : linux_ 10:39:53 INFO - Initialising browser for kraken test... 10:39:53 INFO - TEST-INFO | started process 4675 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTnwgO7/profile http://localhost:49315/getInfo.html) 10:39:57 INFO - PROCESS | 4675 | __metrics Screen width/height:1600/1200 10:39:57 INFO - PROCESS | 4675 | colorDepth:24 10:39:57 INFO - PROCESS | 4675 | Browser inner width/height: 1024/697 10:39:57 INFO - PROCESS | 4675 | __metrics 10:39:57 ERROR - PROCESS | 4675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3018: TypeError: this.tabs is undefined 10:39:58 INFO - TEST-INFO | 4675: exit 0 10:39:58 INFO - Browser initialized. 10:39:58 INFO - Running cycle 1/1 for kraken test... 10:39:58 INFO - TEST-INFO | started process 4805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTnwgO7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:39:58 CRITICAL - PROCESS | 4805 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 10:39:58 INFO - Terminating psutil.Process(pid=4805, name='firefox') 10:39:58 INFO - TEST-INFO | 4805: exit 127 10:39:58 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 10:39:58 ERROR - Traceback (most recent call last): 10:39:58 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 10:39:58 INFO - talos_results.add(mytest.runTest(browser_config, test)) 10:39:58 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 10:39:58 INFO - return self._runTest(browser_config, test_config, setup) 10:39:58 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 232, in _runTest 10:39:58 INFO - else None) 10:39:58 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 10:39:58 INFO - global_counters=self.global_counters 10:39:58 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 10:39:58 INFO - self.parse() 10:39:58 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 10:39:58 INFO - % self.report_tokens) 10:39:58 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 10:39:58 INFO - raise utils.TalosError(message) 10:39:58 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 10:39:58 INFO - TEST-INFO took 5625ms 10:39:58 INFO - SUITE-END | took 1116s 10:39:59 ERROR - Return code: 2 10:39:59 WARNING - setting return code to 2 10:39:59 CRITICAL - # TBPL RETRY # 10:39:59 WARNING - setting return code to 4 10:39:59 INFO - Running post-action listener: _resource_record_post_action 10:39:59 INFO - [mozharness: 2016-11-17 18:39:59.022452Z] Finished run-tests step (success) 10:39:59 INFO - Running post-run listener: _resource_record_post_run 10:39:59 INFO - Total resource usage - Wall time: 1130s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 278044672; Read time: 60; Write time: 198880 10:39:59 INFO - TinderboxPrint: CPU usage
13.4% 10:39:59 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 60 10:39:59 INFO - TinderboxPrint: I/O write bytes / time
278,044,672 / 198,880 10:39:59 INFO - TinderboxPrint: CPU idle
7,811.0 (86.6%) 10:39:59 INFO - TinderboxPrint: CPU user
1,160.7 (12.9%) 10:39:59 INFO - TinderboxPrint: Swap in / out
0 / 0 10:39:59 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 112037888; Read time: 0; Write time: 96968 10:39:59 INFO - run-tests - Wall time: 1117s; CPU: 13.0%; Read bytes: 4096; Write bytes: 166006784; Read time: 16; Write time: 101912 10:39:59 INFO - Running post-run listener: _upload_blobber_files 10:39:59 INFO - Blob upload gear active. 10:39:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:39:59 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 10:39:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:39:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:40:00 INFO - (blobuploader) - INFO - Open directory for files ... 10:40:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:40:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:40:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:00 INFO - (blobuploader) - INFO - Done attempting. 10:40:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:40:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:40:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:03 INFO - (blobuploader) - INFO - Done attempting. 10:40:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:40:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:40:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:04 INFO - (blobuploader) - INFO - Done attempting. 10:40:04 INFO - (blobuploader) - INFO - Iteration through files over. 10:40:04 INFO - Return code: 0 10:40:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:40:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:40:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d2e52c69b445c485b9c8a914fe840e4148e83f1ca999ecc8e4f06978810d2b58d83585b509e55316c2eea6b5f09fabcaf8c5e17e10a74909ace614d4be4fcc9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2c2da67235561ec722388d116938dbae3aab37e0bfb9c011ac3f52b6b26a8cc580917d8387864bb6fa48435c72d847985d8fe49501396ef8129914d3d5f1d31", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eae353fcbae6f11451d11d4cd3e90097db2c631333ce1b62afe156e6b66a06a2be635ac4e4434d6746041843378d7bb6e816c46ed41456db09447730eaed3025"} 10:40:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:40:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:40:04 INFO - Contents: 10:40:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d2e52c69b445c485b9c8a914fe840e4148e83f1ca999ecc8e4f06978810d2b58d83585b509e55316c2eea6b5f09fabcaf8c5e17e10a74909ace614d4be4fcc9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2c2da67235561ec722388d116938dbae3aab37e0bfb9c011ac3f52b6b26a8cc580917d8387864bb6fa48435c72d847985d8fe49501396ef8129914d3d5f1d31", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eae353fcbae6f11451d11d4cd3e90097db2c631333ce1b62afe156e6b66a06a2be635ac4e4434d6746041843378d7bb6e816c46ed41456db09447730eaed3025"} 10:40:04 INFO - Running post-run listener: copy_logs_to_upload_dir 10:40:04 INFO - Copying logs to upload dir... 10:40:04 INFO - mkdir: /builds/slave/test/build/upload/logs 10:40:04 INFO - Copying logs to upload dir... 10:40:04 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1172.324349 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 32 secs) (at 2016-11-17 10:40:04.649216) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 10:40:04.652517) ========= 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=20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d2e52c69b445c485b9c8a914fe840e4148e83f1ca999ecc8e4f06978810d2b58d83585b509e55316c2eea6b5f09fabcaf8c5e17e10a74909ace614d4be4fcc9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2c2da67235561ec722388d116938dbae3aab37e0bfb9c011ac3f52b6b26a8cc580917d8387864bb6fa48435c72d847985d8fe49501396ef8129914d3d5f1d31", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eae353fcbae6f11451d11d4cd3e90097db2c631333ce1b62afe156e6b66a06a2be635ac4e4434d6746041843378d7bb6e816c46ed41456db09447730eaed3025"} build_url:https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011139 build_url: 'https://queue.taskcluster.net/v1/task/BaRQf5QqR2OYY5jMx8_hMg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d2e52c69b445c485b9c8a914fe840e4148e83f1ca999ecc8e4f06978810d2b58d83585b509e55316c2eea6b5f09fabcaf8c5e17e10a74909ace614d4be4fcc9b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2c2da67235561ec722388d116938dbae3aab37e0bfb9c011ac3f52b6b26a8cc580917d8387864bb6fa48435c72d847985d8fe49501396ef8129914d3d5f1d31", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eae353fcbae6f11451d11d4cd3e90097db2c631333ce1b62afe156e6b66a06a2be635ac4e4434d6746041843378d7bb6e816c46ed41456db09447730eaed3025"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 10:40:04.681054) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:40:04.681513) ========= 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=20e666a3d4cfbb71505a330e000001b4-1479406830.708338-492019763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004403 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:40:04.765021) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 10:40:04.765403) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 10:40:04.765725) ========= ========= Total master_lag: 0.17 =========