builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-022 starttime: 1501198400.23 results: success (0) revision: 0aab101edb50b91aa1c16b372fab382a60137482 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:20.233372) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:20.233825) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:20.234441) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:20.253539) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:20.253979) ========= 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=930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-27 16:33:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 109M=0s 2017-07-27 16:33:20 (109 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118996 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:20.390976) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:20.391257) ========= 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=930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022363 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:20.440433) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:20.440727) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0aab101edb50b91aa1c16b372fab382a60137482 --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 0aab101edb50b91aa1c16b372fab382a60137482 --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=930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-27 16:33:20,612 truncating revision to first 12 chars 2017-07-27 16:33:20,612 Setting DEBUG logging. 2017-07-27 16:33:20,612 attempt 1/10 2017-07-27 16:33:20,612 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0aab101edb50?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-27 16:33:20,907 unpacking tar archive at: mozilla-central-0aab101edb50/testing/mozharness/ program finished with exit code 0 elapsedTime=0.654855 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:21.108317) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:21.108650) ========= script_repo_revision: 0aab101edb50b91aa1c16b372fab382a60137482 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:21.112019) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:21.112345) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 16:33:21.123164) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-07-27 16:33:21.123436) ========= /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=930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:33:21 INFO - MultiFileLogger online at 20170727 16:33:21 in /builds/slave/test 16:33:21 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:33:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:33:21 INFO - {'append_to_log': False, 16:33:21 INFO - 'base_work_dir': '/builds/slave/test', 16:33:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:33:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:33:21 INFO - 'branch': 'Firefox-Non-PGO', 16:33:21 INFO - 'buildbot_json_path': 'buildprops.json', 16:33:21 INFO - 'code_coverage': False, 16:33:21 INFO - 'config_files': ('talos/linux_config.py',), 16:33:21 INFO - 'default_actions': ('clobber', 16:33:21 INFO - 'read-buildbot-config', 16:33:21 INFO - 'download-and-extract', 16:33:21 INFO - 'populate-webroot', 16:33:21 INFO - 'create-virtualenv', 16:33:21 INFO - 'install', 16:33:21 INFO - 'setup-mitmproxy', 16:33:21 INFO - 'run-tests'), 16:33:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:33:21 INFO - 'disable_ccov_upload': False, 16:33:21 INFO - 'download_minidump_stackwalk': True, 16:33:21 INFO - 'download_symbols': 'ondemand', 16:33:21 INFO - 'enable_stylo': False, 16:33:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:33:21 INFO - 'tooltool.py': '/tools/tooltool.py', 16:33:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:33:21 INFO - '/tools/misc-python/virtualenv.py')}, 16:33:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:33:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:33:21 INFO - 'gecko_profile': False, 16:33:21 INFO - 'gecko_profile_interval': 0, 16:33:21 INFO - 'installer_path': 'installer.exe', 16:33:21 INFO - 'log_level': 'info', 16:33:21 INFO - 'log_name': 'talos', 16:33:21 INFO - 'log_to_console': True, 16:33:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:33:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:33:21 INFO - 'opt_config_files': (), 16:33:21 INFO - 'pip_index': False, 16:33:21 INFO - 'suite': 'dromaeojs-e10s', 16:33:21 INFO - 'system_bits': '32', 16:33:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:33:21 INFO - 'title': 'talos-linux64-ix-022', 16:33:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:33:21 INFO - 'use_talos_json': True, 16:33:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:33:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:33:21 INFO - 'work_dir': 'build'} 16:33:21 INFO - [mozharness: 2017-07-27 23:33:21.404307Z] Running clobber step. 16:33:21 INFO - Running pre-action listener: _resource_record_pre_action 16:33:21 INFO - Running main action method: clobber 16:33:21 INFO - rmtree: /builds/slave/test/build 16:33:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:33:22 INFO - Running post-action listener: _resource_record_post_action 16:33:22 INFO - [mozharness: 2017-07-27 23:33:22.427798Z] Finished clobber step (success) 16:33:22 INFO - [mozharness: 2017-07-27 23:33:22.427902Z] Running read-buildbot-config step. 16:33:22 INFO - Running pre-action listener: _resource_record_pre_action 16:33:22 INFO - Running main action method: read_buildbot_config 16:33:22 INFO - Using buildbot properties: 16:33:22 INFO - { 16:33:22 INFO - "project": "", 16:33:22 INFO - "product": "firefox", 16:33:22 INFO - "who": "kwierso@gmail.com", 16:33:22 INFO - "installer_path": "public/build/target.tar.bz2", 16:33:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:33:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:33:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:33:22 INFO - "stage_platform": "linux64", 16:33:22 INFO - "basedir": "/builds/slave/test", 16:33:22 INFO - "buildnumber": 1038, 16:33:22 INFO - "platform": "ubuntu64_hw", 16:33:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:33:22 INFO - "slavebuilddir": "test", 16:33:22 INFO - "taskId": "atPxhIXPTCad6YxxWZ-ZPw", 16:33:22 INFO - "branch": "mozilla-central", 16:33:22 INFO - "script_repo_revision": "production", 16:33:22 INFO - "revision": "0aab101edb50b91aa1c16b372fab382a60137482", 16:33:22 INFO - "slavename": "talos-linux64-ix-022", 16:33:22 INFO - "repo_path": "mozilla-central" 16:33:22 INFO - } 16:33:22 INFO - Finding installer, test and symbols from parent task. 16:33:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/atPxhIXPTCad6YxxWZ-ZPw'}, attempt #1 16:33:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/atPxhIXPTCad6YxxWZ-ZPw'}, attempt #1 16:33:23 INFO - Task dependencies: ZxfIrUWxTQKL2cp9g6TJ_A 16:33:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A'}, attempt #1 16:33:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.tar.bz2 16:33:23 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/test_packages.json 16:33:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.crashreporter-symbols.zip 16:33:23 INFO - Running post-action listener: _resource_record_post_action 16:33:23 INFO - [mozharness: 2017-07-27 23:33:23.877630Z] Finished read-buildbot-config step (success) 16:33:23 INFO - [mozharness: 2017-07-27 23:33:23.877850Z] Running download-and-extract step. 16:33:23 INFO - Running pre-action listener: _resource_record_pre_action 16:33:23 INFO - Running main action method: download_and_extract 16:33:23 INFO - mkdir: /builds/slave/test/build/tests 16:33:23 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:33:23 INFO - trying https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.test_packages.json 16:33:23 INFO - Downloading https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:33:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:33:24 INFO - Downloaded 989 bytes. 16:33:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:33:24 INFO - Using the following test package requirements: 16:33:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:33:24 INFO - u'common': [u'target.common.tests.zip'], 16:33:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:33:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:33:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:33:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:33:24 INFO - u'mozbase': [u'target.common.tests.zip'], 16:33:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:33:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:33:24 INFO - u'web-platform': [u'target.common.tests.zip', 16:33:24 INFO - u'target.web-platform.tests.tar.gz'], 16:33:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:33:24 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:33:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.common.tests.zip 16:33:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:33:24 INFO - Fetch https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.common.tests.zip into memory 16:33:27 INFO - Content-Length response header: 72553865 16:33:27 INFO - Bytes received: 72553865 16:33:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:33:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.common.tests.zip 16:33:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:33:38 INFO - Fetch https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.common.tests.zip into memory 16:33:40 INFO - Content-Length response header: 72553865 16:33:40 INFO - Bytes received: 72553865 16:33:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.talos.tests.zip 16:33:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:33:55 INFO - Fetch https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.talos.tests.zip into memory 16:33:56 INFO - Content-Length response header: 14496465 16:33:56 INFO - Bytes received: 14496465 16:33:56 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:33:56 INFO - trying https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.tar.bz2 16:33:56 INFO - Downloading https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:33:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:33:57 INFO - Downloaded 62100447 bytes. 16:33:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.tar.bz2 16:33:57 INFO - mkdir: /builds/slave/test/properties 16:33:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:33:57 INFO - Writing to file /builds/slave/test/properties/build_url 16:33:57 INFO - Contents: 16:33:57 INFO - build_url:https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.tar.bz2 16:33:57 INFO - Running post-action listener: _resource_record_post_action 16:33:57 INFO - Running post-action listener: set_extra_try_arguments 16:33:57 INFO - [mozharness: 2017-07-27 23:33:57.915224Z] Finished download-and-extract step (success) 16:33:57 INFO - [mozharness: 2017-07-27 23:33:57.915320Z] Running populate-webroot step. 16:33:57 INFO - Running pre-action listener: _resource_record_pre_action 16:33:57 INFO - Running main action method: populate_webroot 16:33:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:33:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:33:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:33:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:33:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:33:57 INFO - 'tests': ['damp', 'tps']}, 16:33:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:33:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:33:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:33:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:33:57 INFO - 'other-e10s': {'tests': ['a11yr', 16:33:57 INFO - 'ts_paint', 16:33:57 INFO - 'tpaint', 16:33:57 INFO - 'sessionrestore', 16:33:57 INFO - 'sessionrestore_many_windows', 16:33:57 INFO - 'sessionrestore_no_auto_restore', 16:33:57 INFO - 'tabpaint']}, 16:33:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:33:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:33:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:33:57 INFO - 'talos_options': ['--mitmproxy', 16:33:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:33:57 INFO - '--firstNonBlankPaint'], 16:33:57 INFO - 'tests': ['quantum_pageload_google', 16:33:57 INFO - 'quantum_pageload_youtube', 16:33:57 INFO - 'quantum_pageload_amazon', 16:33:57 INFO - 'quantum_pageload_facebook']}, 16:33:57 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:33:57 INFO - 'talos_options': ['--stylo', 16:33:57 INFO - '--mitmproxy', 16:33:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:33:57 INFO - '--firstNonBlankPaint'], 16:33:57 INFO - 'tests': ['quantum_pageload_google', 16:33:57 INFO - 'quantum_pageload_youtube', 16:33:57 INFO - 'quantum_pageload_amazon', 16:33:57 INFO - 'quantum_pageload_facebook']}, 16:33:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:33:57 INFO - 'tsvgr_opacity', 16:33:57 INFO - 'tart', 16:33:57 INFO - 'tscrollx', 16:33:57 INFO - 'cart', 16:33:57 INFO - 'tsvg_static']}, 16:33:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:33:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:33:57 INFO - 'talos_options': ['--xperf_path', 16:33:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:33:57 INFO - 'tests': ['tp5n']}}} 16:33:57 INFO - Running post-action listener: _resource_record_post_action 16:33:57 INFO - [mozharness: 2017-07-27 23:33:57.925178Z] Finished populate-webroot step (success) 16:33:57 INFO - [mozharness: 2017-07-27 23:33:57.925268Z] Running create-virtualenv step. 16:33:57 INFO - Running pre-action listener: _resource_record_pre_action 16:33:57 INFO - Running main action method: create_virtualenv 16:33:57 INFO - Creating virtualenv /builds/slave/test/build/venv 16:33:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:33:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:33:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:33:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:33:58 INFO - Using real prefix '/usr' 16:33:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:33:58 INFO - Installing distribute.............................................................................................................................................................................................done. 16:34:00 INFO - Installing pip.................done. 16:34:00 INFO - Return code: 0 16:34:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:34:00 INFO - Reading from file tmpfile_stdout 16:34:00 INFO - Output received: 16:34:00 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:34:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:34:00 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:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:00 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:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:00 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:34:00 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:34:00 INFO - Using env: {'DISPLAY': ':0', 16:34:00 INFO - 'HOME': '/home/cltbld', 16:34:00 INFO - 'LANG': 'en_US.UTF-8', 16:34:00 INFO - 'LANGUAGE': 'en_US:en', 16:34:00 INFO - 'LOGNAME': 'cltbld', 16:34:00 INFO - 'MAIL': '/var/mail/cltbld', 16:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:00 INFO - 'MOZ_NO_REMOTE': '1', 16:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:00 INFO - 'NO_EM_RESTART': '1', 16:34:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:00 INFO - 'PWD': '/builds/slave/test', 16:34:00 INFO - 'SHELL': '/bin/bash', 16:34:00 INFO - 'SHLVL': '1', 16:34:00 INFO - 'TERM': 'linux', 16:34:00 INFO - 'TMOUT': '86400', 16:34:00 INFO - 'USER': 'cltbld', 16:34:00 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:00 INFO - '_': '/tools/buildbot/bin/python'} 16:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:34:00 INFO - Cleaning up... 16:34:00 INFO - Return code: 0 16:34:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:34:00 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:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:00 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:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:00 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:34:00 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:34:00 INFO - Using env: {'DISPLAY': ':0', 16:34:00 INFO - 'HOME': '/home/cltbld', 16:34:00 INFO - 'LANG': 'en_US.UTF-8', 16:34:00 INFO - 'LANGUAGE': 'en_US:en', 16:34:00 INFO - 'LOGNAME': 'cltbld', 16:34:00 INFO - 'MAIL': '/var/mail/cltbld', 16:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:00 INFO - 'MOZ_NO_REMOTE': '1', 16:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:00 INFO - 'NO_EM_RESTART': '1', 16:34:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:00 INFO - 'PWD': '/builds/slave/test', 16:34:00 INFO - 'SHELL': '/bin/bash', 16:34:00 INFO - 'SHLVL': '1', 16:34:00 INFO - 'TERM': 'linux', 16:34:00 INFO - 'TMOUT': '86400', 16:34:00 INFO - 'USER': 'cltbld', 16:34:00 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:00 INFO - '_': '/tools/buildbot/bin/python'} 16:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:01 INFO - Downloading/unpacking psutil>=3.1.1 16:34:01 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:34:01 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:34:01 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:34:01 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:34:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:34:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:34:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:34:02 INFO - Installing collected packages: psutil 16:34:02 INFO - Running setup.py install for psutil 16:34:02 INFO - building 'psutil._psutil_linux' extension 16:34:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:34:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:34:02 INFO - building 'psutil._psutil_posix' extension 16:34:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:34:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:34:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:34:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:34:02 INFO - Successfully installed psutil 16:34:02 INFO - Cleaning up... 16:34:02 INFO - Return code: 0 16:34:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:34:02 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:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:02 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:34:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:02 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:02 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:34:02 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:34:02 INFO - Using env: {'DISPLAY': ':0', 16:34:02 INFO - 'HOME': '/home/cltbld', 16:34:02 INFO - 'LANG': 'en_US.UTF-8', 16:34:02 INFO - 'LANGUAGE': 'en_US:en', 16:34:02 INFO - 'LOGNAME': 'cltbld', 16:34:02 INFO - 'MAIL': '/var/mail/cltbld', 16:34:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:02 INFO - 'MOZ_NO_REMOTE': '1', 16:34:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:02 INFO - 'NO_EM_RESTART': '1', 16:34:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:02 INFO - 'PWD': '/builds/slave/test', 16:34:02 INFO - 'SHELL': '/bin/bash', 16:34:02 INFO - 'SHLVL': '1', 16:34:02 INFO - 'TERM': 'linux', 16:34:02 INFO - 'TMOUT': '86400', 16:34:02 INFO - 'USER': 'cltbld', 16:34:02 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:02 INFO - '_': '/tools/buildbot/bin/python'} 16:34:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:34: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:34: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:34: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:34: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:34:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:34:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:34:04 INFO - Installing collected packages: mozsystemmonitor 16:34:04 INFO - Running setup.py install for mozsystemmonitor 16:34:04 INFO - Successfully installed mozsystemmonitor 16:34:04 INFO - Cleaning up... 16:34:04 INFO - Return code: 0 16:34:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:34:04 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:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:04 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:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:04 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:04 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:34:04 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:34:04 INFO - Using env: {'DISPLAY': ':0', 16:34:04 INFO - 'HOME': '/home/cltbld', 16:34:04 INFO - 'LANG': 'en_US.UTF-8', 16:34:04 INFO - 'LANGUAGE': 'en_US:en', 16:34:04 INFO - 'LOGNAME': 'cltbld', 16:34:04 INFO - 'MAIL': '/var/mail/cltbld', 16:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:04 INFO - 'MOZ_NO_REMOTE': '1', 16:34:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:04 INFO - 'NO_EM_RESTART': '1', 16:34:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:04 INFO - 'PWD': '/builds/slave/test', 16:34:04 INFO - 'SHELL': '/bin/bash', 16:34:04 INFO - 'SHLVL': '1', 16:34:04 INFO - 'TERM': 'linux', 16:34:04 INFO - 'TMOUT': '86400', 16:34:04 INFO - 'USER': 'cltbld', 16:34:04 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:04 INFO - '_': '/tools/buildbot/bin/python'} 16:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:04 INFO - Downloading/unpacking jsonschema==2.5.1 16:34:04 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:34:04 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:34:04 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:34:04 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:34:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:34:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:34:05 INFO - warning: no files found matching '*.txt' 16:34:05 INFO - no previously-included directories found matching 'build' 16:34:05 INFO - no previously-included directories found matching 'dist' 16:34:05 INFO - no previously-included directories found matching '.git*' 16:34:05 INFO - Installing collected packages: jsonschema, functools32 16:34:05 INFO - Running setup.py install for functools32 16:34:05 INFO - warning: no files found matching '*.txt' 16:34:05 INFO - no previously-included directories found matching 'build' 16:34:05 INFO - no previously-included directories found matching 'dist' 16:34:05 INFO - no previously-included directories found matching '.git*' 16:34:05 INFO - Successfully installed jsonschema functools32 16:34:05 INFO - Cleaning up... 16:34:06 INFO - Return code: 0 16:34:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:34:06 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:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:06 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:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:06 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:06 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:34:06 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:34:06 INFO - Using env: {'DISPLAY': ':0', 16:34:06 INFO - 'HOME': '/home/cltbld', 16:34:06 INFO - 'LANG': 'en_US.UTF-8', 16:34:06 INFO - 'LANGUAGE': 'en_US:en', 16:34:06 INFO - 'LOGNAME': 'cltbld', 16:34:06 INFO - 'MAIL': '/var/mail/cltbld', 16:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:06 INFO - 'MOZ_NO_REMOTE': '1', 16:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:06 INFO - 'NO_EM_RESTART': '1', 16:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:06 INFO - 'PWD': '/builds/slave/test', 16:34:06 INFO - 'SHELL': '/bin/bash', 16:34:06 INFO - 'SHLVL': '1', 16:34:06 INFO - 'TERM': 'linux', 16:34:06 INFO - 'TMOUT': '86400', 16:34:06 INFO - 'USER': 'cltbld', 16:34:06 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:06 INFO - '_': '/tools/buildbot/bin/python'} 16:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:34:06 INFO - Cleaning up... 16:34:06 INFO - Return code: 0 16:34:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:34:06 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:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:06 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:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:06 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:06 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:34:06 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:34:06 INFO - Using env: {'DISPLAY': ':0', 16:34:06 INFO - 'HOME': '/home/cltbld', 16:34:06 INFO - 'LANG': 'en_US.UTF-8', 16:34:06 INFO - 'LANGUAGE': 'en_US:en', 16:34:06 INFO - 'LOGNAME': 'cltbld', 16:34:06 INFO - 'MAIL': '/var/mail/cltbld', 16:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:06 INFO - 'MOZ_NO_REMOTE': '1', 16:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:06 INFO - 'NO_EM_RESTART': '1', 16:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:06 INFO - 'PWD': '/builds/slave/test', 16:34:06 INFO - 'SHELL': '/bin/bash', 16:34:06 INFO - 'SHLVL': '1', 16:34:06 INFO - 'TERM': 'linux', 16:34:06 INFO - 'TMOUT': '86400', 16:34:06 INFO - 'USER': 'cltbld', 16:34:06 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:06 INFO - '_': '/tools/buildbot/bin/python'} 16:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:06 INFO - Downloading/unpacking blobuploader==1.2.4 16:34:06 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:34:06 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:34:06 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:34:06 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:34:07 INFO - Downloading blobuploader-1.2.4.tar.gz 16:34:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:34:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:34:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:08 INFO - Downloading docopt-0.6.1.tar.gz 16:34:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:34:08 INFO - Installing collected packages: blobuploader, requests, docopt 16:34:08 INFO - Running setup.py install for blobuploader 16:34:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:34:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:34:08 INFO - Running setup.py install for requests 16:34:08 INFO - Running setup.py install for docopt 16:34:08 INFO - Successfully installed blobuploader requests docopt 16:34:08 INFO - Cleaning up... 16:34:08 INFO - Return code: 0 16:34:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34:08 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:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:08 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:34:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:08 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:08 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:34:08 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:34:08 INFO - Using env: {'DISPLAY': ':0', 16:34:08 INFO - 'HOME': '/home/cltbld', 16:34:08 INFO - 'LANG': 'en_US.UTF-8', 16:34:08 INFO - 'LANGUAGE': 'en_US:en', 16:34:08 INFO - 'LOGNAME': 'cltbld', 16:34:08 INFO - 'MAIL': '/var/mail/cltbld', 16:34:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:08 INFO - 'MOZ_NO_REMOTE': '1', 16:34:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:08 INFO - 'NO_EM_RESTART': '1', 16:34:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:08 INFO - 'PWD': '/builds/slave/test', 16:34:08 INFO - 'SHELL': '/bin/bash', 16:34:08 INFO - 'SHLVL': '1', 16:34:08 INFO - 'TERM': 'linux', 16:34:08 INFO - 'TMOUT': '86400', 16:34:08 INFO - 'USER': 'cltbld', 16:34:08 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:08 INFO - '_': '/tools/buildbot/bin/python'} 16:34:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:34:08 INFO - Running setup.py (path:/tmp/pip-p761WW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:34:09 INFO - Running setup.py (path:/tmp/pip-9DmB9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:34:09 INFO - Running setup.py (path:/tmp/pip-lGFXaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:34:09 INFO - Running setup.py (path:/tmp/pip-pQiDh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:34:09 INFO - Running setup.py (path:/tmp/pip-AiVAok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:34:09 INFO - Running setup.py (path:/tmp/pip-xyo_6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:34:09 INFO - Running setup.py (path:/tmp/pip-4WMW0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:34:09 INFO - Running setup.py (path:/tmp/pip-nLYYCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:34:09 INFO - Running setup.py (path:/tmp/pip-TPbmVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:34:09 INFO - Running setup.py (path:/tmp/pip-7PBXVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:34:09 INFO - Running setup.py (path:/tmp/pip-DSEnp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:34:09 INFO - Running setup.py (path:/tmp/pip-FUeuKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:34:09 INFO - Running setup.py (path:/tmp/pip-Fu8_JN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:34:09 INFO - Running setup.py (path:/tmp/pip-822Dzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:09 INFO - Running setup.py (path:/tmp/pip-LcdgVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:34:10 INFO - Running setup.py (path:/tmp/pip-iTkoKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:34:10 INFO - Running setup.py (path:/tmp/pip-IQxvPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:34:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:34:10 INFO - Running setup.py install for manifestparser 16:34:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:34:10 INFO - Running setup.py install for mozcrash 16:34:10 INFO - Running setup.py install for mozdebug 16:34:10 INFO - Running setup.py install for mozdevice 16:34:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:34:10 INFO - Running setup.py install for mozfile 16:34:10 INFO - Running setup.py install for mozhttpd 16:34:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:34:10 INFO - Running setup.py install for mozinfo 16:34:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:34:10 INFO - Running setup.py install for mozInstall 16:34:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:34:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:34:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:34:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:34:10 INFO - Running setup.py install for mozleak 16:34:11 INFO - Running setup.py install for mozlog 16:34:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:34:11 INFO - Running setup.py install for moznetwork 16:34:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:34:11 INFO - Running setup.py install for mozprocess 16:34:11 INFO - Running setup.py install for mozprofile 16:34:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:34:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:34:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:34:11 INFO - Running setup.py install for mozrunner 16:34:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:34:11 INFO - Running setup.py install for mozscreenshot 16:34:11 INFO - Running setup.py install for moztest 16:34:11 INFO - Running setup.py install for mozversion 16:34:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:34:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:34:11 INFO - Cleaning up... 16:34:11 INFO - Return code: 0 16:34:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34: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:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34: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:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:11 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:11 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:34:11 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:34:11 INFO - Using env: {'DISPLAY': ':0', 16:34:11 INFO - 'HOME': '/home/cltbld', 16:34:11 INFO - 'LANG': 'en_US.UTF-8', 16:34:11 INFO - 'LANGUAGE': 'en_US:en', 16:34:11 INFO - 'LOGNAME': 'cltbld', 16:34:11 INFO - 'MAIL': '/var/mail/cltbld', 16:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:11 INFO - 'MOZ_NO_REMOTE': '1', 16:34:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:11 INFO - 'NO_EM_RESTART': '1', 16:34:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:11 INFO - 'PWD': '/builds/slave/test', 16:34:11 INFO - 'SHELL': '/bin/bash', 16:34:11 INFO - 'SHLVL': '1', 16:34:11 INFO - 'TERM': 'linux', 16:34:11 INFO - 'TMOUT': '86400', 16:34:11 INFO - 'USER': 'cltbld', 16:34:11 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:11 INFO - '_': '/tools/buildbot/bin/python'} 16:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:34:12 INFO - Running setup.py (path:/tmp/pip-nwzm5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:34:12 INFO - Running setup.py (path:/tmp/pip-BTyHfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:34:12 INFO - Running setup.py (path:/tmp/pip-DnC78E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:34:12 INFO - Running setup.py (path:/tmp/pip-eyUdTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:34:12 INFO - Running setup.py (path:/tmp/pip-jJfinQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:34:12 INFO - Running setup.py (path:/tmp/pip-5EHwuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:34:12 INFO - Running setup.py (path:/tmp/pip-P0a3WB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:34:12 INFO - Running setup.py (path:/tmp/pip-awFkkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:34:12 INFO - Running setup.py (path:/tmp/pip-SJ2wFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:34:12 INFO - Running setup.py (path:/tmp/pip-4dU4r_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:34:12 INFO - Running setup.py (path:/tmp/pip-z0H1Nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:34:12 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:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:34:12 INFO - Running setup.py (path:/tmp/pip-JNidJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:34:13 INFO - Running setup.py (path:/tmp/pip-ukDmit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:34:13 INFO - Running setup.py (path:/tmp/pip-FrwhaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:13 INFO - Running setup.py (path:/tmp/pip-Ennx0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:34:13 INFO - Running setup.py (path:/tmp/pip-csU5DI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:34:13 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:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:34:13 INFO - Running setup.py (path:/tmp/pip-fUxDEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:34:13 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:34:13 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:34:13 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:34:13 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:34:13 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:34:13 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:34:13 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:34:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:14 INFO - Downloading blessings-1.6.tar.gz 16:34:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:34:14 INFO - Installing collected packages: blessings 16:34:14 INFO - Running setup.py install for blessings 16:34:14 INFO - Successfully installed blessings 16:34:14 INFO - Cleaning up... 16:34:14 INFO - Return code: 0 16:34:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:34:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:34:14 INFO - Reading from file tmpfile_stdout 16:34:14 INFO - Current package versions: 16:34:14 INFO - argparse == 1.2.1 16:34:14 INFO - blessings == 1.6 16:34:14 INFO - blobuploader == 1.2.4 16:34:14 INFO - docopt == 0.6.1 16:34:14 INFO - functools32 == 3.2.3-2 16:34:14 INFO - jsonschema == 2.5.1 16:34:14 INFO - manifestparser == 1.1 16:34:14 INFO - mozInstall == 1.13 16:34:14 INFO - mozcrash == 1.0 16:34:14 INFO - mozdebug == 0.1 16:34:14 INFO - mozdevice == 0.51 16:34:14 INFO - mozfile == 1.2 16:34:14 INFO - mozhttpd == 0.7 16:34:14 INFO - mozinfo == 0.10 16:34:14 INFO - mozleak == 0.1 16:34:14 INFO - mozlog == 3.5 16:34:14 INFO - moznetwork == 0.27 16:34:14 INFO - mozprocess == 0.25 16:34:14 INFO - mozprofile == 0.28 16:34:14 INFO - mozrunner == 6.13 16:34:14 INFO - mozscreenshot == 0.1 16:34:14 INFO - mozsystemmonitor == 0.3 16:34:14 INFO - moztest == 0.8 16:34:14 INFO - mozversion == 1.4 16:34:14 INFO - psutil == 3.1.1 16:34:14 INFO - requests == 1.2.3 16:34:14 INFO - wsgiref == 0.1.2 16:34:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34:14 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:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:14 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:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:14 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:14 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:34:14 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:34:14 INFO - Using env: {'DISPLAY': ':0', 16:34:14 INFO - 'HOME': '/home/cltbld', 16:34:14 INFO - 'LANG': 'en_US.UTF-8', 16:34:14 INFO - 'LANGUAGE': 'en_US:en', 16:34:14 INFO - 'LOGNAME': 'cltbld', 16:34:14 INFO - 'MAIL': '/var/mail/cltbld', 16:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:14 INFO - 'MOZ_NO_REMOTE': '1', 16:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:14 INFO - 'NO_EM_RESTART': '1', 16:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:14 INFO - 'PWD': '/builds/slave/test', 16:34:14 INFO - 'SHELL': '/bin/bash', 16:34:14 INFO - 'SHLVL': '1', 16:34:14 INFO - 'TERM': 'linux', 16:34:14 INFO - 'TMOUT': '86400', 16:34:14 INFO - 'USER': 'cltbld', 16:34:14 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:14 INFO - '_': '/tools/buildbot/bin/python'} 16:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:15 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:34:15 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:34:15 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:34:15 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:34:15 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:34:15 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:34:15 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:34:15 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:34:15 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:34:15 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:16 INFO - Downloading marionette_driver-1.4.0.tar.gz 16:34:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 16:34:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 16:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:34:16 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:34:16 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:34:16 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:34:16 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:34:16 INFO - Installing collected packages: marionette-driver, simplejson 16:34:16 INFO - Running setup.py install for marionette-driver 16:34:16 INFO - Running setup.py install for simplejson 16:34:16 INFO - building 'simplejson._speedups' extension 16:34:16 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:34:17 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:34:17 INFO - Successfully installed marionette-driver simplejson 16:34:17 INFO - Cleaning up... 16:34:17 INFO - Return code: 0 16:34:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:34:17 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:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:17 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:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:17 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2891d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, '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': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', '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:34:17 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:34:17 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:34:17 INFO - Using env: {'DISPLAY': ':0', 16:34:17 INFO - 'HOME': '/home/cltbld', 16:34:17 INFO - 'LANG': 'en_US.UTF-8', 16:34:17 INFO - 'LANGUAGE': 'en_US:en', 16:34:17 INFO - 'LOGNAME': 'cltbld', 16:34:17 INFO - 'MAIL': '/var/mail/cltbld', 16:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:17 INFO - 'MOZ_NO_REMOTE': '1', 16:34:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:17 INFO - 'NO_EM_RESTART': '1', 16:34:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:17 INFO - 'PWD': '/builds/slave/test', 16:34:17 INFO - 'SHELL': '/bin/bash', 16:34:17 INFO - 'SHLVL': '1', 16:34:17 INFO - 'TERM': 'linux', 16:34:17 INFO - 'TMOUT': '86400', 16:34:17 INFO - 'USER': 'cltbld', 16:34:17 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:17 INFO - '_': '/tools/buildbot/bin/python'} 16:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:34:17 INFO - Cleaning up... 16:34:17 INFO - Return code: 0 16:34:17 INFO - Running post-action listener: _resource_record_post_action 16:34:17 INFO - Running post-action listener: _start_resource_monitoring 16:34:18 INFO - Starting resource monitoring. 16:34:18 INFO - [mozharness: 2017-07-27 23:34:18.011367Z] Finished create-virtualenv step (success) 16:34:18 INFO - [mozharness: 2017-07-27 23:34:18.012050Z] Running install step. 16:34:18 INFO - Running pre-action listener: _resource_record_pre_action 16:34:18 INFO - Running main action method: install 16:34:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:34:18 INFO - Reading from file tmpfile_stdout 16:34:18 INFO - Detecting whether we're running mozinstall >=1.0... 16:34:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:34:18 INFO - Reading from file tmpfile_stdout 16:34:18 INFO - Output received: 16:34:18 INFO - Usage: mozinstall [options] installer 16:34:18 INFO - Options: 16:34:18 INFO - -h, --help show this help message and exit 16:34:18 INFO - -d DEST, --destination=DEST 16:34:18 INFO - Directory to install application into. [default: 16:34:18 INFO - "/builds/slave/test"] 16:34:18 INFO - --app=APP Application being installed. [default: firefox] 16:34:18 INFO - mkdir: /builds/slave/test/build/application 16:34:18 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:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:34:36 INFO - Reading from file tmpfile_stdout 16:34:36 INFO - Output received: 16:34:36 INFO - /builds/slave/test/build/application/firefox/firefox 16:34:36 INFO - Running post-action listener: _resource_record_post_action 16:34:36 INFO - [mozharness: 2017-07-27 23:34:36.406983Z] Finished install step (success) 16:34:36 INFO - [mozharness: 2017-07-27 23:34:36.407233Z] Running setup-mitmproxy step. 16:34:36 INFO - Running pre-action listener: _resource_record_pre_action 16:34:36 INFO - Running main action method: setup_mitmproxy 16:34:36 INFO - Skipping: mitmproxy is not required 16:34:36 INFO - Running post-action listener: _resource_record_post_action 16:34:36 INFO - [mozharness: 2017-07-27 23:34:36.408407Z] Finished setup-mitmproxy step (success) 16:34:36 INFO - [mozharness: 2017-07-27 23:34:36.408628Z] Running run-tests step. 16:34:36 INFO - Running pre-action listener: _resource_record_pre_action 16:34:36 INFO - Running pre-action listener: _set_gcov_prefix 16:34:36 INFO - Running main action method: run_tests 16:34:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:34:36 INFO - Python 2.7.3 16:34:36 INFO - Return code: 0 16:34:36 INFO - grabbing minidump binary from tooltool 16:34:36 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:34:36 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288eeb0>, '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 0x288fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2890240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:34:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:34:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:34:36 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:34:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:34:36 INFO - Return code: 0 16:34:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:34:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:34:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:34:36 INFO - ENV: RUST_BACKTRACE is now 1 16:34:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:34:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:34:36 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/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:34:36 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/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-022 --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:34:36 INFO - Using env: {'DISPLAY': ':0', 16:34:36 INFO - 'HOME': '/home/cltbld', 16:34:36 INFO - 'LANG': 'en_US.UTF-8', 16:34:36 INFO - 'LANGUAGE': 'en_US:en', 16:34:36 INFO - 'LOGNAME': 'cltbld', 16:34:36 INFO - 'MAIL': '/var/mail/cltbld', 16:34:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:34:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:34:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:36 INFO - 'MOZ_NO_REMOTE': '1', 16:34:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:34:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:36 INFO - 'NO_EM_RESTART': '1', 16:34:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:36 INFO - 'PWD': '/builds/slave/test', 16:34:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:34:36 INFO - 'RUST_BACKTRACE': '1', 16:34:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:34:36 INFO - 'SHELL': '/bin/bash', 16:34:36 INFO - 'SHLVL': '1', 16:34:36 INFO - 'TERM': 'linux', 16:34:36 INFO - 'TMOUT': '86400', 16:34:36 INFO - 'USER': 'cltbld', 16:34:36 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836', 16:34:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:36 INFO - '_': '/tools/buildbot/bin/python'} 16:34:36 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/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:34:36 INFO - mozversion application_buildid: 20170727224421 16:34:36 INFO - mozversion application_changeset: 0aab101edb50b91aa1c16b372fab382a60137482 16:34:36 INFO - mozversion application_display_name: Nightly 16:34:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:34:36 INFO - mozversion application_name: Firefox 16:34:36 INFO - mozversion application_remotingname: firefox 16:34:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:34:36 INFO - mozversion application_vendor: Mozilla 16:34:36 INFO - mozversion application_version: 56.0a1 16:34:36 INFO - mozversion platform_buildid: 20170727224421 16:34:36 INFO - mozversion platform_changeset: 0aab101edb50b91aa1c16b372fab382a60137482 16:34:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:34:36 INFO - mozversion platform_version: 56.0a1 16:34:36 INFO - using testdate: 1501198476 16:34:36 INFO - actual date: 1501198476 16:34:36 INFO - starting webserver on 'localhost:48024' 16:34:36 INFO - SUITE-START | Running 2 tests 16:34:36 INFO - TEST-START | dromaeo_css 16:34:36 INFO - Initialising browser for dromaeo_css test... 16:34:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48024/getInfo.html -profile /tmp/tmp8BcgWn/profile 16:34:36 INFO - TEST-INFO | started process 14944 (/builds/slave/test/build/application/firefox/firefox http://localhost:48024/getInfo.html) 16:34:44 INFO - TEST-INFO | 14944: exit 0 16:34:45 INFO - Browser initialized. 16:34:45 INFO - Running cycle 1/1 for dromaeo_css test... 16:34:45 INFO - TEST-INFO | started process 15155 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8BcgWn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:34:45 INFO - PID 15155 | 16:34:45 INFO - PID 15155 | (/builds/slave/test/build/application/firefox/firefox:15203): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:34:45 INFO - PID 15155 | 16:34:56 INFO - PID 15155 | RSS: Main: 168820736 16:34:56 INFO - PID 15155 | 16:37:54 INFO - PID 15155 | Cycle 1(1): loaded http://localhost:48024/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48024/tests/dromaeo/cssquery-ext.html) 16:37:55 INFO - PID 15155 | RSS: Main: 173051904 16:37:55 INFO - PID 15155 | 16:40:57 INFO - PID 15155 | Cycle 1(1): loaded http://localhost:48024/tests/dromaeo/cssquery-ext.html (next: http://localhost:48024/tests/dromaeo/cssquery-jquery.html) 16:40:58 INFO - PID 15155 | RSS: Main: 170254336 16:40:58 INFO - PID 15155 | 16:44:00 INFO - PID 15155 | Cycle 1(1): loaded http://localhost:48024/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48024/tests/dromaeo/cssquery-mootools.html) 16:44:00 INFO - PID 15155 | RSS: Main: 172167168 16:44:00 INFO - PID 15155 | 16:47:02 INFO - PID 15155 | Cycle 1(1): loaded http://localhost:48024/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48024/tests/dromaeo/cssquery-prototype.html) 16:47:03 INFO - PID 15155 | RSS: Main: 170520576 16:47:03 INFO - PID 15155 | 16:50:07 INFO - PID 15155 | Cycle 1(1): loaded http://localhost:48024/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48024/tests/dromaeo/cssquery-yui.html) 16:50:07 INFO - PID 15155 | RSS: Main: 171814912 16:50:07 INFO - PID 15155 | 16:53:09 INFO - PID 15155 | Cycle 1(1): loaded http://localhost:48024/tests/dromaeo/cssquery-yui.html (next: http://localhost:48024/tests/dromaeo/cssquery-dojo.html) 16:53:09 INFO - PID 15155 | RSS: Main: 174157824 16:53:09 INFO - PID 15155 | 16:53:09 INFO - PID 15155 | __start_tp_report 16:53:09 INFO - PID 15155 | _x_x_mozilla_page_load 16:53:09 INFO - PID 15155 | _x_x_mozilla_page_load_details 16:53:09 INFO - PID 15155 | |i|pagename|runs| 16:53:09 INFO - PID 15155 | |0;dojo.html;3150.653428122906;3151.952720709189;3181.172895047287;3199.376121656277;3226.1611980884963;6073.39266073393;6217.782377616779;6224.221972253468;6257.71840267188;6299.968500157504;6085.026395776666;6177.444030037295;6202.534809889254;6252.499800015999;6284.214473190851;5642.858928526789;5670.631408958425;5709.115087161496;5713.742881570319;5854.648721076728;5636.577256705743;5726.31284245889;5726.3414707308775;5732.340780810197;5754.338251101114;187.14722581432915;5553.611247212697;5559.972200138994;5574.358948720888;5698.287714035746;5594.104943209067;5598.272224610815;5676.035074037382;5683.403242659502;5687.60186786921;5540.944590554083;5558.805441809558;5615.859603509904;5616.438356164392;5671.007573674632;5941.940580594182;6026.638401695912;6029.487493563009;6039.879202415971;6045.60703554272;6464.191976003;6544.247411547638;6546.639934803584;6622.238442579117;6655.101561289212;6713.462922966151;6790.830229244259;6809.3190680932;6813.829654258683;6816.863662726767;6212.565120441571;6296.590721603081;6299.275583307933;6321.905171422432;6325.715342809539;6961.26906674802;7006.159260888726;7006.4394848412;7011.964940175267;7013.964930175316;6551.4103730664465;6553;6602.1747281589805;6646.435053020451;6664.266930637723;6555.508336874754;6673.766418175438;6677.098591690156;6766.69549870257;6787.2534021257625;6611.63636000015;6615.470762339001;6623.304553021863;6626.668666566652;6627.37039981201;6621.668916554153;6643;6644.136262285871;6649.734010639617;6655.46756259498;5305.946940530545;5410.242566040757;5416.83749487516;5432.402435732066;5448.346198456211;4358.259095953696;4643.303504474288;4708.4820669726305;4710.24636058229;4714.269288260325;5821.446962538552;5823.621464604787;5895.145203945404;5904.704764761745;5912.054071348563;6559.278479367265;6660.23407308156;6664.433523150587;6738.326167383222;6775.22084960226;5791.160281759121;5797.623154494944;5799.82600521985;5801;5802.622829516068;5858.502027327725;5951.970240148771;5962.046072628359;5964.970175149183;5967.045272756425;6375.6174629522375;6399.424051835357;6495.837604059841;6496;6500.544961852719;6642.070110184674;6744.190697116377;6745.055692203194;6748.7300507979135;6749.358810912955;6444.677766111769;6507.544471886922;6516.348365163446;6516.609003459807;6522.5760325578685;6604.801855944329;6608.140941677743;6611.206655201407;6623.6688165592495;6627.4698024158915;6721.563098398784;6753.15585551806;6761.560498567774;6796.76211332601;6805.727770889109;6513.674316284261;6515.696860627988;6570.375814297634;6599.868002640016;6608.900866486911;6405.3594640535575;6472.417482426604;6514.120593719787;6527.9020814688765;6532.05285233629;6463.030545418225;6479.5788273762055;6486.708098135501;6488.188976377953;6503.934960650333;6491.123698300669;6586.2755096940255;6590.80227593173;6594.637294948824;6610.702518386588;5485.100568168135;6232.5637205397;6288.11966324724;6308.558400912076;6375.330590287953 16:53:09 INFO - PID 15155 | |1;ext.html;809.7368355284531;843.2621456225802;861.5390765940223;898.8202359528092;903.4037535226747;39036.41445378318;39159;39753.60246397535;39782.403263951026;39792.204155916865;34728.82635586825;35092.12269693259;35170.47244291338;35205.647943520555;35214.8239258804;6731.528792984505;6834.6582670866255;6841.5552989055805;6843.2472428033125;6843.726250949956;7370.336669699726;7581.393488520933;7581.734639287626;7582;7595.4683172177965;7589.316961473494;7606.771796846076;7609.5434273943465;7613.124490683559;7632.427567932427;7492.9625351873165;7679.155292917774;7679.884801727979;7712.537247765096;7721.150673425918;7495;7548.131964824061;7562.470627056108;7566.546007239528;7611.847763044763;36139.638603613894;36499.81750091259;36613.084672883124;36716.44925326122;36777.63222367769;3537.663921927413;3561.358955388055;3585.0678823505814;3592.119930616989;3594.0655429588232;30069.699303007128;30149.09552713422;30206.395872082434;30266.243343916358;30277.394452110835;4770.833020844254;4816;4820.68665536739;4822.590079843218;4864.854054378339;190951;195030;205086;206712;208318;45718.542814571425;46081.30878036832;46214;46231;46263;48175.27737083947;48267.5173248263;48488.27267590989;48507.757461212466;48584;15423.07461552288;15456.149911754961;15524.223788810514;15574;15676.294566744296;12917.418716157796;12962.157459765085;12983.545575904798;12994.220346779224;12998.935005325102;13665.385057672429;13670.658233544242;13677.110987785762;13741;13799.379027943767;5657.4059723728415;5669.603127781016;5725.713714314267;5875.3243377012195;5922.3485416604135;954.5704433005176;958.8034452937131;960.8010963621111;987.5852142100335;994.721477986165;21450.57098858014;21562.78437215608;21681.132754689635;21946.780532194793;22498.88750556237;1020.2908978260039;1020.9897901020895;1047.2564479219839;1072.983905241422;1083.9403832789114;11841.881581184078;15191.62020949463;15488;15940.601484962968;16190.271437785328;214605;216215;216530;217013;217164;53646;54407.72796135994;54987.72506137444;55034.72482637561;55121.44878551243;1443.0475885915455;1465.6848777512887;1469.7648376259747;1481.3630139040101;1483.147190365544;26326.341841454116;26922.59616105799;27458.45083098287;27493;27539.449211015268;36764.44853327149;37179.25641487209;37186;37233.813830930674;37285.81357093197;193946;194117.05882941556;194483;194877;195711;32615.510767338983;32766;35637.82181089078;35987.64012359843;36538;44507.10985780202;44603.776981116185;45258.09483810371;45671.54328456673;45756;30425.391492170475;30666.846665766527;32214;32378.676213237566;33177.66822331746;30309.545356820105;31746;31993.36013279768;32123.35753284968;32604.184895377806;35571.64428355683;36434;36464.453033205056;36534.63465365419;36756.81621591875;29907.252318692208;31635;32106.19734506656;32249.35501290008;33079.33841323112;23699.170529031402;23998.88000559986;24057.398565036015;24059;24215.75784242135 16:53:09 INFO - PID 15155 | |2;jquery.html;573.5841514901699;573.6672729816706;577.373549698577;583.2213994317585;596.2874365133665;21356;22008.559828803413;22300.107995680155;22317.665235021486;22320.776792232093;25343.49313013743;27637.585436218473;27644.447111057765;27648.170554883385;27733.58399624007;6899.965500172492;7067.328603782633;7068.858622827541;7073.717051317941;7075.575465472062;6533.542652014361;6842.965785171067;6925.619090949996;6999.405050570682;7020.052292940439;6831.351021652935;6836.384725374715;6836.623985680811;6837.384635382815;6842.144731908512;6838.179418469791;6838.589684618939;6839.316068393171;6849.006894000367;6849.760258390982;6676.666166691694;6702.597844129368;6710.597364158118;6718.83202919926;6728.125343705336;30057.69942300571;31464.685353146404;31963.840180798947;32305.192370190933;32653.83673081643;1952.287415093495;1976.2490253703645;1982.900854957261;1983.7321961535151;1983.8016198380192;19420.708689369672;20177.697334539993;20178.486613504047;20197.182253597246;20267.189312427487;2330.5338932213454;2351.412146963259;2414.1429792423614;2428.3443470263105;2433.270018994295;132831.33584332018;134155.32922335423;136141.31929340388;136273;136282.31858840742;20297.391078267676;20369.796302037088;20873.582528349347;22175.223867164572;22652.886735566215;22857.31428057161;24230.51538969246;43455.34816977748;43571.346429803576;43753.343699844525;10269.332493387907;10276.280660353854;10368.118709909742;10429.37423754562;10510.316829406063;9542.475163866055;9554.713358599252;9565.521723913776;9635.410157323962;9719.951400242953;9174.633014679339;9537.380070295409;9638.325317227727;9684;9725.416475011521;2724.7547720704833;2745.4509098180442;2788.261110805638;2807.8034537582175;2871.6984716604456;915.876356691838;946.2997381937455;974.7952929884804;981.8870829854658;993.9602415903283;19314;19561.670070254084;19597.31409400664;19747.802521974598;20314.49213769638;992.6327258914248;1009.9040591143829;1027.5786927359748;1050.3855244681795;1070.7911957168433;11024.779504410028;11113.555457781598;11171.497282622302;11382.715432114097;11583.478743456564;461131;473138;475519;482012;483682;109211.45394272977;109989;110040.4497977505;111500;112801;1206.704357432417;1220.3593113615245;1221.3893053473264;1230.5754514692237;1230.723087305367;17080;17620.91189544044;17782.644347113244;17827.732584011508;18341.174647140644;54523.72738136284;60956;61804;64311.356886430534;64851.67574162099;73346.37277520612;73734;74089.62955185189;76284;78732;21975;22167.22414715477;22182.445438864157;22185.334439966828;22474.77525224727;24068;25092.498150037263;25251.62122568126;25459;25910.481790364465;19371.612567748285;19575.804241957398;19620.509487262934;19843.801561984194;19849;16412.17939103016;16670.916645416695;16729.665406692042;16943.661126777642;17285.740713889554;18762.249510019446;20413.38759837207;20461.283855065;20847.166113355295;20905.790942090993;21668.34994950154;21961.450963726034;22084.33746987593;22137.778622214217;22304;18583.16375763121;18686.626267474847;18722.719159212895;18943.242270309034;19050.237990480226 16:53:09 INFO - PID 15155 | |3;mootools.html;1366.1939455721126;1384.8615138486145;1407.0291498865774;1415.483348577768;1432.6203556057644;12957.416916238768;13141.211527308367;13547.322633868316;13567.525136620221;13574.321283935813;13487.865121348783;13714.451421943135;13714.58856234312;13723.794143087862;13743.725125497484;2494.713107992577;2663.840169589821;2706.986465067672;2708.5530887403556;2709.119536150759;2613.777828884547;2654.721254268303;2654.9336266593295;2654.9601755973677;2655.2432556721337;2573.2794817451145;2604.270804174834;2604.6744156980376;2612.6342312076326;2640.788736901043;2577.536043512167;2612.203278000209;2619.122593931016;2646.695630002555;2647.430802377479;2573.5496288149684;2589.948201035969;2608.29576014475;2610.517054344952;2612.595047767599;13133.34333283332;13368.264745438997;13501.122427042212;13509.189448633113;13515.445723741797;7634.465587408884;7813.882791758128;7870.291673749332;7873.606319683993;7925.28672419485;4859.125357435661;5004.374453193351;5068.493150684939;5095.006473737622;5113.463086375914;1759.3754217252947;1821.5810363616442;1830.469163942455;1865.337805079191;1877.511846919797;21832.454188645253;22188.445288867744;22224.55550888973;22248.555028899333;22250.443738906495;6305.423644088978;6391.904121438182;6414.518911081687;6501.902471462932;6566.146400967939;15207.467738629095;15391.538253852403;15403.92298038525;15409.46066887676;15431.922840385727;7365.116186057708;7467.253274672598;7467.663955121924;7479.925200747923;7496.58768767723;6864.656767161622;6988.650567471708;7066.116735408074;7076.32774886385;7102.289771022856;5002.624803139779;5026.396832380138;5029.622778291643;5096.592272618182;5193.714345711022;1414.5332040426636;1426.3082405033551;1448.8406275309708;1455.3014553014611;1472.9926350368394;811.724013835287;815.7838172884191;833.6457005772522;854.7179430787825;876.1545108969875;6559.967200163968;6660.167479065117;7787.753959366474;8086.636101375452;8167.469114507657;927.3832901120815;971.5919313888043;982.5676702250987;993.3146129170849;1022.5909636145601;4421.911561768811;4462.107578484316;4499.280115181556;4503.68474206809;4544.840930567414;16876.746848797276;17145.914270428817;17318.82681173172;17703.822961770216;17837.554061148316;17216.827831721774;17560.03419811897;17607.471775846745;17650.91174544119;17823.73264401035;9490.098440648128;9861.605535778632;10035.749106272255;10088.293819432569;10104.393736375694;6341.460975816923;7230.602316872622;7298.379637730853;7525.774226773206;7729.111152217567;15099.54701358961;15535.06789592628;15565.299561519532;15709.978851374624;16127.67744645077;15909;16066.357345706041;16270.837291626933;16273.59316017109;16361.83638163603;10361.84457233127;10846.728831779268;11086.61196858106;11180;11203.831942521032;12314.815277770662;12400.19398739079;12468.189567678071;12536.435860386122;12573.1198816082;9343.626254949726;9645.855312170463;9901.356411833496;9987.400755954375;9993.700188994342;9496.810063798546;9630.32587718853;9718.514074296398;9801.362911410735;9826.213902887896;8233.917660823316;8243.711470098518;8250.257476827115;8258.793530161554;8261.504309741435;6061.393860613904;6110.26676798787;6380.074889141141;6459.644719540471;6470.352964703492;6255.530835187288;6273.247210334789;6487.740490380332;6538.934610654022;6579.868402631825 16:53:09 INFO - PID 15155 | |4;prototype.html;490.1960784313726;493.75559098246373;512.7641285008896;515.3274976156115;525.1728527569077;6149.907751383727;6261.812145635627;6270.391262422116;6293.46505547029;6312.368763123697;5794;5891.64650120992;5982.641041537511;6025;6101.816945491642;5094.6433749637545;5146.176611742113;5147.459516750743;5158.200478925773;5171.456997015315;5086.669366491166;5111.926495435944;5152.124138896378;5171.250168725533;5199.818006369778;4806.735629540374;4851.757412129397;5052.797888084472;5069.315642388286;5123.820666276681;4807.23084306511;4862.124817532843;4884.926726099111;4897.465863574174;4912.164931961575;4347.43483347166;4363.738175709467;4376.081022985177;4395.3846461495095;4411.455990403333;5881.911771323434;5932.792352267693;5964.940350596482;5986.2217911671205;6014.6391216527145;5605.5515558755205;5670.829875103712;5672.716364181816;5673.8581535461535;5688.744006519716;6566.934330656728;6568.376004279586;6597;6597.769078082244;6599.954014255548;4840.273958906157;4855.36880205571;4858.829940952085;4868.63678170113;4870.245112007645;6805.863882722368;6883.003928860624;6924.892017277312;6927.095048861503;6929.3715976745425;4994.600431965434;5006.224035274538;5016.698998060128;5021.723805190676;5029.974850125799;4955.90088198234;4988.201887697951;5027.874303142451;5037.773300201499;5058.962912602908;5636.2391077204875;5640.5769567281795;5650.29371328584;5656;5716.714164291769;6468.5472016958365;6541.5093867960095;6583.374579414948;6619;6619.716197911713;1740.2777847193272;1746;1750.9669295115943;1760.4806582058322;1767.9215678436144;1139.8347239650204;1176.2001838749732;1178.4461303187488;1204.8253003314471;1208.5347141350644;5750.1949727038245;5764.904668113045;5783.971080144573;5786.479216870502;5803;6554.721829243347;6558.770443034471;6562.146920900253;6563.540552161303;6598.241202261705;5632.915506267409;5698.2592263005545;5709.257796486513;5718.7426565804635;5731.942680573225;3526.858925642997;3575.0674258315644;3593.4070878304788;3602.2615363850873;3608.512850765113;6421.678916054178;6460.676966151674;6485.902711459332;6494.772682956168;6514.706838192198;6781.694823732845;6810.353016463428;6815.216250131299;6922.2731613180895;6965.641699868578;6424.839379015469;6440.420362167428;6469.353064693493;6474.15835941334;6543.770968016192;6610.9669451652435;6647.667616619053;6724.536863515195;6725.327467253235;6749.460043196631;6524.739010439719;6529.934700652932;6585.769498067544;6606.141201643948;6627.701753421204;6367.235931688227;6499.1551098358805;6515.73937042513;6534.379233972765;6558.311377305505;4938.975305123451;4961.42943561495;4964.503549645006;4973.825916092919;4982.975085124551;6761.256261811099;6764.526483146133;6773.491988100814;6776.45788336922;6801.795946121624;6432.678366081771;6464.353564643498;6472.087560597844;6494.545381823227;6520.706568204537;6427.228732552124;6449.290578036318;6470;6477.546571739933;6483.740650373932;6403.455706265019;6450.516211284079;6451.419372256331;6454.967725161344;6480.57876238043;6265;6362.45919096882;6401.423871851555;6561.40947314744;6620.536562440776;6120.357362476876;6151.415615516519;6217.844553886189;6255.593386429868;6257.4681152102585 16:53:09 INFO - PID 15155 | |5;yui.html;802.3100133884855;827.809603791128;844.463765508902;881.1496905486207;915.5834095486556;17070.48788536343;17157;17236.655266894653;18400.263989440424;18916.148773305198;19342.90328548359;20584.38246852593;21338.89330553349;21359.35921922341;21507.139714411445;1030.711400807773;1062.5962134388922;1063.5320458998017;1063.5798859450513;1063.7287491689608;1001.9499025048755;1059.4914441068292;1063.3035361838008;1063.6862125672906;1063.803196408667;1097.2977294531506;1111.1777284809264;1111.7109551516583;1112.121424074984;1112.7106952192407;1096.2600244834705;1096.3038470571164;1096.8683757949057;1097.462243500687;1098.2751384086464;809.0816922792613;809.4778867630346;810.9270165685058;811.4603788480654;812.3379445751693;17812.643747124985;18160.364387246384;19273.132709028127;19444.902775486175;19473.123709432966;18634.90682546592;18726.344577939708;19272.036398180036;19386.515337116685;19726.60546789056;14411.78382324266;14606.12363258208;14855.182964936925;14919.179445130514;14946.551603451806;3487.930241395183;3490.4240800267926;3517;3520.1903562180587;3528.7882727036204;121955.39022304921;123129.38435307855;126569;128870;129571;15881.285342159399;15933.760993585105;15977.680446391007;16003.27985240667;16138.112403817902;29283.85358073196;29800;30061.398772024873;30118.397632047236;30120.84939575288;8366.37252206087;8440.493570385797;8591.398602097794;8638.352123590756;8639.95680021596;3999.960000399959;4056.817443215062;4058.370952502308;4064.7561146331313;4068.06434520062;4464.866054018385;5111.846644600668;5181.896362072737;5296.25852380667;5916.822495325147;1012.2661070723814;1025.5180065369264;1037.9532921018572;1045.351881833253;1046.3303485769113;640.0942666127378;642.8296501427126;662.7879078694772;666.0841343153164;672.0322735261208;10960.671179864617;13786;13888.4444622216;13993.510227142002;14024.859751402355;621.273110460762;660.0824853453703;660.5739298152664;663.8572706868047;670.5373292428209;4918.360613120271;5777.24895763548;5779.2775903012125;5797.275340582427;5964.791232306935;96301;98196.50901745446;99301;99970.5001474988;100246.03236346737;82606;82942;85929;87653.5617321922;91069.54465227631;70586;73614.63192684109;73913;73936.63031684807;77335.61332193416;10669.306495077795;11560.479778409823;11565.421728913689;11597.594084207012;11613.245139065933;32821;33141.33717325592;33407.66592334046;33459.330813384084;35050.649493505756;43104.78447607742;43922.34116488192;44659;45144.54855451403;45262.547374525835;14590.562283131523;14730.852691472948;18446.538836529195;18965;18994;18814.717779233048;20586.485337866674;21384.78615213828;21841.453963651034;21850.12599496066;19072.427827165207;19234.903825480782;19375.51561210981;19460.61078778388;19698.90150549238;17537.56156096108;17954.910225448788;19189.904050479658;19610.313639022566;19836.801631983497;16682.74975875387;16774.0774257417;17380.21789019472;18678.159482823034;19033.238670453025;12374.938125309316;12649.810252846399;12904.41930113166;13385.464581416634;13453.798193027309;13107.803382949072;14093.365798539295;14388.568342949728;14732.77900831467;14802.703945920806 16:53:09 INFO - PID 15155 | __end_tp_report 16:53:09 INFO - PID 15155 | __start_cc_report 16:53:09 INFO - PID 15155 | _x_x_mozilla_cycle_collect,340 16:53:09 INFO - PID 15155 | __end_cc_report 16:53:09 INFO - PID 15155 | __startTimestamp1501199589712__endTimestamp 16:53:09 INFO - PID 15155 | 16:53:09 INFO - PID 15155 | ------- Summary: start ------- 16:53:09 INFO - PID 15155 | Number of tests: 6 16:53:09 INFO - PID 15155 | 16:53:09 INFO - PID 15155 | [#0] dojo.html Cycles:165 Average:6110.56 Median:6405.36 stddev:867.39 (13.5%) stddev-sans-first:838.40 16:53:09 INFO - PID 15155 | Values: 3150.7 3152.0 3181.2 3199.4 3226.2 6073.4 6217.8 6224.2 6257.7 6300.0 6085.0 6177.4 6202.5 6252.5 6284.2 5642.9 5670.6 5709.1 5713.7 5854.6 5636.6 5726.3 5726.3 5732.3 5754.3 187.1 5553.6 5560.0 5574.4 5698.3 5594.1 5598.3 5676.0 5683.4 5687.6 5540.9 5558.8 5615.9 5616.4 5671.0 5941.9 6026.6 6029.5 6039.9 6045.6 6464.2 6544.2 6546.6 6622.2 6655.1 6713.5 6790.8 6809.3 6813.8 6816.9 6212.6 6296.6 6299.3 6321.9 6325.7 6961.3 7006.2 7006.4 7012.0 7014.0 6551.4 6553.0 6602.2 6646.4 6664.3 6555.5 6673.8 6677.1 6766.7 6787.3 6611.6 6615.5 6623.3 6626.7 6627.4 6621.7 6643.0 6644.1 6649.7 6655.5 5305.9 5410.2 5416.8 5432.4 5448.3 4358.3 4643.3 4708.5 4710.2 4714.3 5821.4 5823.6 5895.1 5904.7 5912.1 6559.3 6660.2 6664.4 6738.3 6775.2 5791.2 5797.6 5799.8 5801.0 5802.6 5858.5 5952.0 5962.0 5965.0 5967.0 6375.6 6399.4 6495.8 6496.0 6500.5 6642.1 6744.2 6745.1 6748.7 6749.4 6444.7 6507.5 6516.3 6516.6 6522.6 6604.8 6608.1 6611.2 6623.7 6627.5 6721.6 6753.2 6761.6 6796.8 6805.7 6513.7 6515.7 6570.4 6599.9 6608.9 6405.4 6472.4 6514.1 6527.9 6532.1 6463.0 6479.6 6486.7 6488.2 6503.9 6491.1 6586.3 6590.8 6594.6 6610.7 5485.1 6232.6 6288.1 6308.6 6375.3 16:53:09 INFO - PID 15155 | 16:53:09 INFO - PID 15155 | [#1] ext.html Cycles:180 Average:37142.42 Median:19611.44 stddev:52845.99 (269.5%) stddev-sans-first:52923.82 16:53:09 INFO - PID 15155 | Values: 809.7 843.3 861.5 898.8 903.4 39036.4 39159.0 39753.6 39782.4 39792.2 34728.8 35092.1 35170.5 35205.6 35214.8 6731.5 6834.7 6841.6 6843.2 6843.7 7370.3 7581.4 7581.7 7582.0 7595.5 7589.3 7606.8 7609.5 7613.1 7632.4 7493.0 7679.2 7679.9 7712.5 7721.2 7495.0 7548.1 7562.5 7566.5 7611.8 36139.6 36499.8 36613.1 36716.4 36777.6 3537.7 3561.4 3585.1 3592.1 3594.1 30069.7 30149.1 30206.4 30266.2 30277.4 4770.8 4816.0 4820.7 4822.6 4864.9 190951.0 195030.0 205086.0 206712.0 208318.0 45718.5 46081.3 46214.0 46231.0 46263.0 48175.3 48267.5 48488.3 48507.8 48584.0 15423.1 15456.1 15524.2 15574.0 15676.3 12917.4 12962.2 12983.5 12994.2 12998.9 13665.4 13670.7 13677.1 13741.0 13799.4 5657.4 5669.6 5725.7 5875.3 5922.3 954.6 958.8 960.8 987.6 994.7 21450.6 21562.8 21681.1 21946.8 22498.9 1020.3 1021.0 1047.3 1073.0 1083.9 11841.9 15191.6 15488.0 15940.6 16190.3 214605.0 216215.0 216530.0 217013.0 217164.0 53646.0 54407.7 54987.7 55034.7 55121.4 1443.0 1465.7 1469.8 1481.4 1483.1 26326.3 26922.6 27458.5 27493.0 27539.4 36764.4 37179.3 37186.0 37233.8 37285.8 193946.0 194117.1 194483.0 194877.0 195711.0 32615.5 32766.0 35637.8 35987.6 36538.0 44507.1 44603.8 45258.1 45671.5 45756.0 30425.4 30666.8 32214.0 32378.7 33177.7 30309.5 31746.0 31993.4 32123.4 32604.2 35571.6 36434.0 36464.5 36534.6 36756.8 29907.3 31635.0 32106.2 32249.4 33079.3 23699.2 23998.9 24057.4 24059.0 24215.8 16:53:09 INFO - PID 15155 | 16:53:09 INFO - PID 15155 | [#2] jquery.html Cycles:180 Average:35658.14 Median:2340.97 stddev:79950.43 (3415.3%) stddev-sans-first:80131.31 16:53:09 INFO - PID 15155 | Values: 573.6 573.7 577.4 583.2 596.3 21356.0 22008.6 22300.1 22317.7 22320.8 25343.5 27637.6 27644.4 27648.2 27733.6 6900.0 7067.3 7068.9 7073.7 7075.6 6533.5 6843.0 6925.6 6999.4 7020.1 6831.4 6836.4 6836.6 6837.4 6842.1 6838.2 6838.6 6839.3 6849.0 6849.8 6676.7 6702.6 6710.6 6718.8 6728.1 30057.7 31464.7 31963.8 32305.2 32653.8 1952.3 1976.2 1982.9 1983.7 1983.8 19420.7 20177.7 20178.5 20197.2 20267.2 2330.5 2351.4 2414.1 2428.3 2433.3 132831.3 134155.3 136141.3 136273.0 136282.3 20297.4 20369.8 20873.6 22175.2 22652.9 22857.3 24230.5 43455.3 43571.3 43753.3 10269.3 10276.3 10368.1 10429.4 10510.3 9542.5 9554.7 9565.5 9635.4 9720.0 9174.6 9537.4 9638.3 9684.0 9725.4 2724.8 2745.5 2788.3 2807.8 2871.7 915.9 946.3 974.8 981.9 994.0 19314.0 19561.7 19597.3 19747.8 20314.5 992.6 1009.9 1027.6 1050.4 1070.8 11024.8 11113.6 11171.5 11382.7 11583.5 461131.0 473138.0 475519.0 482012.0 483682.0 109211.5 109989.0 110040.4 111500.0 112801.0 1206.7 1220.4 1221.4 1230.6 1230.7 17080.0 17620.9 17782.6 17827.7 18341.2 54523.7 60956.0 61804.0 64311.4 64851.7 73346.4 73734.0 74089.6 76284.0 78732.0 21975.0 22167.2 22182.4 22185.3 22474.8 24068.0 25092.5 25251.6 25459.0 25910.5 19371.6 19575.8 19620.5 19843.8 19849.0 16412.2 16670.9 16729.7 16943.7 17285.7 18762.2 20413.4 20461.3 20847.2 20905.8 21668.3 21961.5 22084.3 22137.8 22304.0 18583.2 18686.6 18722.7 18943.2 19050.2 16:53:09 INFO - PID 15155 | 16:53:09 INFO - PID 15155 | [#3] mootools.html Cycles:180 Average:8223.95 Median:2651.08 stddev:5533.50 (208.7%) stddev-sans-first:5525.03 16:53:09 INFO - PID 15155 | Values: 1366.2 1384.9 1407.0 1415.5 1432.6 12957.4 13141.2 13547.3 13567.5 13574.3 13487.9 13714.5 13714.6 13723.8 13743.7 2494.7 2663.8 2707.0 2708.6 2709.1 2613.8 2654.7 2654.9 2655.0 2655.2 2573.3 2604.3 2604.7 2612.6 2640.8 2577.5 2612.2 2619.1 2646.7 2647.4 2573.5 2589.9 2608.3 2610.5 2612.6 13133.3 13368.3 13501.1 13509.2 13515.4 7634.5 7813.9 7870.3 7873.6 7925.3 4859.1 5004.4 5068.5 5095.0 5113.5 1759.4 1821.6 1830.5 1865.3 1877.5 21832.5 22188.4 22224.6 22248.6 22250.4 6305.4 6391.9 6414.5 6501.9 6566.1 15207.5 15391.5 15403.9 15409.5 15431.9 7365.1 7467.3 7467.7 7479.9 7496.6 6864.7 6988.7 7066.1 7076.3 7102.3 5002.6 5026.4 5029.6 5096.6 5193.7 1414.5 1426.3 1448.8 1455.3 1473.0 811.7 815.8 833.6 854.7 876.2 6560.0 6660.2 7787.8 8086.6 8167.5 927.4 971.6 982.6 993.3 1022.6 4421.9 4462.1 4499.3 4503.7 4544.8 16876.7 17145.9 17318.8 17703.8 17837.6 17216.8 17560.0 17607.5 17650.9 17823.7 9490.1 9861.6 10035.7 10088.3 10104.4 6341.5 7230.6 7298.4 7525.8 7729.1 15099.5 15535.1 15565.3 15710.0 16127.7 15909.0 16066.4 16270.8 16273.6 16361.8 10361.8 10846.7 11086.6 11180.0 11203.8 12314.8 12400.2 12468.2 12536.4 12573.1 9343.6 9645.9 9901.4 9987.4 9993.7 9496.8 9630.3 9718.5 9801.4 9826.2 8233.9 8243.7 8250.3 8258.8 8261.5 6061.4 6110.3 6380.1 6459.6 6470.4 6255.5 6273.2 6487.7 6538.9 6579.9 16:53:09 INFO - PID 15155 | 16:53:09 INFO - PID 15155 | [#4] prototype.html Cycles:180 Average:5481.96 Median:5984.43 stddev:1535.12 (25.7%) stddev-sans-first:1493.01 16:53:09 INFO - PID 15155 | Values: 490.2 493.8 512.8 515.3 525.2 6149.9 6261.8 6270.4 6293.5 6312.4 5794.0 5891.6 5982.6 6025.0 6101.8 5094.6 5146.2 5147.5 5158.2 5171.5 5086.7 5111.9 5152.1 5171.3 5199.8 4806.7 4851.8 5052.8 5069.3 5123.8 4807.2 4862.1 4884.9 4897.5 4912.2 4347.4 4363.7 4376.1 4395.4 4411.5 5881.9 5932.8 5964.9 5986.2 6014.6 5605.6 5670.8 5672.7 5673.9 5688.7 6566.9 6568.4 6597.0 6597.8 6600.0 4840.3 4855.4 4858.8 4868.6 4870.2 6805.9 6883.0 6924.9 6927.1 6929.4 4994.6 5006.2 5016.7 5021.7 5030.0 4955.9 4988.2 5027.9 5037.8 5059.0 5636.2 5640.6 5650.3 5656.0 5716.7 6468.5 6541.5 6583.4 6619.0 6619.7 1740.3 1746.0 1751.0 1760.5 1767.9 1139.8 1176.2 1178.4 1204.8 1208.5 5750.2 5764.9 5784.0 5786.5 5803.0 6554.7 6558.8 6562.1 6563.5 6598.2 5632.9 5698.3 5709.3 5718.7 5731.9 3526.9 3575.1 3593.4 3602.3 3608.5 6421.7 6460.7 6485.9 6494.8 6514.7 6781.7 6810.4 6815.2 6922.3 6965.6 6424.8 6440.4 6469.4 6474.2 6543.8 6611.0 6647.7 6724.5 6725.3 6749.5 6524.7 6529.9 6585.8 6606.1 6627.7 6367.2 6499.2 6515.7 6534.4 6558.3 4939.0 4961.4 4964.5 4973.8 4983.0 6761.3 6764.5 6773.5 6776.5 6801.8 6432.7 6464.4 6472.1 6494.5 6520.7 6427.2 6449.3 6470.0 6477.5 6483.7 6403.5 6450.5 6451.4 6455.0 6480.6 6265.0 6362.5 6401.4 6561.4 6620.5 6120.4 6151.4 6217.8 6255.6 6257.5 16:53:09 INFO - PID 15155 | 16:53:09 INFO - PID 15155 | [#5] yui.html Cycles:180 Average:21695.14 Median:19415.71 stddev:29022.38 (149.5%) stddev-sans-first:29061.39 16:53:09 INFO - PID 15155 | Values: 802.3 827.8 844.5 881.1 915.6 17070.5 17157.0 17236.7 18400.3 18916.1 19342.9 20584.4 21338.9 21359.4 21507.1 1030.7 1062.6 1063.5 1063.6 1063.7 1001.9 1059.5 1063.3 1063.7 1063.8 1097.3 1111.2 1111.7 1112.1 1112.7 1096.3 1096.3 1096.9 1097.5 1098.3 809.1 809.5 810.9 811.5 812.3 17812.6 18160.4 19273.1 19444.9 19473.1 18634.9 18726.3 19272.0 19386.5 19726.6 14411.8 14606.1 14855.2 14919.2 14946.6 3487.9 3490.4 3517.0 3520.2 3528.8 121955.4 123129.4 126569.0 128870.0 129571.0 15881.3 15933.8 15977.7 16003.3 16138.1 29283.9 29800.0 30061.4 30118.4 30120.8 8366.4 8440.5 8591.4 8638.4 8640.0 4000.0 4056.8 4058.4 4064.8 4068.1 4464.9 5111.8 5181.9 5296.3 5916.8 1012.3 1025.5 1038.0 1045.4 1046.3 640.1 642.8 662.8 666.1 672.0 10960.7 13786.0 13888.4 13993.5 14024.9 621.3 660.1 660.6 663.9 670.5 4918.4 5777.2 5779.3 5797.3 5964.8 96301.0 98196.5 99301.0 99970.5 100246.0 82606.0 82942.0 85929.0 87653.6 91069.5 70586.0 73614.6 73913.0 73936.6 77335.6 10669.3 11560.5 11565.4 11597.6 11613.2 32821.0 33141.3 33407.7 33459.3 35050.6 43104.8 43922.3 44659.0 45144.5 45262.5 14590.6 14730.9 18446.5 18965.0 18994.0 18814.7 20586.5 21384.8 21841.5 21850.1 19072.4 19234.9 19375.5 19460.6 19698.9 17537.6 17954.9 19189.9 19610.3 19836.8 16682.7 16774.1 17380.2 18678.2 19033.2 12374.9 12649.8 12904.4 13385.5 13453.8 13107.8 14093.4 14388.6 14732.8 14802.7 16:53:09 INFO - PID 15155 | -------- Summary: end -------- 16:53:09 INFO - PID 15155 | 16:53:10 INFO - TEST-INFO | 15155: exit 0 16:53:10 INFO - TEST-OK | dromaeo_css | took 1113643ms 16:53:10 INFO - TEST-START | kraken 16:53:10 INFO - Initialising browser for kraken test... 16:53:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48024/getInfo.html -profile /tmp/tmpGnUWV2/profile 16:53:10 INFO - TEST-INFO | started process 15516 (/builds/slave/test/build/application/firefox/firefox http://localhost:48024/getInfo.html) 16:53:16 INFO - TEST-INFO | 15516: exit 0 16:53:16 INFO - Browser initialized. 16:53:16 INFO - Running cycle 1/1 for kraken test... 16:53:16 INFO - TEST-INFO | started process 15727 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGnUWV2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:53:17 INFO - PID 15727 | 16:53:17 INFO - PID 15727 | (/builds/slave/test/build/application/firefox/firefox:15775): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:53:17 INFO - PID 15727 | 16:53:27 INFO - PID 15727 | RSS: Main: 170774528 16:53:27 INFO - PID 15727 | 16:53:30 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48024/tests/kraken/driver.html?testName=audio-beat-detection) 16:53:31 INFO - PID 15727 | RSS: Main: 177393664 16:53:31 INFO - PID 15727 | 16:53:33 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48024/tests/kraken/driver.html?testName=audio-dft) 16:53:34 INFO - PID 15727 | RSS: Main: 178216960 16:53:34 INFO - PID 15727 | 16:53:37 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48024/tests/kraken/driver.html?testName=audio-fft) 16:53:38 INFO - PID 15727 | RSS: Main: 171347968 16:53:38 INFO - PID 15727 | 16:53:40 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48024/tests/kraken/driver.html?testName=audio-oscillator) 16:53:40 INFO - PID 15727 | RSS: Main: 171778048 16:53:40 INFO - PID 15727 | 16:53:43 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48024/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:53:43 INFO - PID 15727 | RSS: Main: 171937792 16:53:43 INFO - PID 15727 | 16:53:46 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48024/tests/kraken/driver.html?testName=imaging-darkroom) 16:53:47 INFO - PID 15727 | RSS: Main: 174473216 16:53:47 INFO - PID 15727 | 16:53:51 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48024/tests/kraken/driver.html?testName=imaging-desaturate) 16:53:51 INFO - PID 15727 | RSS: Main: 174579712 16:53:51 INFO - PID 15727 | 16:53:54 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48024/tests/kraken/driver.html?testName=json-parse-financial) 16:53:55 INFO - PID 15727 | RSS: Main: 175013888 16:53:55 INFO - PID 15727 | 16:53:56 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48024/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:53:57 INFO - PID 15727 | RSS: Main: 174784512 16:53:57 INFO - PID 15727 | 16:53:59 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48024/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:53:59 INFO - PID 15727 | RSS: Main: 172273664 16:53:59 INFO - PID 15727 | 16:54:01 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48024/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:54:02 INFO - PID 15727 | RSS: Main: 172400640 16:54:02 INFO - PID 15727 | 16:54:05 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48024/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:54:06 INFO - PID 15727 | RSS: Main: 172781568 16:54:06 INFO - PID 15727 | 16:54:09 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48024/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:54:09 INFO - PID 15727 | RSS: Main: 172937216 16:54:09 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | Cycle 1(1): loaded http://localhost:48024/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48024/tests/kraken/driver.html?testName=ai-astar) 16:54:11 INFO - PID 15727 | RSS: Main: 174252032 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | __start_tp_report 16:54:11 INFO - PID 15727 | _x_x_mozilla_page_load 16:54:11 INFO - PID 15727 | _x_x_mozilla_page_load_details 16:54:11 INFO - PID 15727 | |i|pagename|runs| 16:54:11 INFO - PID 15727 | |0;ai-astar;101;115;105;109;108;114;109;99;110;111 16:54:11 INFO - PID 15727 | |1;audio-beat-detection;150;116;141;178;124;152;147;123;183;119 16:54:11 INFO - PID 15727 | |2;audio-dft;163;163;163;163;162;165;166;167;165;161 16:54:11 INFO - PID 15727 | |3;audio-fft;75;79;77;173;75;74;75;77;155;77 16:54:11 INFO - PID 15727 | |4;audio-oscillator;81;83;131;79;80;80;128;82;82;128 16:54:11 INFO - PID 15727 | |5;imaging-gaussian-blur;114;114;113;113;115;114;114;114;115;116 16:54:11 INFO - PID 15727 | |6;imaging-darkroom;150;151;151;151;153;151;150;152;149;153 16:54:11 INFO - PID 15727 | |7;imaging-desaturate;94;98;97;94;94;94;95;99;97;94 16:54:11 INFO - PID 15727 | |8;json-parse-financial;59;58;64;58;57;57;58;60;58;57 16:54:11 INFO - PID 15727 | |9;json-stringify-tinderbox;59;54;54;58;53;56;54;59;53;53 16:54:11 INFO - PID 15727 | |10;stanford-crypto-aes;83;89;82;90;80;80;82;82;81;84 16:54:11 INFO - PID 15727 | |11;stanford-crypto-ccm;126;129;123;125;126;137;137;126;130;177 16:54:11 INFO - PID 15727 | |12;stanford-crypto-pbkdf2;181;177;176;180;176;189;179;185;181;176 16:54:11 INFO - PID 15727 | |13;stanford-crypto-sha256-iterative;72;79;72;73;70;71;69;72;70;71 16:54:11 INFO - PID 15727 | __end_tp_report 16:54:11 INFO - PID 15727 | __start_cc_report 16:54:11 INFO - PID 15727 | _x_x_mozilla_cycle_collect,943 16:54:11 INFO - PID 15727 | __end_cc_report 16:54:11 INFO - PID 15727 | __startTimestamp1501199651821__endTimestamp 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | ------- Summary: start ------- 16:54:11 INFO - PID 15727 | Number of tests: 14 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#0] ai-astar Cycles:10 Average:108.10 Median:110.50 stddev:5.15 (4.7%) stddev-sans-first:4.78 16:54:11 INFO - PID 15727 | Values: 101.0 115.0 105.0 109.0 108.0 114.0 109.0 99.0 110.0 111.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:148.50 stddev:23.66 (15.9%) stddev-sans-first:24.98 16:54:11 INFO - PID 15727 | Values: 150.0 116.0 141.0 178.0 124.0 152.0 147.0 123.0 183.0 119.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#2] audio-dft Cycles:10 Average:163.80 Median:164.00 stddev:1.87 (1.1%) stddev-sans-first:1.96 16:54:11 INFO - PID 15727 | Values: 163.0 163.0 163.0 163.0 162.0 165.0 166.0 167.0 165.0 161.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#3] audio-fft Cycles:10 Average:93.70 Median:76.00 stddev:37.32 (49.1%) stddev-sans-first:38.97 16:54:11 INFO - PID 15727 | Values: 75.0 79.0 77.0 173.0 75.0 74.0 75.0 77.0 155.0 77.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:80.50 stddev:23.23 (28.9%) stddev-sans-first:24.05 16:54:11 INFO - PID 15727 | Values: 81.0 83.0 131.0 79.0 80.0 80.0 128.0 82.0 82.0 128.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 16:54:11 INFO - PID 15727 | Values: 114.0 114.0 113.0 113.0 115.0 114.0 114.0 114.0 115.0 116.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:1.29 (0.9%) stddev-sans-first:1.30 16:54:11 INFO - PID 15727 | Values: 150.0 151.0 151.0 151.0 153.0 151.0 150.0 152.0 149.0 153.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:1.96 (2.0%) stddev-sans-first:1.99 16:54:11 INFO - PID 15727 | Values: 94.0 98.0 97.0 94.0 94.0 94.0 95.0 99.0 97.0 94.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:58.00 stddev:2.12 (3.7%) stddev-sans-first:2.24 16:54:11 INFO - PID 15727 | Values: 59.0 58.0 64.0 58.0 57.0 57.0 58.0 60.0 58.0 57.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#9] json-stringify-tinderbox Cycles:10 Average:55.30 Median:55.00 stddev:2.50 (4.5%) stddev-sans-first:2.26 16:54:11 INFO - PID 15727 | Values: 59.0 54.0 54.0 58.0 53.0 56.0 54.0 59.0 53.0 53.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:82.50 stddev:3.50 (4.2%) stddev-sans-first:3.71 16:54:11 INFO - PID 15727 | Values: 83.0 89.0 82.0 90.0 80.0 80.0 82.0 82.0 81.0 84.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#11] stanford-crypto-ccm Cycles:10 Average:133.60 Median:129.50 stddev:15.99 (12.3%) stddev-sans-first:16.72 16:54:11 INFO - PID 15727 | Values: 126.0 129.0 123.0 125.0 126.0 137.0 137.0 126.0 130.0 177.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:180.50 stddev:4.29 (2.4%) stddev-sans-first:4.54 16:54:11 INFO - PID 15727 | Values: 181.0 177.0 176.0 180.0 176.0 189.0 179.0 185.0 181.0 176.0 16:54:11 INFO - PID 15727 | 16:54:11 INFO - PID 15727 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:72.00 stddev:2.77 (3.8%) stddev-sans-first:2.93 16:54:11 INFO - PID 15727 | Values: 72.0 79.0 72.0 73.0 70.0 71.0 69.0 72.0 70.0 71.0 16:54:11 INFO - PID 15727 | -------- Summary: end -------- 16:54:11 INFO - PID 15727 | 16:54:12 INFO - PID 15727 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:54:12 INFO - TEST-INFO | 15727: exit 0 16:54:12 INFO - TEST-OK | kraken | took 62118ms 16:54:12 INFO - SUITE-END | took 1175s 16:54:12 INFO - Completed test suite (00:19:36) 16:54:12 INFO - javascript benchmark 16:54:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8414.154126977824, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3150.653428122906, 3151.952720709189, 3181.172895047287, 3199.376121656277, 3226.1611980884963, 6073.39266073393, 6217.782377616779, 6224.221972253468, 6257.71840267188, 6299.968500157504, 6085.026395776666, 6177.444030037295, 6202.534809889254, 6252.499800015999, 6284.214473190851, 5642.858928526789, 5670.631408958425, 5709.115087161496, 5713.742881570319, 5854.648721076728, 5636.577256705743, 5726.31284245889, 5726.3414707308775, 5732.340780810197, 5754.338251101114, 187.14722581432915, 5553.611247212697, 5559.972200138994, 5574.358948720888, 5698.287714035746, 5594.104943209067, 5598.272224610815, 5676.035074037382, 5683.403242659502, 5687.60186786921, 5540.944590554083, 5558.805441809558, 5615.859603509904, 5616.438356164392, 5671.007573674632, 5941.940580594182, 6026.638401695912, 6029.487493563009, 6039.879202415971, 6045.60703554272, 6464.191976003, 6544.247411547638, 6546.639934803584, 6622.238442579117, 6655.101561289212, 6713.462922966151, 6790.830229244259, 6809.3190680932, 6813.829654258683, 6816.863662726767, 6212.565120441571, 6296.590721603081, 6299.275583307933, 6321.905171422432, 6325.715342809539, 6961.26906674802, 7006.159260888726, 7006.4394848412, 7011.964940175267, 7013.964930175316, 6551.4103730664465, 6553.0, 6602.1747281589805, 6646.435053020451, 6664.266930637723, 6555.508336874754, 6673.766418175438, 6677.098591690156, 6766.69549870257, 6787.2534021257625, 6611.63636000015, 6615.470762339001, 6623.304553021863, 6626.668666566652, 6627.37039981201, 6621.668916554153, 6643.0, 6644.136262285871, 6649.734010639617, 6655.46756259498, 5305.946940530545, 5410.242566040757, 5416.83749487516, 5432.402435732066, 5448.346198456211, 4358.259095953696, 4643.303504474288, 4708.4820669726305, 4710.24636058229, 4714.269288260325, 5821.446962538552, 5823.621464604787, 5895.145203945404, 5904.704764761745, 5912.054071348563, 6559.278479367265, 6660.23407308156, 6664.433523150587, 6738.326167383222, 6775.22084960226, 5791.160281759121, 5797.623154494944, 5799.82600521985, 5801.0, 5802.622829516068, 5858.502027327725, 5951.970240148771, 5962.046072628359, 5964.970175149183, 5967.045272756425, 6375.6174629522375, 6399.424051835357, 6495.837604059841, 6496.0, 6500.544961852719, 6642.070110184674, 6744.190697116377, 6745.055692203194, 6748.7300507979135, 6749.358810912955, 6444.677766111769, 6507.544471886922, 6516.348365163446, 6516.609003459807, 6522.5760325578685, 6604.801855944329, 6608.140941677743, 6611.206655201407, 6623.6688165592495, 6627.4698024158915, 6721.563098398784, 6753.15585551806, 6761.560498567774, 6796.76211332601, 6805.727770889109, 6513.674316284261, 6515.696860627988, 6570.375814297634, 6599.868002640016, 6608.900866486911, 6405.3594640535575, 6472.417482426604, 6514.120593719787, 6527.9020814688765, 6532.05285233629, 6463.030545418225, 6479.5788273762055, 6486.708098135501, 6488.188976377953, 6503.934960650333, 6491.123698300669, 6586.2755096940255, 6590.80227593173, 6594.637294948824, 6610.702518386588, 5485.100568168135, 6232.5637205397, 6288.11966324724, 6308.558400912076, 6375.330590287953], "value": 6049.034342050554, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [809.7368355284531, 843.2621456225802, 861.5390765940223, 898.8202359528092, 903.4037535226747, 39036.41445378318, 39159.0, 39753.60246397535, 39782.403263951026, 39792.204155916865, 34728.82635586825, 35092.12269693259, 35170.47244291338, 35205.647943520555, 35214.8239258804, 6731.528792984505, 6834.6582670866255, 6841.5552989055805, 6843.2472428033125, 6843.726250949956, 7370.336669699726, 7581.393488520933, 7581.734639287626, 7582.0, 7595.4683172177965, 7589.316961473494, 7606.771796846076, 7609.5434273943465, 7613.124490683559, 7632.427567932427, 7492.9625351873165, 7679.155292917774, 7679.884801727979, 7712.537247765096, 7721.150673425918, 7495.0, 7548.131964824061, 7562.470627056108, 7566.546007239528, 7611.847763044763, 36139.638603613894, 36499.81750091259, 36613.084672883124, 36716.44925326122, 36777.63222367769, 3537.663921927413, 3561.358955388055, 3585.0678823505814, 3592.119930616989, 3594.0655429588232, 30069.699303007128, 30149.09552713422, 30206.395872082434, 30266.243343916358, 30277.394452110835, 4770.833020844254, 4816.0, 4820.68665536739, 4822.590079843218, 4864.854054378339, 190951.0, 195030.0, 205086.0, 206712.0, 208318.0, 45718.542814571425, 46081.30878036832, 46214.0, 46231.0, 46263.0, 48175.27737083947, 48267.5173248263, 48488.27267590989, 48507.757461212466, 48584.0, 15423.07461552288, 15456.149911754961, 15524.223788810514, 15574.0, 15676.294566744296, 12917.418716157796, 12962.157459765085, 12983.545575904798, 12994.220346779224, 12998.935005325102, 13665.385057672429, 13670.658233544242, 13677.110987785762, 13741.0, 13799.379027943767, 5657.4059723728415, 5669.603127781016, 5725.713714314267, 5875.3243377012195, 5922.3485416604135, 954.5704433005176, 958.8034452937131, 960.8010963621111, 987.5852142100335, 994.721477986165, 21450.57098858014, 21562.78437215608, 21681.132754689635, 21946.780532194793, 22498.88750556237, 1020.2908978260039, 1020.9897901020895, 1047.2564479219839, 1072.983905241422, 1083.9403832789114, 11841.881581184078, 15191.62020949463, 15488.0, 15940.601484962968, 16190.271437785328, 214605.0, 216215.0, 216530.0, 217013.0, 217164.0, 53646.0, 54407.72796135994, 54987.72506137444, 55034.72482637561, 55121.44878551243, 1443.0475885915455, 1465.6848777512887, 1469.7648376259747, 1481.3630139040101, 1483.147190365544, 26326.341841454116, 26922.59616105799, 27458.45083098287, 27493.0, 27539.449211015268, 36764.44853327149, 37179.25641487209, 37186.0, 37233.813830930674, 37285.81357093197, 193946.0, 194117.05882941556, 194483.0, 194877.0, 195711.0, 32615.510767338983, 32766.0, 35637.82181089078, 35987.64012359843, 36538.0, 44507.10985780202, 44603.776981116185, 45258.09483810371, 45671.54328456673, 45756.0, 30425.391492170475, 30666.846665766527, 32214.0, 32378.676213237566, 33177.66822331746, 30309.545356820105, 31746.0, 31993.36013279768, 32123.35753284968, 32604.184895377806, 35571.64428355683, 36434.0, 36464.453033205056, 36534.63465365419, 36756.81621591875, 29907.252318692208, 31635.0, 32106.19734506656, 32249.35501290008, 33079.33841323112, 23699.170529031402, 23998.88000559986, 24057.398565036015, 24059.0, 24215.75784242135], "value": 16964.93239696175, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.5841514901699, 573.6672729816706, 577.373549698577, 583.2213994317585, 596.2874365133665, 21356.0, 22008.559828803413, 22300.107995680155, 22317.665235021486, 22320.776792232093, 25343.49313013743, 27637.585436218473, 27644.447111057765, 27648.170554883385, 27733.58399624007, 6899.965500172492, 7067.328603782633, 7068.858622827541, 7073.717051317941, 7075.575465472062, 6533.542652014361, 6842.965785171067, 6925.619090949996, 6999.405050570682, 7020.052292940439, 6831.351021652935, 6836.384725374715, 6836.623985680811, 6837.384635382815, 6842.144731908512, 6838.179418469791, 6838.589684618939, 6839.316068393171, 6849.006894000367, 6849.760258390982, 6676.666166691694, 6702.597844129368, 6710.597364158118, 6718.83202919926, 6728.125343705336, 30057.69942300571, 31464.685353146404, 31963.840180798947, 32305.192370190933, 32653.83673081643, 1952.287415093495, 1976.2490253703645, 1982.900854957261, 1983.7321961535151, 1983.8016198380192, 19420.708689369672, 20177.697334539993, 20178.486613504047, 20197.182253597246, 20267.189312427487, 2330.5338932213454, 2351.412146963259, 2414.1429792423614, 2428.3443470263105, 2433.270018994295, 132831.33584332018, 134155.32922335423, 136141.31929340388, 136273.0, 136282.31858840742, 20297.391078267676, 20369.796302037088, 20873.582528349347, 22175.223867164572, 22652.886735566215, 22857.31428057161, 24230.51538969246, 43455.34816977748, 43571.346429803576, 43753.343699844525, 10269.332493387907, 10276.280660353854, 10368.118709909742, 10429.37423754562, 10510.316829406063, 9542.475163866055, 9554.713358599252, 9565.521723913776, 9635.410157323962, 9719.951400242953, 9174.633014679339, 9537.380070295409, 9638.325317227727, 9684.0, 9725.416475011521, 2724.7547720704833, 2745.4509098180442, 2788.261110805638, 2807.8034537582175, 2871.6984716604456, 915.876356691838, 946.2997381937455, 974.7952929884804, 981.8870829854658, 993.9602415903283, 19314.0, 19561.670070254084, 19597.31409400664, 19747.802521974598, 20314.49213769638, 992.6327258914248, 1009.9040591143829, 1027.5786927359748, 1050.3855244681795, 1070.7911957168433, 11024.779504410028, 11113.555457781598, 11171.497282622302, 11382.715432114097, 11583.478743456564, 461131.0, 473138.0, 475519.0, 482012.0, 483682.0, 109211.45394272977, 109989.0, 110040.4497977505, 111500.0, 112801.0, 1206.704357432417, 1220.3593113615245, 1221.3893053473264, 1230.5754514692237, 1230.723087305367, 17080.0, 17620.91189544044, 17782.644347113244, 17827.732584011508, 18341.174647140644, 54523.72738136284, 60956.0, 61804.0, 64311.356886430534, 64851.67574162099, 73346.37277520612, 73734.0, 74089.62955185189, 76284.0, 78732.0, 21975.0, 22167.22414715477, 22182.445438864157, 22185.334439966828, 22474.77525224727, 24068.0, 25092.498150037263, 25251.62122568126, 25459.0, 25910.481790364465, 19371.612567748285, 19575.804241957398, 19620.509487262934, 19843.801561984194, 19849.0, 16412.17939103016, 16670.916645416695, 16729.665406692042, 16943.661126777642, 17285.740713889554, 18762.249510019446, 20413.38759837207, 20461.283855065, 20847.166113355295, 20905.790942090993, 21668.34994950154, 21961.450963726034, 22084.33746987593, 22137.778622214217, 22304.0, 18583.16375763121, 18686.626267474847, 18722.719159212895, 18943.242270309034, 19050.237990480226], "value": 12823.8536723534, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.1939455721126, 1384.8615138486145, 1407.0291498865774, 1415.483348577768, 1432.6203556057644, 12957.416916238768, 13141.211527308367, 13547.322633868316, 13567.525136620221, 13574.321283935813, 13487.865121348783, 13714.451421943135, 13714.58856234312, 13723.794143087862, 13743.725125497484, 2494.713107992577, 2663.840169589821, 2706.986465067672, 2708.5530887403556, 2709.119536150759, 2613.777828884547, 2654.721254268303, 2654.9336266593295, 2654.9601755973677, 2655.2432556721337, 2573.2794817451145, 2604.270804174834, 2604.6744156980376, 2612.6342312076326, 2640.788736901043, 2577.536043512167, 2612.203278000209, 2619.122593931016, 2646.695630002555, 2647.430802377479, 2573.5496288149684, 2589.948201035969, 2608.29576014475, 2610.517054344952, 2612.595047767599, 13133.34333283332, 13368.264745438997, 13501.122427042212, 13509.189448633113, 13515.445723741797, 7634.465587408884, 7813.882791758128, 7870.291673749332, 7873.606319683993, 7925.28672419485, 4859.125357435661, 5004.374453193351, 5068.493150684939, 5095.006473737622, 5113.463086375914, 1759.3754217252947, 1821.5810363616442, 1830.469163942455, 1865.337805079191, 1877.511846919797, 21832.454188645253, 22188.445288867744, 22224.55550888973, 22248.555028899333, 22250.443738906495, 6305.423644088978, 6391.904121438182, 6414.518911081687, 6501.902471462932, 6566.146400967939, 15207.467738629095, 15391.538253852403, 15403.92298038525, 15409.46066887676, 15431.922840385727, 7365.116186057708, 7467.253274672598, 7467.663955121924, 7479.925200747923, 7496.58768767723, 6864.656767161622, 6988.650567471708, 7066.116735408074, 7076.32774886385, 7102.289771022856, 5002.624803139779, 5026.396832380138, 5029.622778291643, 5096.592272618182, 5193.714345711022, 1414.5332040426636, 1426.3082405033551, 1448.8406275309708, 1455.3014553014611, 1472.9926350368394, 811.724013835287, 815.7838172884191, 833.6457005772522, 854.7179430787825, 876.1545108969875, 6559.967200163968, 6660.167479065117, 7787.753959366474, 8086.636101375452, 8167.469114507657, 927.3832901120815, 971.5919313888043, 982.5676702250987, 993.3146129170849, 1022.5909636145601, 4421.911561768811, 4462.107578484316, 4499.280115181556, 4503.68474206809, 4544.840930567414, 16876.746848797276, 17145.914270428817, 17318.82681173172, 17703.822961770216, 17837.554061148316, 17216.827831721774, 17560.03419811897, 17607.471775846745, 17650.91174544119, 17823.73264401035, 9490.098440648128, 9861.605535778632, 10035.749106272255, 10088.293819432569, 10104.393736375694, 6341.460975816923, 7230.602316872622, 7298.379637730853, 7525.774226773206, 7729.111152217567, 15099.54701358961, 15535.06789592628, 15565.299561519532, 15709.978851374624, 16127.67744645077, 15909.0, 16066.357345706041, 16270.837291626933, 16273.59316017109, 16361.83638163603, 10361.84457233127, 10846.728831779268, 11086.61196858106, 11180.0, 11203.831942521032, 12314.815277770662, 12400.19398739079, 12468.189567678071, 12536.435860386122, 12573.1198816082, 9343.626254949726, 9645.855312170463, 9901.356411833496, 9987.400755954375, 9993.700188994342, 9496.810063798546, 9630.32587718853, 9718.514074296398, 9801.362911410735, 9826.213902887896, 8233.917660823316, 8243.711470098518, 8250.257476827115, 8258.793530161554, 8261.504309741435, 6061.393860613904, 6110.26676798787, 6380.074889141141, 6459.644719540471, 6470.352964703492, 6255.530835187288, 6273.247210334789, 6487.740490380332, 6538.934610654022, 6579.868402631825], "value": 6083.619273443276, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.1960784313726, 493.75559098246373, 512.7641285008896, 515.3274976156115, 525.1728527569077, 6149.907751383727, 6261.812145635627, 6270.391262422116, 6293.46505547029, 6312.368763123697, 5794.0, 5891.64650120992, 5982.641041537511, 6025.0, 6101.816945491642, 5094.6433749637545, 5146.176611742113, 5147.459516750743, 5158.200478925773, 5171.456997015315, 5086.669366491166, 5111.926495435944, 5152.124138896378, 5171.250168725533, 5199.818006369778, 4806.735629540374, 4851.757412129397, 5052.797888084472, 5069.315642388286, 5123.820666276681, 4807.23084306511, 4862.124817532843, 4884.926726099111, 4897.465863574174, 4912.164931961575, 4347.43483347166, 4363.738175709467, 4376.081022985177, 4395.3846461495095, 4411.455990403333, 5881.911771323434, 5932.792352267693, 5964.940350596482, 5986.2217911671205, 6014.6391216527145, 5605.5515558755205, 5670.829875103712, 5672.716364181816, 5673.8581535461535, 5688.744006519716, 6566.934330656728, 6568.376004279586, 6597.0, 6597.769078082244, 6599.954014255548, 4840.273958906157, 4855.36880205571, 4858.829940952085, 4868.63678170113, 4870.245112007645, 6805.863882722368, 6883.003928860624, 6924.892017277312, 6927.095048861503, 6929.3715976745425, 4994.600431965434, 5006.224035274538, 5016.698998060128, 5021.723805190676, 5029.974850125799, 4955.90088198234, 4988.201887697951, 5027.874303142451, 5037.773300201499, 5058.962912602908, 5636.2391077204875, 5640.5769567281795, 5650.29371328584, 5656.0, 5716.714164291769, 6468.5472016958365, 6541.5093867960095, 6583.374579414948, 6619.0, 6619.716197911713, 1740.2777847193272, 1746.0, 1750.9669295115943, 1760.4806582058322, 1767.9215678436144, 1139.8347239650204, 1176.2001838749732, 1178.4461303187488, 1204.8253003314471, 1208.5347141350644, 5750.1949727038245, 5764.904668113045, 5783.971080144573, 5786.479216870502, 5803.0, 6554.721829243347, 6558.770443034471, 6562.146920900253, 6563.540552161303, 6598.241202261705, 5632.915506267409, 5698.2592263005545, 5709.257796486513, 5718.7426565804635, 5731.942680573225, 3526.858925642997, 3575.0674258315644, 3593.4070878304788, 3602.2615363850873, 3608.512850765113, 6421.678916054178, 6460.676966151674, 6485.902711459332, 6494.772682956168, 6514.706838192198, 6781.694823732845, 6810.353016463428, 6815.216250131299, 6922.2731613180895, 6965.641699868578, 6424.839379015469, 6440.420362167428, 6469.353064693493, 6474.15835941334, 6543.770968016192, 6610.9669451652435, 6647.667616619053, 6724.536863515195, 6725.327467253235, 6749.460043196631, 6524.739010439719, 6529.934700652932, 6585.769498067544, 6606.141201643948, 6627.701753421204, 6367.235931688227, 6499.1551098358805, 6515.73937042513, 6534.379233972765, 6558.311377305505, 4938.975305123451, 4961.42943561495, 4964.503549645006, 4973.825916092919, 4982.975085124551, 6761.256261811099, 6764.526483146133, 6773.491988100814, 6776.45788336922, 6801.795946121624, 6432.678366081771, 6464.353564643498, 6472.087560597844, 6494.545381823227, 6520.706568204537, 6427.228732552124, 6449.290578036318, 6470.0, 6477.546571739933, 6483.740650373932, 6403.455706265019, 6450.516211284079, 6451.419372256331, 6454.967725161344, 6480.57876238043, 6265.0, 6362.45919096882, 6401.423871851555, 6561.40947314744, 6620.536562440776, 6120.357362476876, 6151.415615516519, 6217.844553886189, 6255.593386429868, 6257.4681152102585], "value": 5027.2779915923775, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.3100133884855, 827.809603791128, 844.463765508902, 881.1496905486207, 915.5834095486556, 17070.48788536343, 17157.0, 17236.655266894653, 18400.263989440424, 18916.148773305198, 19342.90328548359, 20584.38246852593, 21338.89330553349, 21359.35921922341, 21507.139714411445, 1030.711400807773, 1062.5962134388922, 1063.5320458998017, 1063.5798859450513, 1063.7287491689608, 1001.9499025048755, 1059.4914441068292, 1063.3035361838008, 1063.6862125672906, 1063.803196408667, 1097.2977294531506, 1111.1777284809264, 1111.7109551516583, 1112.121424074984, 1112.7106952192407, 1096.2600244834705, 1096.3038470571164, 1096.8683757949057, 1097.462243500687, 1098.2751384086464, 809.0816922792613, 809.4778867630346, 810.9270165685058, 811.4603788480654, 812.3379445751693, 17812.643747124985, 18160.364387246384, 19273.132709028127, 19444.902775486175, 19473.123709432966, 18634.90682546592, 18726.344577939708, 19272.036398180036, 19386.515337116685, 19726.60546789056, 14411.78382324266, 14606.12363258208, 14855.182964936925, 14919.179445130514, 14946.551603451806, 3487.930241395183, 3490.4240800267926, 3517.0, 3520.1903562180587, 3528.7882727036204, 121955.39022304921, 123129.38435307855, 126569.0, 128870.0, 129571.0, 15881.285342159399, 15933.760993585105, 15977.680446391007, 16003.27985240667, 16138.112403817902, 29283.85358073196, 29800.0, 30061.398772024873, 30118.397632047236, 30120.84939575288, 8366.37252206087, 8440.493570385797, 8591.398602097794, 8638.352123590756, 8639.95680021596, 3999.960000399959, 4056.817443215062, 4058.370952502308, 4064.7561146331313, 4068.06434520062, 4464.866054018385, 5111.846644600668, 5181.896362072737, 5296.25852380667, 5916.822495325147, 1012.2661070723814, 1025.5180065369264, 1037.9532921018572, 1045.351881833253, 1046.3303485769113, 640.0942666127378, 642.8296501427126, 662.7879078694772, 666.0841343153164, 672.0322735261208, 10960.671179864617, 13786.0, 13888.4444622216, 13993.510227142002, 14024.859751402355, 621.273110460762, 660.0824853453703, 660.5739298152664, 663.8572706868047, 670.5373292428209, 4918.360613120271, 5777.24895763548, 5779.2775903012125, 5797.275340582427, 5964.791232306935, 96301.0, 98196.50901745446, 99301.0, 99970.5001474988, 100246.03236346737, 82606.0, 82942.0, 85929.0, 87653.5617321922, 91069.54465227631, 70586.0, 73614.63192684109, 73913.0, 73936.63031684807, 77335.61332193416, 10669.306495077795, 11560.479778409823, 11565.421728913689, 11597.594084207012, 11613.245139065933, 32821.0, 33141.33717325592, 33407.66592334046, 33459.330813384084, 35050.649493505756, 43104.78447607742, 43922.34116488192, 44659.0, 45144.54855451403, 45262.547374525835, 14590.562283131523, 14730.852691472948, 18446.538836529195, 18965.0, 18994.0, 18814.717779233048, 20586.485337866674, 21384.78615213828, 21841.453963651034, 21850.12599496066, 19072.427827165207, 19234.903825480782, 19375.51561210981, 19460.61078778388, 19698.90150549238, 17537.56156096108, 17954.910225448788, 19189.904050479658, 19610.313639022566, 19836.801631983497, 16682.74975875387, 16774.0774257417, 17380.21789019472, 18678.159482823034, 19033.238670453025, 12374.938125309316, 12649.810252846399, 12904.41930113166, 13385.464581416634, 13453.798193027309, 13107.803382949072, 14093.365798539295, 14388.568342949728, 14732.77900831467, 14802.703945920806], "value": 8816.221858608731, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 115.0, 105.0, 109.0, 108.0, 114.0, 109.0, 99.0, 110.0, 111.0], "value": 108.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 141.0, 178.0, 124.0, 152.0, 147.0, 123.0, 183.0, 119.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 163.0, 162.0, 165.0, 166.0, 167.0, 165.0, 161.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 77.0, 173.0, 75.0, 74.0, 75.0, 77.0, 155.0, 77.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 131.0, 79.0, 80.0, 80.0, 128.0, 82.0, 82.0, 128.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 115.0, 114.0, 114.0, 114.0, 115.0, 116.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 151.0, 151.0, 153.0, 151.0, 150.0, 152.0, 149.0, 153.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 97.0, 94.0, 94.0, 94.0, 95.0, 99.0, 97.0, 94.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 64.0, 58.0, 57.0, 57.0, 58.0, 60.0, 58.0, 57.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 54.0, 54.0, 58.0, 53.0, 56.0, 54.0, 59.0, 53.0, 53.0], "value": 55.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 89.0, 82.0, 90.0, 80.0, 80.0, 82.0, 82.0, 81.0, 84.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 129.0, 123.0, 125.0, 126.0, 137.0, 137.0, 126.0, 130.0, 177.0], "value": 133.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 177.0, 176.0, 180.0, 176.0, 189.0, 179.0, 185.0, 181.0, 176.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 72.0, 73.0, 70.0, 71.0, 69.0, 72.0, 70.0, 71.0], "value": 71.9, "unit": "score"}]}]} 16:54:13 INFO - Return code: 0 16:54:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:54:13 INFO - # TBPL SUCCESS # 16:54:13 INFO - Running post-action listener: _package_coverage_data 16:54:13 INFO - Running post-action listener: _resource_record_post_action 16:54:13 INFO - [mozharness: 2017-07-27 23:54:13.093790Z] Finished run-tests step (success) 16:54:13 INFO - Running post-run listener: _resource_record_post_run 16:54:13 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 147456; Write bytes: 442851328; Read time: 52; Write time: 321528 16:54:13 INFO - TinderboxPrint: CPU usage
13.1% 16:54:13 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 52 16:54:13 INFO - TinderboxPrint: I/O write bytes / time
442,851,328 / 321,528 16:54:13 INFO - TinderboxPrint: CPU idle
8,277.2 (86.9%) 16:54:13 INFO - TinderboxPrint: CPU user
1,199.2 (12.6%) 16:54:13 INFO - TinderboxPrint: Swap in / out
0 / 0 16:54:13 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 118513664; Read time: 0; Write time: 96380 16:54:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:54:13 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 143360; Write bytes: 324337664; Read time: 40; Write time: 225148 16:54:13 INFO - Running post-run listener: _upload_blobber_files 16:54:13 INFO - Blob upload gear active. 16:54:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:54:13 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 16:54:13 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'] 16:54:13 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 16:54:14 INFO - (blobuploader) - INFO - Open directory for files ... 16:54:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:54:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:54:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:15 INFO - (blobuploader) - INFO - Done attempting. 16:54:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:54:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:54:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:18 INFO - (blobuploader) - INFO - Done attempting. 16:54:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:54:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:54:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:19 INFO - (blobuploader) - INFO - Done attempting. 16:54:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:54:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:54:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:20 INFO - (blobuploader) - INFO - Done attempting. 16:54:20 INFO - (blobuploader) - INFO - Iteration through files over. 16:54:20 INFO - Return code: 0 16:54:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:54:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:54:20 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3307fc7e6afb7017f89c3c61832065c8496be1867c5eb23f2e9b4b7ccede2dbaf3b03ccca7f6c5102672c9721ebb8d20be8353c10ad4baa433a7522e51fc2cbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/038ac9901f6cf12fa66b4ddf7f5a00e01b86c8af880b4935ef29f9c774b09adb9ef5c1d8c9e379f4bdc72113aca267d87667d78321f7887a1235e622c0e53742", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b34a33009c873d640353df01833500da5a86a37450d1b917e351dd443c4285e8bf2550d48686bc6e2f4efbe09ff7a6e3b0972dc53eae3e769e5d0c182e1487fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46ff6fa75838d95566b6b1efa57827aea6d40233e200378577cf0d2d43ded248edcb4a27016a91270494ac5607946f80c51f86ef9dfd792545f04237c7d7a580"} 16:54:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:54:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:54:20 INFO - Contents: 16:54:20 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3307fc7e6afb7017f89c3c61832065c8496be1867c5eb23f2e9b4b7ccede2dbaf3b03ccca7f6c5102672c9721ebb8d20be8353c10ad4baa433a7522e51fc2cbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/038ac9901f6cf12fa66b4ddf7f5a00e01b86c8af880b4935ef29f9c774b09adb9ef5c1d8c9e379f4bdc72113aca267d87667d78321f7887a1235e622c0e53742", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b34a33009c873d640353df01833500da5a86a37450d1b917e351dd443c4285e8bf2550d48686bc6e2f4efbe09ff7a6e3b0972dc53eae3e769e5d0c182e1487fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46ff6fa75838d95566b6b1efa57827aea6d40233e200378577cf0d2d43ded248edcb4a27016a91270494ac5607946f80c51f86ef9dfd792545f04237c7d7a580"} 16:54:20 INFO - Running post-run listener: copy_logs_to_upload_dir 16:54:20 INFO - Copying logs to upload dir... 16:54:20 INFO - mkdir: /builds/slave/test/build/upload/logs 16:54:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.844855 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-07-27 16:54:19.982342) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 16:54:19.986236) ========= 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=930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836 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/3307fc7e6afb7017f89c3c61832065c8496be1867c5eb23f2e9b4b7ccede2dbaf3b03ccca7f6c5102672c9721ebb8d20be8353c10ad4baa433a7522e51fc2cbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/038ac9901f6cf12fa66b4ddf7f5a00e01b86c8af880b4935ef29f9c774b09adb9ef5c1d8c9e379f4bdc72113aca267d87667d78321f7887a1235e622c0e53742", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b34a33009c873d640353df01833500da5a86a37450d1b917e351dd443c4285e8bf2550d48686bc6e2f4efbe09ff7a6e3b0972dc53eae3e769e5d0c182e1487fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46ff6fa75838d95566b6b1efa57827aea6d40233e200378577cf0d2d43ded248edcb4a27016a91270494ac5607946f80c51f86ef9dfd792545f04237c7d7a580"} build_url:https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011094 build_url: 'https://queue.taskcluster.net/v1/task/ZxfIrUWxTQKL2cp9g6TJ_A/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3307fc7e6afb7017f89c3c61832065c8496be1867c5eb23f2e9b4b7ccede2dbaf3b03ccca7f6c5102672c9721ebb8d20be8353c10ad4baa433a7522e51fc2cbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/038ac9901f6cf12fa66b4ddf7f5a00e01b86c8af880b4935ef29f9c774b09adb9ef5c1d8c9e379f4bdc72113aca267d87667d78321f7887a1235e622c0e53742", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b34a33009c873d640353df01833500da5a86a37450d1b917e351dd443c4285e8bf2550d48686bc6e2f4efbe09ff7a6e3b0972dc53eae3e769e5d0c182e1487fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46ff6fa75838d95566b6b1efa57827aea6d40233e200378577cf0d2d43ded248edcb4a27016a91270494ac5607946f80c51f86ef9dfd792545f04237c7d7a580"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 16:54:20.010435) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:54:20.010740) ========= 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=930c87414c89fc706ee2024c000001c6-1501197397.555207-950331836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004786 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:54:20.093851) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 16:54:20.094178) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 16:54:20.094488) ========= ========= Total master_lag: 0.16 =========