builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-062 starttime: 1501199724.93 results: success (0) revision: a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:24.929574) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:24.929998) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:24.930278) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:24.946519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:24.946774) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-27 16:55:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 27.7M=0s 2017-07-27 16:55:25 (27.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165951 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:25.145395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:25.145716) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014582 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:25.194371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:25.194664) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c --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=a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-27 16:55:25,630 truncating revision to first 12 chars 2017-07-27 16:55:25,630 Setting DEBUG logging. 2017-07-27 16:55:25,631 attempt 1/10 2017-07-27 16:55:25,631 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a4afa89bfdd1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-27 16:55:25,921 unpacking tar archive at: mozilla-central-a4afa89bfdd1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.588739 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:25.795232) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:25.795552) ========= script_repo_revision: a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:25.796015) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:25.796308) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:25.803369) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-07-27 16:55:25.803633) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:55:26 INFO - MultiFileLogger online at 20170727 16:55:26 in /builds/slave/test 16:55:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:55:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:55:26 INFO - {'append_to_log': False, 16:55:26 INFO - 'base_work_dir': '/builds/slave/test', 16:55:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:55:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:55:26 INFO - 'branch': 'Firefox-Non-PGO', 16:55:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:55:26 INFO - 'code_coverage': False, 16:55:26 INFO - 'config_files': ('talos/linux_config.py',), 16:55:26 INFO - 'default_actions': ('clobber', 16:55:26 INFO - 'read-buildbot-config', 16:55:26 INFO - 'download-and-extract', 16:55:26 INFO - 'populate-webroot', 16:55:26 INFO - 'create-virtualenv', 16:55:26 INFO - 'install', 16:55:26 INFO - 'setup-mitmproxy', 16:55:26 INFO - 'run-tests'), 16:55:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:55:26 INFO - 'disable_ccov_upload': False, 16:55:26 INFO - 'download_minidump_stackwalk': True, 16:55:26 INFO - 'download_symbols': 'ondemand', 16:55:26 INFO - 'enable_stylo': False, 16:55:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:55:26 INFO - 'tooltool.py': '/tools/tooltool.py', 16:55:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:55:26 INFO - '/tools/misc-python/virtualenv.py')}, 16:55:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:55:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:55:26 INFO - 'gecko_profile': False, 16:55:26 INFO - 'gecko_profile_interval': 0, 16:55:26 INFO - 'installer_path': 'installer.exe', 16:55:26 INFO - 'log_level': 'info', 16:55:26 INFO - 'log_name': 'talos', 16:55:26 INFO - 'log_to_console': True, 16:55:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:55:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:55:26 INFO - 'opt_config_files': (), 16:55:26 INFO - 'pip_index': False, 16:55:26 INFO - 'suite': 'dromaeojs-e10s', 16:55:26 INFO - 'system_bits': '32', 16:55:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:55:26 INFO - 'title': 'talos-linux64-ix-062', 16:55:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:55:26 INFO - 'use_talos_json': True, 16:55:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:55:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:55:26 INFO - 'work_dir': 'build'} 16:55:26 INFO - [mozharness: 2017-07-27 23:55:26.460882Z] Running clobber step. 16:55:26 INFO - Running pre-action listener: _resource_record_pre_action 16:55:26 INFO - Running main action method: clobber 16:55:26 INFO - rmtree: /builds/slave/test/build 16:55:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:55:27 INFO - Running post-action listener: _resource_record_post_action 16:55:27 INFO - [mozharness: 2017-07-27 23:55:27.557524Z] Finished clobber step (success) 16:55:27 INFO - [mozharness: 2017-07-27 23:55:27.557627Z] Running read-buildbot-config step. 16:55:27 INFO - Running pre-action listener: _resource_record_pre_action 16:55:27 INFO - Running main action method: read_buildbot_config 16:55:27 INFO - Using buildbot properties: 16:55:27 INFO - { 16:55:27 INFO - "project": "", 16:55:27 INFO - "product": "firefox", 16:55:27 INFO - "who": "kwierso@gmail.com", 16:55:27 INFO - "installer_path": "public/build/target.tar.bz2", 16:55:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:55:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:55:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:55:27 INFO - "stage_platform": "linux64", 16:55:27 INFO - "basedir": "/builds/slave/test", 16:55:27 INFO - "buildnumber": 1039, 16:55:27 INFO - "platform": "ubuntu64_hw", 16:55:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:55:27 INFO - "slavebuilddir": "test", 16:55:27 INFO - "taskId": "QfFlSEK3S1GuJxCOVLVGDw", 16:55:27 INFO - "branch": "mozilla-central", 16:55:27 INFO - "script_repo_revision": "production", 16:55:27 INFO - "revision": "a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c", 16:55:27 INFO - "slavename": "talos-linux64-ix-062", 16:55:27 INFO - "repo_path": "mozilla-central" 16:55:27 INFO - } 16:55:27 INFO - Finding installer, test and symbols from parent task. 16:55:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QfFlSEK3S1GuJxCOVLVGDw'}, attempt #1 16:55:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QfFlSEK3S1GuJxCOVLVGDw'}, attempt #1 16:55:28 INFO - Task dependencies: M4-l_DlIR0urDflX_RShmA 16:55:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA'}, attempt #1 16:55:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 16:55:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/test_packages.json 16:55:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.crashreporter-symbols.zip 16:55:28 INFO - Running post-action listener: _resource_record_post_action 16:55:28 INFO - [mozharness: 2017-07-27 23:55:28.982819Z] Finished read-buildbot-config step (success) 16:55:28 INFO - [mozharness: 2017-07-27 23:55:28.983039Z] Running download-and-extract step. 16:55:28 INFO - Running pre-action listener: _resource_record_pre_action 16:55:28 INFO - Running main action method: download_and_extract 16:55:28 INFO - mkdir: /builds/slave/test/build/tests 16:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:55:28 INFO - trying https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.test_packages.json 16:55:28 INFO - Downloading https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:55:29 INFO - Downloaded 989 bytes. 16:55:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:55:29 INFO - Using the following test package requirements: 16:55:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:55:29 INFO - u'common': [u'target.common.tests.zip'], 16:55:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:55:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:55:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:55:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:55:29 INFO - u'mozbase': [u'target.common.tests.zip'], 16:55:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:55:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:55:29 INFO - u'web-platform': [u'target.common.tests.zip', 16:55:29 INFO - u'target.web-platform.tests.tar.gz'], 16:55:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:55:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:55:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip 16:55:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:55:29 INFO - Fetch https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip into memory 16:55:32 INFO - Content-Length response header: 72561184 16:55:32 INFO - Bytes received: 72561184 16:55:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:55:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip 16:55:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:55:43 INFO - Fetch https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip into memory 16:55:44 INFO - Content-Length response header: 72561184 16:55:44 INFO - Bytes received: 72561184 16:55:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.talos.tests.zip 16:55:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:55:56 INFO - Fetch https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.talos.tests.zip into memory 16:55:58 INFO - Content-Length response header: 14496465 16:55:58 INFO - Bytes received: 14496465 16:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:55:58 INFO - trying https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 16:55:58 INFO - Downloading https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:55:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:56:00 INFO - Downloaded 62184176 bytes. 16:56:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 16:56:00 INFO - mkdir: /builds/slave/test/properties 16:56:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:56:00 INFO - Writing to file /builds/slave/test/properties/build_url 16:56:00 INFO - Contents: 16:56:00 INFO - build_url:https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 16:56:00 INFO - Running post-action listener: _resource_record_post_action 16:56:00 INFO - Running post-action listener: set_extra_try_arguments 16:56:00 INFO - [mozharness: 2017-07-27 23:56:00.222176Z] Finished download-and-extract step (success) 16:56:00 INFO - [mozharness: 2017-07-27 23:56:00.222305Z] Running populate-webroot step. 16:56:00 INFO - Running pre-action listener: _resource_record_pre_action 16:56:00 INFO - Running main action method: populate_webroot 16:56:00 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:56:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:56:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:56:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:56:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:56:00 INFO - 'tests': ['damp', 'tps']}, 16:56:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:56:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:56:00 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:56:00 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:56:00 INFO - 'other-e10s': {'tests': ['a11yr', 16:56:00 INFO - 'ts_paint', 16:56:00 INFO - 'tpaint', 16:56:00 INFO - 'sessionrestore', 16:56:00 INFO - 'sessionrestore_many_windows', 16:56:00 INFO - 'sessionrestore_no_auto_restore', 16:56:00 INFO - 'tabpaint']}, 16:56:00 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:56:00 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:56:00 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:56:00 INFO - 'talos_options': ['--mitmproxy', 16:56:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:56:00 INFO - '--firstNonBlankPaint'], 16:56:00 INFO - 'tests': ['quantum_pageload_google', 16:56:00 INFO - 'quantum_pageload_youtube', 16:56:00 INFO - 'quantum_pageload_amazon', 16:56:00 INFO - 'quantum_pageload_facebook']}, 16:56:00 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:56:00 INFO - 'talos_options': ['--stylo', 16:56:00 INFO - '--mitmproxy', 16:56:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:56:00 INFO - '--firstNonBlankPaint'], 16:56:00 INFO - 'tests': ['quantum_pageload_google', 16:56:00 INFO - 'quantum_pageload_youtube', 16:56:00 INFO - 'quantum_pageload_amazon', 16:56:00 INFO - 'quantum_pageload_facebook']}, 16:56:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:56:00 INFO - 'tsvgr_opacity', 16:56:00 INFO - 'tart', 16:56:00 INFO - 'tscrollx', 16:56:00 INFO - 'cart', 16:56:00 INFO - 'tsvg_static']}, 16:56:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:56:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:56:00 INFO - 'talos_options': ['--xperf_path', 16:56:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:56:00 INFO - 'tests': ['tp5n']}}} 16:56:00 INFO - Running post-action listener: _resource_record_post_action 16:56:00 INFO - [mozharness: 2017-07-27 23:56:00.231554Z] Finished populate-webroot step (success) 16:56:00 INFO - [mozharness: 2017-07-27 23:56:00.231645Z] Running create-virtualenv step. 16:56:00 INFO - Running pre-action listener: _resource_record_pre_action 16:56:00 INFO - Running main action method: create_virtualenv 16:56:00 INFO - Creating virtualenv /builds/slave/test/build/venv 16:56:00 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 16:56:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:56:00 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:56:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:56:00 INFO - Using real prefix '/usr' 16:56:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:56:01 INFO - Installing distribute.............................................................................................................................................................................................done. 16:56:03 INFO - Installing pip.................done. 16:56:03 INFO - Return code: 0 16:56:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:56:03 INFO - Reading from file tmpfile_stdout 16:56:03 INFO - Output received: 16:56:03 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:56:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:56:03 INFO - Using env: {'DISPLAY': ':0', 16:56:03 INFO - 'HOME': '/home/cltbld', 16:56:03 INFO - 'LANG': 'en_US.UTF-8', 16:56:03 INFO - 'LANGUAGE': 'en_US:en', 16:56:03 INFO - 'LOGNAME': 'cltbld', 16:56:03 INFO - 'MAIL': '/var/mail/cltbld', 16:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:03 INFO - 'MOZ_NO_REMOTE': '1', 16:56:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:03 INFO - 'NO_EM_RESTART': '1', 16:56:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:03 INFO - 'PWD': '/builds/slave/test', 16:56:03 INFO - 'SHELL': '/bin/bash', 16:56:03 INFO - 'SHLVL': '1', 16:56:03 INFO - 'TERM': 'linux', 16:56:03 INFO - 'TMOUT': '86400', 16:56:03 INFO - 'USER': 'cltbld', 16:56:03 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:03 INFO - '_': '/tools/buildbot/bin/python'} 16:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:03 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 16:56:03 INFO - Cleaning up... 16:56:03 INFO - Return code: 0 16:56:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:56:03 INFO - Using env: {'DISPLAY': ':0', 16:56:03 INFO - 'HOME': '/home/cltbld', 16:56:03 INFO - 'LANG': 'en_US.UTF-8', 16:56:03 INFO - 'LANGUAGE': 'en_US:en', 16:56:03 INFO - 'LOGNAME': 'cltbld', 16:56:03 INFO - 'MAIL': '/var/mail/cltbld', 16:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:03 INFO - 'MOZ_NO_REMOTE': '1', 16:56:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:03 INFO - 'NO_EM_RESTART': '1', 16:56:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:03 INFO - 'PWD': '/builds/slave/test', 16:56:03 INFO - 'SHELL': '/bin/bash', 16:56:03 INFO - 'SHLVL': '1', 16:56:03 INFO - 'TERM': 'linux', 16:56:03 INFO - 'TMOUT': '86400', 16:56:03 INFO - 'USER': 'cltbld', 16:56:03 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:03 INFO - '_': '/tools/buildbot/bin/python'} 16:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:03 INFO - Downloading/unpacking psutil>=3.1.1 16:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:56:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:56:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:56:06 INFO - Installing collected packages: psutil 16:56:06 INFO - Running setup.py install for psutil 16:56:06 INFO - building 'psutil._psutil_linux' extension 16:56:06 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 16:56:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:56:07 INFO - building 'psutil._psutil_posix' extension 16:56:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:56:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:56:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:56:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:56:07 INFO - Successfully installed psutil 16:56:07 INFO - Cleaning up... 16:56:07 INFO - Return code: 0 16:56:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:56:07 INFO - Using env: {'DISPLAY': ':0', 16:56:07 INFO - 'HOME': '/home/cltbld', 16:56:07 INFO - 'LANG': 'en_US.UTF-8', 16:56:07 INFO - 'LANGUAGE': 'en_US:en', 16:56:07 INFO - 'LOGNAME': 'cltbld', 16:56:07 INFO - 'MAIL': '/var/mail/cltbld', 16:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:07 INFO - 'MOZ_NO_REMOTE': '1', 16:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:07 INFO - 'NO_EM_RESTART': '1', 16:56:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:07 INFO - 'PWD': '/builds/slave/test', 16:56:07 INFO - 'SHELL': '/bin/bash', 16:56:07 INFO - 'SHLVL': '1', 16:56:07 INFO - 'TERM': 'linux', 16:56:07 INFO - 'TMOUT': '86400', 16:56:07 INFO - 'USER': 'cltbld', 16:56:07 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:07 INFO - '_': '/tools/buildbot/bin/python'} 16:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:56:09 INFO - Installing collected packages: mozsystemmonitor 16:56:09 INFO - Running setup.py install for mozsystemmonitor 16:56:09 INFO - Successfully installed mozsystemmonitor 16:56:09 INFO - Cleaning up... 16:56:09 INFO - Return code: 0 16:56:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:09 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/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:56:09 INFO - Using env: {'DISPLAY': ':0', 16:56:09 INFO - 'HOME': '/home/cltbld', 16:56:09 INFO - 'LANG': 'en_US.UTF-8', 16:56:09 INFO - 'LANGUAGE': 'en_US:en', 16:56:09 INFO - 'LOGNAME': 'cltbld', 16:56:09 INFO - 'MAIL': '/var/mail/cltbld', 16:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:09 INFO - 'MOZ_NO_REMOTE': '1', 16:56:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:09 INFO - 'NO_EM_RESTART': '1', 16:56:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:09 INFO - 'PWD': '/builds/slave/test', 16:56:09 INFO - 'SHELL': '/bin/bash', 16:56:09 INFO - 'SHLVL': '1', 16:56:09 INFO - 'TERM': 'linux', 16:56:09 INFO - 'TMOUT': '86400', 16:56:09 INFO - 'USER': 'cltbld', 16:56:09 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:09 INFO - '_': '/tools/buildbot/bin/python'} 16:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:09 INFO - Downloading/unpacking jsonschema==2.5.1 16:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:56:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:10 INFO - Downloading functools32-3.2.3-2.tar.gz 16:56:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:56:10 INFO - warning: no files found matching '*.txt' 16:56:10 INFO - no previously-included directories found matching 'build' 16:56:10 INFO - no previously-included directories found matching 'dist' 16:56:10 INFO - no previously-included directories found matching '.git*' 16:56:10 INFO - Installing collected packages: jsonschema, functools32 16:56:10 INFO - Running setup.py install for functools32 16:56:10 INFO - warning: no files found matching '*.txt' 16:56:10 INFO - no previously-included directories found matching 'build' 16:56:10 INFO - no previously-included directories found matching 'dist' 16:56:10 INFO - no previously-included directories found matching '.git*' 16:56:10 INFO - Successfully installed jsonschema functools32 16:56:10 INFO - Cleaning up... 16:56:10 INFO - Return code: 0 16:56:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:56:10 INFO - Using env: {'DISPLAY': ':0', 16:56:10 INFO - 'HOME': '/home/cltbld', 16:56:10 INFO - 'LANG': 'en_US.UTF-8', 16:56:10 INFO - 'LANGUAGE': 'en_US:en', 16:56:10 INFO - 'LOGNAME': 'cltbld', 16:56:10 INFO - 'MAIL': '/var/mail/cltbld', 16:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:10 INFO - 'MOZ_NO_REMOTE': '1', 16:56:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:10 INFO - 'NO_EM_RESTART': '1', 16:56:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:10 INFO - 'PWD': '/builds/slave/test', 16:56:10 INFO - 'SHELL': '/bin/bash', 16:56:10 INFO - 'SHLVL': '1', 16:56:10 INFO - 'TERM': 'linux', 16:56:10 INFO - 'TMOUT': '86400', 16:56:10 INFO - 'USER': 'cltbld', 16:56:10 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:10 INFO - '_': '/tools/buildbot/bin/python'} 16:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:56:11 INFO - Cleaning up... 16:56:11 INFO - Return code: 0 16:56:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:11 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/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:56:11 INFO - Using env: {'DISPLAY': ':0', 16:56:11 INFO - 'HOME': '/home/cltbld', 16:56:11 INFO - 'LANG': 'en_US.UTF-8', 16:56:11 INFO - 'LANGUAGE': 'en_US:en', 16:56:11 INFO - 'LOGNAME': 'cltbld', 16:56:11 INFO - 'MAIL': '/var/mail/cltbld', 16:56:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:11 INFO - 'MOZ_NO_REMOTE': '1', 16:56:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:11 INFO - 'NO_EM_RESTART': '1', 16:56:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:11 INFO - 'PWD': '/builds/slave/test', 16:56:11 INFO - 'SHELL': '/bin/bash', 16:56:11 INFO - 'SHLVL': '1', 16:56:11 INFO - 'TERM': 'linux', 16:56:11 INFO - 'TMOUT': '86400', 16:56:11 INFO - 'USER': 'cltbld', 16:56:11 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:11 INFO - '_': '/tools/buildbot/bin/python'} 16:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:11 INFO - Downloading/unpacking blobuploader==1.2.4 16:56:11 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 16:56:11 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 16:56:11 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 16:56:11 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 16:56:12 INFO - Downloading blobuploader-1.2.4.tar.gz 16:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:56:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:56:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:13 INFO - Downloading docopt-0.6.1.tar.gz 16:56:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:56:13 INFO - Installing collected packages: blobuploader, requests, docopt 16:56:13 INFO - Running setup.py install for blobuploader 16:56:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:56:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:56:13 INFO - Running setup.py install for requests 16:56:13 INFO - Running setup.py install for docopt 16:56:13 INFO - Successfully installed blobuploader requests docopt 16:56:13 INFO - Cleaning up... 16:56:13 INFO - Return code: 0 16:56:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:13 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/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:13 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:56:13 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:56:13 INFO - Using env: {'DISPLAY': ':0', 16:56:13 INFO - 'HOME': '/home/cltbld', 16:56:13 INFO - 'LANG': 'en_US.UTF-8', 16:56:13 INFO - 'LANGUAGE': 'en_US:en', 16:56:13 INFO - 'LOGNAME': 'cltbld', 16:56:13 INFO - 'MAIL': '/var/mail/cltbld', 16:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:13 INFO - 'MOZ_NO_REMOTE': '1', 16:56:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:13 INFO - 'NO_EM_RESTART': '1', 16:56:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:13 INFO - 'PWD': '/builds/slave/test', 16:56:13 INFO - 'SHELL': '/bin/bash', 16:56:13 INFO - 'SHLVL': '1', 16:56:13 INFO - 'TERM': 'linux', 16:56:13 INFO - 'TMOUT': '86400', 16:56:13 INFO - 'USER': 'cltbld', 16:56:13 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:13 INFO - '_': '/tools/buildbot/bin/python'} 16:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:56:13 INFO - Running setup.py (path:/tmp/pip-3Wjgaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:56:13 INFO - Running setup.py (path:/tmp/pip-3pR0wN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:56:14 INFO - Running setup.py (path:/tmp/pip-gNGV5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:56:14 INFO - Running setup.py (path:/tmp/pip-3F2adN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:56:14 INFO - Running setup.py (path:/tmp/pip-zXNIOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:56:14 INFO - Running setup.py (path:/tmp/pip-aN_SyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:56:14 INFO - Running setup.py (path:/tmp/pip-uPiXzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:56:14 INFO - Running setup.py (path:/tmp/pip-fv_Csi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:56:14 INFO - Running setup.py (path:/tmp/pip-bcTTvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:56:14 INFO - Running setup.py (path:/tmp/pip-VcuC3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:56:14 INFO - Running setup.py (path:/tmp/pip-zqKwE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:56:14 INFO - Running setup.py (path:/tmp/pip-k1HFti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:56:14 INFO - Running setup.py (path:/tmp/pip-KMWW59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:56:14 INFO - Running setup.py (path:/tmp/pip-SAzQyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:14 INFO - Running setup.py (path:/tmp/pip-A7Bj2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:56:14 INFO - Running setup.py (path:/tmp/pip-0NJEVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:56:15 INFO - Running setup.py (path:/tmp/pip-D4SYHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:56:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:56:15 INFO - Running setup.py install for manifestparser 16:56:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:56:15 INFO - Running setup.py install for mozcrash 16:56:15 INFO - Running setup.py install for mozdebug 16:56:15 INFO - Running setup.py install for mozdevice 16:56:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:56:15 INFO - Running setup.py install for mozfile 16:56:15 INFO - Running setup.py install for mozhttpd 16:56:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:56:15 INFO - Running setup.py install for mozinfo 16:56:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:56:15 INFO - Running setup.py install for mozInstall 16:56:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:56:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:56:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:56:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:56:15 INFO - Running setup.py install for mozleak 16:56:16 INFO - Running setup.py install for mozlog 16:56:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:56:16 INFO - Running setup.py install for moznetwork 16:56:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:56:16 INFO - Running setup.py install for mozprocess 16:56:16 INFO - Running setup.py install for mozprofile 16:56:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:56:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:56:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:56:16 INFO - Running setup.py install for mozrunner 16:56:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:56:16 INFO - Running setup.py install for mozscreenshot 16:56:16 INFO - Running setup.py install for moztest 16:56:16 INFO - Running setup.py install for mozversion 16:56:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:56:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:56:16 INFO - Cleaning up... 16:56:16 INFO - Return code: 0 16:56:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:56:16 INFO - Using env: {'DISPLAY': ':0', 16:56:16 INFO - 'HOME': '/home/cltbld', 16:56:16 INFO - 'LANG': 'en_US.UTF-8', 16:56:16 INFO - 'LANGUAGE': 'en_US:en', 16:56:16 INFO - 'LOGNAME': 'cltbld', 16:56:16 INFO - 'MAIL': '/var/mail/cltbld', 16:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:16 INFO - 'MOZ_NO_REMOTE': '1', 16:56:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:16 INFO - 'NO_EM_RESTART': '1', 16:56:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:16 INFO - 'PWD': '/builds/slave/test', 16:56:16 INFO - 'SHELL': '/bin/bash', 16:56:16 INFO - 'SHLVL': '1', 16:56:16 INFO - 'TERM': 'linux', 16:56:16 INFO - 'TMOUT': '86400', 16:56:16 INFO - 'USER': 'cltbld', 16:56:16 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:16 INFO - '_': '/tools/buildbot/bin/python'} 16:56:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:56:17 INFO - Running setup.py (path:/tmp/pip-xd8uEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:56:17 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)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:56:17 INFO - Running setup.py (path:/tmp/pip-IYPK6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:56:17 INFO - Running setup.py (path:/tmp/pip-DDgJYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:56:17 INFO - Running setup.py (path:/tmp/pip-6abxCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:56:17 INFO - Running setup.py (path:/tmp/pip-7pXNTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:56:17 INFO - Running setup.py (path:/tmp/pip-9jpept-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:56:17 INFO - Running setup.py (path:/tmp/pip-lgbNnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:56:17 INFO - Running setup.py (path:/tmp/pip-jHbMVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:56:17 INFO - Running setup.py (path:/tmp/pip-D9BKVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:56:17 INFO - Running setup.py (path:/tmp/pip-BHfHaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:56:17 INFO - Running setup.py (path:/tmp/pip-ZYi4RO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:56:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:56:17 INFO - Running setup.py (path:/tmp/pip-u0XyZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 16:56:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:56:18 INFO - Running setup.py (path:/tmp/pip-BJxynF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:56:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:56:18 INFO - Running setup.py (path:/tmp/pip-XfownP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 16:56:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:18 INFO - Running setup.py (path:/tmp/pip-kOlpNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:56:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:56:18 INFO - Running setup.py (path:/tmp/pip-FxDHNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 16:56:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:56:18 INFO - Running setup.py (path:/tmp/pip-qm30vV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:56:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:19 INFO - Downloading blessings-1.6.tar.gz 16:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:56:19 INFO - Installing collected packages: blessings 16:56:19 INFO - Running setup.py install for blessings 16:56:19 INFO - Successfully installed blessings 16:56:19 INFO - Cleaning up... 16:56:19 INFO - Return code: 0 16:56:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:56:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:19 INFO - Reading from file tmpfile_stdout 16:56:19 INFO - Current package versions: 16:56:19 INFO - argparse == 1.2.1 16:56:19 INFO - blessings == 1.6 16:56:19 INFO - blobuploader == 1.2.4 16:56:19 INFO - docopt == 0.6.1 16:56:19 INFO - functools32 == 3.2.3-2 16:56:19 INFO - jsonschema == 2.5.1 16:56:19 INFO - manifestparser == 1.1 16:56:19 INFO - mozInstall == 1.13 16:56:19 INFO - mozcrash == 1.0 16:56:19 INFO - mozdebug == 0.1 16:56:19 INFO - mozdevice == 0.51 16:56:19 INFO - mozfile == 1.2 16:56:19 INFO - mozhttpd == 0.7 16:56:19 INFO - mozinfo == 0.10 16:56:19 INFO - mozleak == 0.1 16:56:19 INFO - mozlog == 3.5 16:56:19 INFO - moznetwork == 0.27 16:56:19 INFO - mozprocess == 0.25 16:56:19 INFO - mozprofile == 0.28 16:56:19 INFO - mozrunner == 6.13 16:56:19 INFO - mozscreenshot == 0.1 16:56:19 INFO - mozsystemmonitor == 0.3 16:56:19 INFO - moztest == 0.8 16:56:19 INFO - mozversion == 1.4 16:56:19 INFO - psutil == 3.1.1 16:56:19 INFO - requests == 1.2.3 16:56:19 INFO - wsgiref == 0.1.2 16:56:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:19 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/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:19 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:56:19 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:56:19 INFO - Using env: {'DISPLAY': ':0', 16:56:19 INFO - 'HOME': '/home/cltbld', 16:56:19 INFO - 'LANG': 'en_US.UTF-8', 16:56:19 INFO - 'LANGUAGE': 'en_US:en', 16:56:19 INFO - 'LOGNAME': 'cltbld', 16:56:19 INFO - 'MAIL': '/var/mail/cltbld', 16:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:19 INFO - 'MOZ_NO_REMOTE': '1', 16:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:19 INFO - 'NO_EM_RESTART': '1', 16:56:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:19 INFO - 'PWD': '/builds/slave/test', 16:56:19 INFO - 'SHELL': '/bin/bash', 16:56:19 INFO - 'SHLVL': '1', 16:56:19 INFO - 'TERM': 'linux', 16:56:19 INFO - 'TMOUT': '86400', 16:56:19 INFO - 'USER': 'cltbld', 16:56:19 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:19 INFO - '_': '/tools/buildbot/bin/python'} 16:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:20 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)) 16:56:20 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)) 16:56:20 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)) 16:56:20 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)) 16:56:20 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)) 16:56:20 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)) 16:56:20 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)) 16:56:20 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)) 16:56:20 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)) 16:56:20 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:56:20 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 16:56:20 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 16:56:20 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 16:56:20 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 16:56:21 INFO - Downloading marionette_driver-1.4.0.tar.gz 16:56:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 16:56:21 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 16:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:56:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:56:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:56:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:56:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:56:21 INFO - Installing collected packages: marionette-driver, simplejson 16:56:21 INFO - Running setup.py install for marionette-driver 16:56:21 INFO - Running setup.py install for simplejson 16:56:21 INFO - building 'simplejson._speedups' extension 16:56:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:56:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:56:22 INFO - Successfully installed marionette-driver simplejson 16:56:22 INFO - Cleaning up... 16:56:22 INFO - Return code: 0 16:56:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:56:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:22 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/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 0x1a631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c30a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', '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 16:56:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:56:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:56:22 INFO - Using env: {'DISPLAY': ':0', 16:56:22 INFO - 'HOME': '/home/cltbld', 16:56:22 INFO - 'LANG': 'en_US.UTF-8', 16:56:22 INFO - 'LANGUAGE': 'en_US:en', 16:56:22 INFO - 'LOGNAME': 'cltbld', 16:56:22 INFO - 'MAIL': '/var/mail/cltbld', 16:56:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:22 INFO - 'MOZ_NO_REMOTE': '1', 16:56:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:22 INFO - 'NO_EM_RESTART': '1', 16:56:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:22 INFO - 'PWD': '/builds/slave/test', 16:56:22 INFO - 'SHELL': '/bin/bash', 16:56:22 INFO - 'SHLVL': '1', 16:56:22 INFO - 'TERM': 'linux', 16:56:22 INFO - 'TMOUT': '86400', 16:56:22 INFO - 'USER': 'cltbld', 16:56:22 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:22 INFO - '_': '/tools/buildbot/bin/python'} 16:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:56:23 INFO - Cleaning up... 16:56:23 INFO - Return code: 0 16:56:23 INFO - Running post-action listener: _resource_record_post_action 16:56:23 INFO - Running post-action listener: _start_resource_monitoring 16:56:23 INFO - Starting resource monitoring. 16:56:23 INFO - [mozharness: 2017-07-27 23:56:23.067418Z] Finished create-virtualenv step (success) 16:56:23 INFO - [mozharness: 2017-07-27 23:56:23.068418Z] Running install step. 16:56:23 INFO - Running pre-action listener: _resource_record_pre_action 16:56:23 INFO - Running main action method: install 16:56:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:23 INFO - Reading from file tmpfile_stdout 16:56:23 INFO - Detecting whether we're running mozinstall >=1.0... 16:56:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:56:23 INFO - Reading from file tmpfile_stdout 16:56:23 INFO - Output received: 16:56:23 INFO - Usage: mozinstall [options] installer 16:56:23 INFO - Options: 16:56:23 INFO - -h, --help show this help message and exit 16:56:23 INFO - -d DEST, --destination=DEST 16:56:23 INFO - Directory to install application into. [default: 16:56:23 INFO - "/builds/slave/test"] 16:56:23 INFO - --app=APP Application being installed. [default: firefox] 16:56:23 INFO - mkdir: /builds/slave/test/build/application 16:56:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:56:41 INFO - Reading from file tmpfile_stdout 16:56:41 INFO - Output received: 16:56:41 INFO - /builds/slave/test/build/application/firefox/firefox 16:56:41 INFO - Running post-action listener: _resource_record_post_action 16:56:41 INFO - [mozharness: 2017-07-27 23:56:41.526235Z] Finished install step (success) 16:56:41 INFO - [mozharness: 2017-07-27 23:56:41.526347Z] Running setup-mitmproxy step. 16:56:41 INFO - Running pre-action listener: _resource_record_pre_action 16:56:41 INFO - Running main action method: setup_mitmproxy 16:56:41 INFO - Skipping: mitmproxy is not required 16:56:41 INFO - Running post-action listener: _resource_record_post_action 16:56:41 INFO - [mozharness: 2017-07-27 23:56:41.526829Z] Finished setup-mitmproxy step (success) 16:56:41 INFO - [mozharness: 2017-07-27 23:56:41.526921Z] Running run-tests step. 16:56:41 INFO - Running pre-action listener: _resource_record_pre_action 16:56:41 INFO - Running pre-action listener: _set_gcov_prefix 16:56:41 INFO - Running main action method: run_tests 16:56:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:56:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:56:41 INFO - Python 2.7.3 16:56:41 INFO - Return code: 0 16:56:41 INFO - grabbing minidump binary from tooltool 16:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:56:41 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 0x1dd0eb0>, '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 0x1dd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd2240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:56:41 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 16:56:41 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 16:56:41 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 16:56:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:56:41 INFO - Return code: 0 16:56:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:56:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:56:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:56:41 INFO - ENV: RUST_BACKTRACE is now 1 16:56:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:56:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:56:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 16:56:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 16:56:41 INFO - Using env: {'DISPLAY': ':0', 16:56:41 INFO - 'HOME': '/home/cltbld', 16:56:41 INFO - 'LANG': 'en_US.UTF-8', 16:56:41 INFO - 'LANGUAGE': 'en_US:en', 16:56:41 INFO - 'LOGNAME': 'cltbld', 16:56:41 INFO - 'MAIL': '/var/mail/cltbld', 16:56:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:56:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:41 INFO - 'MOZ_NO_REMOTE': '1', 16:56:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:56:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:41 INFO - 'NO_EM_RESTART': '1', 16:56:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:41 INFO - 'PWD': '/builds/slave/test', 16:56:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:56:41 INFO - 'RUST_BACKTRACE': '1', 16:56:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:56:41 INFO - 'SHELL': '/bin/bash', 16:56:41 INFO - 'SHLVL': '1', 16:56:41 INFO - 'TERM': 'linux', 16:56:41 INFO - 'TMOUT': '86400', 16:56:41 INFO - 'USER': 'cltbld', 16:56:41 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150', 16:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:41 INFO - '_': '/tools/buildbot/bin/python'} 16:56:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 16:56:42 INFO - mozversion application_buildid: 20170727231007 16:56:42 INFO - mozversion application_changeset: a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c 16:56:42 INFO - mozversion application_display_name: Nightly 16:56:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:56:42 INFO - mozversion application_name: Firefox 16:56:42 INFO - mozversion application_remotingname: firefox 16:56:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:56:42 INFO - mozversion application_vendor: Mozilla 16:56:42 INFO - mozversion application_version: 56.0a1 16:56:42 INFO - mozversion platform_buildid: 20170727231007 16:56:42 INFO - mozversion platform_changeset: a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c 16:56:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:56:42 INFO - mozversion platform_version: 56.0a1 16:56:42 INFO - using testdate: 1501199802 16:56:42 INFO - actual date: 1501199802 16:56:42 INFO - starting webserver on 'localhost:51895' 16:56:42 INFO - SUITE-START | Running 2 tests 16:56:42 INFO - TEST-START | dromaeo_css 16:56:42 INFO - Initialising browser for dromaeo_css test... 16:56:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51895/getInfo.html -profile /tmp/tmpQ0ozYx/profile 16:56:42 INFO - TEST-INFO | started process 3330 (/builds/slave/test/build/application/firefox/firefox http://localhost:51895/getInfo.html) 16:56:51 INFO - TEST-INFO | 3330: exit 0 16:56:51 INFO - Browser initialized. 16:56:51 INFO - Running cycle 1/1 for dromaeo_css test... 16:56:51 INFO - TEST-INFO | started process 3538 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ0ozYx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:56:52 INFO - PID 3538 | 16:56:52 INFO - PID 3538 | (/builds/slave/test/build/application/firefox/firefox:3586): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:56:52 INFO - PID 3538 | 16:57:02 INFO - PID 3538 | RSS: Main: 171114496 16:57:02 INFO - PID 3538 | 17:00:02 INFO - PID 3538 | Cycle 1(1): loaded http://localhost:51895/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51895/tests/dromaeo/cssquery-ext.html) 17:00:03 INFO - PID 3538 | RSS: Main: 172670976 17:00:03 INFO - PID 3538 | 17:03:05 INFO - PID 3538 | Cycle 1(1): loaded http://localhost:51895/tests/dromaeo/cssquery-ext.html (next: http://localhost:51895/tests/dromaeo/cssquery-jquery.html) 17:03:06 INFO - PID 3538 | RSS: Main: 170471424 17:03:06 INFO - PID 3538 | 17:06:08 INFO - PID 3538 | Cycle 1(1): loaded http://localhost:51895/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51895/tests/dromaeo/cssquery-mootools.html) 17:06:08 INFO - PID 3538 | RSS: Main: 172314624 17:06:08 INFO - PID 3538 | 17:09:10 INFO - PID 3538 | Cycle 1(1): loaded http://localhost:51895/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51895/tests/dromaeo/cssquery-prototype.html) 17:09:11 INFO - PID 3538 | RSS: Main: 170774528 17:09:11 INFO - PID 3538 | 17:12:15 INFO - PID 3538 | Cycle 1(1): loaded http://localhost:51895/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51895/tests/dromaeo/cssquery-yui.html) 17:12:15 INFO - PID 3538 | RSS: Main: 172048384 17:12:15 INFO - PID 3538 | 17:15:17 INFO - PID 3538 | Cycle 1(1): loaded http://localhost:51895/tests/dromaeo/cssquery-yui.html (next: http://localhost:51895/tests/dromaeo/cssquery-dojo.html) 17:15:17 INFO - PID 3538 | RSS: Main: 174358528 17:15:17 INFO - PID 3538 | 17:15:17 INFO - PID 3538 | __start_tp_report 17:15:17 INFO - PID 3538 | _x_x_mozilla_page_load 17:15:17 INFO - PID 3538 | _x_x_mozilla_page_load_details 17:15:17 INFO - PID 3538 | |i|pagename|runs| 17:15:17 INFO - PID 3538 | |0;dojo.html;3113.7976031557946;3154.05378386484;3182.6339970903323;3200.919977000575;3222.983885080574;6121.785737499183;6183.25800903891;6197.101420294055;6209.2859321178075;6210.937890621092;6116.6941652917285;6165.722542485588;6211.844703882405;6212.968935155318;6223.626582405048;5470.5350045246205;5575.386707462196;5615.4665306796;5674.205611214434;5696.060150075233;5555.3611334696425;5603.691796951166;5604.8038318658855;5623.831285061455;5657;5690.5447564194765;5718.771249150029;5770.798022069229;5780.682062486561;5807.854803629901;5626.381098079208;5641.52047075995;5702.859428114393;5708.5433165347085;5713;5513.862153446155;5527.894421115793;5576.832695019156;5577.609567330289;5759.452851979097;6057;6058.0610005449225;6066.423689749467;6096.055111457731;6119.204503414572;6564.343565643445;6611.3058128896255;6618;6633.933660663428;6650;6518;6541.574797638138;6566.047923051178;6591.373819487141;6629.602223866584;6151.200343955257;6176.567640265183;6203;6215.81352559424;6235.906461403082;6794.592324460449;6802.251752307242;6804;6818.795436136924;6849.452043836481;6398.680065996681;6407.583507072025;6495.7076931538195;6509.349065093547;6523.7064332105165;6531.216826721288;6578.440832529192;6582.44049255809;6660.766873159416;6671.3328667133865;6362.141110950055;6365.108884756138;6371.776987805404;6373.075903993988;6394.520410969103;6606.933930660632;6632.204135503771;6635.071090047397;6639.2696803352555;6662.766803161866;5352.785888564492;5357.11607584752;5369.4362091980565;5400.405955344909;5424.566034717213;4614.700044497097;4621.260598304255;4633.142868569325;4686.226772582486;4688.367070445446;5772.884542309131;5777.797777077866;5858.150568167592;5876.882462350814;5882.499987501024;6395.392437718409;6513.055606937062;6578.177727784027;6598.637074960923;6779.084823548757;5838.299404071538;5844.26946631671;5863.20846685692;5897.410258974069;5910.645361278251;5964.910526342108;5973.940260597338;5988.131720900445;6018.15745795589;6042.154098426224;6438.098666186737;6525.3800888915475;6543.509236807258;6581.3747693969;6603.075569420285;6258.968705156445;6362.109304697438;6627.867442651216;6736.663166841737;6746.662666866637;6348.492120630431;6362.77730279438;6363.172787537591;6364.490840732823;6365.172527571386;6450.258220304725;6461.2892581816905;6463.15978922737;6480.643564603992;6483.610983340826;6523.054157147282;6598.769043083469;6598.967005165136;6600.80197594073;6602.273749887605;6389.9361006389345;6509.707063182263;6522.21733391996;6582.76960306387;6592.472602191909;6407.0709747085475;6412.519061070345;6509;6512.218533775977;6529.151210342625;6369.172007639163;6376.458001069961;6397.744090236339;6412.871742565216;6414.775482858077;6455.386738259858;6470.676466176767;6499.415052645285;6609.768658096944;6629;6255.530835187288;6289.119523266838;6291.182146320949;6299.244090709144;6317.368263173646 17:15:17 INFO - PID 3538 | |1;ext.html;799.2487062161567;842.2209456252964;855.0850589868841;888.5734847273307;890.8574628059507;38667.22665546691;39533.80233098834;39552.406713899276;39572;39581.802090989506;35716.2856742865;35878.46182307261;35956.10109747258;35991.64008359916;36021.279574408494;6830.795076147723;6916.204636466795;6930.757423490179;6953.165620125566;6961.965190174042;6800.455963522931;6902.861942761142;6908.205556361033;6916.827079323007;6935.029095926574;6822.385985261326;6826.283240259776;6834.111565496479;6867.141607299088;6883.242843287235;7399.926000740004;7572.204918483562;7595.810104747398;7690.846183076307;7701.653425595861;7542.321191092773;7627.313541781211;7630.732924347677;7631;7639.427042971799;37287.254254915024;37789.622103778886;38605.227895442215;38750.22499550021;38764;3469.7744646598144;3544.7518673692593;3550.3609350316933;3570.2680950405106;3572.6605972432576;30584.235394115327;30598.235044123852;30690.84654576713;30740.2314942126;30825.229369265722;4760.976195119037;4762.452317983441;4780.737059461728;4802.303665968415;4838.298446725205;172318.1384093084;175723;186965;187281;201159;47772.28341574879;47847.521524785;47922;47939;47956.28065579019;49332;49403;49413.50586494161;49510.50489495059;49755;16087.839121608868;16107.436239731553;16177.19114044293;16259.430919917746;16362;13541.526046588519;13578.388972496261;13591.52429664957;13613.455461781417;13613.659658508419;13911.09577877455;14106.788398174036;14150.504732334524;14162.929185354007;14295.714085718228;5681.659100453986;5692.8861422771315;5743.684097374685;5760.164776107441;5802.796902108406;972.8248915195333;978.2614126334572;986.1863962231188;1006.6225165562914;1007.3401921741271;22122.44693882666;22216.88891555532;22394.32817015492;22455;22770.772292276866;1084.7776205877776;1087.211771465681;1093.6111138853612;1114.6990312615549;1122.7586068995206;11865.050795936304;11874.1094417919;11877.406129693481;15605.219739013004;15897.68204635924;225035;226363;228213;230963;231382;52912.470875291525;53345.733271333396;53676.19485707718;53690.46309536933;53918;1423.985760142406;1453.128123126116;1463.5755630867143;1463.8389777124507;1473.955781326562;24986.250412487654;26538.071167509046;26567.7343226573;26814.195574132806;26898.731012690405;49030;50142.74928625333;50209.497905020486;50328.245076324616;50399;195250;195860;197052;197976.01011994848;198029;30524.542131867594;31071.378572427973;31093.22266943254;31143;34026.319473609896;40255.79872100738;41608.791956040026;42099.7895010523;42765;42895;30323.241918952204;30676.386472270875;30750.84624576863;31074.22314442157;31536.211594710316;28298.2925426866;30143.698563014088;30525.389492210477;30802.845985769927;31076.53385199269;34160.48759268563;34288.142796430286;34431.311373772885;34552;34587.30825383528;30212.69787302099;30758;30807.53788693216;30914.69085309208;31511.36977260396;22400.439989000406;22673.546529069656;22928;23222;23381.298561043197 17:15:17 INFO - PID 3538 | |2;jquery.html;554.2379228560729;567.2087438023959;577.8266520043989;580.8634970781867;585.1807469542639;21706.674399883996;22373;22634.320970370914;22687.319380418594;22705.20531781386;25072;25140.37149071274;25162.496750065035;25167.986561075115;25174.370640733992;6441.935580644192;6602.66986650668;6604.768833090843;6610.338966103399;6612.107365505667;5872.354041055481;6426.228852537702;6441.291457939623;6487.351264873499;6544.869102617945;6389.872202555946;6390.840228994266;6399.008153736178;6415.550911436201;6417.454516366114;6336.017917222838;6356.0783686365685;6356.2054743157105;6431.935680643181;6446.065320528544;5899.351071382144;5901.350851406385;5909.704514774287;5962.165296858443;5967.6121052131475;28491.57262641062;29413.85293073521;29518.37648929308;29689.257768555744;29994.25014374636;1821.3807305516057;1836.6510363031116;1838.090145378033;1846.6860633692304;1848.4731851422414;18357.908210458994;18841.528961776065;18920.905395472935;18954.14706338218;18988.81011189898;2296.0815673730476;2297.5404919016264;2376.9762302376926;2377.084822343393;2384.880755962195;135841;138108;141029.2948535261;141951;142016;14159.150450972975;14746.63133421673;15090.622734431727;15163.848361516464;15557.14435706047;22475.550488990128;23077.88461057684;23339.18312859042;23385.532289354116;23442.64836027461;10424.68725938223;10467.790644187073;10507.527161277609;10567.524461399256;10679.252452328417;9809.656662016796;9861.359011664219;9886.752831179278;9931.950340248397;9987.650432234983;9209.217216536535;9410.482423466638;9432.19826314771;9474.763130921781;9525.904520980175;2537.7589129032713;2653.5886937524715;2658.494885971659;2697.5414179589516;2738.274357295318;910.7859652981545;940.1773448232797;960.1406740966795;966.6713317471953;971.9708408747749;19096;19579;19826.60346793085;24166.637500437508;25235.495290094095;1009.8889122196553;1016.2987538598492;1029.1715169288645;1050.7793363393773;1055.3878750324793;10285.897141028643;10841.566337346576;11286.153538484647;11423.885761142283;11628.825567616492;518235;534775;548183;548795;549288;122142.38928805299;125390.37304813418;127417;127894;128711.35644321906;1220.920640158387;1252.3362617812575;1267.7274386007052;1283.833101696792;1284.5375664760866;17078.914605426893;17575.82424175793;17920;18223.45329640113;18664.44006679802;46444;48554.514454855;51347.48652513576;51989.220161696845;59330.406695934216;65403;68790.31209687839;72150.27849721436;72923.27076729377;74950;20922.267720629705;21465.785342146377;21498.925053746938;21532.461688457915;21852.344429667137;23994.640080398458;24212.51574968526;24215.636765448886;24258.15096471615;24736.381590459663;18547.72178417352;18608.813911860707;18612.16245268939;18845.90577047106;18884.905575472585;15566.688666226386;16213.918930405272;16766.413175538797;17037.744433833253;17224.569385765455;18673.90663046676;18717.625647487246;18734.625307494047;18929.148188331284;19229.326973555857;20687.8965605171;20867.373978780663;20884;20914.790852091286;21132.154713811276;17086.231119599895;18324;18620.16209270559;18732.906335468237;18744.78158919666 17:15:17 INFO - PID 3538 | |3;mootools.html;1292.399034448981;1351.7769568021276;1363.4546181527382;1367.2070199284412;1374.0656353679506;13548.390322435489;14231.217283049427;14260.643483912881;14284.714305713893;14289;13749.931250343734;14155.43378264868;14500.129992200475;14589.343479543419;14794.926025369858;2673.986630066852;2726.3184203949013;2728.113363156972;2733.931651708713;2735.179446166152;2618.5810270356747;2685.2212858271178;2704.3374373278575;2721.2244510314563;2724.387012922087;2533.8479691218495;2584.3539115221197;2664.9600255996174;2713.74219449152;2716.7011628720825;2569.563174260371;2609.5302845487804;2620.1615483045434;2622.632831403605;2643.339165208698;2594.4421949280995;2618.5155746187015;2638.14260365382;2639.1158961747838;2653.204038788375;13557.25435101069;13657.931710341385;13760;13766.724665506734;13785.241811700353;7490.400767938552;7701.460897737161;7743.496672716312;7756;7768.922310776877;4872.366592343008;4929.950700492985;5050.444451110375;5061.924071138936;5093.363329583802;1825.6713791517523;1833.4316361927845;1838.512794109562;1864.683003889342;1871.840893524052;22267.44331391712;22318.442038948833;22397;22412.66381004286;22497.55004899909;6806.693698783567;6842.55523390978;7175.497715159889;7178.2103968563415;7215.386692131227;14916.552503424698;14922.776158357634;14967.550973470814;15023.624409389853;15045.924770376078;7374.889376659355;7422.331990120807;7450.441216908645;7502.549847009197;7523.699052037967;5726.599138060379;6016.126937180898;6260.561760676709;6939.826504337331;7055.717771289091;4915.287283343967;5002.374703162105;5048.444671086178;5072.326132376307;5184.092783762826;1452.4480697335157;1474.2849717886816;1474.3586539855198;1497.7603583426817;1517.4916403004895;806.5846089264033;814.5804910471112;823.3865770001387;845.8012367093728;867.4361664917853;6255.343188965093;6492.026196070533;6545.574537655038;6616.636085015371;6658.600483970978;663.1147418196762;675.2369822101092;690.2993461636443;695.1692727190958;711.3917600451642;4193.958060419418;5030.949690503121;5141.665791723526;5162.483751624807;5391.487808658249;18584.163712632693;19205.43361550201;19330;19616.11727472267;19750;19431.514212144808;19619.215231390586;19703.802961970196;19746.605067898563;19835.10742016613;9738.367006144577;10838.67483975482;11072.889271107186;11107.55569777216;11167.329960202414;7343.559386436831;7758.379329653727;7783.066032076187;7954.085280192852;8005.799855003438;16118.516444506684;16179.67640647157;16596.751048734037;16616.169191540615;16677.833221667628;15401.383944642088;15880.52358429249;16830.990140591603;17283.7407438886;17590.73613895767;10842.078423334106;11435.428228588704;11586.246893952204;11597.942010289895;11628.53485860556;13305.401256943267;13389;13404.664883377993;13434;13451.865481345452;10197.286189966631;10557.102646275152;10570.154387649123;10748.946255268673;10833.945830270799;9579.425234485952;10231.28381013322;10239.334443261461;10281.794364112826;10314.896851031393;8730.17063378978;8816;8893.110688931056;9008.59461324255;9024.684136055206;6347.90478142819;6602.867942641216;6641.535092543476;6671.766488172891;6810.795676129724;6599.142111525471;6670.232923213893;6720;6817.046612455367;6824.112865327475 17:15:17 INFO - PID 3538 | |4;prototype.html;499.9050180465711;504.4261407924852;514.6963291657922;528.8995090932725;532.2840779152042;6203.410675985785;6206.720697568609;6230.532710046742;6260.998240281556;6267.341929097447;5845;5930;6038.607490513114;6061.9393806062035;6099.451049405553;4855.344528488662;4867.342908707333;4874.390701162355;4883.877903052426;4893.657443978923;4740.217864052427;4838.467768545457;4851.175300198957;4888.093643479046;4909.116359055369;4753.691010084333;4755.90488190236;4792.616590672756;4801.759912004403;4828.130936431441;4797.856064318076;4813.638977076698;4824.758762061901;4833.492483289239;4835.66339225933;4280.4221430106845;4330.830675717571;4393.340998850166;4418.3593378960195;4432;6016.96991515044;6026.216591843032;6033;6049.546284028672;6064.484518815906;5533.418991005927;5580.748866300985;5585.916211256835;5649.051077378298;5649.237352957381;6326.49388048955;6362.9045564316575;6439.420452159281;6442.710078046499;6505.284418713938;4808.326834243208;4814.927776083362;4817.9036419271415;4819.108464933998;4822.565969062801;6788.694508747119;6810.182778066664;6843.794686159423;6884.965575172142;6955.339242771928;4808.471068182497;4825.420949486084;4899.975500122477;4921.261810728348;4922.556969872729;4921.803127874845;4946.406431228275;4952.5542701157065;4988.326575912278;5002.724850133205;5371.919421208685;5515.558755299567;5540;5542.972285138549;5573.498385145356;6139.693015349215;6408.10286559882;6465.741370345228;6525.510586706016;6558.868822623521;1650.011735858288;1683.318256106279;1693;1704.7613334133232;1711.4865540337848;998.3460833154296;1189.6431070678761;1198.9940050299692;1200.3398131028075;1207.885250901163;5599.440055994368;5614.9157762633595;5692.544596432358;5722.54219662426;5777.248957635565;6629.7348106075215;6637.668116594151;6672.532922695462;6689.297623749436;6733.720593087298;5722.456366645162;5736.196932429463;5770.913436298459;5792.739326730307;5798.188253644493;3531.7351198660203;3562.305350456636;3569.3396721606587;3591.1560783215923;3607.7474576779373;6362.681865906686;6373.235211774617;6437.066625339393;6446.645434501147;6455.903161452582;6764.801439532073;6777.525573209828;6805.557638753465;6835.521513494107;6848;6311.873762524632;6337.4613157882095;6399.680015999088;6462.418382345518;6469.159009328851;6497.480201583768;6586.670666466754;6602.438792702482;6602.8349291268105;6618.768343107968;6493.188351456068;6535.869282614226;6547.050677651809;6610.10763546915;6635.203775546772;6336.261605652687;6359.20509936258;6423.357664233539;6453.935460645521;6464.547481676237;4926.162552366178;4944.134776414185;4951.801927922843;4959.20652695567;4972.801087956586;6681.231658359351;6731.225909020525;6731.966340168464;6771.492138089761;6818.624975626388;6370.77702280418;6400.23197216337;6415.166028416276;6468.417842394207;6480.578762380619;6410.871782564416;6435.908329792743;6465.418112369909;6486.643234622141;6487.837804054936;6395.880720873922;6421.743130274736;6430.292667806444;6430.742770289323;6437.549371543947;6484.448821850196;6491.805245842632;6519.956806910872;6533.640649764308;6573.244076931214;5728.914066288926;6268;6298.811035668938;6304.369563043842;6312.589681670676 17:15:17 INFO - PID 3538 | |5;yui.html;886.9645214191432;903.8508646073399;915.5696822493426;918.830016446957;936.6347124621399;17414.564635884093;18281.817181828177;18345.266189352442;18542.721859172107;21289.14843406262;20233.29183478575;21341;21437.892810535963;21507.56984860302;21522.139114435402;1007.0785231513175;1046.6022911293735;1048.2714513413182;1049.884512703602;1050.0969166516788;958.4967891856761;1041.677080105166;1049.228816819637;1057.4501259345136;1063.478895341281;1099.1756182862853;1101.1136035491427;1104.5029736618515;1105.5633024955139;1106.4744246482937;1078.5308390849966;1080.0441609175896;1088.7495875948537;1089.5805115030691;1090.8854570270166;804.4328748232482;804.5253300552644;804.8148925747053;805.0500409516768;806.238104990784;19282.132304046212;19459.805401946083;19617.9019104905;19792;20290.49273768153;19681.704774428395;20208.898955505276;20284.492887677778;20497.28259510925;20653.380398588066;14231.217283049427;14439;14524.491642792556;14541.709165816625;14595;3501.124718820295;3524.9118772030647;3530.894073177809;3530.9646903530893;3531.5408996830247;114789.99999999917;117254.41372793166;120595;122930;126191.36904315511;16117.630001449774;16167;16312.75530867038;16395.098269595048;16427.753583696252;27463.588046179324;29381.26546836346;29543.409131817243;29663.55504667432;29703;7051.89422158668;8121.8375632488205;8723.825523489622;8880.067592902782;8902.910970890338;4325.480942286883;4467.396901418332;4474.910501789946;4477.7984990675495;4486.3494793254795;4933.802647894116;5027.044861476308;5049.26785616091;5097.8725531862;5120.129577971754;1005.7686732051669;1015.2943704125562;1032.8708911386077;1037.5901518899993;1037.8131936251548;618.3136718242747;628.5725706519519;646.8285904235381;663.4526515624628;669.5380187670492;10647.787044259072;11223.270487418291;11481.193565747719;12044;12275.75448491043;622.8598565322766;663.4857985061519;664.4319107163305;668.3116390118186;679.0934102972587;5231.738413079407;5242.7116508591635;5366.141417373279;5399.730013499309;5435.673859568438;98070;98554.50722746484;100388.49805750925;100593;103202;81526;82393;87933.56033219793;89335;89373;71175;74394;74526;74917.62541187259;75274.62362688151;10572.20708446885;10735.409552474688;11508.82736758966;12271.035740153278;13054.15148015376;31783;33444.163895901824;33663.83168084144;34695;35881.82059089688;62542;64738;65065;65433.34566654463;66770;18040.18819153115;18285.360012399502;18466.261349546407;18608.534786630444;18668;20003.09986050602;20162.395128146178;20466.897665511577;20543.589128217653;20551.075201615666;17530.91234543819;17594.208260628046;17712.46862594124;17748.3788067417;17856.375026873997;18035;18077.728834067762;18204.089795510437;18224.544386390447;18282.72575911389;15283.61790955235;16886.493405197863;16977.915110424863;17161.82838171652;17310.82689173092;12162.513499460276;12327.260364378166;12855.550055748005;12925.612231633066;13016.414261358072;13750.65623359424;13978.650533736738;14214;14251.928740356232;14449.277536123362 17:15:17 INFO - PID 3538 | __end_tp_report 17:15:17 INFO - PID 3538 | __start_cc_report 17:15:17 INFO - PID 3538 | _x_x_mozilla_cycle_collect,366 17:15:17 INFO - PID 3538 | __end_cc_report 17:15:17 INFO - PID 3538 | __startTimestamp1501200917627__endTimestamp 17:15:17 INFO - PID 3538 | 17:15:17 INFO - PID 3538 | ------- Summary: start ------- 17:15:17 INFO - PID 3538 | Number of tests: 6 17:15:17 INFO - PID 3538 | 17:15:17 INFO - PID 3538 | [#0] dojo.html Cycles:165 Average:6095.53 Median:6362.11 stddev:696.61 (10.9%) stddev-sans-first:658.31 17:15:17 INFO - PID 3538 | Values: 3113.8 3154.1 3182.6 3200.9 3223.0 6121.8 6183.3 6197.1 6209.3 6210.9 6116.7 6165.7 6211.8 6213.0 6223.6 5470.5 5575.4 5615.5 5674.2 5696.1 5555.4 5603.7 5604.8 5623.8 5657.0 5690.5 5718.8 5770.8 5780.7 5807.9 5626.4 5641.5 5702.9 5708.5 5713.0 5513.9 5527.9 5576.8 5577.6 5759.5 6057.0 6058.1 6066.4 6096.1 6119.2 6564.3 6611.3 6618.0 6633.9 6650.0 6518.0 6541.6 6566.0 6591.4 6629.6 6151.2 6176.6 6203.0 6215.8 6235.9 6794.6 6802.3 6804.0 6818.8 6849.5 6398.7 6407.6 6495.7 6509.3 6523.7 6531.2 6578.4 6582.4 6660.8 6671.3 6362.1 6365.1 6371.8 6373.1 6394.5 6606.9 6632.2 6635.1 6639.3 6662.8 5352.8 5357.1 5369.4 5400.4 5424.6 4614.7 4621.3 4633.1 4686.2 4688.4 5772.9 5777.8 5858.2 5876.9 5882.5 6395.4 6513.1 6578.2 6598.6 6779.1 5838.3 5844.3 5863.2 5897.4 5910.6 5964.9 5973.9 5988.1 6018.2 6042.2 6438.1 6525.4 6543.5 6581.4 6603.1 6259.0 6362.1 6627.9 6736.7 6746.7 6348.5 6362.8 6363.2 6364.5 6365.2 6450.3 6461.3 6463.2 6480.6 6483.6 6523.1 6598.8 6599.0 6600.8 6602.3 6389.9 6509.7 6522.2 6582.8 6592.5 6407.1 6412.5 6509.0 6512.2 6529.2 6369.2 6376.5 6397.7 6412.9 6414.8 6455.4 6470.7 6499.4 6609.8 6629.0 6255.5 6289.1 6291.2 6299.2 6317.4 17:15:17 INFO - PID 3538 | 17:15:17 INFO - PID 3538 | [#1] ext.html Cycles:180 Average:37171.14 Median:34569.65 stddev:52951.66 (153.2%) stddev-sans-first:53029.77 17:15:17 INFO - PID 3538 | Values: 799.2 842.2 855.1 888.6 890.9 38667.2 39533.8 39552.4 39572.0 39581.8 35716.3 35878.5 35956.1 35991.6 36021.3 6830.8 6916.2 6930.8 6953.2 6962.0 6800.5 6902.9 6908.2 6916.8 6935.0 6822.4 6826.3 6834.1 6867.1 6883.2 7399.9 7572.2 7595.8 7690.8 7701.7 7542.3 7627.3 7630.7 7631.0 7639.4 37287.3 37789.6 38605.2 38750.2 38764.0 3469.8 3544.8 3550.4 3570.3 3572.7 30584.2 30598.2 30690.8 30740.2 30825.2 4761.0 4762.5 4780.7 4802.3 4838.3 172318.1 175723.0 186965.0 187281.0 201159.0 47772.3 47847.5 47922.0 47939.0 47956.3 49332.0 49403.0 49413.5 49510.5 49755.0 16087.8 16107.4 16177.2 16259.4 16362.0 13541.5 13578.4 13591.5 13613.5 13613.7 13911.1 14106.8 14150.5 14162.9 14295.7 5681.7 5692.9 5743.7 5760.2 5802.8 972.8 978.3 986.2 1006.6 1007.3 22122.4 22216.9 22394.3 22455.0 22770.8 1084.8 1087.2 1093.6 1114.7 1122.8 11865.1 11874.1 11877.4 15605.2 15897.7 225035.0 226363.0 228213.0 230963.0 231382.0 52912.5 53345.7 53676.2 53690.5 53918.0 1424.0 1453.1 1463.6 1463.8 1474.0 24986.3 26538.1 26567.7 26814.2 26898.7 49030.0 50142.7 50209.5 50328.2 50399.0 195250.0 195860.0 197052.0 197976.0 198029.0 30524.5 31071.4 31093.2 31143.0 34026.3 40255.8 41608.8 42099.8 42765.0 42895.0 30323.2 30676.4 30750.8 31074.2 31536.2 28298.3 30143.7 30525.4 30802.8 31076.5 34160.5 34288.1 34431.3 34552.0 34587.3 30212.7 30758.0 30807.5 30914.7 31511.4 22400.4 22673.5 22928.0 23222.0 23381.3 17:15:17 INFO - PID 3538 | 17:15:17 INFO - PID 3538 | [#2] jquery.html Cycles:180 Average:36777.20 Median:12687.71 stddev:90538.40 (713.6%) stddev-sans-first:90751.53 17:15:17 INFO - PID 3538 | Values: 554.2 567.2 577.8 580.9 585.2 21706.7 22373.0 22634.3 22687.3 22705.2 25072.0 25140.4 25162.5 25168.0 25174.4 6441.9 6602.7 6604.8 6610.3 6612.1 5872.4 6426.2 6441.3 6487.4 6544.9 6389.9 6390.8 6399.0 6415.6 6417.5 6336.0 6356.1 6356.2 6431.9 6446.1 5899.4 5901.4 5909.7 5962.2 5967.6 28491.6 29413.9 29518.4 29689.3 29994.3 1821.4 1836.7 1838.1 1846.7 1848.5 18357.9 18841.5 18920.9 18954.1 18988.8 2296.1 2297.5 2377.0 2377.1 2384.9 135841.0 138108.0 141029.3 141951.0 142016.0 14159.2 14746.6 15090.6 15163.8 15557.1 22475.6 23077.9 23339.2 23385.5 23442.6 10424.7 10467.8 10507.5 10567.5 10679.3 9809.7 9861.4 9886.8 9932.0 9987.7 9209.2 9410.5 9432.2 9474.8 9525.9 2537.8 2653.6 2658.5 2697.5 2738.3 910.8 940.2 960.1 966.7 972.0 19096.0 19579.0 19826.6 24166.6 25235.5 1009.9 1016.3 1029.2 1050.8 1055.4 10285.9 10841.6 11286.2 11423.9 11628.8 518235.0 534775.0 548183.0 548795.0 549288.0 122142.4 125390.4 127417.0 127894.0 128711.4 1220.9 1252.3 1267.7 1283.8 1284.5 17078.9 17575.8 17920.0 18223.5 18664.4 46444.0 48554.5 51347.5 51989.2 59330.4 65403.0 68790.3 72150.3 72923.3 74950.0 20922.3 21465.8 21498.9 21532.5 21852.3 23994.6 24212.5 24215.6 24258.2 24736.4 18547.7 18608.8 18612.2 18845.9 18884.9 15566.7 16213.9 16766.4 17037.7 17224.6 18673.9 18717.6 18734.6 18929.1 19229.3 20687.9 20867.4 20884.0 20914.8 21132.2 17086.2 18324.0 18620.2 18732.9 18744.8 17:15:17 INFO - PID 3538 | 17:15:17 INFO - PID 3538 | [#3] mootools.html Cycles:180 Average:8541.18 Median:2621.40 stddev:5874.85 (224.1%) stddev-sans-first:5866.08 17:15:17 INFO - PID 3538 | Values: 1292.4 1351.8 1363.5 1367.2 1374.1 13548.4 14231.2 14260.6 14284.7 14289.0 13749.9 14155.4 14500.1 14589.3 14794.9 2674.0 2726.3 2728.1 2733.9 2735.2 2618.6 2685.2 2704.3 2721.2 2724.4 2533.8 2584.4 2665.0 2713.7 2716.7 2569.6 2609.5 2620.2 2622.6 2643.3 2594.4 2618.5 2638.1 2639.1 2653.2 13557.3 13657.9 13760.0 13766.7 13785.2 7490.4 7701.5 7743.5 7756.0 7768.9 4872.4 4930.0 5050.4 5061.9 5093.4 1825.7 1833.4 1838.5 1864.7 1871.8 22267.4 22318.4 22397.0 22412.7 22497.6 6806.7 6842.6 7175.5 7178.2 7215.4 14916.6 14922.8 14967.6 15023.6 15045.9 7374.9 7422.3 7450.4 7502.5 7523.7 5726.6 6016.1 6260.6 6939.8 7055.7 4915.3 5002.4 5048.4 5072.3 5184.1 1452.4 1474.3 1474.4 1497.8 1517.5 806.6 814.6 823.4 845.8 867.4 6255.3 6492.0 6545.6 6616.6 6658.6 663.1 675.2 690.3 695.2 711.4 4194.0 5030.9 5141.7 5162.5 5391.5 18584.2 19205.4 19330.0 19616.1 19750.0 19431.5 19619.2 19703.8 19746.6 19835.1 9738.4 10838.7 11072.9 11107.6 11167.3 7343.6 7758.4 7783.1 7954.1 8005.8 16118.5 16179.7 16596.8 16616.2 16677.8 15401.4 15880.5 16831.0 17283.7 17590.7 10842.1 11435.4 11586.2 11597.9 11628.5 13305.4 13389.0 13404.7 13434.0 13451.9 10197.3 10557.1 10570.2 10748.9 10833.9 9579.4 10231.3 10239.3 10281.8 10314.9 8730.2 8816.0 8893.1 9008.6 9024.7 6347.9 6602.9 6641.5 6671.8 6810.8 6599.1 6670.2 6720.0 6817.0 6824.1 17:15:17 INFO - PID 3538 | 17:15:17 INFO - PID 3538 | [#4] prototype.html Cycles:180 Average:5426.49 Median:6044.08 stddev:1537.57 (25.4%) stddev-sans-first:1496.76 17:15:17 INFO - PID 3538 | Values: 499.9 504.4 514.7 528.9 532.3 6203.4 6206.7 6230.5 6261.0 6267.3 5845.0 5930.0 6038.6 6061.9 6099.5 4855.3 4867.3 4874.4 4883.9 4893.7 4740.2 4838.5 4851.2 4888.1 4909.1 4753.7 4755.9 4792.6 4801.8 4828.1 4797.9 4813.6 4824.8 4833.5 4835.7 4280.4 4330.8 4393.3 4418.4 4432.0 6017.0 6026.2 6033.0 6049.5 6064.5 5533.4 5580.7 5585.9 5649.1 5649.2 6326.5 6362.9 6439.4 6442.7 6505.3 4808.3 4814.9 4817.9 4819.1 4822.6 6788.7 6810.2 6843.8 6885.0 6955.3 4808.5 4825.4 4900.0 4921.3 4922.6 4921.8 4946.4 4952.6 4988.3 5002.7 5371.9 5515.6 5540.0 5543.0 5573.5 6139.7 6408.1 6465.7 6525.5 6558.9 1650.0 1683.3 1693.0 1704.8 1711.5 998.3 1189.6 1199.0 1200.3 1207.9 5599.4 5614.9 5692.5 5722.5 5777.2 6629.7 6637.7 6672.5 6689.3 6733.7 5722.5 5736.2 5770.9 5792.7 5798.2 3531.7 3562.3 3569.3 3591.2 3607.7 6362.7 6373.2 6437.1 6446.6 6455.9 6764.8 6777.5 6805.6 6835.5 6848.0 6311.9 6337.5 6399.7 6462.4 6469.2 6497.5 6586.7 6602.4 6602.8 6618.8 6493.2 6535.9 6547.1 6610.1 6635.2 6336.3 6359.2 6423.4 6453.9 6464.5 4926.2 4944.1 4951.8 4959.2 4972.8 6681.2 6731.2 6732.0 6771.5 6818.6 6370.8 6400.2 6415.2 6468.4 6480.6 6410.9 6435.9 6465.4 6486.6 6487.8 6395.9 6421.7 6430.3 6430.7 6437.5 6484.4 6491.8 6520.0 6533.6 6573.2 5728.9 6268.0 6298.8 6304.4 6312.6 17:15:17 INFO - PID 3538 | 17:15:17 INFO - PID 3538 | [#5] yui.html Cycles:180 Average:22100.25 Median:19897.55 stddev:29250.65 (147.0%) stddev-sans-first:29289.33 17:15:17 INFO - PID 3538 | Values: 887.0 903.9 915.6 918.8 936.6 17414.6 18281.8 18345.3 18542.7 21289.1 20233.3 21341.0 21437.9 21507.6 21522.1 1007.1 1046.6 1048.3 1049.9 1050.1 958.5 1041.7 1049.2 1057.5 1063.5 1099.2 1101.1 1104.5 1105.6 1106.5 1078.5 1080.0 1088.7 1089.6 1090.9 804.4 804.5 804.8 805.1 806.2 19282.1 19459.8 19617.9 19792.0 20290.5 19681.7 20208.9 20284.5 20497.3 20653.4 14231.2 14439.0 14524.5 14541.7 14595.0 3501.1 3524.9 3530.9 3531.0 3531.5 114790.0 117254.4 120595.0 122930.0 126191.4 16117.6 16167.0 16312.8 16395.1 16427.8 27463.6 29381.3 29543.4 29663.6 29703.0 7051.9 8121.8 8723.8 8880.1 8902.9 4325.5 4467.4 4474.9 4477.8 4486.3 4933.8 5027.0 5049.3 5097.9 5120.1 1005.8 1015.3 1032.9 1037.6 1037.8 618.3 628.6 646.8 663.5 669.5 10647.8 11223.3 11481.2 12044.0 12275.8 622.9 663.5 664.4 668.3 679.1 5231.7 5242.7 5366.1 5399.7 5435.7 98070.0 98554.5 100388.5 100593.0 103202.0 81526.0 82393.0 87933.6 89335.0 89373.0 71175.0 74394.0 74526.0 74917.6 75274.6 10572.2 10735.4 11508.8 12271.0 13054.2 31783.0 33444.2 33663.8 34695.0 35881.8 62542.0 64738.0 65065.0 65433.3 66770.0 18040.2 18285.4 18466.3 18608.5 18668.0 20003.1 20162.4 20466.9 20543.6 20551.1 17530.9 17594.2 17712.5 17748.4 17856.4 18035.0 18077.7 18204.1 18224.5 18282.7 15283.6 16886.5 16977.9 17161.8 17310.8 12162.5 12327.3 12855.6 12925.6 13016.4 13750.7 13978.7 14214.0 14251.9 14449.3 17:15:17 INFO - PID 3538 | -------- Summary: end -------- 17:15:17 INFO - PID 3538 | 17:15:18 INFO - TEST-INFO | 3538: exit 0 17:15:18 INFO - TEST-OK | dromaeo_css | took 1116074ms 17:15:18 INFO - TEST-START | kraken 17:15:18 INFO - Initialising browser for kraken test... 17:15:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51895/getInfo.html -profile /tmp/tmprvhH26/profile 17:15:18 INFO - TEST-INFO | started process 3891 (/builds/slave/test/build/application/firefox/firefox http://localhost:51895/getInfo.html) 17:15:24 INFO - TEST-INFO | 3891: exit 0 17:15:24 INFO - Browser initialized. 17:15:24 INFO - Running cycle 1/1 for kraken test... 17:15:24 INFO - TEST-INFO | started process 4103 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprvhH26/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:25 INFO - PID 4103 | 17:15:25 INFO - PID 4103 | (/builds/slave/test/build/application/firefox/firefox:4151): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:15:25 INFO - PID 4103 | 17:15:35 INFO - PID 4103 | RSS: Main: 170979328 17:15:35 INFO - PID 4103 | 17:15:38 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51895/tests/kraken/driver.html?testName=audio-beat-detection) 17:15:39 INFO - PID 4103 | RSS: Main: 176402432 17:15:39 INFO - PID 4103 | 17:15:41 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51895/tests/kraken/driver.html?testName=audio-dft) 17:15:42 INFO - PID 4103 | RSS: Main: 176615424 17:15:42 INFO - PID 4103 | 17:15:45 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51895/tests/kraken/driver.html?testName=audio-fft) 17:15:46 INFO - PID 4103 | RSS: Main: 171876352 17:15:46 INFO - PID 4103 | 17:15:48 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51895/tests/kraken/driver.html?testName=audio-oscillator) 17:15:48 INFO - PID 4103 | RSS: Main: 172781568 17:15:48 INFO - PID 4103 | 17:15:51 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51895/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:15:51 INFO - PID 4103 | RSS: Main: 172507136 17:15:51 INFO - PID 4103 | 17:15:54 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51895/tests/kraken/driver.html?testName=imaging-darkroom) 17:15:55 INFO - PID 4103 | RSS: Main: 174829568 17:15:55 INFO - PID 4103 | 17:15:58 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51895/tests/kraken/driver.html?testName=imaging-desaturate) 17:15:59 INFO - PID 4103 | RSS: Main: 174915584 17:15:59 INFO - PID 4103 | 17:16:02 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51895/tests/kraken/driver.html?testName=json-parse-financial) 17:16:03 INFO - PID 4103 | RSS: Main: 174878720 17:16:03 INFO - PID 4103 | 17:16:04 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51895/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:16:05 INFO - PID 4103 | RSS: Main: 174723072 17:16:05 INFO - PID 4103 | 17:16:07 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51895/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:16:07 INFO - PID 4103 | RSS: Main: 172146688 17:16:07 INFO - PID 4103 | 17:16:09 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51895/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:16:10 INFO - PID 4103 | RSS: Main: 171692032 17:16:10 INFO - PID 4103 | 17:16:13 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:16:13 INFO - PID 4103 | RSS: Main: 172466176 17:16:13 INFO - PID 4103 | 17:16:16 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:16:17 INFO - PID 4103 | RSS: Main: 172367872 17:16:17 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | Cycle 1(1): loaded http://localhost:51895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51895/tests/kraken/driver.html?testName=ai-astar) 17:16:19 INFO - PID 4103 | RSS: Main: 174239744 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | __start_tp_report 17:16:19 INFO - PID 4103 | _x_x_mozilla_page_load 17:16:19 INFO - PID 4103 | _x_x_mozilla_page_load_details 17:16:19 INFO - PID 4103 | |i|pagename|runs| 17:16:19 INFO - PID 4103 | |0;ai-astar;101;108;106;109;110;110;110;100;111;114 17:16:19 INFO - PID 4103 | |1;audio-beat-detection;153;125;145;184;122;148;150;124;171;118 17:16:19 INFO - PID 4103 | |2;audio-dft;164;165;166;165;163;164;167;167;164;165 17:16:19 INFO - PID 4103 | |3;audio-fft;76;76;76;189;78;74;77;77;148;77 17:16:19 INFO - PID 4103 | |4;audio-oscillator;79;80;136;80;85;80;131;80;79;147 17:16:19 INFO - PID 4103 | |5;imaging-gaussian-blur;114;114;118;114;113;117;117;115;116;123 17:16:19 INFO - PID 4103 | |6;imaging-darkroom;152;151;153;152;154;151;151;152;152;152 17:16:19 INFO - PID 4103 | |7;imaging-desaturate;95;95;94;95;94;95;94;95;94;94 17:16:19 INFO - PID 4103 | |8;json-parse-financial;56;54;58;57;57;57;56;55;56;56 17:16:19 INFO - PID 4103 | |9;json-stringify-tinderbox;55;54;55;54;54;54;54;55;55;55 17:16:19 INFO - PID 4103 | |10;stanford-crypto-aes;80;81;85;81;79;88;90;79;79;79 17:16:19 INFO - PID 4103 | |11;stanford-crypto-ccm;131;123;130;127;131;125;124;129;130;165 17:16:19 INFO - PID 4103 | |12;stanford-crypto-pbkdf2;184;180;186;172;174;173;174;175;177;179 17:16:19 INFO - PID 4103 | |13;stanford-crypto-sha256-iterative;71;71;68;69;72;70;68;71;68;68 17:16:19 INFO - PID 4103 | __end_tp_report 17:16:19 INFO - PID 4103 | __start_cc_report 17:16:19 INFO - PID 4103 | _x_x_mozilla_cycle_collect,968 17:16:19 INFO - PID 4103 | __end_cc_report 17:16:19 INFO - PID 4103 | __startTimestamp1501200979658__endTimestamp 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | ------- Summary: start ------- 17:16:19 INFO - PID 4103 | Number of tests: 14 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#0] ai-astar Cycles:10 Average:107.90 Median:110.00 stddev:4.41 (4.0%) stddev-sans-first:3.91 17:16:19 INFO - PID 4103 | Values: 101.0 108.0 106.0 109.0 110.0 110.0 110.0 100.0 111.0 114.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#1] audio-beat-detection Cycles:10 Average:144.00 Median:149.00 stddev:22.07 (14.8%) stddev-sans-first:23.17 17:16:19 INFO - PID 4103 | Values: 153.0 125.0 145.0 184.0 122.0 148.0 150.0 124.0 171.0 118.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#2] audio-dft Cycles:10 Average:165.00 Median:165.00 stddev:1.33 (0.8%) stddev-sans-first:1.36 17:16:19 INFO - PID 4103 | Values: 164.0 165.0 166.0 165.0 163.0 164.0 167.0 167.0 164.0 165.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#3] audio-fft Cycles:10 Average:94.80 Median:76.50 stddev:40.04 (52.3%) stddev-sans-first:41.89 17:16:19 INFO - PID 4103 | Values: 76.0 76.0 76.0 189.0 78.0 74.0 77.0 77.0 148.0 77.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:80.00 stddev:28.13 (35.2%) stddev-sans-first:29.01 17:16:19 INFO - PID 4103 | Values: 79.0 80.0 136.0 80.0 85.0 80.0 131.0 80.0 79.0 147.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#5] imaging-gaussian-blur Cycles:10 Average:116.10 Median:116.50 stddev:2.92 (2.5%) stddev-sans-first:3.00 17:16:19 INFO - PID 4103 | Values: 114.0 114.0 118.0 114.0 113.0 117.0 117.0 115.0 116.0 123.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#6] imaging-darkroom Cycles:10 Average:152.00 Median:152.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 17:16:19 INFO - PID 4103 | Values: 152.0 151.0 153.0 152.0 154.0 151.0 151.0 152.0 152.0 152.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 17:16:19 INFO - PID 4103 | Values: 95.0 95.0 94.0 95.0 94.0 95.0 94.0 95.0 94.0 94.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.50 stddev:1.14 (2.0%) stddev-sans-first:1.20 17:16:19 INFO - PID 4103 | Values: 56.0 54.0 58.0 57.0 57.0 57.0 56.0 55.0 56.0 56.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#9] json-stringify-tinderbox Cycles:10 Average:54.50 Median:55.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 17:16:19 INFO - PID 4103 | Values: 55.0 54.0 55.0 54.0 54.0 54.0 54.0 55.0 55.0 55.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:81.00 stddev:4.09 (5.1%) stddev-sans-first:4.27 17:16:19 INFO - PID 4103 | Values: 80.0 81.0 85.0 81.0 79.0 88.0 90.0 79.0 79.0 79.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#11] stanford-crypto-ccm Cycles:10 Average:131.50 Median:130.00 stddev:12.13 (9.3%) stddev-sans-first:12.87 17:16:19 INFO - PID 4103 | Values: 131.0 123.0 130.0 127.0 131.0 125.0 124.0 129.0 130.0 165.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.40 Median:178.00 stddev:4.77 (2.7%) stddev-sans-first:4.42 17:16:19 INFO - PID 4103 | Values: 184.0 180.0 186.0 172.0 174.0 173.0 174.0 175.0 177.0 179.0 17:16:19 INFO - PID 4103 | 17:16:19 INFO - PID 4103 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.50 stddev:1.58 (2.2%) stddev-sans-first:1.59 17:16:19 INFO - PID 4103 | Values: 71.0 71.0 68.0 69.0 72.0 70.0 68.0 71.0 68.0 68.0 17:16:19 INFO - PID 4103 | -------- Summary: end -------- 17:16:19 INFO - PID 4103 | 17:16:20 INFO - PID 4103 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:16:20 INFO - TEST-INFO | 4103: exit 0 17:16:20 INFO - TEST-OK | kraken | took 62008ms 17:16:20 INFO - SUITE-END | took 1178s 17:16:20 INFO - Completed test suite (00:19:38) 17:16:20 INFO - javascript benchmark 17:16:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8361.084167721487, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3113.7976031557946, 3154.05378386484, 3182.6339970903323, 3200.919977000575, 3222.983885080574, 6121.785737499183, 6183.25800903891, 6197.101420294055, 6209.2859321178075, 6210.937890621092, 6116.6941652917285, 6165.722542485588, 6211.844703882405, 6212.968935155318, 6223.626582405048, 5470.5350045246205, 5575.386707462196, 5615.4665306796, 5674.205611214434, 5696.060150075233, 5555.3611334696425, 5603.691796951166, 5604.8038318658855, 5623.831285061455, 5657.0, 5690.5447564194765, 5718.771249150029, 5770.798022069229, 5780.682062486561, 5807.854803629901, 5626.381098079208, 5641.52047075995, 5702.859428114393, 5708.5433165347085, 5713.0, 5513.862153446155, 5527.894421115793, 5576.832695019156, 5577.609567330289, 5759.452851979097, 6057.0, 6058.0610005449225, 6066.423689749467, 6096.055111457731, 6119.204503414572, 6564.343565643445, 6611.3058128896255, 6618.0, 6633.933660663428, 6650.0, 6518.0, 6541.574797638138, 6566.047923051178, 6591.373819487141, 6629.602223866584, 6151.200343955257, 6176.567640265183, 6203.0, 6215.81352559424, 6235.906461403082, 6794.592324460449, 6802.251752307242, 6804.0, 6818.795436136924, 6849.452043836481, 6398.680065996681, 6407.583507072025, 6495.7076931538195, 6509.349065093547, 6523.7064332105165, 6531.216826721288, 6578.440832529192, 6582.44049255809, 6660.766873159416, 6671.3328667133865, 6362.141110950055, 6365.108884756138, 6371.776987805404, 6373.075903993988, 6394.520410969103, 6606.933930660632, 6632.204135503771, 6635.071090047397, 6639.2696803352555, 6662.766803161866, 5352.785888564492, 5357.11607584752, 5369.4362091980565, 5400.405955344909, 5424.566034717213, 4614.700044497097, 4621.260598304255, 4633.142868569325, 4686.226772582486, 4688.367070445446, 5772.884542309131, 5777.797777077866, 5858.150568167592, 5876.882462350814, 5882.499987501024, 6395.392437718409, 6513.055606937062, 6578.177727784027, 6598.637074960923, 6779.084823548757, 5838.299404071538, 5844.26946631671, 5863.20846685692, 5897.410258974069, 5910.645361278251, 5964.910526342108, 5973.940260597338, 5988.131720900445, 6018.15745795589, 6042.154098426224, 6438.098666186737, 6525.3800888915475, 6543.509236807258, 6581.3747693969, 6603.075569420285, 6258.968705156445, 6362.109304697438, 6627.867442651216, 6736.663166841737, 6746.662666866637, 6348.492120630431, 6362.77730279438, 6363.172787537591, 6364.490840732823, 6365.172527571386, 6450.258220304725, 6461.2892581816905, 6463.15978922737, 6480.643564603992, 6483.610983340826, 6523.054157147282, 6598.769043083469, 6598.967005165136, 6600.80197594073, 6602.273749887605, 6389.9361006389345, 6509.707063182263, 6522.21733391996, 6582.76960306387, 6592.472602191909, 6407.0709747085475, 6412.519061070345, 6509.0, 6512.218533775977, 6529.151210342625, 6369.172007639163, 6376.458001069961, 6397.744090236339, 6412.871742565216, 6414.775482858077, 6455.386738259858, 6470.676466176767, 6499.415052645285, 6609.768658096944, 6629.0, 6255.530835187288, 6289.119523266838, 6291.182146320949, 6299.244090709144, 6317.368263173646], "value": 6044.8672272100985, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.2487062161567, 842.2209456252964, 855.0850589868841, 888.5734847273307, 890.8574628059507, 38667.22665546691, 39533.80233098834, 39552.406713899276, 39572.0, 39581.802090989506, 35716.2856742865, 35878.46182307261, 35956.10109747258, 35991.64008359916, 36021.279574408494, 6830.795076147723, 6916.204636466795, 6930.757423490179, 6953.165620125566, 6961.965190174042, 6800.455963522931, 6902.861942761142, 6908.205556361033, 6916.827079323007, 6935.029095926574, 6822.385985261326, 6826.283240259776, 6834.111565496479, 6867.141607299088, 6883.242843287235, 7399.926000740004, 7572.204918483562, 7595.810104747398, 7690.846183076307, 7701.653425595861, 7542.321191092773, 7627.313541781211, 7630.732924347677, 7631.0, 7639.427042971799, 37287.254254915024, 37789.622103778886, 38605.227895442215, 38750.22499550021, 38764.0, 3469.7744646598144, 3544.7518673692593, 3550.3609350316933, 3570.2680950405106, 3572.6605972432576, 30584.235394115327, 30598.235044123852, 30690.84654576713, 30740.2314942126, 30825.229369265722, 4760.976195119037, 4762.452317983441, 4780.737059461728, 4802.303665968415, 4838.298446725205, 172318.1384093084, 175723.0, 186965.0, 187281.0, 201159.0, 47772.28341574879, 47847.521524785, 47922.0, 47939.0, 47956.28065579019, 49332.0, 49403.0, 49413.50586494161, 49510.50489495059, 49755.0, 16087.839121608868, 16107.436239731553, 16177.19114044293, 16259.430919917746, 16362.0, 13541.526046588519, 13578.388972496261, 13591.52429664957, 13613.455461781417, 13613.659658508419, 13911.09577877455, 14106.788398174036, 14150.504732334524, 14162.929185354007, 14295.714085718228, 5681.659100453986, 5692.8861422771315, 5743.684097374685, 5760.164776107441, 5802.796902108406, 972.8248915195333, 978.2614126334572, 986.1863962231188, 1006.6225165562914, 1007.3401921741271, 22122.44693882666, 22216.88891555532, 22394.32817015492, 22455.0, 22770.772292276866, 1084.7776205877776, 1087.211771465681, 1093.6111138853612, 1114.6990312615549, 1122.7586068995206, 11865.050795936304, 11874.1094417919, 11877.406129693481, 15605.219739013004, 15897.68204635924, 225035.0, 226363.0, 228213.0, 230963.0, 231382.0, 52912.470875291525, 53345.733271333396, 53676.19485707718, 53690.46309536933, 53918.0, 1423.985760142406, 1453.128123126116, 1463.5755630867143, 1463.8389777124507, 1473.955781326562, 24986.250412487654, 26538.071167509046, 26567.7343226573, 26814.195574132806, 26898.731012690405, 49030.0, 50142.74928625333, 50209.497905020486, 50328.245076324616, 50399.0, 195250.0, 195860.0, 197052.0, 197976.01011994848, 198029.0, 30524.542131867594, 31071.378572427973, 31093.22266943254, 31143.0, 34026.319473609896, 40255.79872100738, 41608.791956040026, 42099.7895010523, 42765.0, 42895.0, 30323.241918952204, 30676.386472270875, 30750.84624576863, 31074.22314442157, 31536.211594710316, 28298.2925426866, 30143.698563014088, 30525.389492210477, 30802.845985769927, 31076.53385199269, 34160.48759268563, 34288.142796430286, 34431.311373772885, 34552.0, 34587.30825383528, 30212.69787302099, 30758.0, 30807.53788693216, 30914.69085309208, 31511.36977260396, 22400.439989000406, 22673.546529069656, 22928.0, 23222.0, 23381.298561043197], "value": 16904.430997371917, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.2379228560729, 567.2087438023959, 577.8266520043989, 580.8634970781867, 585.1807469542639, 21706.674399883996, 22373.0, 22634.320970370914, 22687.319380418594, 22705.20531781386, 25072.0, 25140.37149071274, 25162.496750065035, 25167.986561075115, 25174.370640733992, 6441.935580644192, 6602.66986650668, 6604.768833090843, 6610.338966103399, 6612.107365505667, 5872.354041055481, 6426.228852537702, 6441.291457939623, 6487.351264873499, 6544.869102617945, 6389.872202555946, 6390.840228994266, 6399.008153736178, 6415.550911436201, 6417.454516366114, 6336.017917222838, 6356.0783686365685, 6356.2054743157105, 6431.935680643181, 6446.065320528544, 5899.351071382144, 5901.350851406385, 5909.704514774287, 5962.165296858443, 5967.6121052131475, 28491.57262641062, 29413.85293073521, 29518.37648929308, 29689.257768555744, 29994.25014374636, 1821.3807305516057, 1836.6510363031116, 1838.090145378033, 1846.6860633692304, 1848.4731851422414, 18357.908210458994, 18841.528961776065, 18920.905395472935, 18954.14706338218, 18988.81011189898, 2296.0815673730476, 2297.5404919016264, 2376.9762302376926, 2377.084822343393, 2384.880755962195, 135841.0, 138108.0, 141029.2948535261, 141951.0, 142016.0, 14159.150450972975, 14746.63133421673, 15090.622734431727, 15163.848361516464, 15557.14435706047, 22475.550488990128, 23077.88461057684, 23339.18312859042, 23385.532289354116, 23442.64836027461, 10424.68725938223, 10467.790644187073, 10507.527161277609, 10567.524461399256, 10679.252452328417, 9809.656662016796, 9861.359011664219, 9886.752831179278, 9931.950340248397, 9987.650432234983, 9209.217216536535, 9410.482423466638, 9432.19826314771, 9474.763130921781, 9525.904520980175, 2537.7589129032713, 2653.5886937524715, 2658.494885971659, 2697.5414179589516, 2738.274357295318, 910.7859652981545, 940.1773448232797, 960.1406740966795, 966.6713317471953, 971.9708408747749, 19096.0, 19579.0, 19826.60346793085, 24166.637500437508, 25235.495290094095, 1009.8889122196553, 1016.2987538598492, 1029.1715169288645, 1050.7793363393773, 1055.3878750324793, 10285.897141028643, 10841.566337346576, 11286.153538484647, 11423.885761142283, 11628.825567616492, 518235.0, 534775.0, 548183.0, 548795.0, 549288.0, 122142.38928805299, 125390.37304813418, 127417.0, 127894.0, 128711.35644321906, 1220.920640158387, 1252.3362617812575, 1267.7274386007052, 1283.833101696792, 1284.5375664760866, 17078.914605426893, 17575.82424175793, 17920.0, 18223.45329640113, 18664.44006679802, 46444.0, 48554.514454855, 51347.48652513576, 51989.220161696845, 59330.406695934216, 65403.0, 68790.31209687839, 72150.27849721436, 72923.27076729377, 74950.0, 20922.267720629705, 21465.785342146377, 21498.925053746938, 21532.461688457915, 21852.344429667137, 23994.640080398458, 24212.51574968526, 24215.636765448886, 24258.15096471615, 24736.381590459663, 18547.72178417352, 18608.813911860707, 18612.16245268939, 18845.90577047106, 18884.905575472585, 15566.688666226386, 16213.918930405272, 16766.413175538797, 17037.744433833253, 17224.569385765455, 18673.90663046676, 18717.625647487246, 18734.625307494047, 18929.148188331284, 19229.326973555857, 20687.8965605171, 20867.373978780663, 20884.0, 20914.790852091286, 21132.154713811276, 17086.231119599895, 18324.0, 18620.16209270559, 18732.906335468237, 18744.78158919666], "value": 12256.528724506734, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.399034448981, 1351.7769568021276, 1363.4546181527382, 1367.2070199284412, 1374.0656353679506, 13548.390322435489, 14231.217283049427, 14260.643483912881, 14284.714305713893, 14289.0, 13749.931250343734, 14155.43378264868, 14500.129992200475, 14589.343479543419, 14794.926025369858, 2673.986630066852, 2726.3184203949013, 2728.113363156972, 2733.931651708713, 2735.179446166152, 2618.5810270356747, 2685.2212858271178, 2704.3374373278575, 2721.2244510314563, 2724.387012922087, 2533.8479691218495, 2584.3539115221197, 2664.9600255996174, 2713.74219449152, 2716.7011628720825, 2569.563174260371, 2609.5302845487804, 2620.1615483045434, 2622.632831403605, 2643.339165208698, 2594.4421949280995, 2618.5155746187015, 2638.14260365382, 2639.1158961747838, 2653.204038788375, 13557.25435101069, 13657.931710341385, 13760.0, 13766.724665506734, 13785.241811700353, 7490.400767938552, 7701.460897737161, 7743.496672716312, 7756.0, 7768.922310776877, 4872.366592343008, 4929.950700492985, 5050.444451110375, 5061.924071138936, 5093.363329583802, 1825.6713791517523, 1833.4316361927845, 1838.512794109562, 1864.683003889342, 1871.840893524052, 22267.44331391712, 22318.442038948833, 22397.0, 22412.66381004286, 22497.55004899909, 6806.693698783567, 6842.55523390978, 7175.497715159889, 7178.2103968563415, 7215.386692131227, 14916.552503424698, 14922.776158357634, 14967.550973470814, 15023.624409389853, 15045.924770376078, 7374.889376659355, 7422.331990120807, 7450.441216908645, 7502.549847009197, 7523.699052037967, 5726.599138060379, 6016.126937180898, 6260.561760676709, 6939.826504337331, 7055.717771289091, 4915.287283343967, 5002.374703162105, 5048.444671086178, 5072.326132376307, 5184.092783762826, 1452.4480697335157, 1474.2849717886816, 1474.3586539855198, 1497.7603583426817, 1517.4916403004895, 806.5846089264033, 814.5804910471112, 823.3865770001387, 845.8012367093728, 867.4361664917853, 6255.343188965093, 6492.026196070533, 6545.574537655038, 6616.636085015371, 6658.600483970978, 663.1147418196762, 675.2369822101092, 690.2993461636443, 695.1692727190958, 711.3917600451642, 4193.958060419418, 5030.949690503121, 5141.665791723526, 5162.483751624807, 5391.487808658249, 18584.163712632693, 19205.43361550201, 19330.0, 19616.11727472267, 19750.0, 19431.514212144808, 19619.215231390586, 19703.802961970196, 19746.605067898563, 19835.10742016613, 9738.367006144577, 10838.67483975482, 11072.889271107186, 11107.55569777216, 11167.329960202414, 7343.559386436831, 7758.379329653727, 7783.066032076187, 7954.085280192852, 8005.799855003438, 16118.516444506684, 16179.67640647157, 16596.751048734037, 16616.169191540615, 16677.833221667628, 15401.383944642088, 15880.52358429249, 16830.990140591603, 17283.7407438886, 17590.73613895767, 10842.078423334106, 11435.428228588704, 11586.246893952204, 11597.942010289895, 11628.53485860556, 13305.401256943267, 13389.0, 13404.664883377993, 13434.0, 13451.865481345452, 10197.286189966631, 10557.102646275152, 10570.154387649123, 10748.946255268673, 10833.945830270799, 9579.425234485952, 10231.28381013322, 10239.334443261461, 10281.794364112826, 10314.896851031393, 8730.17063378978, 8816.0, 8893.110688931056, 9008.59461324255, 9024.684136055206, 6347.90478142819, 6602.867942641216, 6641.535092543476, 6671.766488172891, 6810.795676129724, 6599.142111525471, 6670.232923213893, 6720.0, 6817.046612455367, 6824.112865327475], "value": 6187.662126694086, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.9050180465711, 504.4261407924852, 514.6963291657922, 528.8995090932725, 532.2840779152042, 6203.410675985785, 6206.720697568609, 6230.532710046742, 6260.998240281556, 6267.341929097447, 5845.0, 5930.0, 6038.607490513114, 6061.9393806062035, 6099.451049405553, 4855.344528488662, 4867.342908707333, 4874.390701162355, 4883.877903052426, 4893.657443978923, 4740.217864052427, 4838.467768545457, 4851.175300198957, 4888.093643479046, 4909.116359055369, 4753.691010084333, 4755.90488190236, 4792.616590672756, 4801.759912004403, 4828.130936431441, 4797.856064318076, 4813.638977076698, 4824.758762061901, 4833.492483289239, 4835.66339225933, 4280.4221430106845, 4330.830675717571, 4393.340998850166, 4418.3593378960195, 4432.0, 6016.96991515044, 6026.216591843032, 6033.0, 6049.546284028672, 6064.484518815906, 5533.418991005927, 5580.748866300985, 5585.916211256835, 5649.051077378298, 5649.237352957381, 6326.49388048955, 6362.9045564316575, 6439.420452159281, 6442.710078046499, 6505.284418713938, 4808.326834243208, 4814.927776083362, 4817.9036419271415, 4819.108464933998, 4822.565969062801, 6788.694508747119, 6810.182778066664, 6843.794686159423, 6884.965575172142, 6955.339242771928, 4808.471068182497, 4825.420949486084, 4899.975500122477, 4921.261810728348, 4922.556969872729, 4921.803127874845, 4946.406431228275, 4952.5542701157065, 4988.326575912278, 5002.724850133205, 5371.919421208685, 5515.558755299567, 5540.0, 5542.972285138549, 5573.498385145356, 6139.693015349215, 6408.10286559882, 6465.741370345228, 6525.510586706016, 6558.868822623521, 1650.011735858288, 1683.318256106279, 1693.0, 1704.7613334133232, 1711.4865540337848, 998.3460833154296, 1189.6431070678761, 1198.9940050299692, 1200.3398131028075, 1207.885250901163, 5599.440055994368, 5614.9157762633595, 5692.544596432358, 5722.54219662426, 5777.248957635565, 6629.7348106075215, 6637.668116594151, 6672.532922695462, 6689.297623749436, 6733.720593087298, 5722.456366645162, 5736.196932429463, 5770.913436298459, 5792.739326730307, 5798.188253644493, 3531.7351198660203, 3562.305350456636, 3569.3396721606587, 3591.1560783215923, 3607.7474576779373, 6362.681865906686, 6373.235211774617, 6437.066625339393, 6446.645434501147, 6455.903161452582, 6764.801439532073, 6777.525573209828, 6805.557638753465, 6835.521513494107, 6848.0, 6311.873762524632, 6337.4613157882095, 6399.680015999088, 6462.418382345518, 6469.159009328851, 6497.480201583768, 6586.670666466754, 6602.438792702482, 6602.8349291268105, 6618.768343107968, 6493.188351456068, 6535.869282614226, 6547.050677651809, 6610.10763546915, 6635.203775546772, 6336.261605652687, 6359.20509936258, 6423.357664233539, 6453.935460645521, 6464.547481676237, 4926.162552366178, 4944.134776414185, 4951.801927922843, 4959.20652695567, 4972.801087956586, 6681.231658359351, 6731.225909020525, 6731.966340168464, 6771.492138089761, 6818.624975626388, 6370.77702280418, 6400.23197216337, 6415.166028416276, 6468.417842394207, 6480.578762380619, 6410.871782564416, 6435.908329792743, 6465.418112369909, 6486.643234622141, 6487.837804054936, 6395.880720873922, 6421.743130274736, 6430.292667806444, 6430.742770289323, 6437.549371543947, 6484.448821850196, 6491.805245842632, 6519.956806910872, 6533.640649764308, 6573.244076931214, 5728.914066288926, 6268.0, 6298.811035668938, 6304.369563043842, 6312.589681670676], "value": 4972.869810951181, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.9645214191432, 903.8508646073399, 915.5696822493426, 918.830016446957, 936.6347124621399, 17414.564635884093, 18281.817181828177, 18345.266189352442, 18542.721859172107, 21289.14843406262, 20233.29183478575, 21341.0, 21437.892810535963, 21507.56984860302, 21522.139114435402, 1007.0785231513175, 1046.6022911293735, 1048.2714513413182, 1049.884512703602, 1050.0969166516788, 958.4967891856761, 1041.677080105166, 1049.228816819637, 1057.4501259345136, 1063.478895341281, 1099.1756182862853, 1101.1136035491427, 1104.5029736618515, 1105.5633024955139, 1106.4744246482937, 1078.5308390849966, 1080.0441609175896, 1088.7495875948537, 1089.5805115030691, 1090.8854570270166, 804.4328748232482, 804.5253300552644, 804.8148925747053, 805.0500409516768, 806.238104990784, 19282.132304046212, 19459.805401946083, 19617.9019104905, 19792.0, 20290.49273768153, 19681.704774428395, 20208.898955505276, 20284.492887677778, 20497.28259510925, 20653.380398588066, 14231.217283049427, 14439.0, 14524.491642792556, 14541.709165816625, 14595.0, 3501.124718820295, 3524.9118772030647, 3530.894073177809, 3530.9646903530893, 3531.5408996830247, 114789.99999999917, 117254.41372793166, 120595.0, 122930.0, 126191.36904315511, 16117.630001449774, 16167.0, 16312.75530867038, 16395.098269595048, 16427.753583696252, 27463.588046179324, 29381.26546836346, 29543.409131817243, 29663.55504667432, 29703.0, 7051.89422158668, 8121.8375632488205, 8723.825523489622, 8880.067592902782, 8902.910970890338, 4325.480942286883, 4467.396901418332, 4474.910501789946, 4477.7984990675495, 4486.3494793254795, 4933.802647894116, 5027.044861476308, 5049.26785616091, 5097.8725531862, 5120.129577971754, 1005.7686732051669, 1015.2943704125562, 1032.8708911386077, 1037.5901518899993, 1037.8131936251548, 618.3136718242747, 628.5725706519519, 646.8285904235381, 663.4526515624628, 669.5380187670492, 10647.787044259072, 11223.270487418291, 11481.193565747719, 12044.0, 12275.75448491043, 622.8598565322766, 663.4857985061519, 664.4319107163305, 668.3116390118186, 679.0934102972587, 5231.738413079407, 5242.7116508591635, 5366.141417373279, 5399.730013499309, 5435.673859568438, 98070.0, 98554.50722746484, 100388.49805750925, 100593.0, 103202.0, 81526.0, 82393.0, 87933.56033219793, 89335.0, 89373.0, 71175.0, 74394.0, 74526.0, 74917.62541187259, 75274.62362688151, 10572.20708446885, 10735.409552474688, 11508.82736758966, 12271.035740153278, 13054.15148015376, 31783.0, 33444.163895901824, 33663.83168084144, 34695.0, 35881.82059089688, 62542.0, 64738.0, 65065.0, 65433.34566654463, 66770.0, 18040.18819153115, 18285.360012399502, 18466.261349546407, 18608.534786630444, 18668.0, 20003.09986050602, 20162.395128146178, 20466.897665511577, 20543.589128217653, 20551.075201615666, 17530.91234543819, 17594.208260628046, 17712.46862594124, 17748.3788067417, 17856.375026873997, 18035.0, 18077.728834067762, 18204.089795510437, 18224.544386390447, 18282.72575911389, 15283.61790955235, 16886.493405197863, 16977.915110424863, 17161.82838171652, 17310.82689173092, 12162.513499460276, 12327.260364378166, 12855.550055748005, 12925.612231633066, 13016.414261358072, 13750.65623359424, 13978.650533736738, 14214.0, 14251.928740356232, 14449.277536123362], "value": 8864.602363569607, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1543.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 106.0, 109.0, 110.0, 110.0, 110.0, 100.0, 111.0, 114.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 125.0, 145.0, 184.0, 122.0, 148.0, 150.0, 124.0, 171.0, 118.0], "value": 144.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 166.0, 165.0, 163.0, 164.0, 167.0, 167.0, 164.0, 165.0], "value": 165.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 189.0, 78.0, 74.0, 77.0, 77.0, 148.0, 77.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 136.0, 80.0, 85.0, 80.0, 131.0, 80.0, 79.0, 147.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 118.0, 114.0, 113.0, 117.0, 117.0, 115.0, 116.0, 123.0], "value": 116.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 153.0, 152.0, 154.0, 151.0, 151.0, 152.0, 152.0, 152.0], "value": 152.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 58.0, 57.0, 57.0, 57.0, 56.0, 55.0, 56.0, 56.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 85.0, 81.0, 79.0, 88.0, 90.0, 79.0, 79.0, 79.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 123.0, 130.0, 127.0, 131.0, 125.0, 124.0, 129.0, 130.0, 165.0], "value": 131.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 180.0, 186.0, 172.0, 174.0, 173.0, 174.0, 175.0, 177.0, 179.0], "value": 177.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 68.0, 69.0, 72.0, 70.0, 68.0, 71.0, 68.0, 68.0], "value": 69.6, "unit": "score"}]}]} 17:16:20 INFO - Return code: 0 17:16:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:16:20 INFO - # TBPL SUCCESS # 17:16:20 INFO - Running post-action listener: _package_coverage_data 17:16:20 INFO - Running post-action listener: _resource_record_post_action 17:16:20 INFO - [mozharness: 2017-07-28 00:16:20.969868Z] Finished run-tests step (success) 17:16:20 INFO - Running post-run listener: _resource_record_post_run 17:16:21 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 5562368; Write bytes: 432005120; Read time: 2188; Write time: 460716 17:16:21 INFO - TinderboxPrint: CPU usage
13.2% 17:16:21 INFO - TinderboxPrint: I/O read bytes / time
5,562,368 / 2,188 17:16:21 INFO - TinderboxPrint: I/O write bytes / time
432,005,120 / 460,716 17:16:21 INFO - TinderboxPrint: CPU idle
8,284.8 (86.8%) 17:16:21 INFO - TinderboxPrint: CPU user
1,201.8 (12.6%) 17:16:21 INFO - TinderboxPrint: Swap in / out
0 / 0 17:16:21 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107220992; Read time: 0; Write time: 109780 17:16:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:16:21 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 3530752; Write bytes: 274317312; Read time: 1840; Write time: 322636 17:16:21 INFO - Running post-run listener: _upload_blobber_files 17:16:21 INFO - Blob upload gear active. 17:16:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:16:21 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 17:16:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:16:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:16:22 INFO - (blobuploader) - INFO - Open directory for files ... 17:16:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:16:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:16:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:22 INFO - (blobuploader) - INFO - Done attempting. 17:16:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:16:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:16:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:23 INFO - (blobuploader) - INFO - Done attempting. 17:16:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:16:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:16:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:27 INFO - (blobuploader) - INFO - Done attempting. 17:16:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:16:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:16:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:28 INFO - (blobuploader) - INFO - Done attempting. 17:16:28 INFO - (blobuploader) - INFO - Iteration through files over. 17:16:28 INFO - Return code: 0 17:16:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:16:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:16:28 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed93287aa402ffa5ecc184d71214d746b6773e7e437562095e1c17282505db72f1ef15849478c157eb60b72cfd7b8d0131119c0c8d67dc9f616302faf013cb96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2498f467a70cf038384c1de6f854eb2ddcf6abb1274c64bec116bccc2359eab651b749a8d88bda6182493e3f0f4d9eb8b17745121f88a0d75fe300c8f64af09b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/742b02f9f4ed6aa624c9419132cd46ec4754643caac437efc591001b95fa013e6102bd521ccb81edf4c342ec373d842364a97969c146b53b81774f7e3a693067", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f1b1c336cd26696585db598f9d49c00676e0f37ab80d5db01542d2954c50ad742aff4ffafe89feb06c2fc2c658584d462609a657a2ae286cc7e6aa592a1f5c8"} 17:16:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:16:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:16:28 INFO - Contents: 17:16:28 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed93287aa402ffa5ecc184d71214d746b6773e7e437562095e1c17282505db72f1ef15849478c157eb60b72cfd7b8d0131119c0c8d67dc9f616302faf013cb96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2498f467a70cf038384c1de6f854eb2ddcf6abb1274c64bec116bccc2359eab651b749a8d88bda6182493e3f0f4d9eb8b17745121f88a0d75fe300c8f64af09b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/742b02f9f4ed6aa624c9419132cd46ec4754643caac437efc591001b95fa013e6102bd521ccb81edf4c342ec373d842364a97969c146b53b81774f7e3a693067", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f1b1c336cd26696585db598f9d49c00676e0f37ab80d5db01542d2954c50ad742aff4ffafe89feb06c2fc2c658584d462609a657a2ae286cc7e6aa592a1f5c8"} 17:16:28 INFO - Running post-run listener: copy_logs_to_upload_dir 17:16:28 INFO - Copying logs to upload dir... 17:16:28 INFO - mkdir: /builds/slave/test/build/upload/logs 17:16:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.733656 ========= master_lag: 0.45 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-07-27 17:16:28.989810) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 17:16:28.993191) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed93287aa402ffa5ecc184d71214d746b6773e7e437562095e1c17282505db72f1ef15849478c157eb60b72cfd7b8d0131119c0c8d67dc9f616302faf013cb96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2498f467a70cf038384c1de6f854eb2ddcf6abb1274c64bec116bccc2359eab651b749a8d88bda6182493e3f0f4d9eb8b17745121f88a0d75fe300c8f64af09b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/742b02f9f4ed6aa624c9419132cd46ec4754643caac437efc591001b95fa013e6102bd521ccb81edf4c342ec373d842364a97969c146b53b81774f7e3a693067", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f1b1c336cd26696585db598f9d49c00676e0f37ab80d5db01542d2954c50ad742aff4ffafe89feb06c2fc2c658584d462609a657a2ae286cc7e6aa592a1f5c8"} build_url:https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011186 build_url: 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed93287aa402ffa5ecc184d71214d746b6773e7e437562095e1c17282505db72f1ef15849478c157eb60b72cfd7b8d0131119c0c8d67dc9f616302faf013cb96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2498f467a70cf038384c1de6f854eb2ddcf6abb1274c64bec116bccc2359eab651b749a8d88bda6182493e3f0f4d9eb8b17745121f88a0d75fe300c8f64af09b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/742b02f9f4ed6aa624c9419132cd46ec4754643caac437efc591001b95fa013e6102bd521ccb81edf4c342ec373d842364a97969c146b53b81774f7e3a693067", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f1b1c336cd26696585db598f9d49c00676e0f37ab80d5db01542d2954c50ad742aff4ffafe89feb06c2fc2c658584d462609a657a2ae286cc7e6aa592a1f5c8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 17:16:29.017074) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 17:16:29.017379) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1501199724.144689-1402776150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004320 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 17:16:29.101544) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 17:16:29.101871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 17:16:29.102141) ========= ========= Total master_lag: 0.62 =========