builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-072 starttime: 1501149860.52 results: success (0) revision: c1ed71da57073d0f5fdbd279c9ad6037f9ae2d06 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:20.519689) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:20.520260) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:20.520644) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:20.574016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:20.574312) ========= 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=199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-27 03:04: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% 36.9M=0s 2017-07-27 03:04:21 (36.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.180651 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:20.772951) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:20.773240) ========= 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=199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020056 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:20.822495) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-27 03:04:20.822821) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c1ed71da57073d0f5fdbd279c9ad6037f9ae2d06 --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 c1ed71da57073d0f5fdbd279c9ad6037f9ae2d06 --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=199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-27 03:04:21,191 truncating revision to first 12 chars 2017-07-27 03:04:21,191 Setting DEBUG logging. 2017-07-27 03:04:21,191 attempt 1/10 2017-07-27 03:04:21,191 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c1ed71da5707?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-27 03:04:22,478 unpacking tar archive at: mozilla-central-c1ed71da5707/testing/mozharness/ program finished with exit code 0 elapsedTime=1.643532 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-27 03:04:22.487678) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:22.487991) ========= script_repo_revision: c1ed71da57073d0f5fdbd279c9ad6037f9ae2d06 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:22.488375) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:22.488728) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 03:04:22.499148) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-07-27 03:04:22.499431) ========= /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=199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:04:22 INFO - MultiFileLogger online at 20170727 03:04:22 in /builds/slave/test 03:04:22 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 03:04:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:04:22 INFO - {'append_to_log': False, 03:04:22 INFO - 'base_work_dir': '/builds/slave/test', 03:04:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:04:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:04:22 INFO - 'branch': 'Firefox-Non-PGO', 03:04:22 INFO - 'buildbot_json_path': 'buildprops.json', 03:04:22 INFO - 'code_coverage': False, 03:04:22 INFO - 'config_files': ('talos/linux_config.py',), 03:04:22 INFO - 'default_actions': ('clobber', 03:04:22 INFO - 'read-buildbot-config', 03:04:22 INFO - 'download-and-extract', 03:04:22 INFO - 'populate-webroot', 03:04:22 INFO - 'create-virtualenv', 03:04:22 INFO - 'install', 03:04:22 INFO - 'setup-mitmproxy', 03:04:22 INFO - 'run-tests'), 03:04:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:04:22 INFO - 'disable_ccov_upload': False, 03:04:22 INFO - 'download_minidump_stackwalk': True, 03:04:22 INFO - 'download_symbols': 'ondemand', 03:04:22 INFO - 'enable_stylo': False, 03:04:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:04:22 INFO - 'tooltool.py': '/tools/tooltool.py', 03:04:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:04:22 INFO - '/tools/misc-python/virtualenv.py')}, 03:04:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:04:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:04:22 INFO - 'gecko_profile': False, 03:04:22 INFO - 'gecko_profile_interval': 0, 03:04:22 INFO - 'installer_path': 'installer.exe', 03:04:22 INFO - 'log_level': 'info', 03:04:22 INFO - 'log_name': 'talos', 03:04:22 INFO - 'log_to_console': True, 03:04:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:04:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:04:22 INFO - 'opt_config_files': (), 03:04:22 INFO - 'pip_index': False, 03:04:22 INFO - 'suite': 'dromaeojs-e10s', 03:04:22 INFO - 'system_bits': '32', 03:04:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:04:22 INFO - 'title': 'talos-linux64-ix-072', 03:04:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:04:22 INFO - 'use_talos_json': True, 03:04:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:04:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:04:22 INFO - 'work_dir': 'build'} 03:04:22 INFO - [mozharness: 2017-07-27 10:04:22.973495Z] Running clobber step. 03:04:22 INFO - Running pre-action listener: _resource_record_pre_action 03:04:22 INFO - Running main action method: clobber 03:04:22 INFO - rmtree: /builds/slave/test/build 03:04:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:04:24 INFO - Running post-action listener: _resource_record_post_action 03:04:24 INFO - [mozharness: 2017-07-27 10:04:24.525829Z] Finished clobber step (success) 03:04:24 INFO - [mozharness: 2017-07-27 10:04:24.525930Z] Running read-buildbot-config step. 03:04:24 INFO - Running pre-action listener: _resource_record_pre_action 03:04:24 INFO - Running main action method: read_buildbot_config 03:04:24 INFO - Using buildbot properties: 03:04:24 INFO - { 03:04:24 INFO - "project": "", 03:04:24 INFO - "product": "firefox", 03:04:24 INFO - "who": "cbook@mozilla.com", 03:04:24 INFO - "installer_path": "public/build/target.tar.bz2", 03:04:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:04:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:04:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:04:24 INFO - "stage_platform": "linux64", 03:04:24 INFO - "basedir": "/builds/slave/test", 03:04:24 INFO - "buildnumber": 1035, 03:04:24 INFO - "platform": "ubuntu64_hw", 03:04:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:04:24 INFO - "slavebuilddir": "test", 03:04:24 INFO - "taskId": "cGIWAOduSNWR9635JT7AUg", 03:04:24 INFO - "branch": "mozilla-central", 03:04:24 INFO - "script_repo_revision": "production", 03:04:24 INFO - "revision": "c1ed71da57073d0f5fdbd279c9ad6037f9ae2d06", 03:04:24 INFO - "slavename": "talos-linux64-ix-072", 03:04:24 INFO - "repo_path": "mozilla-central" 03:04:24 INFO - } 03:04:24 INFO - Finding installer, test and symbols from parent task. 03:04:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cGIWAOduSNWR9635JT7AUg'}, attempt #1 03:04:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cGIWAOduSNWR9635JT7AUg'}, attempt #1 03:04:25 INFO - Task dependencies: LE8WFHDDS_OwuiBTElAJQA 03:04:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA'}, attempt #1 03:04:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.tar.bz2 03:04:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/test_packages.json 03:04:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.crashreporter-symbols.zip 03:04:25 INFO - Running post-action listener: _resource_record_post_action 03:04:25 INFO - [mozharness: 2017-07-27 10:04:25.882805Z] Finished read-buildbot-config step (success) 03:04:25 INFO - [mozharness: 2017-07-27 10:04:25.883122Z] Running download-and-extract step. 03:04:25 INFO - Running pre-action listener: _resource_record_pre_action 03:04:25 INFO - Running main action method: download_and_extract 03:04:25 INFO - mkdir: /builds/slave/test/build/tests 03:04:26 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')]} 03:04:26 INFO - trying https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.test_packages.json 03:04:26 INFO - Downloading https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:04:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:04:26 INFO - Downloaded 989 bytes. 03:04:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:04:26 INFO - Using the following test package requirements: 03:04:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:04:26 INFO - u'common': [u'target.common.tests.zip'], 03:04:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:04:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:04:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:04:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:04:26 INFO - u'mozbase': [u'target.common.tests.zip'], 03:04:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:04:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:04:26 INFO - u'web-platform': [u'target.common.tests.zip', 03:04:26 INFO - u'target.web-platform.tests.tar.gz'], 03:04:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:04:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:04:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.common.tests.zip 03:04:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:04:26 INFO - Fetch https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.common.tests.zip into memory 03:04:28 INFO - Content-Length response header: 72549731 03:04:28 INFO - Bytes received: 72549731 03:04:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:04:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.common.tests.zip 03:04:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:04:39 INFO - Fetch https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.common.tests.zip into memory 03:04:40 INFO - Content-Length response header: 72549731 03:04:40 INFO - Bytes received: 72549731 03:04:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.talos.tests.zip 03:04:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:04:53 INFO - Fetch https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.talos.tests.zip into memory 03:04:53 INFO - Content-Length response header: 14496465 03:04:53 INFO - Bytes received: 14496465 03:04:54 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')]} 03:04:54 INFO - trying https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.tar.bz2 03:04:54 INFO - Downloading https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:04:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:04:56 INFO - Downloaded 62109966 bytes. 03:04:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.tar.bz2 03:04:56 INFO - mkdir: /builds/slave/test/properties 03:04:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:04:56 INFO - Writing to file /builds/slave/test/properties/build_url 03:04:56 INFO - Contents: 03:04:56 INFO - build_url:https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.tar.bz2 03:04:56 INFO - Running post-action listener: _resource_record_post_action 03:04:56 INFO - Running post-action listener: set_extra_try_arguments 03:04:56 INFO - [mozharness: 2017-07-27 10:04:56.310138Z] Finished download-and-extract step (success) 03:04:56 INFO - [mozharness: 2017-07-27 10:04:56.310368Z] Running populate-webroot step. 03:04:56 INFO - Running pre-action listener: _resource_record_pre_action 03:04:56 INFO - Running main action method: populate_webroot 03:04:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:04:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:04:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:04:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:04:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:04:56 INFO - 'tests': ['damp', 'tps']}, 03:04:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:04:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:04:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:04:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:04:56 INFO - 'other-e10s': {'tests': ['a11yr', 03:04:56 INFO - 'ts_paint', 03:04:56 INFO - 'tpaint', 03:04:56 INFO - 'sessionrestore', 03:04:56 INFO - 'sessionrestore_many_windows', 03:04:56 INFO - 'sessionrestore_no_auto_restore', 03:04:56 INFO - 'tabpaint']}, 03:04:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:04:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:04:56 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:04:56 INFO - 'talos_options': ['--mitmproxy', 03:04:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:04:56 INFO - '--firstNonBlankPaint'], 03:04:56 INFO - 'tests': ['quantum_pageload_google', 03:04:56 INFO - 'quantum_pageload_youtube', 03:04:56 INFO - 'quantum_pageload_amazon', 03:04:56 INFO - 'quantum_pageload_facebook']}, 03:04:56 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:04:56 INFO - 'talos_options': ['--stylo', 03:04:56 INFO - '--mitmproxy', 03:04:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:04:56 INFO - '--firstNonBlankPaint'], 03:04:56 INFO - 'tests': ['quantum_pageload_google', 03:04:56 INFO - 'quantum_pageload_youtube', 03:04:56 INFO - 'quantum_pageload_amazon', 03:04:56 INFO - 'quantum_pageload_facebook']}, 03:04:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:04:56 INFO - 'tsvgr_opacity', 03:04:56 INFO - 'tart', 03:04:56 INFO - 'tscrollx', 03:04:56 INFO - 'cart', 03:04:56 INFO - 'tsvg_static']}, 03:04:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:04:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:04:56 INFO - 'talos_options': ['--xperf_path', 03:04:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:04:56 INFO - 'tests': ['tp5n']}}} 03:04:56 INFO - Running post-action listener: _resource_record_post_action 03:04:56 INFO - [mozharness: 2017-07-27 10:04:56.321441Z] Finished populate-webroot step (success) 03:04:56 INFO - [mozharness: 2017-07-27 10:04:56.321532Z] Running create-virtualenv step. 03:04:56 INFO - Running pre-action listener: _resource_record_pre_action 03:04:56 INFO - Running main action method: create_virtualenv 03:04:56 INFO - Creating virtualenv /builds/slave/test/build/venv 03:04:56 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 03:04:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:04:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:04:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:04:56 INFO - Using real prefix '/usr' 03:04:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:04:56 INFO - Installing distribute.............................................................................................................................................................................................done. 03:04:58 INFO - Installing pip.................done. 03:04:58 INFO - Return code: 0 03:04:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:04:59 INFO - Reading from file tmpfile_stdout 03:04:59 INFO - Output received: 03:04:59 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) 03:04:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:04:59 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')]} 03:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:59 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')]} 03:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:59 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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:04:59 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 03:04:59 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 03:04:59 INFO - Using env: {'DISPLAY': ':0', 03:04:59 INFO - 'HOME': '/home/cltbld', 03:04:59 INFO - 'LANG': 'en_US.UTF-8', 03:04:59 INFO - 'LANGUAGE': 'en_US:en', 03:04:59 INFO - 'LOGNAME': 'cltbld', 03:04:59 INFO - 'MAIL': '/var/mail/cltbld', 03:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:59 INFO - 'MOZ_NO_REMOTE': '1', 03:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:59 INFO - 'NO_EM_RESTART': '1', 03:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:59 INFO - 'PWD': '/builds/slave/test', 03:04:59 INFO - 'SHELL': '/bin/bash', 03:04:59 INFO - 'SHLVL': '1', 03:04:59 INFO - 'TERM': 'linux', 03:04:59 INFO - 'TMOUT': '86400', 03:04:59 INFO - 'USER': 'cltbld', 03:04:59 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:59 INFO - '_': '/tools/buildbot/bin/python'} 03:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:59 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 03:04:59 INFO - Cleaning up... 03:04:59 INFO - Return code: 0 03:04:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:04:59 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')]} 03:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:59 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')]} 03:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:59 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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:04:59 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 03:04:59 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 03:04:59 INFO - Using env: {'DISPLAY': ':0', 03:04:59 INFO - 'HOME': '/home/cltbld', 03:04:59 INFO - 'LANG': 'en_US.UTF-8', 03:04:59 INFO - 'LANGUAGE': 'en_US:en', 03:04:59 INFO - 'LOGNAME': 'cltbld', 03:04:59 INFO - 'MAIL': '/var/mail/cltbld', 03:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:59 INFO - 'MOZ_NO_REMOTE': '1', 03:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:04:59 INFO - 'NO_EM_RESTART': '1', 03:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:59 INFO - 'PWD': '/builds/slave/test', 03:04:59 INFO - 'SHELL': '/bin/bash', 03:04:59 INFO - 'SHLVL': '1', 03:04:59 INFO - 'TERM': 'linux', 03:04:59 INFO - 'TMOUT': '86400', 03:04:59 INFO - 'USER': 'cltbld', 03:04:59 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:59 INFO - '_': '/tools/buildbot/bin/python'} 03:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:59 INFO - Downloading/unpacking psutil>=3.1.1 03:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:05:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:00 INFO - Installing collected packages: psutil 03:05:00 INFO - Running setup.py install for psutil 03:05:00 INFO - building 'psutil._psutil_linux' extension 03:05:00 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 03:05:01 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 03:05:01 INFO - building 'psutil._psutil_posix' extension 03:05:01 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 03:05:01 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 03:05:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:01 INFO - Successfully installed psutil 03:05:01 INFO - Cleaning up... 03:05:01 INFO - Return code: 0 03:05:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:05:01 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')]} 03:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:01 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')]} 03:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:01 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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:05:01 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 03:05:01 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 03:05:01 INFO - Using env: {'DISPLAY': ':0', 03:05:01 INFO - 'HOME': '/home/cltbld', 03:05:01 INFO - 'LANG': 'en_US.UTF-8', 03:05:01 INFO - 'LANGUAGE': 'en_US:en', 03:05:01 INFO - 'LOGNAME': 'cltbld', 03:05:01 INFO - 'MAIL': '/var/mail/cltbld', 03:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:01 INFO - 'MOZ_NO_REMOTE': '1', 03:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:01 INFO - 'NO_EM_RESTART': '1', 03:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:01 INFO - 'PWD': '/builds/slave/test', 03:05:01 INFO - 'SHELL': '/bin/bash', 03:05:01 INFO - 'SHLVL': '1', 03:05:01 INFO - 'TERM': 'linux', 03:05:01 INFO - 'TMOUT': '86400', 03:05:01 INFO - 'USER': 'cltbld', 03:05:01 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:01 INFO - '_': '/tools/buildbot/bin/python'} 03:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:05: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 03:05: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 03:05: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 03:05: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 03:05:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:05:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:05:02 INFO - Installing collected packages: mozsystemmonitor 03:05:02 INFO - Running setup.py install for mozsystemmonitor 03:05:02 INFO - Successfully installed mozsystemmonitor 03:05:02 INFO - Cleaning up... 03:05:02 INFO - Return code: 0 03:05:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:05: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')]} 03:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05: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')]} 03:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05: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', '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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:05: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:05: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 jsonschema==2.5.1 03:05:02 INFO - Using env: {'DISPLAY': ':0', 03:05:02 INFO - 'HOME': '/home/cltbld', 03:05:02 INFO - 'LANG': 'en_US.UTF-8', 03:05:02 INFO - 'LANGUAGE': 'en_US:en', 03:05:02 INFO - 'LOGNAME': 'cltbld', 03:05:02 INFO - 'MAIL': '/var/mail/cltbld', 03:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:02 INFO - 'MOZ_NO_REMOTE': '1', 03:05:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:02 INFO - 'NO_EM_RESTART': '1', 03:05:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:02 INFO - 'PWD': '/builds/slave/test', 03:05:02 INFO - 'SHELL': '/bin/bash', 03:05:02 INFO - 'SHLVL': '1', 03:05:02 INFO - 'TERM': 'linux', 03:05:02 INFO - 'TMOUT': '86400', 03:05:02 INFO - 'USER': 'cltbld', 03:05:02 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:02 INFO - '_': '/tools/buildbot/bin/python'} 03:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:03 INFO - Downloading/unpacking jsonschema==2.5.1 03:05: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 03:05: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 03:05: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 03:05: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 03:05:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:05:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:05: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 03:05: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 03:05: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 03:05: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 03:05:04 INFO - Downloading functools32-3.2.3-2.tar.gz 03:05:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:05:04 INFO - warning: no files found matching '*.txt' 03:05:04 INFO - no previously-included directories found matching 'build' 03:05:04 INFO - no previously-included directories found matching 'dist' 03:05:04 INFO - no previously-included directories found matching '.git*' 03:05:04 INFO - Installing collected packages: jsonschema, functools32 03:05:04 INFO - Running setup.py install for functools32 03:05:04 INFO - warning: no files found matching '*.txt' 03:05:04 INFO - no previously-included directories found matching 'build' 03:05:04 INFO - no previously-included directories found matching 'dist' 03:05:04 INFO - no previously-included directories found matching '.git*' 03:05:04 INFO - Successfully installed jsonschema functools32 03:05:04 INFO - Cleaning up... 03:05:04 INFO - Return code: 0 03:05:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:05: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')]} 03:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05: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')]} 03:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05: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', '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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:05: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:05: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 functools32==3.2.3-2 03:05:04 INFO - Using env: {'DISPLAY': ':0', 03:05:04 INFO - 'HOME': '/home/cltbld', 03:05:04 INFO - 'LANG': 'en_US.UTF-8', 03:05:04 INFO - 'LANGUAGE': 'en_US:en', 03:05:04 INFO - 'LOGNAME': 'cltbld', 03:05:04 INFO - 'MAIL': '/var/mail/cltbld', 03:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:04 INFO - 'MOZ_NO_REMOTE': '1', 03:05:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:04 INFO - 'NO_EM_RESTART': '1', 03:05:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:04 INFO - 'PWD': '/builds/slave/test', 03:05:04 INFO - 'SHELL': '/bin/bash', 03:05:04 INFO - 'SHLVL': '1', 03:05:04 INFO - 'TERM': 'linux', 03:05:04 INFO - 'TMOUT': '86400', 03:05:04 INFO - 'USER': 'cltbld', 03:05:04 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:04 INFO - '_': '/tools/buildbot/bin/python'} 03:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:05:04 INFO - Cleaning up... 03:05:04 INFO - Return code: 0 03:05:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:05: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')]} 03:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05: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')]} 03:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05: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', '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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:05: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:05: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 blobuploader==1.2.4 03:05:04 INFO - Using env: {'DISPLAY': ':0', 03:05:04 INFO - 'HOME': '/home/cltbld', 03:05:04 INFO - 'LANG': 'en_US.UTF-8', 03:05:04 INFO - 'LANGUAGE': 'en_US:en', 03:05:04 INFO - 'LOGNAME': 'cltbld', 03:05:04 INFO - 'MAIL': '/var/mail/cltbld', 03:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:04 INFO - 'MOZ_NO_REMOTE': '1', 03:05:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:04 INFO - 'NO_EM_RESTART': '1', 03:05:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:04 INFO - 'PWD': '/builds/slave/test', 03:05:04 INFO - 'SHELL': '/bin/bash', 03:05:04 INFO - 'SHLVL': '1', 03:05:04 INFO - 'TERM': 'linux', 03:05:04 INFO - 'TMOUT': '86400', 03:05:04 INFO - 'USER': 'cltbld', 03:05:04 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:04 INFO - '_': '/tools/buildbot/bin/python'} 03:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:05 INFO - Downloading/unpacking blobuploader==1.2.4 03:05: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 03:05: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 03:05: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 03:05: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 03:05:06 INFO - Downloading blobuploader-1.2.4.tar.gz 03:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:05:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:05: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 03:05: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 03:05: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 03:05: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 03:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:05:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:05: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 03:05: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 03:05: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 03:05: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 03:05:06 INFO - Downloading docopt-0.6.1.tar.gz 03:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:05:06 INFO - Installing collected packages: blobuploader, requests, docopt 03:05:06 INFO - Running setup.py install for blobuploader 03:05:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:05:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:05:06 INFO - Running setup.py install for requests 03:05:07 INFO - Running setup.py install for docopt 03:05:07 INFO - Successfully installed blobuploader requests docopt 03:05:07 INFO - Cleaning up... 03:05:07 INFO - Return code: 0 03:05:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:07 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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:05:07 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 03:05:07 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 03:05:07 INFO - Using env: {'DISPLAY': ':0', 03:05:07 INFO - 'HOME': '/home/cltbld', 03:05:07 INFO - 'LANG': 'en_US.UTF-8', 03:05:07 INFO - 'LANGUAGE': 'en_US:en', 03:05:07 INFO - 'LOGNAME': 'cltbld', 03:05:07 INFO - 'MAIL': '/var/mail/cltbld', 03:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:07 INFO - 'MOZ_NO_REMOTE': '1', 03:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:07 INFO - 'NO_EM_RESTART': '1', 03:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:07 INFO - 'PWD': '/builds/slave/test', 03:05:07 INFO - 'SHELL': '/bin/bash', 03:05:07 INFO - 'SHLVL': '1', 03:05:07 INFO - 'TERM': 'linux', 03:05:07 INFO - 'TMOUT': '86400', 03:05:07 INFO - 'USER': 'cltbld', 03:05:07 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:07 INFO - '_': '/tools/buildbot/bin/python'} 03:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:07 INFO - Running setup.py (path:/tmp/pip-wcx5ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:07 INFO - Running setup.py (path:/tmp/pip-ACRvO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:07 INFO - Running setup.py (path:/tmp/pip-c4ltoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:07 INFO - Running setup.py (path:/tmp/pip-sMdSrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:07 INFO - Running setup.py (path:/tmp/pip-LCMwPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:07 INFO - Running setup.py (path:/tmp/pip-usM4t8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:07 INFO - Running setup.py (path:/tmp/pip-DIQiAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:08 INFO - Running setup.py (path:/tmp/pip-2Z_YVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:08 INFO - Running setup.py (path:/tmp/pip-mWqr91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:08 INFO - Running setup.py (path:/tmp/pip-oGu0El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:08 INFO - Running setup.py (path:/tmp/pip-FaAo7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:08 INFO - Running setup.py (path:/tmp/pip-fNfFcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:08 INFO - Running setup.py (path:/tmp/pip-DoDZR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:08 INFO - Running setup.py (path:/tmp/pip-57ZCIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:08 INFO - Running setup.py (path:/tmp/pip-IGkhsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:08 INFO - Running setup.py (path:/tmp/pip-rvF0aQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:08 INFO - Running setup.py (path:/tmp/pip-gAHi2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:05:08 INFO - Running setup.py install for manifestparser 03:05:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:05:08 INFO - Running setup.py install for mozcrash 03:05:08 INFO - Running setup.py install for mozdebug 03:05:09 INFO - Running setup.py install for mozdevice 03:05:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:05:09 INFO - Running setup.py install for mozfile 03:05:09 INFO - Running setup.py install for mozhttpd 03:05:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:05:09 INFO - Running setup.py install for mozinfo 03:05:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:05:09 INFO - Running setup.py install for mozInstall 03:05:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:05:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:05:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:05:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:05:09 INFO - Running setup.py install for mozleak 03:05:09 INFO - Running setup.py install for mozlog 03:05:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:05:09 INFO - Running setup.py install for moznetwork 03:05:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:05:09 INFO - Running setup.py install for mozprocess 03:05:10 INFO - Running setup.py install for mozprofile 03:05:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:05:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:05:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:05:10 INFO - Running setup.py install for mozrunner 03:05:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:05:10 INFO - Running setup.py install for mozscreenshot 03:05:10 INFO - Running setup.py install for moztest 03:05:10 INFO - Running setup.py install for mozversion 03:05:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:05:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:05:10 INFO - Cleaning up... 03:05:10 INFO - Return code: 0 03:05:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:10 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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:05:10 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 03:05:10 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 03:05:10 INFO - Using env: {'DISPLAY': ':0', 03:05:10 INFO - 'HOME': '/home/cltbld', 03:05:10 INFO - 'LANG': 'en_US.UTF-8', 03:05:10 INFO - 'LANGUAGE': 'en_US:en', 03:05:10 INFO - 'LOGNAME': 'cltbld', 03:05:10 INFO - 'MAIL': '/var/mail/cltbld', 03:05:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:10 INFO - 'MOZ_NO_REMOTE': '1', 03:05:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:10 INFO - 'NO_EM_RESTART': '1', 03:05:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:10 INFO - 'PWD': '/builds/slave/test', 03:05:10 INFO - 'SHELL': '/bin/bash', 03:05:10 INFO - 'SHLVL': '1', 03:05:10 INFO - 'TERM': 'linux', 03:05:10 INFO - 'TMOUT': '86400', 03:05:10 INFO - 'USER': 'cltbld', 03:05:10 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:05:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:10 INFO - '_': '/tools/buildbot/bin/python'} 03:05:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:10 INFO - Running setup.py (path:/tmp/pip-YIOaN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05:10 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)) 03:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:10 INFO - Running setup.py (path:/tmp/pip-C9o3eG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:10 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)) 03:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:10 INFO - Running setup.py (path:/tmp/pip-4Op9A3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:11 INFO - Running setup.py (path:/tmp/pip-WtyPmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:11 INFO - Running setup.py (path:/tmp/pip-qKRz_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:11 INFO - Running setup.py (path:/tmp/pip-FIDbwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:11 INFO - Running setup.py (path:/tmp/pip-GIHbjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:11 INFO - Running setup.py (path:/tmp/pip-8IDGqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:11 INFO - Running setup.py (path:/tmp/pip-ZSbxue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:11 INFO - Running setup.py (path:/tmp/pip-fqxht0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:11 INFO - Running setup.py (path:/tmp/pip-QdWKVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:11 INFO - Running setup.py (path:/tmp/pip-BTaJbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:11 INFO - Running setup.py (path:/tmp/pip-KGiG4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:11 INFO - Running setup.py (path:/tmp/pip-w2ZAZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:11 INFO - Running setup.py (path:/tmp/pip-MH1_t8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:11 INFO - Running setup.py (path:/tmp/pip-rfuy0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:11 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)) 03:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:11 INFO - Running setup.py (path:/tmp/pip-COS2cG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:12 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)) 03:05:12 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)) 03:05:12 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)) 03:05:12 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)) 03:05:12 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)) 03:05:12 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)) 03:05:12 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)) 03:05:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:13 INFO - Downloading blessings-1.6.tar.gz 03:05:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:05:13 INFO - Installing collected packages: blessings 03:05:13 INFO - Running setup.py install for blessings 03:05:13 INFO - Successfully installed blessings 03:05:13 INFO - Cleaning up... 03:05:13 INFO - Return code: 0 03:05:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:05:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:13 INFO - Reading from file tmpfile_stdout 03:05:13 INFO - Current package versions: 03:05:13 INFO - argparse == 1.2.1 03:05:13 INFO - blessings == 1.6 03:05:13 INFO - blobuploader == 1.2.4 03:05:13 INFO - docopt == 0.6.1 03:05:13 INFO - functools32 == 3.2.3-2 03:05:13 INFO - jsonschema == 2.5.1 03:05:13 INFO - manifestparser == 1.1 03:05:13 INFO - mozInstall == 1.13 03:05:13 INFO - mozcrash == 1.0 03:05:13 INFO - mozdebug == 0.1 03:05:13 INFO - mozdevice == 0.51 03:05:13 INFO - mozfile == 1.2 03:05:13 INFO - mozhttpd == 0.7 03:05:13 INFO - mozinfo == 0.10 03:05:13 INFO - mozleak == 0.1 03:05:13 INFO - mozlog == 3.5 03:05:13 INFO - moznetwork == 0.27 03:05:13 INFO - mozprocess == 0.25 03:05:13 INFO - mozprofile == 0.28 03:05:13 INFO - mozrunner == 6.13 03:05:13 INFO - mozscreenshot == 0.1 03:05:13 INFO - mozsystemmonitor == 0.3 03:05:13 INFO - moztest == 0.8 03:05:13 INFO - mozversion == 1.4 03:05:13 INFO - psutil == 3.1.1 03:05:13 INFO - requests == 1.2.3 03:05:13 INFO - wsgiref == 0.1.2 03:05:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:13 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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:05:13 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 03:05:13 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 03:05:13 INFO - Using env: {'DISPLAY': ':0', 03:05:13 INFO - 'HOME': '/home/cltbld', 03:05:13 INFO - 'LANG': 'en_US.UTF-8', 03:05:13 INFO - 'LANGUAGE': 'en_US:en', 03:05:13 INFO - 'LOGNAME': 'cltbld', 03:05:13 INFO - 'MAIL': '/var/mail/cltbld', 03:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:13 INFO - 'MOZ_NO_REMOTE': '1', 03:05:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:13 INFO - 'NO_EM_RESTART': '1', 03:05:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:13 INFO - 'PWD': '/builds/slave/test', 03:05:13 INFO - 'SHELL': '/bin/bash', 03:05:13 INFO - 'SHLVL': '1', 03:05:13 INFO - 'TERM': 'linux', 03:05:13 INFO - 'TMOUT': '86400', 03:05:13 INFO - 'USER': 'cltbld', 03:05:13 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:13 INFO - '_': '/tools/buildbot/bin/python'} 03:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:13 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)) 03:05:13 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)) 03:05:13 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)) 03:05:13 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)) 03:05:13 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)) 03:05:13 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)) 03:05:13 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)) 03:05:13 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)) 03:05:13 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)) 03:05:13 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:05: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 03:05: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 03:05: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 03:05: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 03:05:14 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:05:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:05:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 03:05:14 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 03:05:14 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 03:05:14 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 03:05:14 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 03:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:05:15 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)) 03:05:15 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)) 03:05:15 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)) 03:05:15 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)) 03:05:15 INFO - Installing collected packages: marionette-driver, simplejson 03:05:15 INFO - Running setup.py install for marionette-driver 03:05:15 INFO - Running setup.py install for simplejson 03:05:15 INFO - building 'simplejson._speedups' extension 03:05:15 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 03:05:16 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 03:05:16 INFO - Successfully installed marionette-driver simplejson 03:05:16 INFO - Cleaning up... 03:05:16 INFO - Return code: 0 03:05:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:16 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 0x26b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a21d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, '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': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', '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 03:05:16 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 03:05:16 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 03:05:16 INFO - Using env: {'DISPLAY': ':0', 03:05:16 INFO - 'HOME': '/home/cltbld', 03:05:16 INFO - 'LANG': 'en_US.UTF-8', 03:05:16 INFO - 'LANGUAGE': 'en_US:en', 03:05:16 INFO - 'LOGNAME': 'cltbld', 03:05:16 INFO - 'MAIL': '/var/mail/cltbld', 03:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:16 INFO - 'MOZ_NO_REMOTE': '1', 03:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:16 INFO - 'NO_EM_RESTART': '1', 03:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:16 INFO - 'PWD': '/builds/slave/test', 03:05:16 INFO - 'SHELL': '/bin/bash', 03:05:16 INFO - 'SHLVL': '1', 03:05:16 INFO - 'TERM': 'linux', 03:05:16 INFO - 'TMOUT': '86400', 03:05:16 INFO - 'USER': 'cltbld', 03:05:16 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:16 INFO - '_': '/tools/buildbot/bin/python'} 03:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:05:16 INFO - Cleaning up... 03:05:16 INFO - Return code: 0 03:05:16 INFO - Running post-action listener: _resource_record_post_action 03:05:16 INFO - Running post-action listener: _start_resource_monitoring 03:05:16 INFO - Starting resource monitoring. 03:05:16 INFO - [mozharness: 2017-07-27 10:05:16.576360Z] Finished create-virtualenv step (success) 03:05:16 INFO - [mozharness: 2017-07-27 10:05:16.576882Z] Running install step. 03:05:16 INFO - Running pre-action listener: _resource_record_pre_action 03:05:16 INFO - Running main action method: install 03:05:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:16 INFO - Reading from file tmpfile_stdout 03:05:16 INFO - Detecting whether we're running mozinstall >=1.0... 03:05:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:05:16 INFO - Reading from file tmpfile_stdout 03:05:16 INFO - Output received: 03:05:16 INFO - Usage: mozinstall [options] installer 03:05:16 INFO - Options: 03:05:16 INFO - -h, --help show this help message and exit 03:05:16 INFO - -d DEST, --destination=DEST 03:05:16 INFO - Directory to install application into. [default: 03:05:16 INFO - "/builds/slave/test"] 03:05:16 INFO - --app=APP Application being installed. [default: firefox] 03:05:16 INFO - mkdir: /builds/slave/test/build/application 03:05:16 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'] 03:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:05:34 INFO - Reading from file tmpfile_stdout 03:05:34 INFO - Output received: 03:05:34 INFO - /builds/slave/test/build/application/firefox/firefox 03:05:34 INFO - Running post-action listener: _resource_record_post_action 03:05:34 INFO - [mozharness: 2017-07-27 10:05:34.900315Z] Finished install step (success) 03:05:34 INFO - [mozharness: 2017-07-27 10:05:34.900578Z] Running setup-mitmproxy step. 03:05:34 INFO - Running pre-action listener: _resource_record_pre_action 03:05:34 INFO - Running main action method: setup_mitmproxy 03:05:34 INFO - Skipping: mitmproxy is not required 03:05:34 INFO - Running post-action listener: _resource_record_post_action 03:05:34 INFO - [mozharness: 2017-07-27 10:05:34.901720Z] Finished setup-mitmproxy step (success) 03:05:34 INFO - [mozharness: 2017-07-27 10:05:34.901943Z] Running run-tests step. 03:05:34 INFO - Running pre-action listener: _resource_record_pre_action 03:05:34 INFO - Running pre-action listener: _set_gcov_prefix 03:05:34 INFO - Running main action method: run_tests 03:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:05:34 INFO - Python 2.7.3 03:05:34 INFO - Return code: 0 03:05:34 INFO - grabbing minidump binary from tooltool 03:05:34 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')]} 03:05:34 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 0x2a1eeb0>, '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 0x2a1fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a20240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:05:34 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 03:05:34 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 03:05:34 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 03:05:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:05:35 INFO - Return code: 0 03:05:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:05:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:05:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:05:35 INFO - ENV: RUST_BACKTRACE is now 1 03:05:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:05:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:05:35 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/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 03:05:35 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/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-072 --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 03:05:35 INFO - Using env: {'DISPLAY': ':0', 03:05:35 INFO - 'HOME': '/home/cltbld', 03:05:35 INFO - 'LANG': 'en_US.UTF-8', 03:05:35 INFO - 'LANGUAGE': 'en_US:en', 03:05:35 INFO - 'LOGNAME': 'cltbld', 03:05:35 INFO - 'MAIL': '/var/mail/cltbld', 03:05:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:05:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:35 INFO - 'MOZ_NO_REMOTE': '1', 03:05:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:35 INFO - 'NO_EM_RESTART': '1', 03:05:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:35 INFO - 'PWD': '/builds/slave/test', 03:05:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:05:35 INFO - 'RUST_BACKTRACE': '1', 03:05:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:05:35 INFO - 'SHELL': '/bin/bash', 03:05:35 INFO - 'SHLVL': '1', 03:05:35 INFO - 'TERM': 'linux', 03:05:35 INFO - 'TMOUT': '86400', 03:05:35 INFO - 'USER': 'cltbld', 03:05:35 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221', 03:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:35 INFO - '_': '/tools/buildbot/bin/python'} 03:05:35 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/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 03:05:35 INFO - mozversion application_buildid: 20170727085605 03:05:35 INFO - mozversion application_changeset: c1ed71da57073d0f5fdbd279c9ad6037f9ae2d06 03:05:35 INFO - mozversion application_display_name: Nightly 03:05:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:05:35 INFO - mozversion application_name: Firefox 03:05:35 INFO - mozversion application_remotingname: firefox 03:05:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:05:35 INFO - mozversion application_vendor: Mozilla 03:05:35 INFO - mozversion application_version: 56.0a1 03:05:35 INFO - mozversion platform_buildid: 20170727085605 03:05:35 INFO - mozversion platform_changeset: c1ed71da57073d0f5fdbd279c9ad6037f9ae2d06 03:05:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:05:35 INFO - mozversion platform_version: 56.0a1 03:05:35 INFO - using testdate: 1501149935 03:05:35 INFO - actual date: 1501149935 03:05:35 INFO - starting webserver on 'localhost:42733' 03:05:35 INFO - SUITE-START | Running 2 tests 03:05:35 INFO - TEST-START | dromaeo_css 03:05:35 INFO - Initialising browser for dromaeo_css test... 03:05:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42733/getInfo.html -profile /tmp/tmpgOA5BY/profile 03:05:35 INFO - TEST-INFO | started process 19666 (/builds/slave/test/build/application/firefox/firefox http://localhost:42733/getInfo.html) 03:05:41 INFO - TEST-INFO | 19666: exit 0 03:05:41 INFO - Browser initialized. 03:05:41 INFO - Running cycle 1/1 for dromaeo_css test... 03:05:41 INFO - TEST-INFO | started process 19876 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgOA5BY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:05:42 INFO - PID 19876 | 03:05:42 INFO - PID 19876 | (/builds/slave/test/build/application/firefox/firefox:19925): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:05:42 INFO - PID 19876 | 03:05:53 INFO - PID 19876 | RSS: Main: 168112128 03:05:53 INFO - PID 19876 | 03:08:52 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:42733/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42733/tests/dromaeo/cssquery-ext.html) 03:08:53 INFO - PID 19876 | RSS: Main: 172146688 03:08:53 INFO - PID 19876 | 03:11:55 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:42733/tests/dromaeo/cssquery-ext.html (next: http://localhost:42733/tests/dromaeo/cssquery-jquery.html) 03:11:56 INFO - PID 19876 | RSS: Main: 169873408 03:11:56 INFO - PID 19876 | 03:14:57 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:42733/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42733/tests/dromaeo/cssquery-mootools.html) 03:14:58 INFO - PID 19876 | RSS: Main: 171433984 03:14:58 INFO - PID 19876 | 03:18:00 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:42733/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42733/tests/dromaeo/cssquery-prototype.html) 03:18:00 INFO - PID 19876 | RSS: Main: 169934848 03:18:00 INFO - PID 19876 | 03:21:04 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:42733/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42733/tests/dromaeo/cssquery-yui.html) 03:21:05 INFO - PID 19876 | RSS: Main: 170835968 03:21:05 INFO - PID 19876 | 03:24:07 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:42733/tests/dromaeo/cssquery-yui.html (next: http://localhost:42733/tests/dromaeo/cssquery-dojo.html) 03:24:07 INFO - PID 19876 | RSS: Main: 172564480 03:24:07 INFO - PID 19876 | 03:24:07 INFO - PID 19876 | __start_tp_report 03:24:07 INFO - PID 19876 | _x_x_mozilla_page_load 03:24:07 INFO - PID 19876 | _x_x_mozilla_page_load_details 03:24:07 INFO - PID 19876 | |i|pagename|runs| 03:24:07 INFO - PID 19876 | |0;dojo.html;3071.1247294521067;3095.5047192449215;3105.2547388626735;3115.7040081192285;3122.8126312421255;6180.814575562729;6207.441330280274;6270.65511396873;6310.684465776716;6319.462845658113;6006.819795406134;6115.052166914124;6138.87722245556;6153.138560601519;6173.969130154354;5607.299087614048;5618.129189975561;5642.266505354298;5651.632643878155;5654.067078931971;5669.433056694339;5718.17086522454;5722.914156287638;5756.769729210827;5779.508741756935;5632.5212356949705;5689.943100569003;5717.685527295997;5746.856328591798;5778.0466223073145;5471.616986810925;5597.132444471114;5600.159976003632;5618.943810561883;5688.516476099537;5571.804986825442;5588.30146231721;5657.20799088128;5660.915086273709;5682.289713785777;5924.1113832924975;5995.610285331483;6036.275646922353;6073.817785466443;6122;6449.032645103272;6480.773172938972;6521.315261897527;6527.477801775846;6528.673566321712;6510.446612037935;6563.3108523604815;6572.277049524549;6592.93407065928;6640.701168447432;6130.5402094842175;6175.845603859894;6214.0989556514505;6215.564910456315;6223.719932603044;6724.596524208563;6752.426043786235;6752.763653272112;6829.658517074126;6834.829129271808;6300.432961033529;6430.935690643128;6434.967825160844;6447.645379504079;6508.186476690414;6536.934630653727;6595.70319335631;6614.966925165343;6620.536562440583;6641.037049627874;6619.3380661933425;6639.435647969976;6685.765998190137;6706.798796036127;6711.362420570038;6516.2506311773805;6584.407403333723;6617.106690596708;6669.666516674244;6676.833079172962;5222.268882356473;5253.133233016586;5276.947230527646;5279.709615971082;5287.629865909426;4546.931796023062;4569.474510431276;4610.031893302377;4622.653301002439;4646.4191976003;5611.607187496836;5612.438756124355;5692.715364231772;5697;5705.372409035002;6559.409653131145;6561.606303621798;6585.769498067544;6660.167479065117;6667.633280169637;5499.120140777456;5574.080276754371;5585.3297604287745;5678.716064196857;5713.371529131792;5763.337216220189;5773.278340207474;5779.739911703983;5859.882802344015;5864.091065884794;6433.195850518685;6486;6513.73945042193;6516.57642253252;6536.313687062885;6615.338466153346;6636.037774522763;6640.701168447335;6734.966325168343;6741.52809303344;6335.809925702236;6425.357464253537;6427;6427.967860160669;6507.186601674791;6499.707513161825;6501.382368674968;6504.447121994494;6507.316731743099;6516.446102081376;6552.3775241352;6645.933540664531;6646.8005959821285;6647;6651.035599838093;6415.967920160369;6417.133686952388;6421.293657697743;6422.678866056772;6438.291787903233;6424.550281480439;6425.357464253724;6442.806715798533;6459.806205813833;6464.967675161594;6234.3453937337745;6340.397662222082;6345.492360611048;6411.166548348685;6418.069379939976;6412.1023056771155;6433.678316084083;6437.806865794034;6476.255230648536;6542.411182993363;6144.631322120687;6231.2834024086;6236.251649801871;6276.309605943251;6431.967840160769 03:24:07 INFO - PID 19876 | |1;ext.html;778.567894818376;845.6194712379429;854.2567965869695;855.619249434002;1157.739508610562;29649.703502964963;40652.39021414678;41537.16925661493;41637.375439368356;41766.791166044124;41789.37315940256;41986.16027679444;42088.78955605225;42095.36856947148;42116.57883421165;7601.087869455673;7638.579878106688;7785.727499537517;7794.337481314095;7814;7609.961950190242;7687.231276872296;7710.383169346439;7717.266859648353;7724.266194711493;7676.769696909102;7696.384289256874;7718.845623087563;7734.613269336511;7738.922610773905;7669.118051424074;7695.461317707763;7703.537787732754;7704.46068775186;7719.922800772005;7634.351080158138;7680.692772289101;7691.077070751546;7692.538447693157;7703.3067023968115;38402.615973840184;38709.61290387088;38743.22513549713;38803.805980970195;38869.61130388688;3502.859885604573;3541.043918142087;3541.8583256669704;3547.2018795771005;3550.55618047744;31506.842465787755;31677.52483712746;31833.204169895707;31834.840825795724;31839.363212735614;4875.219964805614;4904.068227036887;4908.607311415079;4913;4957.206846904514;188575;188882;189118;190318;190820;49398;49562.75218623956;49651;49704;49814.750926245135;51672;52200;52243.477565224624;52298.215526767126;52563.474365256625;14182.716345673236;16296.918515407348;16515.6696866062;16552.917235413744;16562.50312490627;11609;13207.933960330329;13213;13227;13334.53329133495;14482.565523034325;14518.782218266735;14612.415503379958;14618.122912625277;14690.779638305434;5815.5929084963645;5883.6469811810575;5912.940870591325;5917.319508256605;5960.791372301948;988.5600907596195;989.5942663507927;997.7854761226371;1018.7758693087509;1019.658414431159;21680.28715347618;21914.89042554809;22232;22602.321930342117;23066.077356905873;1091.5142761471227;1098.3958822647512;1104;1113.3486910157649;1118.166965610609;11759.588414405454;11773.646790596296;11781.941090294666;11990.640280791591;12121.939390302992;228192;229915;230987;231228;231377;53281.2007819883;53340.46659533433;53820;53977.73011134919;54101.72949135229;1432.0548438031058;1481.4370539195038;1494.4321157960123;1498.1086253679043;1510.6298956755666;21875.45311367165;22176.667349990086;22501.887490562443;22683.546329072997;22798.886005569864;37075.44386834253;37485.4377184337;37505;37539.24921501609;37557.62442375541;193091;194005;194248;194327;194918;32608.34783304273;33348.833255834536;33556.49665255072;33597.496037558965;33602;43875.78062109797;43968.56031439773;44406;47167.76416118035;48470.75764621295;33400.32998350024;34364.31271374608;34676.65323346734;34761.304773904885;34984.47523287102;27958.860205698842;28922.855385722938;29172.56241156342;29173.124806255848;29207;36776.264474709824;37807.43288850725;37909.62090379061;38015.80992095022;38333.80833095816;30488;31096;32583.511247331782;33069.66930330762;33095.50356744699;23399.883000584887;23450.530989379775;24022.759772402052;24178.274651760476;24252.908619112535 03:24:07 INFO - PID 19876 | |2;jquery.html;571.1233256950582;571.5627544928129;575.6488541989386;581.6742624130485;594.2275042444822;21674.674879876813;22459.326220213377;22475.201983841293;22585.096596136136;22630.43423914399;24661.260162195118;25324.620130698007;26455.470890582226;26665.86667066662;26688.733112668866;6389.936100638992;6503.902441463382;6516.413522782948;6545.312742162052;6545.672716364186;6387.329330420309;6430.7106180222;6441.6135031898;6441.774537891175;6452.2257329120575;6077.848053798646;6620.702068406933;6627.933720662804;6642.933570664304;6644.202695676526;6496.967515162418;6568.901466478007;6572.7042283097135;6574.408303252706;6585.967070164666;6081.17904082952;6115.5107591393025;6126.846828829271;6145.907811382833;6146.508279337642;28553.428931421462;29613.555796663066;30031;30179.849100754574;30193;1856.8421684156729;1914.176903931309;1917.712343148525;1926.0369815092454;1936.7579052618423;19157.61684766297;19761.703574446394;19816.30642927505;19897.801021989886;19904.50238744043;2320.3155069254612;2348.448114693063;2432.404061005065;2438.207582535683;2442.487077713682;127463;127592;130132;130318;130348;14519.709605808035;14597.927010365092;14887.330070146652;15195.392184312504;15444.227788610524;22676.433089172904;23000.884995574914;23137.652935205984;23752;23843.642345364835;10111.64609238673;10142.746431339276;10219.489025548693;10319.638812641522;10460;9342;9363.34456588046;9382.437053776795;9503.334766566273;9524.476153811607;9243.260539156985;9275.350725449154;9298.767530811649;9323.207527360115;9564.426134431957;2572.0939278038527;2598.4673142005845;2627.7240889706686;2756.415061339738;2790.121111849761;934.6915517879083;945.3246026914525;968.8352979993418;988.3229987458614;995.721198064543;19312.71030934537;19369.806301936802;19554.902225489066;19688.75677566014;20221.595568088556;1015;1025.251566356549;1031.83490641497;1045.8274384726435;1048.1981284317503;10510.369377837354;10689.30519516229;10763.730906727238;11215.158863085302;11235.045021173128;439717;447828;451725;458253;462376;119080;124636.37681811533;124889;125187;126748.36625816811;1142.0349804415255;1309.5809341010786;1313.3105119811985;1315.7434300311347;1321.2666969831835;20461.386158414676;21247.893760531715;21422.250221242182;21513.13947442085;21967.560648786613;53696.73151634373;54933.450665494434;57036.71481642565;58853.41146588479;59591.702041489516;76003.23996760182;76230.61884690763;76518;77447.61276193809;79207;14606.342714578082;14745.11529308245;14867.40530378816;14888.330025148678;14996.775048374502;23367;23657.64513532264;23782.048718051083;23936.88031559831;24223.51552968966;16220.91889540592;18413.723794142832;18725.25098996025;18866.3396781112;18997.335093271668;15871.52385428439;16265.349386024427;16634.417795377103;17238.310467581156;17480.47558573245;18351.174197160894;19177.712334315274;19410.126544305258;19630.214791408183;19645.70531442001;14458.638534036732;14475.710485790014;14573.489927852474;14584.249890013267;14593.416263349347;18875.244990200237;18993.955332456848;19006.334778282693;19022.429327120208;19210 03:24:07 INFO - PID 19876 | |3;mootools.html;1336.9331533423328;1382.364112508246;1383.1770096792409;1403.5298175111343;1406.247657503236;13415.798763018562;14537.56387308381;14554.781678274821;14565.490207842728;14567.490137845178;14219.71560568788;14449.422023119088;14501.129932204049;14501.202433866134;14539.78190327146;2630.053180854886;2693.168645321181;2697.3526353675074;2703.91888243353;2714.8506832124226;2519.282004628681;2561.1420174241653;2629.8290611110215;2636.393629465224;2643.3259518822674;2556.5909454487287;2573.90991315304;2597.610358446239;2597.870106494677;2599.1422830465995;2497.7002759668776;2534.480431511545;2545.2618740565213;2547.656066431045;2549.9235022949247;2391.330427480291;2448.755124487555;2468.5803413419762;2479.6280557916275;2492.0779311654805;13445.596632100955;13545.45818167282;13564.593062208149;13574.321283935764;13575.321233938264;7260.709571617129;7342.118945726494;7506.66220020095;7516.022917020752;7517.4737768356235;5051.217061355459;5128;5241.68549887008;5270.710110943896;5296.179092240709;1856.1925562380409;1858.2381223698353;1858.8048254933171;1882.6517094337592;1887.462073309114;21554.353369398945;22169.667454988186;22184.667229991563;22217.333479995625;22280.665790013;6678.962916310572;6819.283975182633;6921.927101299307;6980.616066116311;7161.212266650664;18332;18355.724664130048;18359.082045897652;18367.81632183688;18413.355532556565;7733.4973226740085;7740.690372385154;7953.164917683675;7954.840903181904;7975.680972761141;6816.250212476623;7032;7090.539114957614;7098.183708873443;7143.285671432815;4779.569838714532;4912.852614421573;4948.134076536592;4972.5276098770555;4980.327655766425;1357.545222350504;1370.6847425092285;1377.5109836008273;1377.9272222942893;1382.640513466506;851.7827953871723;861.5649097205871;877.0659247900965;895.3866601378077;906.8277027364779;8087.919120808834;8137.552434616035;8444.535550544657;8463.74608761738;8480.279176270085;967.5887747707337;980.8185485685028;998.9051040151174;1007.1036777268237;1028.953697083633;4856.150173719585;5148.871278218075;5196.506331898464;5209.505097015777;5363.785448582091;17314.65370692579;17868.374606888956;17902.46292611224;17913.552161195814;17917;18314.358997435025;19747.111379987648;19762.20951161966;19771.802281976998;19815.702764458543;9982.650607228858;10165.542550585242;10181.287309888237;10186.898131018743;10192.898071019492;7498.32515073646;8000.759977200693;8368.916310837058;8413.368997325102;8532.23209911111;16408.261628226996;16551.337946482006;17156.828431715523;17208.225629846936;17571;15847.762283565986;16720.665586688443;16758.916205418896;17461.38885139014;17836.732449013016;11403.885961140282;11633.53465861356;11645.825312620149;11645.88354116448;11650;13140.60578182656;13152.934235328763;13231.867681323449;13235.80146297787;13271.203727776365;10033.247506437192;10141.645042423479;10195.082442580204;10708.089812366035;10761.784764304513;9227.492487913229;9936.056074672895;10001.499925003865;10013.649522266685;10048.69853904384;8831.955840220759;8902.510361930155;9175.678851240174;9467.337286389886;9477.478738669439;6763.2898545652;6772.950192720136;6880.243173251039;7034;7040.295970402919;6601.603903765789;6965.547239429421;6989.790306290819;7052.365287124184;7085.751998680022 03:24:07 INFO - PID 19876 | |4;prototype.html;496.2085473669497;500.2970825989486;513.3839392728726;524.4755244755245;531.8058908498397;6144.60060096094;6195.132681424599;6206.6586337751405;6208.16189814375;6284.805886881501;5890.263717035371;6024.367441418643;6059.818205453832;6069.241344831896;6070.573415247424;4865.68373055752;4870.537298956611;4888.266759986012;4907.656464047518;4925.261210818388;4869.902601947977;4888.560029597335;4910.288008238803;4923;4966.602671786249;4723.197056500403;4874.756262186894;4893.4861839506875;4897.7795999180125;4914.950850491503;4926.704397736129;4931.3589233399425;4932.235503496964;4942.849860310776;4960.156773348539;4394.714343567658;4405.955940440586;4425.601695847357;4441.400410944513;4444.73331600105;6016.729247183887;6037.788677396314;6037.849053773647;6046.969765151146;6047.334793172748;5620.662760234399;5638.605297629168;5654.491095801398;5660.575456840754;5691.886162276773;6415.775447859349;6470.832271189157;6471.482281417475;6497.902531462032;6519.315471875432;4750.881227969294;4760;4772.0217355442055;4799.400074990626;4804.25534042224;6757.966210168966;6767.289434609344;6814.62519561424;6853.828654283633;6878.312168783182;4917.385326834145;4934;4952.133376659071;4956.4547899731;4982.451930287665;4975.875603109951;4990.051890140934;4998.925016124761;4999.375078115236;5009.749512524432;5637.210790489335;5650.604457687922;5654.293213348332;5670.7731690732735;5693.402192769782;6448.420631747339;6453.935460645427;6494.9350506495275;6540.182477190351;6598.505112116514;1632.1675945267998;1659.1621231278368;1689.6620675864876;1734.8785585008927;1745.6159644878107;1166.848309719731;1196.1208511743862;1210.7578484302999;1221.4259298129864;1225.3566877389442;5493.983613031602;5503.477169668875;5522.530584900329;5547.611667183259;5586.581006424534;6440.872847251712;6483.384078512534;6515.576487528296;6540.934590654033;6543.476521878239;5510.724463776795;5585.888282234332;5672.205891175238;5678.517326027334;5695.544356451557;3496.283261931349;3497.793261324839;3501.824908754552;3516.701080408143;3562.608113107556;6401.679916004275;6413.711382987777;6423.646699431482;6477.384648458389;6488.221413430419;6783.0164626128935;6825.9317406826285;6838.658067096725;6862.176538815374;6926.722931082801;6446.935530644633;6448.806535803933;6457.257415397289;6459.192600924885;6490.383413575702;6668;6678.699458524281;6686.465082793462;6737.797866064026;6747.291534388818;6408.872901678732;6442.162518872704;6546.345365463606;6613.2394774601535;6620.8344791380605;6341.239051313872;6372.681365931592;6399.6800159992745;6413.621071469656;6477.611343319416;4850.442199147002;4930.457649658463;4955.331030310862;4956.628252880976;4956.77694503741;6695.631740254333;6699.43054840344;6723.731050757915;6727.798166055026;6750.257471678014;6402.167718196604;6474.125992990886;6516.315786842503;6522.478201743944;6548.1487406638735;6352.460040896391;6385.265694445198;6401.423871851555;6414.647194404352;6415.454686351526;6196.1635179250225;6311.905321420274;6333.809985700436;6387.712552935036;6394.968025159845;6261.843453913689;6366.076918846834;6426.518011149276;6482.967585162232;6491.545591808528;6307.526935479766;6331.746730130743;6350;6352.745890164527;6393.7122829471855 03:24:07 INFO - PID 19876 | |5;yui.html;821.2854816309812;845.1759534453909;854.3293514591047;911.3028533172122;939.5302348825585;18413.355532556365;18792;19148.329808456707;19153.425397238105;19172.712409313837;20346.694799578017;22066.66899996501;22168.556628867413;22173.778262217373;22299.55400891981;1034.8137335279666;1047.5757318286069;1048.3343077145992;1048.8584041154422;1048.979020419591;963.7108867339805;1041.052642095693;1045.7437927707722;1045.9215558833118;1054.140875186724;1102.7518808268155;1112.6439539347411;1112.8664560252741;1113.62136873463;1114.554178328671;1090.929089609173;1100.8073587122221;1103.3765922253901;1103.823388257875;1104.7679987202698;805.0661232969785;811.8011087283594;813.8128230506958;814.861473549498;814.9185081491863;18067.63864722698;18584.44246672602;19107.331243406552;19792.70310945337;19975.50061248466;20528;20819.89590052055;20988.790112098988;21186.470338241514;21330.786692133035;15233.923830380778;15652.295646695811;15743;15762;16159.83840161595;3466.861325546975;3517.032815975602;3523.2777280657406;3535.8939231823088;3538.6815186633326;116963.4151829244;119220.40389797995;121850.99999999911;127887;128696;16690.49928502147;17092.743608845878;17138.40015599448;17211.397601083903;17226.397076102276;29904.85047574748;31036.53445198324;31890.840545797124;31922.521162182587;32021.8398908004;8878.067802880734;8900.643974240958;9057.77355566116;9062.818743625223;9083.636654533744;4034.9394759078637;4193.91612167761;4195.098053918422;4196.139791342767;4207.747535147901;4883.389576302962;5415.918761218585;5839.591228613956;5891.675957822275;5907.350191478934;1050.5167622893555;1079.940603266812;1093.6390990973118;1100.9284396514201;1104.3429159649995;659.8548319369731;664.9832057812852;667.7562689618352;702.4450683959717;708.1714394158846;11418.828717569244;12182.329971851634;12191.573294934635;12726.363681816058;12844.614661560168;650.648649729141;688.1501345837886;693.4729605499856;699.1260923845193;705.4497491956282;5217.478252174752;5469.425710300441;5477.315335583052;5605.915911261334;5665.3768085510565;98342.5082874581;99695;103277;103584;104835.4758226204;87121;87525;89219;91901.5404922971;94675;77203;78315.60842195753;78355.21644783593;78428.21571784209;82503.58748206221;12458.190217635825;12512.812307815571;12639.304838233986;12871.613851584467;13134.540291089766;36074.27851442904;36932.44601331036;37421.25157496889;37450.43824342582;38790.224195515366;62397.68801156147;66494;69306;70457;70797;18599.44201673952;18810.24759009624;18967.620647587246;19000.52498687544;19059.618807623494;20907.8954605226;21124.788752112283;21132.78867211308;21192.470188245417;21274.680879786505;17601.82398176002;17624.82375176283;17651.117444127485;19470.415887523395;19519.12163952654;18881.339153129575;18903;19594.412167634993;19597.216111355956;19897.204111835363;17040.06279654581;17353.56616084608;17970.91014544919;18039.188236529124;18431.44705658779;12639.304838233986;12658.810117848056;12697.98416126726;13310.534131305358;14096.436142554183;14292.28538573088;14638.121712697272;14951.102933824006;15132.773008404663;15195.92402038027 03:24:07 INFO - PID 19876 | __end_tp_report 03:24:07 INFO - PID 19876 | __start_cc_report 03:24:07 INFO - PID 19876 | _x_x_mozilla_cycle_collect,348 03:24:07 INFO - PID 19876 | __end_cc_report 03:24:07 INFO - PID 19876 | __startTimestamp1501151047427__endTimestamp 03:24:07 INFO - PID 19876 | 03:24:07 INFO - PID 19876 | ------- Summary: start ------- 03:24:07 INFO - PID 19876 | Number of tests: 6 03:24:07 INFO - PID 19876 | 03:24:07 INFO - PID 19876 | [#0] dojo.html Cycles:165 Average:6072.58 Median:6340.40 stddev:721.08 (11.4%) stddev-sans-first:683.77 03:24:07 INFO - PID 19876 | Values: 3071.1 3095.5 3105.3 3115.7 3122.8 6180.8 6207.4 6270.7 6310.7 6319.5 6006.8 6115.1 6138.9 6153.1 6174.0 5607.3 5618.1 5642.3 5651.6 5654.1 5669.4 5718.2 5722.9 5756.8 5779.5 5632.5 5689.9 5717.7 5746.9 5778.0 5471.6 5597.1 5600.2 5618.9 5688.5 5571.8 5588.3 5657.2 5660.9 5682.3 5924.1 5995.6 6036.3 6073.8 6122.0 6449.0 6480.8 6521.3 6527.5 6528.7 6510.4 6563.3 6572.3 6592.9 6640.7 6130.5 6175.8 6214.1 6215.6 6223.7 6724.6 6752.4 6752.8 6829.7 6834.8 6300.4 6430.9 6435.0 6447.6 6508.2 6536.9 6595.7 6615.0 6620.5 6641.0 6619.3 6639.4 6685.8 6706.8 6711.4 6516.3 6584.4 6617.1 6669.7 6676.8 5222.3 5253.1 5276.9 5279.7 5287.6 4546.9 4569.5 4610.0 4622.7 4646.4 5611.6 5612.4 5692.7 5697.0 5705.4 6559.4 6561.6 6585.8 6660.2 6667.6 5499.1 5574.1 5585.3 5678.7 5713.4 5763.3 5773.3 5779.7 5859.9 5864.1 6433.2 6486.0 6513.7 6516.6 6536.3 6615.3 6636.0 6640.7 6735.0 6741.5 6335.8 6425.4 6427.0 6428.0 6507.2 6499.7 6501.4 6504.4 6507.3 6516.4 6552.4 6645.9 6646.8 6647.0 6651.0 6416.0 6417.1 6421.3 6422.7 6438.3 6424.6 6425.4 6442.8 6459.8 6465.0 6234.3 6340.4 6345.5 6411.2 6418.1 6412.1 6433.7 6437.8 6476.3 6542.4 6144.6 6231.3 6236.3 6276.3 6432.0 03:24:07 INFO - PID 19876 | 03:24:07 INFO - PID 19876 | [#1] ext.html Cycles:180 Average:37551.91 Median:3541.45 stddev:53268.45 (1504.1%) stddev-sans-first:53346.32 03:24:07 INFO - PID 19876 | Values: 778.6 845.6 854.3 855.6 1157.7 29649.7 40652.4 41537.2 41637.4 41766.8 41789.4 41986.2 42088.8 42095.4 42116.6 7601.1 7638.6 7785.7 7794.3 7814.0 7610.0 7687.2 7710.4 7717.3 7724.3 7676.8 7696.4 7718.8 7734.6 7738.9 7669.1 7695.5 7703.5 7704.5 7719.9 7634.4 7680.7 7691.1 7692.5 7703.3 38402.6 38709.6 38743.2 38803.8 38869.6 3502.9 3541.0 3541.9 3547.2 3550.6 31506.8 31677.5 31833.2 31834.8 31839.4 4875.2 4904.1 4908.6 4913.0 4957.2 188575.0 188882.0 189118.0 190318.0 190820.0 49398.0 49562.8 49651.0 49704.0 49814.8 51672.0 52200.0 52243.5 52298.2 52563.5 14182.7 16296.9 16515.7 16552.9 16562.5 11609.0 13207.9 13213.0 13227.0 13334.5 14482.6 14518.8 14612.4 14618.1 14690.8 5815.6 5883.6 5912.9 5917.3 5960.8 988.6 989.6 997.8 1018.8 1019.7 21680.3 21914.9 22232.0 22602.3 23066.1 1091.5 1098.4 1104.0 1113.3 1118.2 11759.6 11773.6 11781.9 11990.6 12121.9 228192.0 229915.0 230987.0 231228.0 231377.0 53281.2 53340.5 53820.0 53977.7 54101.7 1432.1 1481.4 1494.4 1498.1 1510.6 21875.5 22176.7 22501.9 22683.5 22798.9 37075.4 37485.4 37505.0 37539.2 37557.6 193091.0 194005.0 194248.0 194327.0 194918.0 32608.3 33348.8 33556.5 33597.5 33602.0 43875.8 43968.6 44406.0 47167.8 48470.8 33400.3 34364.3 34676.7 34761.3 34984.5 27958.9 28922.9 29172.6 29173.1 29207.0 36776.3 37807.4 37909.6 38015.8 38333.8 30488.0 31096.0 32583.5 33069.7 33095.5 23399.9 23450.5 24022.8 24178.3 24252.9 03:24:07 INFO - PID 19876 | 03:24:07 INFO - PID 19876 | [#2] jquery.html Cycles:180 Average:34036.08 Median:22838.66 stddev:76760.43 (336.1%) stddev-sans-first:76934.64 03:24:07 INFO - PID 19876 | Values: 571.1 571.6 575.6 581.7 594.2 21674.7 22459.3 22475.2 22585.1 22630.4 24661.3 25324.6 26455.5 26665.9 26688.7 6389.9 6503.9 6516.4 6545.3 6545.7 6387.3 6430.7 6441.6 6441.8 6452.2 6077.8 6620.7 6627.9 6642.9 6644.2 6497.0 6568.9 6572.7 6574.4 6586.0 6081.2 6115.5 6126.8 6145.9 6146.5 28553.4 29613.6 30031.0 30179.8 30193.0 1856.8 1914.2 1917.7 1926.0 1936.8 19157.6 19761.7 19816.3 19897.8 19904.5 2320.3 2348.4 2432.4 2438.2 2442.5 127463.0 127592.0 130132.0 130318.0 130348.0 14519.7 14597.9 14887.3 15195.4 15444.2 22676.4 23000.9 23137.7 23752.0 23843.6 10111.6 10142.7 10219.5 10319.6 10460.0 9342.0 9363.3 9382.4 9503.3 9524.5 9243.3 9275.4 9298.8 9323.2 9564.4 2572.1 2598.5 2627.7 2756.4 2790.1 934.7 945.3 968.8 988.3 995.7 19312.7 19369.8 19554.9 19688.8 20221.6 1015.0 1025.3 1031.8 1045.8 1048.2 10510.4 10689.3 10763.7 11215.2 11235.0 439717.0 447828.0 451725.0 458253.0 462376.0 119080.0 124636.4 124889.0 125187.0 126748.4 1142.0 1309.6 1313.3 1315.7 1321.3 20461.4 21247.9 21422.3 21513.1 21967.6 53696.7 54933.5 57036.7 58853.4 59591.7 76003.2 76230.6 76518.0 77447.6 79207.0 14606.3 14745.1 14867.4 14888.3 14996.8 23367.0 23657.6 23782.0 23936.9 24223.5 16220.9 18413.7 18725.3 18866.3 18997.3 15871.5 16265.3 16634.4 17238.3 17480.5 18351.2 19177.7 19410.1 19630.2 19645.7 14458.6 14475.7 14573.5 14584.2 14593.4 18875.2 18994.0 19006.3 19022.4 19210.0 03:24:07 INFO - PID 19876 | 03:24:07 INFO - PID 19876 | [#3] mootools.html Cycles:180 Average:8679.47 Median:2526.88 stddev:5906.44 (233.7%) stddev-sans-first:5897.24 03:24:07 INFO - PID 19876 | Values: 1336.9 1382.4 1383.2 1403.5 1406.2 13415.8 14537.6 14554.8 14565.5 14567.5 14219.7 14449.4 14501.1 14501.2 14539.8 2630.1 2693.2 2697.4 2703.9 2714.9 2519.3 2561.1 2629.8 2636.4 2643.3 2556.6 2573.9 2597.6 2597.9 2599.1 2497.7 2534.5 2545.3 2547.7 2549.9 2391.3 2448.8 2468.6 2479.6 2492.1 13445.6 13545.5 13564.6 13574.3 13575.3 7260.7 7342.1 7506.7 7516.0 7517.5 5051.2 5128.0 5241.7 5270.7 5296.2 1856.2 1858.2 1858.8 1882.7 1887.5 21554.4 22169.7 22184.7 22217.3 22280.7 6679.0 6819.3 6921.9 6980.6 7161.2 18332.0 18355.7 18359.1 18367.8 18413.4 7733.5 7740.7 7953.2 7954.8 7975.7 6816.3 7032.0 7090.5 7098.2 7143.3 4779.6 4912.9 4948.1 4972.5 4980.3 1357.5 1370.7 1377.5 1377.9 1382.6 851.8 861.6 877.1 895.4 906.8 8087.9 8137.6 8444.5 8463.7 8480.3 967.6 980.8 998.9 1007.1 1029.0 4856.2 5148.9 5196.5 5209.5 5363.8 17314.7 17868.4 17902.5 17913.6 17917.0 18314.4 19747.1 19762.2 19771.8 19815.7 9982.7 10165.5 10181.3 10186.9 10192.9 7498.3 8000.8 8368.9 8413.4 8532.2 16408.3 16551.3 17156.8 17208.2 17571.0 15847.8 16720.7 16758.9 17461.4 17836.7 11403.9 11633.5 11645.8 11645.9 11650.0 13140.6 13152.9 13231.9 13235.8 13271.2 10033.2 10141.6 10195.1 10708.1 10761.8 9227.5 9936.1 10001.5 10013.6 10048.7 8832.0 8902.5 9175.7 9467.3 9477.5 6763.3 6773.0 6880.2 7034.0 7040.3 6601.6 6965.5 6989.8 7052.4 7085.8 03:24:07 INFO - PID 19876 | 03:24:07 INFO - PID 19876 | [#4] prototype.html Cycles:180 Average:5436.31 Median:6047.15 stddev:1531.31 (25.3%) stddev-sans-first:1490.04 03:24:07 INFO - PID 19876 | Values: 496.2 500.3 513.4 524.5 531.8 6144.6 6195.1 6206.7 6208.2 6284.8 5890.3 6024.4 6059.8 6069.2 6070.6 4865.7 4870.5 4888.3 4907.7 4925.3 4869.9 4888.6 4910.3 4923.0 4966.6 4723.2 4874.8 4893.5 4897.8 4915.0 4926.7 4931.4 4932.2 4942.8 4960.2 4394.7 4406.0 4425.6 4441.4 4444.7 6016.7 6037.8 6037.8 6047.0 6047.3 5620.7 5638.6 5654.5 5660.6 5691.9 6415.8 6470.8 6471.5 6497.9 6519.3 4750.9 4760.0 4772.0 4799.4 4804.3 6758.0 6767.3 6814.6 6853.8 6878.3 4917.4 4934.0 4952.1 4956.5 4982.5 4975.9 4990.1 4998.9 4999.4 5009.7 5637.2 5650.6 5654.3 5670.8 5693.4 6448.4 6453.9 6494.9 6540.2 6598.5 1632.2 1659.2 1689.7 1734.9 1745.6 1166.8 1196.1 1210.8 1221.4 1225.4 5494.0 5503.5 5522.5 5547.6 5586.6 6440.9 6483.4 6515.6 6540.9 6543.5 5510.7 5585.9 5672.2 5678.5 5695.5 3496.3 3497.8 3501.8 3516.7 3562.6 6401.7 6413.7 6423.6 6477.4 6488.2 6783.0 6825.9 6838.7 6862.2 6926.7 6446.9 6448.8 6457.3 6459.2 6490.4 6668.0 6678.7 6686.5 6737.8 6747.3 6408.9 6442.2 6546.3 6613.2 6620.8 6341.2 6372.7 6399.7 6413.6 6477.6 4850.4 4930.5 4955.3 4956.6 4956.8 6695.6 6699.4 6723.7 6727.8 6750.3 6402.2 6474.1 6516.3 6522.5 6548.1 6352.5 6385.3 6401.4 6414.6 6415.5 6196.2 6311.9 6333.8 6387.7 6395.0 6261.8 6366.1 6426.5 6483.0 6491.5 6307.5 6331.7 6350.0 6352.7 6393.7 03:24:07 INFO - PID 19876 | 03:24:07 INFO - PID 19876 | [#5] yui.html Cycles:180 Average:23020.38 Median:19936.35 stddev:30227.07 (151.6%) stddev-sans-first:30265.90 03:24:07 INFO - PID 19876 | Values: 821.3 845.2 854.3 911.3 939.5 18413.4 18792.0 19148.3 19153.4 19172.7 20346.7 22066.7 22168.6 22173.8 22299.6 1034.8 1047.6 1048.3 1048.9 1049.0 963.7 1041.1 1045.7 1045.9 1054.1 1102.8 1112.6 1112.9 1113.6 1114.6 1090.9 1100.8 1103.4 1103.8 1104.8 805.1 811.8 813.8 814.9 814.9 18067.6 18584.4 19107.3 19792.7 19975.5 20528.0 20819.9 20988.8 21186.5 21330.8 15233.9 15652.3 15743.0 15762.0 16159.8 3466.9 3517.0 3523.3 3535.9 3538.7 116963.4 119220.4 121851.0 127887.0 128696.0 16690.5 17092.7 17138.4 17211.4 17226.4 29904.9 31036.5 31890.8 31922.5 32021.8 8878.1 8900.6 9057.8 9062.8 9083.6 4034.9 4193.9 4195.1 4196.1 4207.7 4883.4 5415.9 5839.6 5891.7 5907.4 1050.5 1079.9 1093.6 1100.9 1104.3 659.9 665.0 667.8 702.4 708.2 11418.8 12182.3 12191.6 12726.4 12844.6 650.6 688.2 693.5 699.1 705.4 5217.5 5469.4 5477.3 5605.9 5665.4 98342.5 99695.0 103277.0 103584.0 104835.5 87121.0 87525.0 89219.0 91901.5 94675.0 77203.0 78315.6 78355.2 78428.2 82503.6 12458.2 12512.8 12639.3 12871.6 13134.5 36074.3 36932.4 37421.3 37450.4 38790.2 62397.7 66494.0 69306.0 70457.0 70797.0 18599.4 18810.2 18967.6 19000.5 19059.6 20907.9 21124.8 21132.8 21192.5 21274.7 17601.8 17624.8 17651.1 19470.4 19519.1 18881.3 18903.0 19594.4 19597.2 19897.2 17040.1 17353.6 17970.9 18039.2 18431.4 12639.3 12658.8 12698.0 13310.5 14096.4 14292.3 14638.1 14951.1 15132.8 15195.9 03:24:07 INFO - PID 19876 | -------- Summary: end -------- 03:24:07 INFO - PID 19876 | 03:24:08 INFO - TEST-INFO | 19876: exit 0 03:24:08 INFO - TEST-OK | dromaeo_css | took 1112862ms 03:24:08 INFO - TEST-START | kraken 03:24:08 INFO - Initialising browser for kraken test... 03:24:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42733/getInfo.html -profile /tmp/tmpN0B51J/profile 03:24:08 INFO - TEST-INFO | started process 20239 (/builds/slave/test/build/application/firefox/firefox http://localhost:42733/getInfo.html) 03:24:14 INFO - TEST-INFO | 20239: exit 0 03:24:14 INFO - Browser initialized. 03:24:14 INFO - Running cycle 1/1 for kraken test... 03:24:14 INFO - TEST-INFO | started process 20449 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN0B51J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:24:15 INFO - PID 20449 | 03:24:15 INFO - PID 20449 | (/builds/slave/test/build/application/firefox/firefox:20497): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:24:15 INFO - PID 20449 | 03:24:25 INFO - PID 20449 | RSS: Main: 170545152 03:24:25 INFO - PID 20449 | 03:24:28 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42733/tests/kraken/driver.html?testName=audio-beat-detection) 03:24:29 INFO - PID 20449 | RSS: Main: 175357952 03:24:29 INFO - PID 20449 | 03:24:31 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42733/tests/kraken/driver.html?testName=audio-dft) 03:24:32 INFO - PID 20449 | RSS: Main: 176025600 03:24:32 INFO - PID 20449 | 03:24:35 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42733/tests/kraken/driver.html?testName=audio-fft) 03:24:35 INFO - PID 20449 | RSS: Main: 171053056 03:24:35 INFO - PID 20449 | 03:24:38 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42733/tests/kraken/driver.html?testName=audio-oscillator) 03:24:38 INFO - PID 20449 | RSS: Main: 171024384 03:24:38 INFO - PID 20449 | 03:24:40 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42733/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:24:41 INFO - PID 20449 | RSS: Main: 171659264 03:24:41 INFO - PID 20449 | 03:24:44 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42733/tests/kraken/driver.html?testName=imaging-darkroom) 03:24:45 INFO - PID 20449 | RSS: Main: 174133248 03:24:45 INFO - PID 20449 | 03:24:48 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42733/tests/kraken/driver.html?testName=imaging-desaturate) 03:24:49 INFO - PID 20449 | RSS: Main: 174362624 03:24:49 INFO - PID 20449 | 03:24:52 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42733/tests/kraken/driver.html?testName=json-parse-financial) 03:24:52 INFO - PID 20449 | RSS: Main: 174456832 03:24:52 INFO - PID 20449 | 03:24:54 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42733/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:24:54 INFO - PID 20449 | RSS: Main: 174366720 03:24:54 INFO - PID 20449 | 03:24:56 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42733/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:24:57 INFO - PID 20449 | RSS: Main: 174112768 03:24:57 INFO - PID 20449 | 03:24:59 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42733/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:25:00 INFO - PID 20449 | RSS: Main: 171536384 03:25:00 INFO - PID 20449 | 03:25:02 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42733/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:25:03 INFO - PID 20449 | RSS: Main: 172855296 03:25:03 INFO - PID 20449 | 03:25:06 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42733/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:25:07 INFO - PID 20449 | RSS: Main: 172310528 03:25:07 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | Cycle 1(1): loaded http://localhost:42733/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42733/tests/kraken/driver.html?testName=ai-astar) 03:25:09 INFO - PID 20449 | RSS: Main: 174030848 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | __start_tp_report 03:25:09 INFO - PID 20449 | _x_x_mozilla_page_load 03:25:09 INFO - PID 20449 | _x_x_mozilla_page_load_details 03:25:09 INFO - PID 20449 | |i|pagename|runs| 03:25:09 INFO - PID 20449 | |0;ai-astar;99;108;104;107;109;109;117;100;111;110 03:25:09 INFO - PID 20449 | |1;audio-beat-detection;144;117;141;179;118;156;142;123;175;128 03:25:09 INFO - PID 20449 | |2;audio-dft;164;163;164;163;162;162;166;163;171;167 03:25:09 INFO - PID 20449 | |3;audio-fft;76;74;75;181;76;74;77;74;157;77 03:25:09 INFO - PID 20449 | |4;audio-oscillator;79;78;134;79;78;79;121;78;79;129 03:25:09 INFO - PID 20449 | |5;imaging-gaussian-blur;113;114;114;114;114;115;114;114;113;114 03:25:09 INFO - PID 20449 | |6;imaging-darkroom;152;150;151;151;153;151;150;151;152;151 03:25:09 INFO - PID 20449 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;93;96 03:25:09 INFO - PID 20449 | |8;json-parse-financial;55;53;53;54;58;58;53;53;54;53 03:25:09 INFO - PID 20449 | |9;json-stringify-tinderbox;54;55;55;55;54;54;55;55;55;56 03:25:09 INFO - PID 20449 | |10;stanford-crypto-aes;79;88;87;78;81;78;77;94;78;86 03:25:09 INFO - PID 20449 | |11;stanford-crypto-ccm;123;134;124;139;128;123;135;123;139;167 03:25:09 INFO - PID 20449 | |12;stanford-crypto-pbkdf2;185;173;182;177;189;180;174;171;173;180 03:25:09 INFO - PID 20449 | |13;stanford-crypto-sha256-iterative;68;66;67;69;66;66;70;67;67;67 03:25:09 INFO - PID 20449 | __end_tp_report 03:25:09 INFO - PID 20449 | __start_cc_report 03:25:09 INFO - PID 20449 | _x_x_mozilla_cycle_collect,970 03:25:09 INFO - PID 20449 | __end_cc_report 03:25:09 INFO - PID 20449 | __startTimestamp1501151109290__endTimestamp 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | ------- Summary: start ------- 03:25:09 INFO - PID 20449 | Number of tests: 14 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#0] ai-astar Cycles:10 Average:107.40 Median:109.50 stddev:5.32 (4.9%) stddev-sans-first:4.69 03:25:09 INFO - PID 20449 | Values: 99.0 108.0 104.0 107.0 109.0 109.0 117.0 100.0 111.0 110.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:143.00 stddev:22.15 (15.5%) stddev-sans-first:23.49 03:25:09 INFO - PID 20449 | Values: 144.0 117.0 141.0 179.0 118.0 156.0 142.0 123.0 175.0 128.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#2] audio-dft Cycles:10 Average:164.50 Median:164.00 stddev:2.80 (1.7%) stddev-sans-first:2.96 03:25:09 INFO - PID 20449 | Values: 164.0 163.0 164.0 163.0 162.0 162.0 166.0 163.0 171.0 167.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#3] audio-fft Cycles:10 Average:94.10 Median:75.50 stddev:39.90 (52.8%) stddev-sans-first:41.77 03:25:09 INFO - PID 20449 | Values: 76.0 74.0 75.0 181.0 76.0 74.0 77.0 74.0 157.0 77.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:78.50 stddev:24.08 (30.7%) stddev-sans-first:24.97 03:25:09 INFO - PID 20449 | Values: 79.0 78.0 134.0 79.0 78.0 79.0 121.0 78.0 79.0 129.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 03:25:09 INFO - PID 20449 | Values: 113.0 114.0 114.0 114.0 114.0 115.0 114.0 114.0 113.0 114.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#6] imaging-darkroom Cycles:10 Average:151.20 Median:151.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 03:25:09 INFO - PID 20449 | Values: 152.0 150.0 151.0 151.0 153.0 151.0 150.0 151.0 152.0 151.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 03:25:09 INFO - PID 20449 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 96.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:2.01 (3.7%) stddev-sans-first:2.12 03:25:09 INFO - PID 20449 | Values: 55.0 53.0 53.0 54.0 58.0 58.0 53.0 53.0 54.0 53.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:55.00 stddev:0.63 (1.1%) stddev-sans-first:0.60 03:25:09 INFO - PID 20449 | Values: 54.0 55.0 55.0 55.0 54.0 54.0 55.0 55.0 55.0 56.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.50 stddev:5.78 (6.9%) stddev-sans-first:5.98 03:25:09 INFO - PID 20449 | Values: 79.0 88.0 87.0 78.0 81.0 78.0 77.0 94.0 78.0 86.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#11] stanford-crypto-ccm Cycles:10 Average:133.50 Median:134.50 stddev:13.48 (10.0%) stddev-sans-first:13.76 03:25:09 INFO - PID 20449 | Values: 123.0 134.0 124.0 139.0 128.0 123.0 135.0 123.0 139.0 167.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:180.00 stddev:5.85 (3.3%) stddev-sans-first:5.70 03:25:09 INFO - PID 20449 | Values: 185.0 173.0 182.0 177.0 189.0 180.0 174.0 171.0 173.0 180.0 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:1.34 (2.0%) stddev-sans-first:1.39 03:25:09 INFO - PID 20449 | Values: 68.0 66.0 67.0 69.0 66.0 66.0 70.0 67.0 67.0 67.0 03:25:09 INFO - PID 20449 | -------- Summary: end -------- 03:25:09 INFO - PID 20449 | 03:25:09 INFO - PID 20449 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:25:09 INFO - TEST-INFO | 20449: exit 0 03:25:09 INFO - TEST-OK | kraken | took 61794ms 03:25:09 INFO - SUITE-END | took 1174s 03:25:09 INFO - Completed test suite (00:19:34) 03:25:09 INFO - javascript benchmark 03:25:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8440.461773047035, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3071.1247294521067, 3095.5047192449215, 3105.2547388626735, 3115.7040081192285, 3122.8126312421255, 6180.814575562729, 6207.441330280274, 6270.65511396873, 6310.684465776716, 6319.462845658113, 6006.819795406134, 6115.052166914124, 6138.87722245556, 6153.138560601519, 6173.969130154354, 5607.299087614048, 5618.129189975561, 5642.266505354298, 5651.632643878155, 5654.067078931971, 5669.433056694339, 5718.17086522454, 5722.914156287638, 5756.769729210827, 5779.508741756935, 5632.5212356949705, 5689.943100569003, 5717.685527295997, 5746.856328591798, 5778.0466223073145, 5471.616986810925, 5597.132444471114, 5600.159976003632, 5618.943810561883, 5688.516476099537, 5571.804986825442, 5588.30146231721, 5657.20799088128, 5660.915086273709, 5682.289713785777, 5924.1113832924975, 5995.610285331483, 6036.275646922353, 6073.817785466443, 6122.0, 6449.032645103272, 6480.773172938972, 6521.315261897527, 6527.477801775846, 6528.673566321712, 6510.446612037935, 6563.3108523604815, 6572.277049524549, 6592.93407065928, 6640.701168447432, 6130.5402094842175, 6175.845603859894, 6214.0989556514505, 6215.564910456315, 6223.719932603044, 6724.596524208563, 6752.426043786235, 6752.763653272112, 6829.658517074126, 6834.829129271808, 6300.432961033529, 6430.935690643128, 6434.967825160844, 6447.645379504079, 6508.186476690414, 6536.934630653727, 6595.70319335631, 6614.966925165343, 6620.536562440583, 6641.037049627874, 6619.3380661933425, 6639.435647969976, 6685.765998190137, 6706.798796036127, 6711.362420570038, 6516.2506311773805, 6584.407403333723, 6617.106690596708, 6669.666516674244, 6676.833079172962, 5222.268882356473, 5253.133233016586, 5276.947230527646, 5279.709615971082, 5287.629865909426, 4546.931796023062, 4569.474510431276, 4610.031893302377, 4622.653301002439, 4646.4191976003, 5611.607187496836, 5612.438756124355, 5692.715364231772, 5697.0, 5705.372409035002, 6559.409653131145, 6561.606303621798, 6585.769498067544, 6660.167479065117, 6667.633280169637, 5499.120140777456, 5574.080276754371, 5585.3297604287745, 5678.716064196857, 5713.371529131792, 5763.337216220189, 5773.278340207474, 5779.739911703983, 5859.882802344015, 5864.091065884794, 6433.195850518685, 6486.0, 6513.73945042193, 6516.57642253252, 6536.313687062885, 6615.338466153346, 6636.037774522763, 6640.701168447335, 6734.966325168343, 6741.52809303344, 6335.809925702236, 6425.357464253537, 6427.0, 6427.967860160669, 6507.186601674791, 6499.707513161825, 6501.382368674968, 6504.447121994494, 6507.316731743099, 6516.446102081376, 6552.3775241352, 6645.933540664531, 6646.8005959821285, 6647.0, 6651.035599838093, 6415.967920160369, 6417.133686952388, 6421.293657697743, 6422.678866056772, 6438.291787903233, 6424.550281480439, 6425.357464253724, 6442.806715798533, 6459.806205813833, 6464.967675161594, 6234.3453937337745, 6340.397662222082, 6345.492360611048, 6411.166548348685, 6418.069379939976, 6412.1023056771155, 6433.678316084083, 6437.806865794034, 6476.255230648536, 6542.411182993363, 6144.631322120687, 6231.2834024086, 6236.251649801871, 6276.309605943251, 6431.967840160769], "value": 6018.036809763329, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.567894818376, 845.6194712379429, 854.2567965869695, 855.619249434002, 1157.739508610562, 29649.703502964963, 40652.39021414678, 41537.16925661493, 41637.375439368356, 41766.791166044124, 41789.37315940256, 41986.16027679444, 42088.78955605225, 42095.36856947148, 42116.57883421165, 7601.087869455673, 7638.579878106688, 7785.727499537517, 7794.337481314095, 7814.0, 7609.961950190242, 7687.231276872296, 7710.383169346439, 7717.266859648353, 7724.266194711493, 7676.769696909102, 7696.384289256874, 7718.845623087563, 7734.613269336511, 7738.922610773905, 7669.118051424074, 7695.461317707763, 7703.537787732754, 7704.46068775186, 7719.922800772005, 7634.351080158138, 7680.692772289101, 7691.077070751546, 7692.538447693157, 7703.3067023968115, 38402.615973840184, 38709.61290387088, 38743.22513549713, 38803.805980970195, 38869.61130388688, 3502.859885604573, 3541.043918142087, 3541.8583256669704, 3547.2018795771005, 3550.55618047744, 31506.842465787755, 31677.52483712746, 31833.204169895707, 31834.840825795724, 31839.363212735614, 4875.219964805614, 4904.068227036887, 4908.607311415079, 4913.0, 4957.206846904514, 188575.0, 188882.0, 189118.0, 190318.0, 190820.0, 49398.0, 49562.75218623956, 49651.0, 49704.0, 49814.750926245135, 51672.0, 52200.0, 52243.477565224624, 52298.215526767126, 52563.474365256625, 14182.716345673236, 16296.918515407348, 16515.6696866062, 16552.917235413744, 16562.50312490627, 11609.0, 13207.933960330329, 13213.0, 13227.0, 13334.53329133495, 14482.565523034325, 14518.782218266735, 14612.415503379958, 14618.122912625277, 14690.779638305434, 5815.5929084963645, 5883.6469811810575, 5912.940870591325, 5917.319508256605, 5960.791372301948, 988.5600907596195, 989.5942663507927, 997.7854761226371, 1018.7758693087509, 1019.658414431159, 21680.28715347618, 21914.89042554809, 22232.0, 22602.321930342117, 23066.077356905873, 1091.5142761471227, 1098.3958822647512, 1104.0, 1113.3486910157649, 1118.166965610609, 11759.588414405454, 11773.646790596296, 11781.941090294666, 11990.640280791591, 12121.939390302992, 228192.0, 229915.0, 230987.0, 231228.0, 231377.0, 53281.2007819883, 53340.46659533433, 53820.0, 53977.73011134919, 54101.72949135229, 1432.0548438031058, 1481.4370539195038, 1494.4321157960123, 1498.1086253679043, 1510.6298956755666, 21875.45311367165, 22176.667349990086, 22501.887490562443, 22683.546329072997, 22798.886005569864, 37075.44386834253, 37485.4377184337, 37505.0, 37539.24921501609, 37557.62442375541, 193091.0, 194005.0, 194248.0, 194327.0, 194918.0, 32608.34783304273, 33348.833255834536, 33556.49665255072, 33597.496037558965, 33602.0, 43875.78062109797, 43968.56031439773, 44406.0, 47167.76416118035, 48470.75764621295, 33400.32998350024, 34364.31271374608, 34676.65323346734, 34761.304773904885, 34984.47523287102, 27958.860205698842, 28922.855385722938, 29172.56241156342, 29173.124806255848, 29207.0, 36776.264474709824, 37807.43288850725, 37909.62090379061, 38015.80992095022, 38333.80833095816, 30488.0, 31096.0, 32583.511247331782, 33069.66930330762, 33095.50356744699, 23399.883000584887, 23450.530989379775, 24022.759772402052, 24178.274651760476, 24252.908619112535], "value": 17176.212167754835, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.1233256950582, 571.5627544928129, 575.6488541989386, 581.6742624130485, 594.2275042444822, 21674.674879876813, 22459.326220213377, 22475.201983841293, 22585.096596136136, 22630.43423914399, 24661.260162195118, 25324.620130698007, 26455.470890582226, 26665.86667066662, 26688.733112668866, 6389.936100638992, 6503.902441463382, 6516.413522782948, 6545.312742162052, 6545.672716364186, 6387.329330420309, 6430.7106180222, 6441.6135031898, 6441.774537891175, 6452.2257329120575, 6077.848053798646, 6620.702068406933, 6627.933720662804, 6642.933570664304, 6644.202695676526, 6496.967515162418, 6568.901466478007, 6572.7042283097135, 6574.408303252706, 6585.967070164666, 6081.17904082952, 6115.5107591393025, 6126.846828829271, 6145.907811382833, 6146.508279337642, 28553.428931421462, 29613.555796663066, 30031.0, 30179.849100754574, 30193.0, 1856.8421684156729, 1914.176903931309, 1917.712343148525, 1926.0369815092454, 1936.7579052618423, 19157.61684766297, 19761.703574446394, 19816.30642927505, 19897.801021989886, 19904.50238744043, 2320.3155069254612, 2348.448114693063, 2432.404061005065, 2438.207582535683, 2442.487077713682, 127463.0, 127592.0, 130132.0, 130318.0, 130348.0, 14519.709605808035, 14597.927010365092, 14887.330070146652, 15195.392184312504, 15444.227788610524, 22676.433089172904, 23000.884995574914, 23137.652935205984, 23752.0, 23843.642345364835, 10111.64609238673, 10142.746431339276, 10219.489025548693, 10319.638812641522, 10460.0, 9342.0, 9363.34456588046, 9382.437053776795, 9503.334766566273, 9524.476153811607, 9243.260539156985, 9275.350725449154, 9298.767530811649, 9323.207527360115, 9564.426134431957, 2572.0939278038527, 2598.4673142005845, 2627.7240889706686, 2756.415061339738, 2790.121111849761, 934.6915517879083, 945.3246026914525, 968.8352979993418, 988.3229987458614, 995.721198064543, 19312.71030934537, 19369.806301936802, 19554.902225489066, 19688.75677566014, 20221.595568088556, 1015.0, 1025.251566356549, 1031.83490641497, 1045.8274384726435, 1048.1981284317503, 10510.369377837354, 10689.30519516229, 10763.730906727238, 11215.158863085302, 11235.045021173128, 439717.0, 447828.0, 451725.0, 458253.0, 462376.0, 119080.0, 124636.37681811533, 124889.0, 125187.0, 126748.36625816811, 1142.0349804415255, 1309.5809341010786, 1313.3105119811985, 1315.7434300311347, 1321.2666969831835, 20461.386158414676, 21247.893760531715, 21422.250221242182, 21513.13947442085, 21967.560648786613, 53696.73151634373, 54933.450665494434, 57036.71481642565, 58853.41146588479, 59591.702041489516, 76003.23996760182, 76230.61884690763, 76518.0, 77447.61276193809, 79207.0, 14606.342714578082, 14745.11529308245, 14867.40530378816, 14888.330025148678, 14996.775048374502, 23367.0, 23657.64513532264, 23782.048718051083, 23936.88031559831, 24223.51552968966, 16220.91889540592, 18413.723794142832, 18725.25098996025, 18866.3396781112, 18997.335093271668, 15871.52385428439, 16265.349386024427, 16634.417795377103, 17238.310467581156, 17480.47558573245, 18351.174197160894, 19177.712334315274, 19410.126544305258, 19630.214791408183, 19645.70531442001, 14458.638534036732, 14475.710485790014, 14573.489927852474, 14584.249890013267, 14593.416263349347, 18875.244990200237, 18993.955332456848, 19006.334778282693, 19022.429327120208, 19210.0], "value": 12071.141011324213, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.9331533423328, 1382.364112508246, 1383.1770096792409, 1403.5298175111343, 1406.247657503236, 13415.798763018562, 14537.56387308381, 14554.781678274821, 14565.490207842728, 14567.490137845178, 14219.71560568788, 14449.422023119088, 14501.129932204049, 14501.202433866134, 14539.78190327146, 2630.053180854886, 2693.168645321181, 2697.3526353675074, 2703.91888243353, 2714.8506832124226, 2519.282004628681, 2561.1420174241653, 2629.8290611110215, 2636.393629465224, 2643.3259518822674, 2556.5909454487287, 2573.90991315304, 2597.610358446239, 2597.870106494677, 2599.1422830465995, 2497.7002759668776, 2534.480431511545, 2545.2618740565213, 2547.656066431045, 2549.9235022949247, 2391.330427480291, 2448.755124487555, 2468.5803413419762, 2479.6280557916275, 2492.0779311654805, 13445.596632100955, 13545.45818167282, 13564.593062208149, 13574.321283935764, 13575.321233938264, 7260.709571617129, 7342.118945726494, 7506.66220020095, 7516.022917020752, 7517.4737768356235, 5051.217061355459, 5128.0, 5241.68549887008, 5270.710110943896, 5296.179092240709, 1856.1925562380409, 1858.2381223698353, 1858.8048254933171, 1882.6517094337592, 1887.462073309114, 21554.353369398945, 22169.667454988186, 22184.667229991563, 22217.333479995625, 22280.665790013, 6678.962916310572, 6819.283975182633, 6921.927101299307, 6980.616066116311, 7161.212266650664, 18332.0, 18355.724664130048, 18359.082045897652, 18367.81632183688, 18413.355532556565, 7733.4973226740085, 7740.690372385154, 7953.164917683675, 7954.840903181904, 7975.680972761141, 6816.250212476623, 7032.0, 7090.539114957614, 7098.183708873443, 7143.285671432815, 4779.569838714532, 4912.852614421573, 4948.134076536592, 4972.5276098770555, 4980.327655766425, 1357.545222350504, 1370.6847425092285, 1377.5109836008273, 1377.9272222942893, 1382.640513466506, 851.7827953871723, 861.5649097205871, 877.0659247900965, 895.3866601378077, 906.8277027364779, 8087.919120808834, 8137.552434616035, 8444.535550544657, 8463.74608761738, 8480.279176270085, 967.5887747707337, 980.8185485685028, 998.9051040151174, 1007.1036777268237, 1028.953697083633, 4856.150173719585, 5148.871278218075, 5196.506331898464, 5209.505097015777, 5363.785448582091, 17314.65370692579, 17868.374606888956, 17902.46292611224, 17913.552161195814, 17917.0, 18314.358997435025, 19747.111379987648, 19762.20951161966, 19771.802281976998, 19815.702764458543, 9982.650607228858, 10165.542550585242, 10181.287309888237, 10186.898131018743, 10192.898071019492, 7498.32515073646, 8000.759977200693, 8368.916310837058, 8413.368997325102, 8532.23209911111, 16408.261628226996, 16551.337946482006, 17156.828431715523, 17208.225629846936, 17571.0, 15847.762283565986, 16720.665586688443, 16758.916205418896, 17461.38885139014, 17836.732449013016, 11403.885961140282, 11633.53465861356, 11645.825312620149, 11645.88354116448, 11650.0, 13140.60578182656, 13152.934235328763, 13231.867681323449, 13235.80146297787, 13271.203727776365, 10033.247506437192, 10141.645042423479, 10195.082442580204, 10708.089812366035, 10761.784764304513, 9227.492487913229, 9936.056074672895, 10001.499925003865, 10013.649522266685, 10048.69853904384, 8831.955840220759, 8902.510361930155, 9175.678851240174, 9467.337286389886, 9477.478738669439, 6763.2898545652, 6772.950192720136, 6880.243173251039, 7034.0, 7040.295970402919, 6601.603903765789, 6965.547239429421, 6989.790306290819, 7052.365287124184, 7085.751998680022], "value": 6328.343024111779, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.2085473669497, 500.2970825989486, 513.3839392728726, 524.4755244755245, 531.8058908498397, 6144.60060096094, 6195.132681424599, 6206.6586337751405, 6208.16189814375, 6284.805886881501, 5890.263717035371, 6024.367441418643, 6059.818205453832, 6069.241344831896, 6070.573415247424, 4865.68373055752, 4870.537298956611, 4888.266759986012, 4907.656464047518, 4925.261210818388, 4869.902601947977, 4888.560029597335, 4910.288008238803, 4923.0, 4966.602671786249, 4723.197056500403, 4874.756262186894, 4893.4861839506875, 4897.7795999180125, 4914.950850491503, 4926.704397736129, 4931.3589233399425, 4932.235503496964, 4942.849860310776, 4960.156773348539, 4394.714343567658, 4405.955940440586, 4425.601695847357, 4441.400410944513, 4444.73331600105, 6016.729247183887, 6037.788677396314, 6037.849053773647, 6046.969765151146, 6047.334793172748, 5620.662760234399, 5638.605297629168, 5654.491095801398, 5660.575456840754, 5691.886162276773, 6415.775447859349, 6470.832271189157, 6471.482281417475, 6497.902531462032, 6519.315471875432, 4750.881227969294, 4760.0, 4772.0217355442055, 4799.400074990626, 4804.25534042224, 6757.966210168966, 6767.289434609344, 6814.62519561424, 6853.828654283633, 6878.312168783182, 4917.385326834145, 4934.0, 4952.133376659071, 4956.4547899731, 4982.451930287665, 4975.875603109951, 4990.051890140934, 4998.925016124761, 4999.375078115236, 5009.749512524432, 5637.210790489335, 5650.604457687922, 5654.293213348332, 5670.7731690732735, 5693.402192769782, 6448.420631747339, 6453.935460645427, 6494.9350506495275, 6540.182477190351, 6598.505112116514, 1632.1675945267998, 1659.1621231278368, 1689.6620675864876, 1734.8785585008927, 1745.6159644878107, 1166.848309719731, 1196.1208511743862, 1210.7578484302999, 1221.4259298129864, 1225.3566877389442, 5493.983613031602, 5503.477169668875, 5522.530584900329, 5547.611667183259, 5586.581006424534, 6440.872847251712, 6483.384078512534, 6515.576487528296, 6540.934590654033, 6543.476521878239, 5510.724463776795, 5585.888282234332, 5672.205891175238, 5678.517326027334, 5695.544356451557, 3496.283261931349, 3497.793261324839, 3501.824908754552, 3516.701080408143, 3562.608113107556, 6401.679916004275, 6413.711382987777, 6423.646699431482, 6477.384648458389, 6488.221413430419, 6783.0164626128935, 6825.9317406826285, 6838.658067096725, 6862.176538815374, 6926.722931082801, 6446.935530644633, 6448.806535803933, 6457.257415397289, 6459.192600924885, 6490.383413575702, 6668.0, 6678.699458524281, 6686.465082793462, 6737.797866064026, 6747.291534388818, 6408.872901678732, 6442.162518872704, 6546.345365463606, 6613.2394774601535, 6620.8344791380605, 6341.239051313872, 6372.681365931592, 6399.6800159992745, 6413.621071469656, 6477.611343319416, 4850.442199147002, 4930.457649658463, 4955.331030310862, 4956.628252880976, 4956.77694503741, 6695.631740254333, 6699.43054840344, 6723.731050757915, 6727.798166055026, 6750.257471678014, 6402.167718196604, 6474.125992990886, 6516.315786842503, 6522.478201743944, 6548.1487406638735, 6352.460040896391, 6385.265694445198, 6401.423871851555, 6414.647194404352, 6415.454686351526, 6196.1635179250225, 6311.905321420274, 6333.809985700436, 6387.712552935036, 6394.968025159845, 6261.843453913689, 6366.076918846834, 6426.518011149276, 6482.967585162232, 6491.545591808528, 6307.526935479766, 6331.746730130743, 6350.0, 6352.745890164527, 6393.7122829471855], "value": 4985.9329539192795, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.2854816309812, 845.1759534453909, 854.3293514591047, 911.3028533172122, 939.5302348825585, 18413.355532556365, 18792.0, 19148.329808456707, 19153.425397238105, 19172.712409313837, 20346.694799578017, 22066.66899996501, 22168.556628867413, 22173.778262217373, 22299.55400891981, 1034.8137335279666, 1047.5757318286069, 1048.3343077145992, 1048.8584041154422, 1048.979020419591, 963.7108867339805, 1041.052642095693, 1045.7437927707722, 1045.9215558833118, 1054.140875186724, 1102.7518808268155, 1112.6439539347411, 1112.8664560252741, 1113.62136873463, 1114.554178328671, 1090.929089609173, 1100.8073587122221, 1103.3765922253901, 1103.823388257875, 1104.7679987202698, 805.0661232969785, 811.8011087283594, 813.8128230506958, 814.861473549498, 814.9185081491863, 18067.63864722698, 18584.44246672602, 19107.331243406552, 19792.70310945337, 19975.50061248466, 20528.0, 20819.89590052055, 20988.790112098988, 21186.470338241514, 21330.786692133035, 15233.923830380778, 15652.295646695811, 15743.0, 15762.0, 16159.83840161595, 3466.861325546975, 3517.032815975602, 3523.2777280657406, 3535.8939231823088, 3538.6815186633326, 116963.4151829244, 119220.40389797995, 121850.99999999911, 127887.0, 128696.0, 16690.49928502147, 17092.743608845878, 17138.40015599448, 17211.397601083903, 17226.397076102276, 29904.85047574748, 31036.53445198324, 31890.840545797124, 31922.521162182587, 32021.8398908004, 8878.067802880734, 8900.643974240958, 9057.77355566116, 9062.818743625223, 9083.636654533744, 4034.9394759078637, 4193.91612167761, 4195.098053918422, 4196.139791342767, 4207.747535147901, 4883.389576302962, 5415.918761218585, 5839.591228613956, 5891.675957822275, 5907.350191478934, 1050.5167622893555, 1079.940603266812, 1093.6390990973118, 1100.9284396514201, 1104.3429159649995, 659.8548319369731, 664.9832057812852, 667.7562689618352, 702.4450683959717, 708.1714394158846, 11418.828717569244, 12182.329971851634, 12191.573294934635, 12726.363681816058, 12844.614661560168, 650.648649729141, 688.1501345837886, 693.4729605499856, 699.1260923845193, 705.4497491956282, 5217.478252174752, 5469.425710300441, 5477.315335583052, 5605.915911261334, 5665.3768085510565, 98342.5082874581, 99695.0, 103277.0, 103584.0, 104835.4758226204, 87121.0, 87525.0, 89219.0, 91901.5404922971, 94675.0, 77203.0, 78315.60842195753, 78355.21644783593, 78428.21571784209, 82503.58748206221, 12458.190217635825, 12512.812307815571, 12639.304838233986, 12871.613851584467, 13134.540291089766, 36074.27851442904, 36932.44601331036, 37421.25157496889, 37450.43824342582, 38790.224195515366, 62397.68801156147, 66494.0, 69306.0, 70457.0, 70797.0, 18599.44201673952, 18810.24759009624, 18967.620647587246, 19000.52498687544, 19059.618807623494, 20907.8954605226, 21124.788752112283, 21132.78867211308, 21192.470188245417, 21274.680879786505, 17601.82398176002, 17624.82375176283, 17651.117444127485, 19470.415887523395, 19519.12163952654, 18881.339153129575, 18903.0, 19594.412167634993, 19597.216111355956, 19897.204111835363, 17040.06279654581, 17353.56616084608, 17970.91014544919, 18039.188236529124, 18431.44705658779, 12639.304838233986, 12658.810117848056, 12697.98416126726, 13310.534131305358, 14096.436142554183, 14292.28538573088, 14638.121712697272, 14951.102933824006, 15132.773008404663, 15195.92402038027], "value": 9183.379215207608, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1531.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 104.0, 107.0, 109.0, 109.0, 117.0, 100.0, 111.0, 110.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 141.0, 179.0, 118.0, 156.0, 142.0, 123.0, 175.0, 128.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 163.0, 162.0, 162.0, 166.0, 163.0, 171.0, 167.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 181.0, 76.0, 74.0, 77.0, 74.0, 157.0, 77.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 134.0, 79.0, 78.0, 79.0, 121.0, 78.0, 79.0, 129.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 150.0, 151.0, 151.0, 153.0, 151.0, 150.0, 151.0, 152.0, 151.0], "value": 151.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 96.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 54.0, 58.0, 58.0, 53.0, 53.0, 54.0, 53.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 55.0, 54.0, 54.0, 55.0, 55.0, 55.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 88.0, 87.0, 78.0, 81.0, 78.0, 77.0, 94.0, 78.0, 86.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 134.0, 124.0, 139.0, 128.0, 123.0, 135.0, 123.0, 139.0, 167.0], "value": 133.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 173.0, 182.0, 177.0, 189.0, 180.0, 174.0, 171.0, 173.0, 180.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 69.0, 66.0, 66.0, 70.0, 67.0, 67.0, 67.0], "value": 67.3, "unit": "score"}]}]} 03:25:10 INFO - Return code: 0 03:25:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:25:10 INFO - # TBPL SUCCESS # 03:25:10 INFO - Running post-action listener: _package_coverage_data 03:25:10 INFO - Running post-action listener: _resource_record_post_action 03:25:10 INFO - [mozharness: 2017-07-27 10:25:10.091678Z] Finished run-tests step (success) 03:25:10 INFO - Running post-run listener: _resource_record_post_run 03:25:10 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 20480; Write bytes: 433754112; Read time: 292; Write time: 260352 03:25:10 INFO - TinderboxPrint: CPU usage
13.1% 03:25:10 INFO - TinderboxPrint: I/O read bytes / time
20,480 / 292 03:25:10 INFO - TinderboxPrint: I/O write bytes / time
433,754,112 / 260,352 03:25:10 INFO - TinderboxPrint: CPU idle
8,264.3 (86.9%) 03:25:10 INFO - TinderboxPrint: CPU user
1,198.4 (12.6%) 03:25:10 INFO - TinderboxPrint: Swap in / out
0 / 0 03:25:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 106835968; Read time: 80; Write time: 60808 03:25:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:25:10 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 12288; Write bytes: 326918144; Read time: 196; Write time: 199544 03:25:10 INFO - Running post-run listener: _upload_blobber_files 03:25:10 INFO - Blob upload gear active. 03:25:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:25:10 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 03:25:10 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'] 03:25:10 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 03:25:11 INFO - (blobuploader) - INFO - Open directory for files ... 03:25:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:25:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:25:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:18 INFO - (blobuploader) - INFO - Done attempting. 03:25:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:25:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:25:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:19 INFO - (blobuploader) - INFO - Done attempting. 03:25:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:25:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:25:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:19 INFO - (blobuploader) - INFO - Done attempting. 03:25:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:25:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:25:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:20 INFO - (blobuploader) - INFO - Done attempting. 03:25:20 INFO - (blobuploader) - INFO - Iteration through files over. 03:25:20 INFO - Return code: 0 03:25:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:25:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:25:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1434344ad2f9a47d2176e6d71a4f09f26ce0de82325a7afa719f97e545184b2a8242c2ac3d1eccbfbdf72dc528ca033a191b46fffdab5f75233320adcefa75cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0267e6beb48281ad1ee97ac3d5765729fb31a8bb7f2f190d9977bb898c2145382dbfd16615d75e5932cf51f7eb53566578b8015f108419625f2c34908a3eebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2623cb726b0a28ae51e3f35d9a0a8c14c12240f6e7f324da961c22e5d2f0fe8cd3e8a2cc47f12445db6f8b9432649a9de752f7e99d3eca104364fb5c130a4a19", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/343d909e670610d0924af2786691a83f068e9fa5ccec3f934f45f64f2612bd7424c704416a9dd8a48be6d35bb3bf551158d50dd880a74e6b9e05f5ba9b75704b"} 03:25:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:25:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:25:20 INFO - Contents: 03:25:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1434344ad2f9a47d2176e6d71a4f09f26ce0de82325a7afa719f97e545184b2a8242c2ac3d1eccbfbdf72dc528ca033a191b46fffdab5f75233320adcefa75cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0267e6beb48281ad1ee97ac3d5765729fb31a8bb7f2f190d9977bb898c2145382dbfd16615d75e5932cf51f7eb53566578b8015f108419625f2c34908a3eebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2623cb726b0a28ae51e3f35d9a0a8c14c12240f6e7f324da961c22e5d2f0fe8cd3e8a2cc47f12445db6f8b9432649a9de752f7e99d3eca104364fb5c130a4a19", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/343d909e670610d0924af2786691a83f068e9fa5ccec3f934f45f64f2612bd7424c704416a9dd8a48be6d35bb3bf551158d50dd880a74e6b9e05f5ba9b75704b"} 03:25:20 INFO - Running post-run listener: copy_logs_to_upload_dir 03:25:20 INFO - Copying logs to upload dir... 03:25:20 INFO - mkdir: /builds/slave/test/build/upload/logs 03:25:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.782069 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-07-27 03:25:20.279195) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 03:25:20.283298) ========= 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=199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1434344ad2f9a47d2176e6d71a4f09f26ce0de82325a7afa719f97e545184b2a8242c2ac3d1eccbfbdf72dc528ca033a191b46fffdab5f75233320adcefa75cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0267e6beb48281ad1ee97ac3d5765729fb31a8bb7f2f190d9977bb898c2145382dbfd16615d75e5932cf51f7eb53566578b8015f108419625f2c34908a3eebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2623cb726b0a28ae51e3f35d9a0a8c14c12240f6e7f324da961c22e5d2f0fe8cd3e8a2cc47f12445db6f8b9432649a9de752f7e99d3eca104364fb5c130a4a19", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/343d909e670610d0924af2786691a83f068e9fa5ccec3f934f45f64f2612bd7424c704416a9dd8a48be6d35bb3bf551158d50dd880a74e6b9e05f5ba9b75704b"} build_url:https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011290 build_url: 'https://queue.taskcluster.net/v1/task/LE8WFHDDS_OwuiBTElAJQA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1434344ad2f9a47d2176e6d71a4f09f26ce0de82325a7afa719f97e545184b2a8242c2ac3d1eccbfbdf72dc528ca033a191b46fffdab5f75233320adcefa75cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f0267e6beb48281ad1ee97ac3d5765729fb31a8bb7f2f190d9977bb898c2145382dbfd16615d75e5932cf51f7eb53566578b8015f108419625f2c34908a3eebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2623cb726b0a28ae51e3f35d9a0a8c14c12240f6e7f324da961c22e5d2f0fe8cd3e8a2cc47f12445db6f8b9432649a9de752f7e99d3eca104364fb5c130a4a19", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/343d909e670610d0924af2786691a83f068e9fa5ccec3f934f45f64f2612bd7424c704416a9dd8a48be6d35bb3bf551158d50dd880a74e6b9e05f5ba9b75704b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 03:25:20.308540) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 03:25:20.309038) ========= 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=199fb4c72b90437b990b419b000001c5-1501148914.548752-1193412221 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004343 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 03:25:20.395320) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 03:25:20.396163) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 03:25:20.396855) ========= ========= Total master_lag: 0.16 =========