builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-082 starttime: 1501062763.28 results: success (0) revision: 4f821dab4306fd0aff947ce55a1304acd7e4cf95 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:43.285197) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:43.285593) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:43.285893) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:43.301398) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:43.301678) ========= 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=51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-26 02:52:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2017-07-26 02:52:44 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096289 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:43.411473) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:43.411853) ========= 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=51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020734 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:43.464367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:43.467734) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4f821dab4306fd0aff947ce55a1304acd7e4cf95 --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 4f821dab4306fd0aff947ce55a1304acd7e4cf95 --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=51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-26 02:52:44,657 truncating revision to first 12 chars 2017-07-26 02:52:44,657 Setting DEBUG logging. 2017-07-26 02:52:44,657 attempt 1/10 2017-07-26 02:52:44,657 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4f821dab4306?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-26 02:52:44,944 unpacking tar archive at: mozilla-central-4f821dab4306/testing/mozharness/ program finished with exit code 0 elapsedTime=0.571827 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:44.053218) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:44.053539) ========= script_repo_revision: 4f821dab4306fd0aff947ce55a1304acd7e4cf95 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:44.054009) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:44.054269) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-26 02:52:44.064105) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-07-26 02:52:44.064494) ========= /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=51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:52:45 INFO - MultiFileLogger online at 20170726 02:52:45 in /builds/slave/test 02:52:45 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 02:52:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:52:45 INFO - {'append_to_log': False, 02:52:45 INFO - 'base_work_dir': '/builds/slave/test', 02:52:45 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:52:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:52:45 INFO - 'branch': 'Firefox-Non-PGO', 02:52:45 INFO - 'buildbot_json_path': 'buildprops.json', 02:52:45 INFO - 'code_coverage': False, 02:52:45 INFO - 'config_files': ('talos/linux_config.py',), 02:52:45 INFO - 'default_actions': ('clobber', 02:52:45 INFO - 'read-buildbot-config', 02:52:45 INFO - 'download-and-extract', 02:52:45 INFO - 'populate-webroot', 02:52:45 INFO - 'create-virtualenv', 02:52:45 INFO - 'install', 02:52:45 INFO - 'setup-mitmproxy', 02:52:45 INFO - 'run-tests'), 02:52:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:52:45 INFO - 'disable_ccov_upload': False, 02:52:45 INFO - 'download_minidump_stackwalk': True, 02:52:45 INFO - 'download_symbols': 'ondemand', 02:52:45 INFO - 'enable_stylo': False, 02:52:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:52:45 INFO - 'tooltool.py': '/tools/tooltool.py', 02:52:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:52:45 INFO - '/tools/misc-python/virtualenv.py')}, 02:52:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:52:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:52:45 INFO - 'gecko_profile': False, 02:52:45 INFO - 'gecko_profile_interval': 0, 02:52:45 INFO - 'installer_path': 'installer.exe', 02:52:45 INFO - 'log_level': 'info', 02:52:45 INFO - 'log_name': 'talos', 02:52:45 INFO - 'log_to_console': True, 02:52:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:52:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:52:45 INFO - 'opt_config_files': (), 02:52:45 INFO - 'pip_index': False, 02:52:45 INFO - 'suite': 'dromaeojs-e10s', 02:52:45 INFO - 'system_bits': '32', 02:52:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:52:45 INFO - 'title': 'talos-linux64-ix-082', 02:52:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:52:45 INFO - 'use_talos_json': True, 02:52:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:52:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:52:45 INFO - 'work_dir': 'build'} 02:52:45 INFO - [mozharness: 2017-07-26 09:52:45.358310Z] Running clobber step. 02:52:45 INFO - Running pre-action listener: _resource_record_pre_action 02:52:45 INFO - Running main action method: clobber 02:52:45 INFO - rmtree: /builds/slave/test/build 02:52:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:52:46 INFO - Running post-action listener: _resource_record_post_action 02:52:46 INFO - [mozharness: 2017-07-26 09:52:46.908026Z] Finished clobber step (success) 02:52:46 INFO - [mozharness: 2017-07-26 09:52:46.908127Z] Running read-buildbot-config step. 02:52:46 INFO - Running pre-action listener: _resource_record_pre_action 02:52:46 INFO - Running main action method: read_buildbot_config 02:52:46 INFO - Using buildbot properties: 02:52:46 INFO - { 02:52:46 INFO - "project": "", 02:52:46 INFO - "product": "firefox", 02:52:46 INFO - "who": "cbook@mozilla.com", 02:52:46 INFO - "installer_path": "public/build/target.tar.bz2", 02:52:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:52:46 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:52:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 02:52:46 INFO - "stage_platform": "linux64", 02:52:46 INFO - "basedir": "/builds/slave/test", 02:52:46 INFO - "buildnumber": 743, 02:52:46 INFO - "platform": "ubuntu64_hw", 02:52:46 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:52:46 INFO - "slavebuilddir": "test", 02:52:46 INFO - "taskId": "IvsbxhoqQHmUOIyg5nMRQw", 02:52:46 INFO - "branch": "mozilla-central", 02:52:46 INFO - "script_repo_revision": "production", 02:52:46 INFO - "revision": "4f821dab4306fd0aff947ce55a1304acd7e4cf95", 02:52:46 INFO - "slavename": "talos-linux64-ix-082", 02:52:46 INFO - "repo_path": "mozilla-central" 02:52:46 INFO - } 02:52:46 INFO - Finding installer, test and symbols from parent task. 02:52:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IvsbxhoqQHmUOIyg5nMRQw'}, attempt #1 02:52:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IvsbxhoqQHmUOIyg5nMRQw'}, attempt #1 02:52:47 INFO - Task dependencies: J3w66787QiyxdpLlfODfTA 02:52:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA'}, attempt #1 02:52:48 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.tar.bz2 02:52:48 INFO - Set test_url: https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/test_packages.json 02:52:48 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.crashreporter-symbols.zip 02:52:48 INFO - Running post-action listener: _resource_record_post_action 02:52:48 INFO - [mozharness: 2017-07-26 09:52:48.355622Z] Finished read-buildbot-config step (success) 02:52:48 INFO - [mozharness: 2017-07-26 09:52:48.355843Z] Running download-and-extract step. 02:52:48 INFO - Running pre-action listener: _resource_record_pre_action 02:52:48 INFO - Running main action method: download_and_extract 02:52:48 INFO - mkdir: /builds/slave/test/build/tests 02:52:48 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')]} 02:52:48 INFO - trying https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.test_packages.json 02:52:48 INFO - Downloading https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:52:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:52:48 INFO - Downloaded 989 bytes. 02:52:48 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:52:48 INFO - Using the following test package requirements: 02:52:48 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:52:48 INFO - u'common': [u'target.common.tests.zip'], 02:52:48 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:52:48 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:52:48 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:52:48 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:52:48 INFO - u'mozbase': [u'target.common.tests.zip'], 02:52:48 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:52:48 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:52:48 INFO - u'web-platform': [u'target.common.tests.zip', 02:52:48 INFO - u'target.web-platform.tests.tar.gz'], 02:52:48 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:52:48 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:52:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.common.tests.zip 02:52:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:52:48 INFO - Fetch https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.common.tests.zip into memory 02:52:50 INFO - Content-Length response header: 72522256 02:52:50 INFO - Bytes received: 72522256 02:53:01 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:53:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.common.tests.zip 02:53:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:53:01 INFO - Fetch https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.common.tests.zip into memory 02:53:02 INFO - Content-Length response header: 72522256 02:53:02 INFO - Bytes received: 72522256 02:53:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.talos.tests.zip 02:53:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:53:14 INFO - Fetch https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.talos.tests.zip into memory 02:53:15 INFO - Content-Length response header: 14496448 02:53:15 INFO - Bytes received: 14496448 02:53:15 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')]} 02:53:15 INFO - trying https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.tar.bz2 02:53:15 INFO - Downloading https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:53:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:53:16 INFO - Downloaded 62161460 bytes. 02:53:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.tar.bz2 02:53:16 INFO - mkdir: /builds/slave/test/properties 02:53:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:53:16 INFO - Writing to file /builds/slave/test/properties/build_url 02:53:16 INFO - Contents: 02:53:16 INFO - build_url:https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.tar.bz2 02:53:16 INFO - Running post-action listener: _resource_record_post_action 02:53:16 INFO - Running post-action listener: set_extra_try_arguments 02:53:16 INFO - [mozharness: 2017-07-26 09:53:16.725645Z] Finished download-and-extract step (success) 02:53:16 INFO - [mozharness: 2017-07-26 09:53:16.725742Z] Running populate-webroot step. 02:53:16 INFO - Running pre-action listener: _resource_record_pre_action 02:53:16 INFO - Running main action method: populate_webroot 02:53:16 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:53:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:53:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:53:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:53:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:53:16 INFO - 'tests': ['damp', 'tps']}, 02:53:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:53:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:53:16 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:53:16 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:53:16 INFO - 'other-e10s': {'tests': ['a11yr', 02:53:16 INFO - 'ts_paint', 02:53:16 INFO - 'tpaint', 02:53:16 INFO - 'sessionrestore', 02:53:16 INFO - 'sessionrestore_many_windows', 02:53:16 INFO - 'sessionrestore_no_auto_restore', 02:53:16 INFO - 'tabpaint']}, 02:53:16 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:53:16 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:53:16 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:53:16 INFO - 'talos_options': ['--mitmproxy', 02:53:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:53:16 INFO - '--firstNonBlankPaint'], 02:53:16 INFO - 'tests': ['quantum_pageload_google', 02:53:16 INFO - 'quantum_pageload_youtube', 02:53:16 INFO - 'quantum_pageload_amazon', 02:53:16 INFO - 'quantum_pageload_facebook']}, 02:53:16 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:53:16 INFO - 'talos_options': ['--stylo', 02:53:16 INFO - '--mitmproxy', 02:53:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:53:16 INFO - '--firstNonBlankPaint'], 02:53:16 INFO - 'tests': ['quantum_pageload_google', 02:53:16 INFO - 'quantum_pageload_youtube', 02:53:16 INFO - 'quantum_pageload_amazon', 02:53:16 INFO - 'quantum_pageload_facebook']}, 02:53:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:53:16 INFO - 'tsvgr_opacity', 02:53:16 INFO - 'tart', 02:53:16 INFO - 'tscrollx', 02:53:16 INFO - 'cart', 02:53:16 INFO - 'tsvg_static']}, 02:53:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:53:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:53:16 INFO - 'talos_options': ['--xperf_path', 02:53:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:53:16 INFO - 'tests': ['tp5n']}}} 02:53:16 INFO - Running post-action listener: _resource_record_post_action 02:53:16 INFO - [mozharness: 2017-07-26 09:53:16.733042Z] Finished populate-webroot step (success) 02:53:16 INFO - [mozharness: 2017-07-26 09:53:16.733150Z] Running create-virtualenv step. 02:53:16 INFO - Running pre-action listener: _resource_record_pre_action 02:53:16 INFO - Running main action method: create_virtualenv 02:53:16 INFO - Creating virtualenv /builds/slave/test/build/venv 02:53:16 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 02:53:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:53:16 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:53:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:53:16 INFO - Using real prefix '/usr' 02:53:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:53:17 INFO - Installing distribute.............................................................................................................................................................................................done. 02:53:19 INFO - Installing pip.................done. 02:53:19 INFO - Return code: 0 02:53:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:53:19 INFO - Reading from file tmpfile_stdout 02:53:19 INFO - Output received: 02:53:19 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) 02:53:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:19 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 02:53:19 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 02:53:19 INFO - Using env: {'DISPLAY': ':0', 02:53:19 INFO - 'HOME': '/home/cltbld', 02:53:19 INFO - 'LANG': 'en_US.UTF-8', 02:53:19 INFO - 'LANGUAGE': 'en_US:en', 02:53:19 INFO - 'LOGNAME': 'cltbld', 02:53:19 INFO - 'MAIL': '/var/mail/cltbld', 02:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:19 INFO - 'MOZ_NO_REMOTE': '1', 02:53:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:19 INFO - 'NO_EM_RESTART': '1', 02:53:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:19 INFO - 'PWD': '/builds/slave/test', 02:53:19 INFO - 'SHELL': '/bin/bash', 02:53:19 INFO - 'SHLVL': '1', 02:53:19 INFO - 'TERM': 'linux', 02:53:19 INFO - 'TMOUT': '86400', 02:53:19 INFO - 'USER': 'cltbld', 02:53:19 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:19 INFO - '_': '/tools/buildbot/bin/python'} 02:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:19 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 02:53:19 INFO - Cleaning up... 02:53:19 INFO - Return code: 0 02:53:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:19 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 02:53:19 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 02:53:19 INFO - Using env: {'DISPLAY': ':0', 02:53:19 INFO - 'HOME': '/home/cltbld', 02:53:19 INFO - 'LANG': 'en_US.UTF-8', 02:53:19 INFO - 'LANGUAGE': 'en_US:en', 02:53:19 INFO - 'LOGNAME': 'cltbld', 02:53:19 INFO - 'MAIL': '/var/mail/cltbld', 02:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:19 INFO - 'MOZ_NO_REMOTE': '1', 02:53:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:19 INFO - 'NO_EM_RESTART': '1', 02:53:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:19 INFO - 'PWD': '/builds/slave/test', 02:53:19 INFO - 'SHELL': '/bin/bash', 02:53:19 INFO - 'SHLVL': '1', 02:53:19 INFO - 'TERM': 'linux', 02:53:19 INFO - 'TMOUT': '86400', 02:53:19 INFO - 'USER': 'cltbld', 02:53:19 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:19 INFO - '_': '/tools/buildbot/bin/python'} 02:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:19 INFO - Downloading/unpacking psutil>=3.1.1 02:53:19 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 02:53:19 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 02:53:19 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 02:53:19 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 02:53:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:53:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:53:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:53:21 INFO - Installing collected packages: psutil 02:53:21 INFO - Running setup.py install for psutil 02:53:21 INFO - building 'psutil._psutil_linux' extension 02:53:21 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 02:53:21 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 02:53:21 INFO - building 'psutil._psutil_posix' extension 02:53:21 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 02:53:21 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 02:53:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:53:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:53:21 INFO - Successfully installed psutil 02:53:21 INFO - Cleaning up... 02:53:21 INFO - Return code: 0 02:53:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:53:21 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')]} 02:53:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:21 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')]} 02:53:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:21 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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:21 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 02:53:21 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 02:53:21 INFO - Using env: {'DISPLAY': ':0', 02:53:21 INFO - 'HOME': '/home/cltbld', 02:53:21 INFO - 'LANG': 'en_US.UTF-8', 02:53:21 INFO - 'LANGUAGE': 'en_US:en', 02:53:21 INFO - 'LOGNAME': 'cltbld', 02:53:21 INFO - 'MAIL': '/var/mail/cltbld', 02:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:21 INFO - 'MOZ_NO_REMOTE': '1', 02:53:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:21 INFO - 'NO_EM_RESTART': '1', 02:53:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:21 INFO - 'PWD': '/builds/slave/test', 02:53:21 INFO - 'SHELL': '/bin/bash', 02:53:21 INFO - 'SHLVL': '1', 02:53:21 INFO - 'TERM': 'linux', 02:53:21 INFO - 'TMOUT': '86400', 02:53:21 INFO - 'USER': 'cltbld', 02:53:21 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:21 INFO - '_': '/tools/buildbot/bin/python'} 02:53:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:53:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:53:23 INFO - Installing collected packages: mozsystemmonitor 02:53:23 INFO - Running setup.py install for mozsystemmonitor 02:53:23 INFO - Successfully installed mozsystemmonitor 02:53:23 INFO - Cleaning up... 02:53:23 INFO - Return code: 0 02:53:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:53:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:23 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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:23 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 02:53:23 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 02:53:23 INFO - Using env: {'DISPLAY': ':0', 02:53:23 INFO - 'HOME': '/home/cltbld', 02:53:23 INFO - 'LANG': 'en_US.UTF-8', 02:53:23 INFO - 'LANGUAGE': 'en_US:en', 02:53:23 INFO - 'LOGNAME': 'cltbld', 02:53:23 INFO - 'MAIL': '/var/mail/cltbld', 02:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:23 INFO - 'MOZ_NO_REMOTE': '1', 02:53:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:23 INFO - 'NO_EM_RESTART': '1', 02:53:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:23 INFO - 'PWD': '/builds/slave/test', 02:53:23 INFO - 'SHELL': '/bin/bash', 02:53:23 INFO - 'SHLVL': '1', 02:53:23 INFO - 'TERM': 'linux', 02:53:23 INFO - 'TMOUT': '86400', 02:53:23 INFO - 'USER': 'cltbld', 02:53:23 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:23 INFO - '_': '/tools/buildbot/bin/python'} 02:53:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:23 INFO - Downloading/unpacking jsonschema==2.5.1 02:53:23 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 02:53:23 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 02:53:23 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 02:53:23 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 02:53:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:53:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:53:24 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 02:53:24 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 02:53:24 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 02:53:24 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 02:53:24 INFO - Downloading functools32-3.2.3-2.tar.gz 02:53:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:53:24 INFO - warning: no files found matching '*.txt' 02:53:24 INFO - no previously-included directories found matching 'build' 02:53:24 INFO - no previously-included directories found matching 'dist' 02:53:24 INFO - no previously-included directories found matching '.git*' 02:53:24 INFO - Installing collected packages: jsonschema, functools32 02:53:24 INFO - Running setup.py install for functools32 02:53:24 INFO - warning: no files found matching '*.txt' 02:53:24 INFO - no previously-included directories found matching 'build' 02:53:24 INFO - no previously-included directories found matching 'dist' 02:53:24 INFO - no previously-included directories found matching '.git*' 02:53:25 INFO - Successfully installed jsonschema functools32 02:53:25 INFO - Cleaning up... 02:53:25 INFO - Return code: 0 02:53:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:53:25 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')]} 02:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:25 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')]} 02:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:25 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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:25 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 02:53:25 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 02:53:25 INFO - Using env: {'DISPLAY': ':0', 02:53:25 INFO - 'HOME': '/home/cltbld', 02:53:25 INFO - 'LANG': 'en_US.UTF-8', 02:53:25 INFO - 'LANGUAGE': 'en_US:en', 02:53:25 INFO - 'LOGNAME': 'cltbld', 02:53:25 INFO - 'MAIL': '/var/mail/cltbld', 02:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:25 INFO - 'MOZ_NO_REMOTE': '1', 02:53:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:25 INFO - 'NO_EM_RESTART': '1', 02:53:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:25 INFO - 'PWD': '/builds/slave/test', 02:53:25 INFO - 'SHELL': '/bin/bash', 02:53:25 INFO - 'SHLVL': '1', 02:53:25 INFO - 'TERM': 'linux', 02:53:25 INFO - 'TMOUT': '86400', 02:53:25 INFO - 'USER': 'cltbld', 02:53:25 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:25 INFO - '_': '/tools/buildbot/bin/python'} 02:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:53:25 INFO - Cleaning up... 02:53:25 INFO - Return code: 0 02:53:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:53:25 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')]} 02:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:25 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')]} 02:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:25 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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:25 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 02:53:25 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 02:53:25 INFO - Using env: {'DISPLAY': ':0', 02:53:25 INFO - 'HOME': '/home/cltbld', 02:53:25 INFO - 'LANG': 'en_US.UTF-8', 02:53:25 INFO - 'LANGUAGE': 'en_US:en', 02:53:25 INFO - 'LOGNAME': 'cltbld', 02:53:25 INFO - 'MAIL': '/var/mail/cltbld', 02:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:25 INFO - 'MOZ_NO_REMOTE': '1', 02:53:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:25 INFO - 'NO_EM_RESTART': '1', 02:53:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:25 INFO - 'PWD': '/builds/slave/test', 02:53:25 INFO - 'SHELL': '/bin/bash', 02:53:25 INFO - 'SHLVL': '1', 02:53:25 INFO - 'TERM': 'linux', 02:53:25 INFO - 'TMOUT': '86400', 02:53:25 INFO - 'USER': 'cltbld', 02:53:25 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:25 INFO - '_': '/tools/buildbot/bin/python'} 02:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:25 INFO - Downloading/unpacking blobuploader==1.2.4 02:53:25 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 02:53:25 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 02:53:25 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 02:53:25 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 02:53:26 INFO - Downloading blobuploader-1.2.4.tar.gz 02:53:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:53:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:53:26 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 02:53:26 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 02:53:26 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 02:53:26 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 02:53:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:53:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:53:26 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 02:53:26 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 02:53:26 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 02:53:26 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 02:53:27 INFO - Downloading docopt-0.6.1.tar.gz 02:53:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:53:27 INFO - Installing collected packages: blobuploader, requests, docopt 02:53:27 INFO - Running setup.py install for blobuploader 02:53:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:53:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:53:27 INFO - Running setup.py install for requests 02:53:27 INFO - Running setup.py install for docopt 02:53:27 INFO - Successfully installed blobuploader requests docopt 02:53:27 INFO - Cleaning up... 02:53:27 INFO - Return code: 0 02:53:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:53:27 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')]} 02:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:27 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')]} 02:53:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:27 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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:27 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 02:53:27 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 02:53:27 INFO - Using env: {'DISPLAY': ':0', 02:53:27 INFO - 'HOME': '/home/cltbld', 02:53:27 INFO - 'LANG': 'en_US.UTF-8', 02:53:27 INFO - 'LANGUAGE': 'en_US:en', 02:53:27 INFO - 'LOGNAME': 'cltbld', 02:53:27 INFO - 'MAIL': '/var/mail/cltbld', 02:53:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:27 INFO - 'MOZ_NO_REMOTE': '1', 02:53:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:27 INFO - 'NO_EM_RESTART': '1', 02:53:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:27 INFO - 'PWD': '/builds/slave/test', 02:53:27 INFO - 'SHELL': '/bin/bash', 02:53:27 INFO - 'SHLVL': '1', 02:53:27 INFO - 'TERM': 'linux', 02:53:27 INFO - 'TMOUT': '86400', 02:53:27 INFO - 'USER': 'cltbld', 02:53:27 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:27 INFO - '_': '/tools/buildbot/bin/python'} 02:53:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:53:27 INFO - Running setup.py (path:/tmp/pip-3RZwWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:53:28 INFO - Running setup.py (path:/tmp/pip-awvjik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:53:28 INFO - Running setup.py (path:/tmp/pip-0615IY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:53:28 INFO - Running setup.py (path:/tmp/pip-s3fK5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:53:28 INFO - Running setup.py (path:/tmp/pip-9nRg0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:53:28 INFO - Running setup.py (path:/tmp/pip-ZtUsFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:53:28 INFO - Running setup.py (path:/tmp/pip-fkjdzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:53:28 INFO - Running setup.py (path:/tmp/pip-vUJ8pY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:53:28 INFO - Running setup.py (path:/tmp/pip-xeWpLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:53:28 INFO - Running setup.py (path:/tmp/pip-bWA6_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:53:28 INFO - Running setup.py (path:/tmp/pip-husoJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:53:28 INFO - Running setup.py (path:/tmp/pip-R6Wumc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:53:28 INFO - Running setup.py (path:/tmp/pip-MHNXND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:53:28 INFO - Running setup.py (path:/tmp/pip-ug8PuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:28 INFO - Running setup.py (path:/tmp/pip-O_DJpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:53:29 INFO - Running setup.py (path:/tmp/pip-DVdTCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:53:29 INFO - Running setup.py (path:/tmp/pip-2MzlAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:53:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:53:29 INFO - Running setup.py install for manifestparser 02:53:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:53:29 INFO - Running setup.py install for mozcrash 02:53:29 INFO - Running setup.py install for mozdebug 02:53:29 INFO - Running setup.py install for mozdevice 02:53:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:53:29 INFO - Running setup.py install for mozfile 02:53:29 INFO - Running setup.py install for mozhttpd 02:53:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:53:29 INFO - Running setup.py install for mozinfo 02:53:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:53:29 INFO - Running setup.py install for mozInstall 02:53:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:53:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:53:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:53:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:53:29 INFO - Running setup.py install for mozleak 02:53:30 INFO - Running setup.py install for mozlog 02:53:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:53:30 INFO - Running setup.py install for moznetwork 02:53:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:53:30 INFO - Running setup.py install for mozprocess 02:53:30 INFO - Running setup.py install for mozprofile 02:53:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:53:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:53:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:53:30 INFO - Running setup.py install for mozrunner 02:53:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:53:30 INFO - Running setup.py install for mozscreenshot 02:53:30 INFO - Running setup.py install for moztest 02:53:30 INFO - Running setup.py install for mozversion 02:53:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:53:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:53:30 INFO - Cleaning up... 02:53:30 INFO - Return code: 0 02:53:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:53:31 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')]} 02:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:31 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')]} 02:53:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:31 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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:31 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 02:53:31 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 02:53:31 INFO - Using env: {'DISPLAY': ':0', 02:53:31 INFO - 'HOME': '/home/cltbld', 02:53:31 INFO - 'LANG': 'en_US.UTF-8', 02:53:31 INFO - 'LANGUAGE': 'en_US:en', 02:53:31 INFO - 'LOGNAME': 'cltbld', 02:53:31 INFO - 'MAIL': '/var/mail/cltbld', 02:53:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:31 INFO - 'MOZ_NO_REMOTE': '1', 02:53:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:31 INFO - 'NO_EM_RESTART': '1', 02:53:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:31 INFO - 'PWD': '/builds/slave/test', 02:53:31 INFO - 'SHELL': '/bin/bash', 02:53:31 INFO - 'SHLVL': '1', 02:53:31 INFO - 'TERM': 'linux', 02:53:31 INFO - 'TMOUT': '86400', 02:53:31 INFO - 'USER': 'cltbld', 02:53:31 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:31 INFO - '_': '/tools/buildbot/bin/python'} 02:53:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:53:31 INFO - Running setup.py (path:/tmp/pip-IuGm9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:53:31 INFO - Running setup.py (path:/tmp/pip-l5BbT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:53:31 INFO - Running setup.py (path:/tmp/pip-eCsOmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:53:31 INFO - Running setup.py (path:/tmp/pip-vAnpAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:53:31 INFO - Running setup.py (path:/tmp/pip-Gv3gQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:53:31 INFO - Running setup.py (path:/tmp/pip-_DDJoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:53:31 INFO - Running setup.py (path:/tmp/pip-Gm0fzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:53:31 INFO - Running setup.py (path:/tmp/pip-kI7WQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:53:31 INFO - Running setup.py (path:/tmp/pip-j0dAf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:53:31 INFO - Running setup.py (path:/tmp/pip-fTy60r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:53:31 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)) 02:53:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:53:31 INFO - Running setup.py (path:/tmp/pip-QmGJch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:53:32 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)) 02:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:53:32 INFO - Running setup.py (path:/tmp/pip-pLdVnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:53:32 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)) 02:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:53:32 INFO - Running setup.py (path:/tmp/pip-gGlXdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:53:32 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)) 02:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:53:32 INFO - Running setup.py (path:/tmp/pip-bBuyu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:53:32 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)) 02:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:32 INFO - Running setup.py (path:/tmp/pip-XhC3RO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:32 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)) 02:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:53:32 INFO - Running setup.py (path:/tmp/pip-1h3cjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:53:32 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)) 02:53:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:53:32 INFO - Running setup.py (path:/tmp/pip-OJEIMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:53:32 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)) 02:53:32 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)) 02:53:32 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)) 02:53:32 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)) 02:53:32 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)) 02:53:32 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)) 02:53:32 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)) 02:53:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:53:32 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 02:53:32 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 02:53:32 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 02:53:32 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 02:53:33 INFO - Downloading blessings-1.6.tar.gz 02:53:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:53:33 INFO - Installing collected packages: blessings 02:53:33 INFO - Running setup.py install for blessings 02:53:33 INFO - Successfully installed blessings 02:53:33 INFO - Cleaning up... 02:53:33 INFO - Return code: 0 02:53:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:53:34 INFO - Reading from file tmpfile_stdout 02:53:34 INFO - Current package versions: 02:53:34 INFO - argparse == 1.2.1 02:53:34 INFO - blessings == 1.6 02:53:34 INFO - blobuploader == 1.2.4 02:53:34 INFO - docopt == 0.6.1 02:53:34 INFO - functools32 == 3.2.3-2 02:53:34 INFO - jsonschema == 2.5.1 02:53:34 INFO - manifestparser == 1.1 02:53:34 INFO - mozInstall == 1.13 02:53:34 INFO - mozcrash == 1.0 02:53:34 INFO - mozdebug == 0.1 02:53:34 INFO - mozdevice == 0.51 02:53:34 INFO - mozfile == 1.2 02:53:34 INFO - mozhttpd == 0.7 02:53:34 INFO - mozinfo == 0.10 02:53:34 INFO - mozleak == 0.1 02:53:34 INFO - mozlog == 3.5 02:53:34 INFO - moznetwork == 0.27 02:53:34 INFO - mozprocess == 0.25 02:53:34 INFO - mozprofile == 0.28 02:53:34 INFO - mozrunner == 6.13 02:53:34 INFO - mozscreenshot == 0.1 02:53:34 INFO - mozsystemmonitor == 0.3 02:53:34 INFO - moztest == 0.8 02:53:34 INFO - mozversion == 1.4 02:53:34 INFO - psutil == 3.1.1 02:53:34 INFO - requests == 1.2.3 02:53:34 INFO - wsgiref == 0.1.2 02:53:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:53: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')]} 02:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53: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')]} 02:53:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:34 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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:34 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 02:53:34 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 02:53:34 INFO - Using env: {'DISPLAY': ':0', 02:53:34 INFO - 'HOME': '/home/cltbld', 02:53:34 INFO - 'LANG': 'en_US.UTF-8', 02:53:34 INFO - 'LANGUAGE': 'en_US:en', 02:53:34 INFO - 'LOGNAME': 'cltbld', 02:53:34 INFO - 'MAIL': '/var/mail/cltbld', 02:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:34 INFO - 'MOZ_NO_REMOTE': '1', 02:53:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:34 INFO - 'NO_EM_RESTART': '1', 02:53:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:34 INFO - 'PWD': '/builds/slave/test', 02:53:34 INFO - 'SHELL': '/bin/bash', 02:53:34 INFO - 'SHLVL': '1', 02:53:34 INFO - 'TERM': 'linux', 02:53:34 INFO - 'TMOUT': '86400', 02:53:34 INFO - 'USER': 'cltbld', 02:53:34 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:34 INFO - '_': '/tools/buildbot/bin/python'} 02:53:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:34 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)) 02:53:34 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)) 02:53:34 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)) 02:53:34 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)) 02:53:34 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)) 02:53:34 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)) 02:53:34 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)) 02:53:34 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)) 02:53:34 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)) 02:53:34 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:53:34 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 02:53:34 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 02:53:34 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 02:53:34 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 02:53:35 INFO - Downloading marionette_driver-1.4.0.tar.gz 02:53:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 02:53:35 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 02:53:35 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 02:53:35 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 02:53:35 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 02:53:35 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 02:53:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:53:35 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)) 02:53:35 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)) 02:53:35 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)) 02:53:35 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)) 02:53:35 INFO - Installing collected packages: marionette-driver, simplejson 02:53:35 INFO - Running setup.py install for marionette-driver 02:53:35 INFO - Running setup.py install for simplejson 02:53:35 INFO - building 'simplejson._speedups' extension 02:53:35 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 02:53:36 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 02:53:36 INFO - Successfully installed marionette-driver simplejson 02:53:36 INFO - Cleaning up... 02:53:36 INFO - Return code: 0 02:53:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:53:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:36 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 0xaeb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe5bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, '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': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', '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 02:53:36 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 02:53:36 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 02:53:36 INFO - Using env: {'DISPLAY': ':0', 02:53:36 INFO - 'HOME': '/home/cltbld', 02:53:36 INFO - 'LANG': 'en_US.UTF-8', 02:53:36 INFO - 'LANGUAGE': 'en_US:en', 02:53:36 INFO - 'LOGNAME': 'cltbld', 02:53:36 INFO - 'MAIL': '/var/mail/cltbld', 02:53:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:36 INFO - 'MOZ_NO_REMOTE': '1', 02:53:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:36 INFO - 'NO_EM_RESTART': '1', 02:53:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:36 INFO - 'PWD': '/builds/slave/test', 02:53:36 INFO - 'SHELL': '/bin/bash', 02:53:36 INFO - 'SHLVL': '1', 02:53:36 INFO - 'TERM': 'linux', 02:53:36 INFO - 'TMOUT': '86400', 02:53:36 INFO - 'USER': 'cltbld', 02:53:36 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:36 INFO - '_': '/tools/buildbot/bin/python'} 02:53:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:53:36 INFO - Cleaning up... 02:53:37 INFO - Return code: 0 02:53:37 INFO - Running post-action listener: _resource_record_post_action 02:53:37 INFO - Running post-action listener: _start_resource_monitoring 02:53:37 INFO - Starting resource monitoring. 02:53:37 INFO - [mozharness: 2017-07-26 09:53:37.041500Z] Finished create-virtualenv step (success) 02:53:37 INFO - [mozharness: 2017-07-26 09:53:37.041970Z] Running install step. 02:53:37 INFO - Running pre-action listener: _resource_record_pre_action 02:53:37 INFO - Running main action method: install 02:53:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:53:37 INFO - Reading from file tmpfile_stdout 02:53:37 INFO - Detecting whether we're running mozinstall >=1.0... 02:53:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:53:37 INFO - Reading from file tmpfile_stdout 02:53:37 INFO - Output received: 02:53:37 INFO - Usage: mozinstall [options] installer 02:53:37 INFO - Options: 02:53:37 INFO - -h, --help show this help message and exit 02:53:37 INFO - -d DEST, --destination=DEST 02:53:37 INFO - Directory to install application into. [default: 02:53:37 INFO - "/builds/slave/test"] 02:53:37 INFO - --app=APP Application being installed. [default: firefox] 02:53:37 INFO - mkdir: /builds/slave/test/build/application 02:53:37 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'] 02:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:53:55 INFO - Reading from file tmpfile_stdout 02:53:55 INFO - Output received: 02:53:55 INFO - /builds/slave/test/build/application/firefox/firefox 02:53:55 INFO - Running post-action listener: _resource_record_post_action 02:53:55 INFO - [mozharness: 2017-07-26 09:53:55.405493Z] Finished install step (success) 02:53:55 INFO - [mozharness: 2017-07-26 09:53:55.405748Z] Running setup-mitmproxy step. 02:53:55 INFO - Running pre-action listener: _resource_record_pre_action 02:53:55 INFO - Running main action method: setup_mitmproxy 02:53:55 INFO - Skipping: mitmproxy is not required 02:53:55 INFO - Running post-action listener: _resource_record_post_action 02:53:55 INFO - [mozharness: 2017-07-26 09:53:55.406884Z] Finished setup-mitmproxy step (success) 02:53:55 INFO - [mozharness: 2017-07-26 09:53:55.407105Z] Running run-tests step. 02:53:55 INFO - Running pre-action listener: _resource_record_pre_action 02:53:55 INFO - Running pre-action listener: _set_gcov_prefix 02:53:55 INFO - Running main action method: run_tests 02:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:53:55 INFO - Python 2.7.3 02:53:55 INFO - Return code: 0 02:53:55 INFO - grabbing minidump binary from tooltool 02:53:55 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')]} 02:53:55 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 0xe58eb0>, '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 0xe59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5a240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:53:55 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 02:53:55 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 02:53:55 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 02:53:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:53:55 INFO - Return code: 0 02:53:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:53:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:53:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:53:55 INFO - ENV: RUST_BACKTRACE is now 1 02:53:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:53:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:53:55 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/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 02:53:55 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/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-082 --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 02:53:55 INFO - Using env: {'DISPLAY': ':0', 02:53:55 INFO - 'HOME': '/home/cltbld', 02:53:55 INFO - 'LANG': 'en_US.UTF-8', 02:53:55 INFO - 'LANGUAGE': 'en_US:en', 02:53:55 INFO - 'LOGNAME': 'cltbld', 02:53:55 INFO - 'MAIL': '/var/mail/cltbld', 02:53:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:53:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:55 INFO - 'MOZ_NO_REMOTE': '1', 02:53:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:53:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:55 INFO - 'NO_EM_RESTART': '1', 02:53:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:55 INFO - 'PWD': '/builds/slave/test', 02:53:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:53:55 INFO - 'RUST_BACKTRACE': '1', 02:53:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:53:55 INFO - 'SHELL': '/bin/bash', 02:53:55 INFO - 'SHLVL': '1', 02:53:55 INFO - 'TERM': 'linux', 02:53:55 INFO - 'TMOUT': '86400', 02:53:55 INFO - 'USER': 'cltbld', 02:53:55 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431', 02:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:55 INFO - '_': '/tools/buildbot/bin/python'} 02:53:55 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/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 02:53:55 INFO - mozversion application_buildid: 20170726090220 02:53:55 INFO - mozversion application_changeset: 4f821dab4306fd0aff947ce55a1304acd7e4cf95 02:53:55 INFO - mozversion application_display_name: Nightly 02:53:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:53:55 INFO - mozversion application_name: Firefox 02:53:55 INFO - mozversion application_remotingname: firefox 02:53:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:53:55 INFO - mozversion application_vendor: Mozilla 02:53:55 INFO - mozversion application_version: 56.0a1 02:53:55 INFO - mozversion platform_buildid: 20170726090220 02:53:55 INFO - mozversion platform_changeset: 4f821dab4306fd0aff947ce55a1304acd7e4cf95 02:53:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:53:55 INFO - mozversion platform_version: 56.0a1 02:53:55 INFO - using testdate: 1501062835 02:53:55 INFO - actual date: 1501062835 02:53:55 INFO - starting webserver on 'localhost:49441' 02:53:55 INFO - SUITE-START | Running 2 tests 02:53:55 INFO - TEST-START | dromaeo_css 02:53:55 INFO - Initialising browser for dromaeo_css test... 02:53:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49441/getInfo.html -profile /tmp/tmpvfnHcS/profile 02:53:55 INFO - TEST-INFO | started process 19089 (/builds/slave/test/build/application/firefox/firefox http://localhost:49441/getInfo.html) 02:54:01 INFO - TEST-INFO | 19089: exit 0 02:54:01 INFO - Browser initialized. 02:54:01 INFO - Running cycle 1/1 for dromaeo_css test... 02:54:01 INFO - TEST-INFO | started process 19298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvfnHcS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:54:02 INFO - PID 19298 | 02:54:02 INFO - PID 19298 | (/builds/slave/test/build/application/firefox/firefox:19346): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:54:02 INFO - PID 19298 | 02:54:13 INFO - PID 19298 | RSS: Main: 169656320 02:54:13 INFO - PID 19298 | 02:57:12 INFO - PID 19298 | Cycle 1(1): loaded http://localhost:49441/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49441/tests/dromaeo/cssquery-ext.html) 02:57:13 INFO - PID 19298 | RSS: Main: 173277184 02:57:13 INFO - PID 19298 | 03:00:15 INFO - PID 19298 | Cycle 1(1): loaded http://localhost:49441/tests/dromaeo/cssquery-ext.html (next: http://localhost:49441/tests/dromaeo/cssquery-jquery.html) 03:00:16 INFO - PID 19298 | RSS: Main: 171446272 03:00:16 INFO - PID 19298 | 03:03:17 INFO - PID 19298 | Cycle 1(1): loaded http://localhost:49441/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49441/tests/dromaeo/cssquery-mootools.html) 03:03:18 INFO - PID 19298 | RSS: Main: 172412928 03:03:18 INFO - PID 19298 | 03:06:20 INFO - PID 19298 | Cycle 1(1): loaded http://localhost:49441/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49441/tests/dromaeo/cssquery-prototype.html) 03:06:21 INFO - PID 19298 | RSS: Main: 171884544 03:06:21 INFO - PID 19298 | 03:09:24 INFO - PID 19298 | Cycle 1(1): loaded http://localhost:49441/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49441/tests/dromaeo/cssquery-yui.html) 03:09:25 INFO - PID 19298 | RSS: Main: 173010944 03:09:25 INFO - PID 19298 | 03:12:27 INFO - PID 19298 | Cycle 1(1): loaded http://localhost:49441/tests/dromaeo/cssquery-yui.html (next: http://localhost:49441/tests/dromaeo/cssquery-dojo.html) 03:12:27 INFO - PID 19298 | RSS: Main: 175656960 03:12:27 INFO - PID 19298 | 03:12:27 INFO - PID 19298 | __start_tp_report 03:12:27 INFO - PID 19298 | _x_x_mozilla_page_load 03:12:27 INFO - PID 19298 | _x_x_mozilla_page_load_details 03:12:27 INFO - PID 19298 | |i|pagename|runs| 03:12:27 INFO - PID 19298 | |0;dojo.html;3075.7078077582623;3079.8152110873343;3106.9378612427754;3122.2818751687096;3124.312651216733;6150.138980542728;6202.193714817079;6248.968920128179;6263.968680156599;6264.906026409608;6068.6662233577135;6140.662263575501;6141.447269745722;6150.723217455214;6158.384161583839;5583.3300003999575;5599.020171469987;5619.241402410663;5632.746526406321;5692.658440493584;5686.829395118153;5689.260396148516;5733.226014488033;5766.9711651441685;5775.364709881909;5520.972395138038;5593.664380137164;5610.635308704921;5688.516476099537;5701.458361455655;5620.5503559715125;5680.147977803363;5708.286464191976;5716.857078573027;5719.485246327808;5568.86077848053;5602.187682785973;5665.263515742969;5671.914921276184;5678.346990096151;5986.73059712314;6041.72812223451;6110.052941794029;6166.8766624667705;6194.876102477971;6310.716017779211;6409.198850143732;6414.935850641527;6496.93503064968;6619.304972977816;6676.966615166942;6704.296048914891;6712.261651218362;6713.865722685568;6716.3283671632935;6146.8770624587705;6153.938460615382;6196.721147548371;6240;6245.344238854855;6662.7334906604165;6733.562318449285;6749.291324410964;6781.084553585301;6810.727570897207;6481.384268494486;6498.5126115541525;6557.770478033246;6575.375339342755;6580.045893345438;6606.075149479077;6610.96694516534;6677.9666101670155;6698.4641228701585;6698.8995165072565;6430.099786029959;6529.2817790043055;6582.17722784652;6591;6610.239822420387;6508.576942498722;6522;6549.083128362033;6586.769463068769;6595.70319335631;5271.288376069258;5306.28365170697;5323.920141197885;5365.731713414314;5366.946330536644;4496.685232033792;4521.344405061313;4542.318652202196;4556.97721511397;4619.561141691601;5720;5748.252727145445;5801.3038435388025;5802.65184088956;5812.709364531841;6589.406953374123;6611.933880661228;6632.933670663231;6640.667966601651;6724.957631567115;5648.943510564924;5720.971395142998;5743.052396354555;5805.274340707411;5819.447152520588;5796.275465566804;5865.237519122486;5883.911741323884;5887.4406931341455;5891.941080589225;6397.904031439532;6414.422701956846;6479.676016199265;6497.610143391412;6503.674816259168;6506.154199953976;6544.8363790905605;6578.76974305897;6680.2651708313015;6685.164354455693;6336.556441049082;6383.234011918414;6442.967785161044;6461.741530338922;6464.09502669636;6511.837204069936;6576.0464732612545;6595;6597.1423714916755;6603.933960660332;6655.700493477708;6657.73369065232;6661.267260601427;6744.393004629411;6747.865042699217;6387.457066149429;6466.385693359123;6468.288488266381;6489.935100648933;6492.253390860112;6413.8717225656155;6418.743250269937;6437.452816510462;6517;6523.478121750343;6251.531135164972;6332.271788744353;6347.492200623847;6405.743770249138;6445.967770161119;6386.425221729881;6457.483401327789;6549.901751473827;6557.180352455943;6578.473722102125;6251.6561589113035;6277.874442511215;6283.622982621057;6302.495800336053;6386.010168423902 03:12:27 INFO - PID 19298 | |1;ext.html;803.8030682482793;855.3542075732822;868.9565521723912;876.031984656954;1160.6808127764868;29872.402551948977;30786.07641770745;40397;40877.59122408775;40991.38512922308;40851.18297634045;41366.7931660342;41395;41441.585584144224;41553.16893662125;7429.851402971937;7515.699372025112;7624.123225829046;7685.308322250996;7688.115866675348;7494.175640679521;7547.811304717371;7555.320021198091;7556.886646700304;7566.962165189167;7539.660715267826;7582.317591416771;7608.429367797409;7609.80975475612;7615.5430674159725;7583.165851756303;7598.27816357448;7613.847723045508;7627.733029343946;7630.847383052335;7494.475386722932;7546.584937828417;7556.47104702671;7561.735339263154;7563.584002879839;37967.4304885427;38036.23927521434;38084;38242.80878595617;38260.42609360862;3475.6176820549717;3511.3679537683206;3513.2622149348667;3524.400851855165;3525.8765943191866;30359.544606830914;30544.930927417656;30590.235244119074;30647;30762.53856192159;4739.928901066487;4787.569118779326;4792.640551958617;4843.321934929113;4930.087933732271;190062.04968975065;190684;191943;191982;192325;48558.51441485611;48644;48655.75672121617;48664;48725.512744872096;54909.450905490434;55820.16269755957;56135.43864561384;56259;56271;16383.017018978899;16547;16562.58593535171;16653.666926661397;16717.49847504577;13478.39347229377;13508.797368039488;13509.66225844342;13511.391987360592;13559.322033898266;14629.85370146285;14710.926445367919;14833.851661483248;14961;15050.247487625575;5926.7925622603;5938.317093534212;5983.6708981005595;5990.700464976734;6034.366391528826;941.87755591774;962;967.8257913575624;980.3431700760508;981.1513041219293;22325.43257525373;22340.553188936454;22752.317430477113;22863.428414289774;22973.770262297163;1056.2711728907184;1070.4808168038496;1101.884302148279;1107.2304748199924;1108.0969010256615;11838.467268972918;11905.345206013579;12054.337011464453;12061.095417843697;12169.20900141488;229421;229724;231501;232018;233403;51920.22119668208;51954.74022629863;52411.47588524142;52465.47534524606;52507.47492525026;1411.1744629391715;1474.620377478886;1484.5323723027211;1486;1499.2503748125937;21710.34868953934;21734.130634774432;22028.33914982553;22056.669149962443;27065.86467067652;51591;51827.74086129545;51895.74052129715;52121.7393913028;52175.73912130415;211193;212187;218261;218409;219318;37055.62944370522;37066.81466592757;37291.62708372882;37529.62470375261;37546.436803447425;42976.57023429726;44584.55415445804;44705.77647111744;44845.55154448414;45065.54934450614;30960;32771;33866.830665846515;34513;35253.647463525034;32470.837645811618;33184.17438305863;34077.318453630294;34230.65769342275;34796.65203347934;37775.24449511049;38113;38120.42819357656;38427;38504.614953850105;32561.837190813894;32655.18362040968;32803;33027.33945321128;33132.503012455316;23334.76665233326;24247.393815154057;24486.75513244916;24567;25036.62445063289 03:12:27 INFO - PID 19298 | |2;jquery.html;582.1529674323859;586.1442294250394;588.05910543131;588.2499812738757;601.7322291580246;21455.463613409654;22053.22813701521;22305.219317323896;22334;22350.10599576019;25400.221984461095;26038.21885343438;26066.478670426626;26117.21648350548;26150.084747033856;6446.355364463563;6603.504737144734;6608.471322294205;6608.735650573972;6617.966910165443;6121.632702037892;6441.162648855642;6480.708368123445;6491.415772580467;6500.93499065008;6466.288708242089;6478.157839480885;6484.221893372802;6497.415232629062;6497.642629655367;6435.292117867031;6474.5791523550815;6481.578697384655;6487.870242595145;6503.317151699074;5996.730147143346;5999.580029397944;6001.639901605918;6003.699815009232;6006.849828754316;28094;29063;29544.704552954412;30354.84822575895;30369;1906.4280715785349;1958.1579920634122;1959.8530110241788;1961.1174971262847;1961.137099676138;18758.1558829853;19211.711824322647;19308.613827723366;19501.9024904876;19544.31594894172;2236.7875051870046;2285.7942785149417;2290.1526435218907;2307.3539408965516;2334.9299521014395;132556;134578;138049;138613.3069334647;139013.3049334757;14446.711065778625;14826.627739561834;15153.924230378998;15325.46360877364;15805.841941580667;23706.525869482513;23754.406139846298;23775.167869124845;23792.167274145322;23806.523869522513;9842;10069;10176.338537995007;10228.488575571191;10229.079382855578;8629.741107766777;9317.813643727088;9380.577873995686;9409.247260219166;9477.336586438883;8058.395620328499;11584.536618535165;11738.413079345999;11828.112891532997;12052.698682532831;3722.3671975764187;3856.5179352580926;3857.228554289153;3893.162969961472;3928.508936382952;933.9673111441202;973.732223638505;982.5273228119449;994.7065615643403;998.7652901568125;19978;20136.295229666892;20323.18707251723;20514.897425512776;21011.47471313199;1013.4932533733133;1019.143919107938;1025.2054657640388;1052.55792567122;1066.3814987307344;10997.450127493594;11275.37985410794;11520.884791152148;11575.247608905394;11586.934412826264;444521;467100;475607;475969;477229;105235.4738226304;109731.45134274277;118620.40689796496;122957.38521307336;123255.38372308081;1268.473583462871;1293.1012946002627;1303.6024012676035;1306.4708792938875;1307.5554311534124;20182.49543761359;21094.261700840398;21127.471813204793;21371.35885923425;21719.782802171776;53611.46388536221;55759.16361254503;57471.71264143653;57764.71117644385;58153;70230;70447;70584.29415705982;70588.64705676438;70800;12360.938195308965;12827.807582886451;13318.533851315158;14394.928025359806;14721.190334531704;22272.443188920406;22604;22668.886655566617;22695.659565106205;22748.20381286647;17729.202185901406;17874.642507150045;18105;18107.818921810613;18116.547086322422;15267.160306183267;15353.232338382813;16020.599485012968;16611.41860034893;17017.31930722757;18135.727964080812;18683.439496815117;18756.531086722942;18891.811081889005;18975.43073707791;14488.420463181776;14506.274686265855;14577.635559111108;14627.561173164822;14646.487372942322;16901.577460563585;17722.11389430549;17902.820971790636;17918.5520361992;18081.638367232845 03:12:27 INFO - PID 19298 | |3;mootools.html;1380.7514647363475;1401.2713389037701;1419;1419.41094445805;1432.9856701432982;12724.554640587581;13213.471461141555;13777.104488208272;13802.171869687812;13822.930885345584;13655.112417692842;13866.098703584285;13869.791953120712;13913.234772087531;13947.930260348709;2692.5422678144714;2757.310672331917;2757.696653368128;2757.986210068947;2765.308672831792;2606.582946728524;2764.875580598878;2767.3911739417294;2772.417792263617;2772.9445411091765;2655.9336016599545;2676.1302576662565;2684.7181045990183;2689.650345455088;2701.5407380745223;2577.5489289374286;2582.496413199427;2591.3780692633823;2605.049157057679;2650.8276961997503;2595.4419799743146;2623.553995820715;2625.133705877056;2630.302969713008;2657;13093.214407135602;13307.600771976759;13342.93328533361;13354.26551539665;13355.532556360578;7336.853262934765;7423.4061275097865;7684.961575192144;7689.884651730228;7695.884561731578;4826.251930950708;5083.720395378253;5097.108006098918;5108.310378098983;5168.405633352175;2167.2847960173103;2207.381933058746;2296.885155742223;2328;2336.7333686324237;21676.89161554198;22082.668759968452;22099.779002210093;22122.557548849094;22168.88915555428;6515.381038801306;6567.770128045496;6623.801285961333;6677.432418244503;6724.731010759515;17224.05267710263;17252.74120888188;17259.913700431418;17323.04723240209;17354.13229338528;7494.512856664408;7621.580813055224;7631.3513351365755;7672.117706463717;7673.117591477052;6671.699773510204;6875.690593923286;6896.3793258605965;6909.343612356818;6925.6190909500465;5234.110201265794;5399.514043736082;5442.340086273645;5445.782168713286;5475.780968761284;1673.9330426783035;1679.1184628070116;1690.1549225387307;1710.598009467774;1743.938962136319;767.2480968650642;767.5202998126172;784.1178673991759;811.4887620798977;817.8895849060419;6288.779892703825;6579.539432239697;6603.108580341593;6636.867262654817;6663.200415950117;706.869229192601;951.6859436385976;977.1108291454884;1010.2170817616407;1012.6050840172338;4565.269556870885;4977.452480227172;5043.823466178666;5133.640645154878;5163.509466600667;17491.650166996587;17942.282308707505;17956.1021948902;18416.17127229278;18431.90784046098;16598.6680266394;17341.132943352783;17455.039972801616;18341.90829045846;18550.536236594195;10189.184865210766;10849.132069434427;10859.674209773719;10864.78270434587;10874.130069594414;7512.924870751441;7633.923660763543;7934.841303173789;8177.6320065596;8294.83410331802;15884.52346429609;16109.033457992558;16145.273462694442;16473.176341182654;16862.494125176265;16281.674366512367;16336.836631634007;16437;16626.83373166253;16766.24551895192;10868.076213521623;10907.018368346888;10949.945250273699;11027.22809403334;11091.500882460146;12230.388480576114;12564.37178141071;12567.120301579167;12579.496820127093;12644.747105058032;9809.215262778818;10232.283740138417;10325.793484130425;10593.417362045162;10699.732506687395;9538.904610953801;10041.799164016533;10157.288989770645;10277.691669249934;10337.844932326172;8780.209781119729;8811.383203175972;8857.734267971971;8897.599608017785;8933.776655583662;6167.907481387693;6223.533235007483;6514.218293804774;6671.833204169935;6700.832479187864;6564.245111812203;6609;6956.721731130698;7071.752488662872;7073.151221853411 03:12:27 INFO - PID 19298 | |4;prototype.html;482.88207434432894;496.3869621018042;516.1741214057511;530.0670819357929;531.4287141323078;6230.750769969207;6234;6260.060990851374;6274.498040156782;6340.873182536347;5899.882002359961;6061.6363018218935;6062.757489700407;6130.172426722391;6142.4164704353125;4926.482719314483;4952;4961.255811628249;4978.278149668296;5000.0999820032375;4828.7585620719;4973.527514886098;4998.325560937073;5007.2489126630935;5018.197088465846;4881.633877459187;4910.803567857281;4927.876803079916;4931.137051016076;4933.802647894098;4950.153661644569;4974.328465657126;4985.975070124626;5000.924986125211;5021.84934451967;4453.020335526179;4455.955440445587;4473.060657261979;4473.575010374009;4496.168208881368;6013.7070529835855;6095.542834287447;6132.11084392765;6151.87696246077;6175.783847565359;5533.418991005927;5660.943390566124;5685.204071429962;5726.599138060337;5729.914051289234;6549.312322206148;6588.275289718127;6591.307912669148;6606.1412016438035;6616.27221005689;4877.170880950247;4897.131266299619;4910.533499317559;4912.115819152551;4920;6841.2132604750595;6883.552569083094;6905.620190889499;6917.377436030782;7029.086218791575;4937.7777999990085;4944.678595891256;5021.221710634857;5027.949720502748;5031.698098114125;5027.572656324253;5059.114654935445;5065.39215294167;5078.7460626968505;5123.641345105807;5586.832395028156;5635.267415236075;5654.971725141348;5724.198612194296;5744.195812586241;6528.543001989815;6529.008652360981;6530.575512591667;6551.836204094936;6598.86802263952;1669.2655231698013;1729.9740503892451;1865.309835360925;1932.7437165842034;1939.6702560564738;1415.1438379780084;1426.8145141131586;1433.5341014170435;1444.7760597107256;1461.576142918563;5666.263385759825;5692.857678558069;5768.394318596487;5779.133130030445;5798.217240672539;6693.397594216543;6706.899396509057;6719.798406047827;6742.8651426972165;6760.830979225559;5706.1497726931775;5739;5755.280589926259;5797.695518508386;5813.505852002542;3549.2369140634887;3568.660977207161;3595.209054008114;3626.5104210931186;3631.1648320886343;6333.746650133943;6447.7743278985945;6454.225492940877;6494;6509.707063182168;6634.436072933855;6777.525573209828;6796.592204467748;6800.4219641330055;6906.309369063053;6402.775902843471;6413.204302795225;6435.292117867031;6522.967385163044;6551.739649820899;6730.798076057726;6731.427828634621;6739.3835222872485;6755.1556055493065;6755.419627597381;6470.773522926675;6578.105633521235;6608.669566521751;6642.169728783902;6677.399034086956;6493.448056915215;6534.183227096613;6534.399072227335;6544.640044797582;6561.540692151694;4982.825601103944;4998.325226094431;5007.949920500749;5014.623903207347;5061.848144555522;6462.224533056064;6565.80302590923;6571.244306904767;6574.276829548841;6614.570052946543;6167.475764560063;6366.076918846648;6373.745050198126;6385.489160867027;6446.0975463434215;6367.42693157618;6392.872142557216;6416.32628573993;6451.096926406627;6530.281669016309;6324.683765811599;6356.968215158895;6364.4908407326375;6404.359564043559;6421.848680691834;6542.640154791532;6546.770863019771;6574.901376479453;6579.736810527525;6597;6386.0304958194665;6405.743770249138;6408.58344207625;6421.422072013541;6436.841368553705 03:12:27 INFO - PID 19298 | |5;yui.html;860.7288704058217;893.1957445959158;902.9277657787374;902.9729108126757;928.3779867488781;16888.408905688302;17991.37030203943;18030.819691803095;18044.368447104353;21865.562688746217;19639.312624058162;20414.285500007503;20681.689774653354;20751.169953201854;20762.96185190738;961.0629636104812;1006.0291818395248;1009.6264382178604;1011.044562888071;1013.9797204055951;1022.3712416863613;1050.6637875879721;1052.1319911073356;1057.6456886942883;1059.0256963593513;1112.677323576162;1120.1263014848432;1123.309164863607;1125.9324440533553;1129.2941911305434;1111.0778054215064;1119.3843386137592;1120.7702421003676;1121.624255874283;1122.9045531129864;816.5998660656295;819.1317203764029;819.8360327934378;820;821.1296026212175;18234.361797337162;18377.724334135;18403.907980460146;18645.906770466197;20339.593208135902;20268.79731202684;20715.482112947146;21148.365549033555;21160.153593856227;21177;15648.608784780357;16487.34050637973;16653.50039498817;16668.166591670368;16862.578435539086;3559.5906470755936;3616.529851119364;3617.855285788565;3619.149499867529;3621.5835178954494;121921;125395;126994;129004;130835.34582327123;14850.480233191787;15540.145292008821;15620.297086631128;15832.92083539598;15947.36210551565;30579.388412232074;30597.847010764803;30629.234269143006;30656.69343306583;30931.536026959613;7516.323530882247;7731.613419329124;7792.493487923381;8245.464044837066;8389.790255243668;4135.379693046068;4314.460692413449;4319.503257125408;4321.222180007566;4324.8486302979245;5160.7935682573025;5209;5253.789848406097;5308.708021058879;5316.813911513079;1066.4081434803604;1083.7832433513329;1094.6770702642739;1094.8412480190327;1098.3355070182424;649.7433513762038;653.8267359149829;678.35217367414;683.6195015647673;698.7659134190002;10552.788944221225;11071.889281107247;11773.941130294294;11840.230385024946;12448.688782780502;646.444058110025;677.1975209376905;680.5746408494691;693.0678237770292;693.9271376505495;5088.0078384714625;5135.512126347991;5147.1507201312115;5200.76596553156;5349.358077030781;97888;98148;99165.50417248011;100561;106367;83151;85481.57259213789;89495.10504894998;90540;90545.54727226454;78525.60737196277;79488.20511794924;81807;82206.58896705478;85189;11684.591039313584;12010.399480026139;12350.320732359807;12392.938035309766;12709.491620335082;33873.15317117092;35989.527857691144;36030.8198459006;36471.90584282476;36651;66005.33994660185;66099.67800644056;66376;67257.66371168113;71171;17757.378491752726;17838.464846054638;18071.367502137364;18106.18522166532;18109.547261318574;15255.923720381326;18145.183466743765;18818.24727010959;20147.597048058662;20512.38462846117;18236.17937192803;18337.449876503728;18545;18598.81401186025;18903.43289701311;17434.5641358962;17675.292988280326;17749.556261093578;17811.732824007908;18054.5486362842;17315.567110821827;17540.12299385;17734.91132544329;17781.377651782124;18002.189901454727;12077.51689932393;12894.548690795777;13126.803097953347;13336.733265334833;13591.932040339734;13021.283829389475;14397.784033239719;14398.568042958728;14403.711925761636;14511.346989385715 03:12:27 INFO - PID 19298 | __end_tp_report 03:12:27 INFO - PID 19298 | __start_cc_report 03:12:27 INFO - PID 19298 | _x_x_mozilla_cycle_collect,364 03:12:27 INFO - PID 19298 | __end_cc_report 03:12:27 INFO - PID 19298 | __startTimestamp1501063947457__endTimestamp 03:12:27 INFO - PID 19298 | 03:12:27 INFO - PID 19298 | ------- Summary: start ------- 03:12:27 INFO - PID 19298 | Number of tests: 6 03:12:27 INFO - PID 19298 | 03:12:27 INFO - PID 19298 | [#0] dojo.html Cycles:165 Average:6090.82 Median:6336.56 stddev:721.20 (11.4%) stddev-sans-first:683.52 03:12:27 INFO - PID 19298 | Values: 3075.7 3079.8 3106.9 3122.3 3124.3 6150.1 6202.2 6249.0 6264.0 6264.9 6068.7 6140.7 6141.4 6150.7 6158.4 5583.3 5599.0 5619.2 5632.7 5692.7 5686.8 5689.3 5733.2 5767.0 5775.4 5521.0 5593.7 5610.6 5688.5 5701.5 5620.6 5680.1 5708.3 5716.9 5719.5 5568.9 5602.2 5665.3 5671.9 5678.3 5986.7 6041.7 6110.1 6166.9 6194.9 6310.7 6409.2 6414.9 6496.9 6619.3 6677.0 6704.3 6712.3 6713.9 6716.3 6146.9 6153.9 6196.7 6240.0 6245.3 6662.7 6733.6 6749.3 6781.1 6810.7 6481.4 6498.5 6557.8 6575.4 6580.0 6606.1 6611.0 6678.0 6698.5 6698.9 6430.1 6529.3 6582.2 6591.0 6610.2 6508.6 6522.0 6549.1 6586.8 6595.7 5271.3 5306.3 5323.9 5365.7 5366.9 4496.7 4521.3 4542.3 4557.0 4619.6 5720.0 5748.3 5801.3 5802.7 5812.7 6589.4 6611.9 6632.9 6640.7 6725.0 5648.9 5721.0 5743.1 5805.3 5819.4 5796.3 5865.2 5883.9 5887.4 5891.9 6397.9 6414.4 6479.7 6497.6 6503.7 6506.2 6544.8 6578.8 6680.3 6685.2 6336.6 6383.2 6443.0 6461.7 6464.1 6511.8 6576.0 6595.0 6597.1 6603.9 6655.7 6657.7 6661.3 6744.4 6747.9 6387.5 6466.4 6468.3 6489.9 6492.3 6413.9 6418.7 6437.5 6517.0 6523.5 6251.5 6332.3 6347.5 6405.7 6446.0 6386.4 6457.5 6549.9 6557.2 6578.5 6251.7 6277.9 6283.6 6302.5 6386.0 03:12:27 INFO - PID 19298 | 03:12:27 INFO - PID 19298 | [#1] ext.html Cycles:180 Average:38812.03 Median:37410.63 stddev:55391.44 (148.1%) stddev-sans-first:55473.30 03:12:27 INFO - PID 19298 | Values: 803.8 855.4 869.0 876.0 1160.7 29872.4 30786.1 40397.0 40877.6 40991.4 40851.2 41366.8 41395.0 41441.6 41553.2 7429.9 7515.7 7624.1 7685.3 7688.1 7494.2 7547.8 7555.3 7556.9 7567.0 7539.7 7582.3 7608.4 7609.8 7615.5 7583.2 7598.3 7613.8 7627.7 7630.8 7494.5 7546.6 7556.5 7561.7 7563.6 37967.4 38036.2 38084.0 38242.8 38260.4 3475.6 3511.4 3513.3 3524.4 3525.9 30359.5 30544.9 30590.2 30647.0 30762.5 4739.9 4787.6 4792.6 4843.3 4930.1 190062.0 190684.0 191943.0 191982.0 192325.0 48558.5 48644.0 48655.8 48664.0 48725.5 54909.5 55820.2 56135.4 56259.0 56271.0 16383.0 16547.0 16562.6 16653.7 16717.5 13478.4 13508.8 13509.7 13511.4 13559.3 14629.9 14710.9 14833.9 14961.0 15050.2 5926.8 5938.3 5983.7 5990.7 6034.4 941.9 962.0 967.8 980.3 981.2 22325.4 22340.6 22752.3 22863.4 22973.8 1056.3 1070.5 1101.9 1107.2 1108.1 11838.5 11905.3 12054.3 12061.1 12169.2 229421.0 229724.0 231501.0 232018.0 233403.0 51920.2 51954.7 52411.5 52465.5 52507.5 1411.2 1474.6 1484.5 1486.0 1499.3 21710.3 21734.1 22028.3 22056.7 27065.9 51591.0 51827.7 51895.7 52121.7 52175.7 211193.0 212187.0 218261.0 218409.0 219318.0 37055.6 37066.8 37291.6 37529.6 37546.4 42976.6 44584.6 44705.8 44845.6 45065.5 30960.0 32771.0 33866.8 34513.0 35253.6 32470.8 33184.2 34077.3 34230.7 34796.7 37775.2 38113.0 38120.4 38427.0 38504.6 32561.8 32655.2 32803.0 33027.3 33132.5 23334.8 24247.4 24486.8 24567.0 25036.6 03:12:27 INFO - PID 19298 | 03:12:27 INFO - PID 19298 | [#2] jquery.html Cycles:180 Average:34214.50 Median:22288.83 stddev:79158.86 (355.2%) stddev-sans-first:79340.65 03:12:27 INFO - PID 19298 | Values: 582.2 586.1 588.1 588.2 601.7 21455.5 22053.2 22305.2 22334.0 22350.1 25400.2 26038.2 26066.5 26117.2 26150.1 6446.4 6603.5 6608.5 6608.7 6618.0 6121.6 6441.2 6480.7 6491.4 6500.9 6466.3 6478.2 6484.2 6497.4 6497.6 6435.3 6474.6 6481.6 6487.9 6503.3 5996.7 5999.6 6001.6 6003.7 6006.8 28094.0 29063.0 29544.7 30354.8 30369.0 1906.4 1958.2 1959.9 1961.1 1961.1 18758.2 19211.7 19308.6 19501.9 19544.3 2236.8 2285.8 2290.2 2307.4 2334.9 132556.0 134578.0 138049.0 138613.3 139013.3 14446.7 14826.6 15153.9 15325.5 15805.8 23706.5 23754.4 23775.2 23792.2 23806.5 9842.0 10069.0 10176.3 10228.5 10229.1 8629.7 9317.8 9380.6 9409.2 9477.3 8058.4 11584.5 11738.4 11828.1 12052.7 3722.4 3856.5 3857.2 3893.2 3928.5 934.0 973.7 982.5 994.7 998.8 19978.0 20136.3 20323.2 20514.9 21011.5 1013.5 1019.1 1025.2 1052.6 1066.4 10997.5 11275.4 11520.9 11575.2 11586.9 444521.0 467100.0 475607.0 475969.0 477229.0 105235.5 109731.5 118620.4 122957.4 123255.4 1268.5 1293.1 1303.6 1306.5 1307.6 20182.5 21094.3 21127.5 21371.4 21719.8 53611.5 55759.2 57471.7 57764.7 58153.0 70230.0 70447.0 70584.3 70588.6 70800.0 12360.9 12827.8 13318.5 14394.9 14721.2 22272.4 22604.0 22668.9 22695.7 22748.2 17729.2 17874.6 18105.0 18107.8 18116.5 15267.2 15353.2 16020.6 16611.4 17017.3 18135.7 18683.4 18756.5 18891.8 18975.4 14488.4 14506.3 14577.6 14627.6 14646.5 16901.6 17722.1 17902.8 17918.6 18081.6 03:12:27 INFO - PID 19298 | 03:12:27 INFO - PID 19298 | [#3] mootools.html Cycles:180 Average:8451.29 Median:2615.07 stddev:5655.65 (216.3%) stddev-sans-first:5646.56 03:12:27 INFO - PID 19298 | Values: 1380.8 1401.3 1419.0 1419.4 1433.0 12724.6 13213.5 13777.1 13802.2 13822.9 13655.1 13866.1 13869.8 13913.2 13947.9 2692.5 2757.3 2757.7 2758.0 2765.3 2606.6 2764.9 2767.4 2772.4 2772.9 2655.9 2676.1 2684.7 2689.7 2701.5 2577.5 2582.5 2591.4 2605.0 2650.8 2595.4 2623.6 2625.1 2630.3 2657.0 13093.2 13307.6 13342.9 13354.3 13355.5 7336.9 7423.4 7685.0 7689.9 7695.9 4826.3 5083.7 5097.1 5108.3 5168.4 2167.3 2207.4 2296.9 2328.0 2336.7 21676.9 22082.7 22099.8 22122.6 22168.9 6515.4 6567.8 6623.8 6677.4 6724.7 17224.1 17252.7 17259.9 17323.0 17354.1 7494.5 7621.6 7631.4 7672.1 7673.1 6671.7 6875.7 6896.4 6909.3 6925.6 5234.1 5399.5 5442.3 5445.8 5475.8 1673.9 1679.1 1690.2 1710.6 1743.9 767.2 767.5 784.1 811.5 817.9 6288.8 6579.5 6603.1 6636.9 6663.2 706.9 951.7 977.1 1010.2 1012.6 4565.3 4977.5 5043.8 5133.6 5163.5 17491.7 17942.3 17956.1 18416.2 18431.9 16598.7 17341.1 17455.0 18341.9 18550.5 10189.2 10849.1 10859.7 10864.8 10874.1 7512.9 7633.9 7934.8 8177.6 8294.8 15884.5 16109.0 16145.3 16473.2 16862.5 16281.7 16336.8 16437.0 16626.8 16766.2 10868.1 10907.0 10949.9 11027.2 11091.5 12230.4 12564.4 12567.1 12579.5 12644.7 9809.2 10232.3 10325.8 10593.4 10699.7 9538.9 10041.8 10157.3 10277.7 10337.8 8780.2 8811.4 8857.7 8897.6 8933.8 6167.9 6223.5 6514.2 6671.8 6700.8 6564.2 6609.0 6956.7 7071.8 7073.2 03:12:27 INFO - PID 19298 | 03:12:27 INFO - PID 19298 | [#4] prototype.html Cycles:180 Average:5499.17 Median:6131.14 stddev:1512.21 (24.7%) stddev-sans-first:1468.83 03:12:27 INFO - PID 19298 | Values: 482.9 496.4 516.2 530.1 531.4 6230.8 6234.0 6260.1 6274.5 6340.9 5899.9 6061.6 6062.8 6130.2 6142.4 4926.5 4952.0 4961.3 4978.3 5000.1 4828.8 4973.5 4998.3 5007.2 5018.2 4881.6 4910.8 4927.9 4931.1 4933.8 4950.2 4974.3 4986.0 5000.9 5021.8 4453.0 4456.0 4473.1 4473.6 4496.2 6013.7 6095.5 6132.1 6151.9 6175.8 5533.4 5660.9 5685.2 5726.6 5729.9 6549.3 6588.3 6591.3 6606.1 6616.3 4877.2 4897.1 4910.5 4912.1 4920.0 6841.2 6883.6 6905.6 6917.4 7029.1 4937.8 4944.7 5021.2 5027.9 5031.7 5027.6 5059.1 5065.4 5078.7 5123.6 5586.8 5635.3 5655.0 5724.2 5744.2 6528.5 6529.0 6530.6 6551.8 6598.9 1669.3 1730.0 1865.3 1932.7 1939.7 1415.1 1426.8 1433.5 1444.8 1461.6 5666.3 5692.9 5768.4 5779.1 5798.2 6693.4 6706.9 6719.8 6742.9 6760.8 5706.1 5739.0 5755.3 5797.7 5813.5 3549.2 3568.7 3595.2 3626.5 3631.2 6333.7 6447.8 6454.2 6494.0 6509.7 6634.4 6777.5 6796.6 6800.4 6906.3 6402.8 6413.2 6435.3 6523.0 6551.7 6730.8 6731.4 6739.4 6755.2 6755.4 6470.8 6578.1 6608.7 6642.2 6677.4 6493.4 6534.2 6534.4 6544.6 6561.5 4982.8 4998.3 5007.9 5014.6 5061.8 6462.2 6565.8 6571.2 6574.3 6614.6 6167.5 6366.1 6373.7 6385.5 6446.1 6367.4 6392.9 6416.3 6451.1 6530.3 6324.7 6357.0 6364.5 6404.4 6421.8 6542.6 6546.8 6574.9 6579.7 6597.0 6386.0 6405.7 6408.6 6421.4 6436.8 03:12:27 INFO - PID 19298 | 03:12:27 INFO - PID 19298 | [#5] yui.html Cycles:180 Average:22701.58 Median:18860.84 stddev:30494.88 (161.7%) stddev-sans-first:30536.33 03:12:27 INFO - PID 19298 | Values: 860.7 893.2 902.9 903.0 928.4 16888.4 17991.4 18030.8 18044.4 21865.6 19639.3 20414.3 20681.7 20751.2 20763.0 961.1 1006.0 1009.6 1011.0 1014.0 1022.4 1050.7 1052.1 1057.6 1059.0 1112.7 1120.1 1123.3 1125.9 1129.3 1111.1 1119.4 1120.8 1121.6 1122.9 816.6 819.1 819.8 820.0 821.1 18234.4 18377.7 18403.9 18645.9 20339.6 20268.8 20715.5 21148.4 21160.2 21177.0 15648.6 16487.3 16653.5 16668.2 16862.6 3559.6 3616.5 3617.9 3619.1 3621.6 121921.0 125395.0 126994.0 129004.0 130835.3 14850.5 15540.1 15620.3 15832.9 15947.4 30579.4 30597.8 30629.2 30656.7 30931.5 7516.3 7731.6 7792.5 8245.5 8389.8 4135.4 4314.5 4319.5 4321.2 4324.8 5160.8 5209.0 5253.8 5308.7 5316.8 1066.4 1083.8 1094.7 1094.8 1098.3 649.7 653.8 678.4 683.6 698.8 10552.8 11071.9 11773.9 11840.2 12448.7 646.4 677.2 680.6 693.1 693.9 5088.0 5135.5 5147.2 5200.8 5349.4 97888.0 98148.0 99165.5 100561.0 106367.0 83151.0 85481.6 89495.1 90540.0 90545.5 78525.6 79488.2 81807.0 82206.6 85189.0 11684.6 12010.4 12350.3 12392.9 12709.5 33873.2 35989.5 36030.8 36471.9 36651.0 66005.3 66099.7 66376.0 67257.7 71171.0 17757.4 17838.5 18071.4 18106.2 18109.5 15255.9 18145.2 18818.2 20147.6 20512.4 18236.2 18337.4 18545.0 18598.8 18903.4 17434.6 17675.3 17749.6 17811.7 18054.5 17315.6 17540.1 17734.9 17781.4 18002.2 12077.5 12894.5 13126.8 13336.7 13591.9 13021.3 14397.8 14398.6 14403.7 14511.3 03:12:27 INFO - PID 19298 | -------- Summary: end -------- 03:12:27 INFO - PID 19298 | 03:12:28 INFO - TEST-INFO | 19298: exit 0 03:12:28 INFO - TEST-OK | dromaeo_css | took 1112355ms 03:12:28 INFO - TEST-START | kraken 03:12:28 INFO - Initialising browser for kraken test... 03:12:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49441/getInfo.html -profile /tmp/tmpxPrkMj/profile 03:12:28 INFO - TEST-INFO | started process 19667 (/builds/slave/test/build/application/firefox/firefox http://localhost:49441/getInfo.html) 03:12:34 INFO - TEST-INFO | 19667: exit 0 03:12:34 INFO - Browser initialized. 03:12:34 INFO - Running cycle 1/1 for kraken test... 03:12:34 INFO - TEST-INFO | started process 19876 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxPrkMj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:12:35 INFO - PID 19876 | 03:12:35 INFO - PID 19876 | (/builds/slave/test/build/application/firefox/firefox:19925): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:12:35 INFO - PID 19876 | 03:12:45 INFO - PID 19876 | RSS: Main: 171163648 03:12:45 INFO - PID 19876 | 03:12:48 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49441/tests/kraken/driver.html?testName=audio-beat-detection) 03:12:49 INFO - PID 19876 | RSS: Main: 176975872 03:12:49 INFO - PID 19876 | 03:12:51 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49441/tests/kraken/driver.html?testName=audio-dft) 03:12:52 INFO - PID 19876 | RSS: Main: 177786880 03:12:52 INFO - PID 19876 | 03:12:55 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49441/tests/kraken/driver.html?testName=audio-fft) 03:12:55 INFO - PID 19876 | RSS: Main: 170872832 03:12:55 INFO - PID 19876 | 03:12:58 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49441/tests/kraken/driver.html?testName=audio-oscillator) 03:12:58 INFO - PID 19876 | RSS: Main: 172277760 03:12:58 INFO - PID 19876 | 03:13:00 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49441/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:13:01 INFO - PID 19876 | RSS: Main: 172531712 03:13:01 INFO - PID 19876 | 03:13:04 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49441/tests/kraken/driver.html?testName=imaging-darkroom) 03:13:05 INFO - PID 19876 | RSS: Main: 175194112 03:13:05 INFO - PID 19876 | 03:13:08 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49441/tests/kraken/driver.html?testName=imaging-desaturate) 03:13:09 INFO - PID 19876 | RSS: Main: 175468544 03:13:09 INFO - PID 19876 | 03:13:12 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49441/tests/kraken/driver.html?testName=json-parse-financial) 03:13:12 INFO - PID 19876 | RSS: Main: 175407104 03:13:12 INFO - PID 19876 | 03:13:14 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49441/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:13:15 INFO - PID 19876 | RSS: Main: 175251456 03:13:15 INFO - PID 19876 | 03:13:16 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49441/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:13:17 INFO - PID 19876 | RSS: Main: 176066560 03:13:17 INFO - PID 19876 | 03:13:19 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49441/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:13:20 INFO - PID 19876 | RSS: Main: 172498944 03:13:20 INFO - PID 19876 | 03:13:22 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49441/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:13:23 INFO - PID 19876 | RSS: Main: 172900352 03:13:23 INFO - PID 19876 | 03:13:26 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49441/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:13:27 INFO - PID 19876 | RSS: Main: 173551616 03:13:27 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | Cycle 1(1): loaded http://localhost:49441/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49441/tests/kraken/driver.html?testName=ai-astar) 03:13:29 INFO - PID 19876 | RSS: Main: 174940160 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | __start_tp_report 03:13:29 INFO - PID 19876 | _x_x_mozilla_page_load 03:13:29 INFO - PID 19876 | _x_x_mozilla_page_load_details 03:13:29 INFO - PID 19876 | |i|pagename|runs| 03:13:29 INFO - PID 19876 | |0;ai-astar;98;111;101;110;108;110;109;100;110;109 03:13:29 INFO - PID 19876 | |1;audio-beat-detection;152;122;148;171;116;152;141;119;167;118 03:13:29 INFO - PID 19876 | |2;audio-dft;163;161;163;164;163;164;164;168;166;163 03:13:29 INFO - PID 19876 | |3;audio-fft;75;77;74;181;75;87;75;76;179;74 03:13:29 INFO - PID 19876 | |4;audio-oscillator;79;79;131;80;79;80;131;79;82;135 03:13:29 INFO - PID 19876 | |5;imaging-gaussian-blur;115;116;115;118;115;114;113;115;114;114 03:13:29 INFO - PID 19876 | |6;imaging-darkroom;150;152;151;150;151;151;151;153;152;150 03:13:29 INFO - PID 19876 | |7;imaging-desaturate;94;94;95;94;94;94;95;95;95;94 03:13:29 INFO - PID 19876 | |8;json-parse-financial;55;55;55;54;55;54;62;54;55;56 03:13:29 INFO - PID 19876 | |9;json-stringify-tinderbox;54;54;54;54;55;55;55;55;54;55 03:13:29 INFO - PID 19876 | |10;stanford-crypto-aes;77;78;79;78;78;78;80;78;78;80 03:13:29 INFO - PID 19876 | |11;stanford-crypto-ccm;138;132;137;130;125;150;136;129;123;157 03:13:29 INFO - PID 19876 | |12;stanford-crypto-pbkdf2;177;175;193;185;179;181;176;180;174;181 03:13:29 INFO - PID 19876 | |13;stanford-crypto-sha256-iterative;70;69;68;69;75;67;71;70;67;70 03:13:29 INFO - PID 19876 | __end_tp_report 03:13:29 INFO - PID 19876 | __start_cc_report 03:13:29 INFO - PID 19876 | _x_x_mozilla_cycle_collect,992 03:13:29 INFO - PID 19876 | __end_cc_report 03:13:29 INFO - PID 19876 | __startTimestamp1501064009469__endTimestamp 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | ------- Summary: start ------- 03:13:29 INFO - PID 19876 | Number of tests: 14 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#0] ai-astar Cycles:10 Average:106.60 Median:110.00 stddev:4.90 (4.5%) stddev-sans-first:4.10 03:13:29 INFO - PID 19876 | Values: 98.0 111.0 101.0 110.0 108.0 110.0 109.0 100.0 110.0 109.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:150.00 stddev:20.72 (13.8%) stddev-sans-first:21.56 03:13:29 INFO - PID 19876 | Values: 152.0 122.0 148.0 171.0 116.0 152.0 141.0 119.0 167.0 118.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.00 stddev:1.91 (1.2%) stddev-sans-first:2.00 03:13:29 INFO - PID 19876 | Values: 163.0 161.0 163.0 164.0 163.0 164.0 164.0 168.0 166.0 163.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#3] audio-fft Cycles:10 Average:97.30 Median:75.00 stddev:43.75 (58.3%) stddev-sans-first:45.66 03:13:29 INFO - PID 19876 | Values: 75.0 77.0 74.0 181.0 75.0 87.0 75.0 76.0 179.0 74.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:79.00 stddev:25.46 (32.2%) stddev-sans-first:26.29 03:13:29 INFO - PID 19876 | Values: 79.0 79.0 131.0 80.0 79.0 80.0 131.0 79.0 82.0 135.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:115.00 stddev:1.37 (1.2%) stddev-sans-first:1.45 03:13:29 INFO - PID 19876 | Values: 115.0 116.0 115.0 118.0 115.0 114.0 113.0 115.0 114.0 114.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:0.99 (0.7%) stddev-sans-first:0.97 03:13:29 INFO - PID 19876 | Values: 150.0 152.0 151.0 150.0 151.0 151.0 151.0 153.0 152.0 150.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 03:13:29 INFO - PID 19876 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.00 stddev:2.37 (4.3%) stddev-sans-first:2.51 03:13:29 INFO - PID 19876 | Values: 55.0 55.0 55.0 54.0 55.0 54.0 62.0 54.0 55.0 56.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#9] json-stringify-tinderbox Cycles:10 Average:54.50 Median:55.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 03:13:29 INFO - PID 19876 | Values: 54.0 54.0 54.0 54.0 55.0 55.0 55.0 55.0 54.0 55.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:0.97 (1.2%) stddev-sans-first:0.88 03:13:29 INFO - PID 19876 | Values: 77.0 78.0 79.0 78.0 78.0 78.0 80.0 78.0 78.0 80.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:136.50 stddev:10.71 (7.8%) stddev-sans-first:11.33 03:13:29 INFO - PID 19876 | Values: 138.0 132.0 137.0 130.0 125.0 150.0 136.0 129.0 123.0 157.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.10 Median:180.50 stddev:5.61 (3.1%) stddev-sans-first:5.83 03:13:29 INFO - PID 19876 | Values: 177.0 175.0 193.0 185.0 179.0 181.0 176.0 180.0 174.0 181.0 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:2.32 (3.3%) stddev-sans-first:2.46 03:13:29 INFO - PID 19876 | Values: 70.0 69.0 68.0 69.0 75.0 67.0 71.0 70.0 67.0 70.0 03:13:29 INFO - PID 19876 | -------- Summary: end -------- 03:13:29 INFO - PID 19876 | 03:13:29 INFO - PID 19876 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:13:30 INFO - TEST-INFO | 19876: exit 0 03:13:30 INFO - TEST-OK | kraken | took 62024ms 03:13:30 INFO - SUITE-END | took 1174s 03:13:30 INFO - Completed test suite (00:19:34) 03:13:30 INFO - javascript benchmark 03:13:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8453.912990483066, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3075.7078077582623, 3079.8152110873343, 3106.9378612427754, 3122.2818751687096, 3124.312651216733, 6150.138980542728, 6202.193714817079, 6248.968920128179, 6263.968680156599, 6264.906026409608, 6068.6662233577135, 6140.662263575501, 6141.447269745722, 6150.723217455214, 6158.384161583839, 5583.3300003999575, 5599.020171469987, 5619.241402410663, 5632.746526406321, 5692.658440493584, 5686.829395118153, 5689.260396148516, 5733.226014488033, 5766.9711651441685, 5775.364709881909, 5520.972395138038, 5593.664380137164, 5610.635308704921, 5688.516476099537, 5701.458361455655, 5620.5503559715125, 5680.147977803363, 5708.286464191976, 5716.857078573027, 5719.485246327808, 5568.86077848053, 5602.187682785973, 5665.263515742969, 5671.914921276184, 5678.346990096151, 5986.73059712314, 6041.72812223451, 6110.052941794029, 6166.8766624667705, 6194.876102477971, 6310.716017779211, 6409.198850143732, 6414.935850641527, 6496.93503064968, 6619.304972977816, 6676.966615166942, 6704.296048914891, 6712.261651218362, 6713.865722685568, 6716.3283671632935, 6146.8770624587705, 6153.938460615382, 6196.721147548371, 6240.0, 6245.344238854855, 6662.7334906604165, 6733.562318449285, 6749.291324410964, 6781.084553585301, 6810.727570897207, 6481.384268494486, 6498.5126115541525, 6557.770478033246, 6575.375339342755, 6580.045893345438, 6606.075149479077, 6610.96694516534, 6677.9666101670155, 6698.4641228701585, 6698.8995165072565, 6430.099786029959, 6529.2817790043055, 6582.17722784652, 6591.0, 6610.239822420387, 6508.576942498722, 6522.0, 6549.083128362033, 6586.769463068769, 6595.70319335631, 5271.288376069258, 5306.28365170697, 5323.920141197885, 5365.731713414314, 5366.946330536644, 4496.685232033792, 4521.344405061313, 4542.318652202196, 4556.97721511397, 4619.561141691601, 5720.0, 5748.252727145445, 5801.3038435388025, 5802.65184088956, 5812.709364531841, 6589.406953374123, 6611.933880661228, 6632.933670663231, 6640.667966601651, 6724.957631567115, 5648.943510564924, 5720.971395142998, 5743.052396354555, 5805.274340707411, 5819.447152520588, 5796.275465566804, 5865.237519122486, 5883.911741323884, 5887.4406931341455, 5891.941080589225, 6397.904031439532, 6414.422701956846, 6479.676016199265, 6497.610143391412, 6503.674816259168, 6506.154199953976, 6544.8363790905605, 6578.76974305897, 6680.2651708313015, 6685.164354455693, 6336.556441049082, 6383.234011918414, 6442.967785161044, 6461.741530338922, 6464.09502669636, 6511.837204069936, 6576.0464732612545, 6595.0, 6597.1423714916755, 6603.933960660332, 6655.700493477708, 6657.73369065232, 6661.267260601427, 6744.393004629411, 6747.865042699217, 6387.457066149429, 6466.385693359123, 6468.288488266381, 6489.935100648933, 6492.253390860112, 6413.8717225656155, 6418.743250269937, 6437.452816510462, 6517.0, 6523.478121750343, 6251.531135164972, 6332.271788744353, 6347.492200623847, 6405.743770249138, 6445.967770161119, 6386.425221729881, 6457.483401327789, 6549.901751473827, 6557.180352455943, 6578.473722102125, 6251.6561589113035, 6277.874442511215, 6283.622982621057, 6302.495800336053, 6386.010168423902], "value": 6036.089056325445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.8030682482793, 855.3542075732822, 868.9565521723912, 876.031984656954, 1160.6808127764868, 29872.402551948977, 30786.07641770745, 40397.0, 40877.59122408775, 40991.38512922308, 40851.18297634045, 41366.7931660342, 41395.0, 41441.585584144224, 41553.16893662125, 7429.851402971937, 7515.699372025112, 7624.123225829046, 7685.308322250996, 7688.115866675348, 7494.175640679521, 7547.811304717371, 7555.320021198091, 7556.886646700304, 7566.962165189167, 7539.660715267826, 7582.317591416771, 7608.429367797409, 7609.80975475612, 7615.5430674159725, 7583.165851756303, 7598.27816357448, 7613.847723045508, 7627.733029343946, 7630.847383052335, 7494.475386722932, 7546.584937828417, 7556.47104702671, 7561.735339263154, 7563.584002879839, 37967.4304885427, 38036.23927521434, 38084.0, 38242.80878595617, 38260.42609360862, 3475.6176820549717, 3511.3679537683206, 3513.2622149348667, 3524.400851855165, 3525.8765943191866, 30359.544606830914, 30544.930927417656, 30590.235244119074, 30647.0, 30762.53856192159, 4739.928901066487, 4787.569118779326, 4792.640551958617, 4843.321934929113, 4930.087933732271, 190062.04968975065, 190684.0, 191943.0, 191982.0, 192325.0, 48558.51441485611, 48644.0, 48655.75672121617, 48664.0, 48725.512744872096, 54909.450905490434, 55820.16269755957, 56135.43864561384, 56259.0, 56271.0, 16383.017018978899, 16547.0, 16562.58593535171, 16653.666926661397, 16717.49847504577, 13478.39347229377, 13508.797368039488, 13509.66225844342, 13511.391987360592, 13559.322033898266, 14629.85370146285, 14710.926445367919, 14833.851661483248, 14961.0, 15050.247487625575, 5926.7925622603, 5938.317093534212, 5983.6708981005595, 5990.700464976734, 6034.366391528826, 941.87755591774, 962.0, 967.8257913575624, 980.3431700760508, 981.1513041219293, 22325.43257525373, 22340.553188936454, 22752.317430477113, 22863.428414289774, 22973.770262297163, 1056.2711728907184, 1070.4808168038496, 1101.884302148279, 1107.2304748199924, 1108.0969010256615, 11838.467268972918, 11905.345206013579, 12054.337011464453, 12061.095417843697, 12169.20900141488, 229421.0, 229724.0, 231501.0, 232018.0, 233403.0, 51920.22119668208, 51954.74022629863, 52411.47588524142, 52465.47534524606, 52507.47492525026, 1411.1744629391715, 1474.620377478886, 1484.5323723027211, 1486.0, 1499.2503748125937, 21710.34868953934, 21734.130634774432, 22028.33914982553, 22056.669149962443, 27065.86467067652, 51591.0, 51827.74086129545, 51895.74052129715, 52121.7393913028, 52175.73912130415, 211193.0, 212187.0, 218261.0, 218409.0, 219318.0, 37055.62944370522, 37066.81466592757, 37291.62708372882, 37529.62470375261, 37546.436803447425, 42976.57023429726, 44584.55415445804, 44705.77647111744, 44845.55154448414, 45065.54934450614, 30960.0, 32771.0, 33866.830665846515, 34513.0, 35253.647463525034, 32470.837645811618, 33184.17438305863, 34077.318453630294, 34230.65769342275, 34796.65203347934, 37775.24449511049, 38113.0, 38120.42819357656, 38427.0, 38504.614953850105, 32561.837190813894, 32655.18362040968, 32803.0, 33027.33945321128, 33132.503012455316, 23334.76665233326, 24247.393815154057, 24486.75513244916, 24567.0, 25036.62445063289], "value": 17468.006672695312, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.1529674323859, 586.1442294250394, 588.05910543131, 588.2499812738757, 601.7322291580246, 21455.463613409654, 22053.22813701521, 22305.219317323896, 22334.0, 22350.10599576019, 25400.221984461095, 26038.21885343438, 26066.478670426626, 26117.21648350548, 26150.084747033856, 6446.355364463563, 6603.504737144734, 6608.471322294205, 6608.735650573972, 6617.966910165443, 6121.632702037892, 6441.162648855642, 6480.708368123445, 6491.415772580467, 6500.93499065008, 6466.288708242089, 6478.157839480885, 6484.221893372802, 6497.415232629062, 6497.642629655367, 6435.292117867031, 6474.5791523550815, 6481.578697384655, 6487.870242595145, 6503.317151699074, 5996.730147143346, 5999.580029397944, 6001.639901605918, 6003.699815009232, 6006.849828754316, 28094.0, 29063.0, 29544.704552954412, 30354.84822575895, 30369.0, 1906.4280715785349, 1958.1579920634122, 1959.8530110241788, 1961.1174971262847, 1961.137099676138, 18758.1558829853, 19211.711824322647, 19308.613827723366, 19501.9024904876, 19544.31594894172, 2236.7875051870046, 2285.7942785149417, 2290.1526435218907, 2307.3539408965516, 2334.9299521014395, 132556.0, 134578.0, 138049.0, 138613.3069334647, 139013.3049334757, 14446.711065778625, 14826.627739561834, 15153.924230378998, 15325.46360877364, 15805.841941580667, 23706.525869482513, 23754.406139846298, 23775.167869124845, 23792.167274145322, 23806.523869522513, 9842.0, 10069.0, 10176.338537995007, 10228.488575571191, 10229.079382855578, 8629.741107766777, 9317.813643727088, 9380.577873995686, 9409.247260219166, 9477.336586438883, 8058.395620328499, 11584.536618535165, 11738.413079345999, 11828.112891532997, 12052.698682532831, 3722.3671975764187, 3856.5179352580926, 3857.228554289153, 3893.162969961472, 3928.508936382952, 933.9673111441202, 973.732223638505, 982.5273228119449, 994.7065615643403, 998.7652901568125, 19978.0, 20136.295229666892, 20323.18707251723, 20514.897425512776, 21011.47471313199, 1013.4932533733133, 1019.143919107938, 1025.2054657640388, 1052.55792567122, 1066.3814987307344, 10997.450127493594, 11275.37985410794, 11520.884791152148, 11575.247608905394, 11586.934412826264, 444521.0, 467100.0, 475607.0, 475969.0, 477229.0, 105235.4738226304, 109731.45134274277, 118620.40689796496, 122957.38521307336, 123255.38372308081, 1268.473583462871, 1293.1012946002627, 1303.6024012676035, 1306.4708792938875, 1307.5554311534124, 20182.49543761359, 21094.261700840398, 21127.471813204793, 21371.35885923425, 21719.782802171776, 53611.46388536221, 55759.16361254503, 57471.71264143653, 57764.71117644385, 58153.0, 70230.0, 70447.0, 70584.29415705982, 70588.64705676438, 70800.0, 12360.938195308965, 12827.807582886451, 13318.533851315158, 14394.928025359806, 14721.190334531704, 22272.443188920406, 22604.0, 22668.886655566617, 22695.659565106205, 22748.20381286647, 17729.202185901406, 17874.642507150045, 18105.0, 18107.818921810613, 18116.547086322422, 15267.160306183267, 15353.232338382813, 16020.599485012968, 16611.41860034893, 17017.31930722757, 18135.727964080812, 18683.439496815117, 18756.531086722942, 18891.811081889005, 18975.43073707791, 14488.420463181776, 14506.274686265855, 14577.635559111108, 14627.561173164822, 14646.487372942322, 16901.577460563585, 17722.11389430549, 17902.820971790636, 17918.5520361992, 18081.638367232845], "value": 12133.65326920058, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.7514647363475, 1401.2713389037701, 1419.0, 1419.41094445805, 1432.9856701432982, 12724.554640587581, 13213.471461141555, 13777.104488208272, 13802.171869687812, 13822.930885345584, 13655.112417692842, 13866.098703584285, 13869.791953120712, 13913.234772087531, 13947.930260348709, 2692.5422678144714, 2757.310672331917, 2757.696653368128, 2757.986210068947, 2765.308672831792, 2606.582946728524, 2764.875580598878, 2767.3911739417294, 2772.417792263617, 2772.9445411091765, 2655.9336016599545, 2676.1302576662565, 2684.7181045990183, 2689.650345455088, 2701.5407380745223, 2577.5489289374286, 2582.496413199427, 2591.3780692633823, 2605.049157057679, 2650.8276961997503, 2595.4419799743146, 2623.553995820715, 2625.133705877056, 2630.302969713008, 2657.0, 13093.214407135602, 13307.600771976759, 13342.93328533361, 13354.26551539665, 13355.532556360578, 7336.853262934765, 7423.4061275097865, 7684.961575192144, 7689.884651730228, 7695.884561731578, 4826.251930950708, 5083.720395378253, 5097.108006098918, 5108.310378098983, 5168.405633352175, 2167.2847960173103, 2207.381933058746, 2296.885155742223, 2328.0, 2336.7333686324237, 21676.89161554198, 22082.668759968452, 22099.779002210093, 22122.557548849094, 22168.88915555428, 6515.381038801306, 6567.770128045496, 6623.801285961333, 6677.432418244503, 6724.731010759515, 17224.05267710263, 17252.74120888188, 17259.913700431418, 17323.04723240209, 17354.13229338528, 7494.512856664408, 7621.580813055224, 7631.3513351365755, 7672.117706463717, 7673.117591477052, 6671.699773510204, 6875.690593923286, 6896.3793258605965, 6909.343612356818, 6925.6190909500465, 5234.110201265794, 5399.514043736082, 5442.340086273645, 5445.782168713286, 5475.780968761284, 1673.9330426783035, 1679.1184628070116, 1690.1549225387307, 1710.598009467774, 1743.938962136319, 767.2480968650642, 767.5202998126172, 784.1178673991759, 811.4887620798977, 817.8895849060419, 6288.779892703825, 6579.539432239697, 6603.108580341593, 6636.867262654817, 6663.200415950117, 706.869229192601, 951.6859436385976, 977.1108291454884, 1010.2170817616407, 1012.6050840172338, 4565.269556870885, 4977.452480227172, 5043.823466178666, 5133.640645154878, 5163.509466600667, 17491.650166996587, 17942.282308707505, 17956.1021948902, 18416.17127229278, 18431.90784046098, 16598.6680266394, 17341.132943352783, 17455.039972801616, 18341.90829045846, 18550.536236594195, 10189.184865210766, 10849.132069434427, 10859.674209773719, 10864.78270434587, 10874.130069594414, 7512.924870751441, 7633.923660763543, 7934.841303173789, 8177.6320065596, 8294.83410331802, 15884.52346429609, 16109.033457992558, 16145.273462694442, 16473.176341182654, 16862.494125176265, 16281.674366512367, 16336.836631634007, 16437.0, 16626.83373166253, 16766.24551895192, 10868.076213521623, 10907.018368346888, 10949.945250273699, 11027.22809403334, 11091.500882460146, 12230.388480576114, 12564.37178141071, 12567.120301579167, 12579.496820127093, 12644.747105058032, 9809.215262778818, 10232.283740138417, 10325.793484130425, 10593.417362045162, 10699.732506687395, 9538.904610953801, 10041.799164016533, 10157.288989770645, 10277.691669249934, 10337.844932326172, 8780.209781119729, 8811.383203175972, 8857.734267971971, 8897.599608017785, 8933.776655583662, 6167.907481387693, 6223.533235007483, 6514.218293804774, 6671.833204169935, 6700.832479187864, 6564.245111812203, 6609.0, 6956.721731130698, 7071.752488662872, 7073.151221853411], "value": 6266.976967590455, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.88207434432894, 496.3869621018042, 516.1741214057511, 530.0670819357929, 531.4287141323078, 6230.750769969207, 6234.0, 6260.060990851374, 6274.498040156782, 6340.873182536347, 5899.882002359961, 6061.6363018218935, 6062.757489700407, 6130.172426722391, 6142.4164704353125, 4926.482719314483, 4952.0, 4961.255811628249, 4978.278149668296, 5000.0999820032375, 4828.7585620719, 4973.527514886098, 4998.325560937073, 5007.2489126630935, 5018.197088465846, 4881.633877459187, 4910.803567857281, 4927.876803079916, 4931.137051016076, 4933.802647894098, 4950.153661644569, 4974.328465657126, 4985.975070124626, 5000.924986125211, 5021.84934451967, 4453.020335526179, 4455.955440445587, 4473.060657261979, 4473.575010374009, 4496.168208881368, 6013.7070529835855, 6095.542834287447, 6132.11084392765, 6151.87696246077, 6175.783847565359, 5533.418991005927, 5660.943390566124, 5685.204071429962, 5726.599138060337, 5729.914051289234, 6549.312322206148, 6588.275289718127, 6591.307912669148, 6606.1412016438035, 6616.27221005689, 4877.170880950247, 4897.131266299619, 4910.533499317559, 4912.115819152551, 4920.0, 6841.2132604750595, 6883.552569083094, 6905.620190889499, 6917.377436030782, 7029.086218791575, 4937.7777999990085, 4944.678595891256, 5021.221710634857, 5027.949720502748, 5031.698098114125, 5027.572656324253, 5059.114654935445, 5065.39215294167, 5078.7460626968505, 5123.641345105807, 5586.832395028156, 5635.267415236075, 5654.971725141348, 5724.198612194296, 5744.195812586241, 6528.543001989815, 6529.008652360981, 6530.575512591667, 6551.836204094936, 6598.86802263952, 1669.2655231698013, 1729.9740503892451, 1865.309835360925, 1932.7437165842034, 1939.6702560564738, 1415.1438379780084, 1426.8145141131586, 1433.5341014170435, 1444.7760597107256, 1461.576142918563, 5666.263385759825, 5692.857678558069, 5768.394318596487, 5779.133130030445, 5798.217240672539, 6693.397594216543, 6706.899396509057, 6719.798406047827, 6742.8651426972165, 6760.830979225559, 5706.1497726931775, 5739.0, 5755.280589926259, 5797.695518508386, 5813.505852002542, 3549.2369140634887, 3568.660977207161, 3595.209054008114, 3626.5104210931186, 3631.1648320886343, 6333.746650133943, 6447.7743278985945, 6454.225492940877, 6494.0, 6509.707063182168, 6634.436072933855, 6777.525573209828, 6796.592204467748, 6800.4219641330055, 6906.309369063053, 6402.775902843471, 6413.204302795225, 6435.292117867031, 6522.967385163044, 6551.739649820899, 6730.798076057726, 6731.427828634621, 6739.3835222872485, 6755.1556055493065, 6755.419627597381, 6470.773522926675, 6578.105633521235, 6608.669566521751, 6642.169728783902, 6677.399034086956, 6493.448056915215, 6534.183227096613, 6534.399072227335, 6544.640044797582, 6561.540692151694, 4982.825601103944, 4998.325226094431, 5007.949920500749, 5014.623903207347, 5061.848144555522, 6462.224533056064, 6565.80302590923, 6571.244306904767, 6574.276829548841, 6614.570052946543, 6167.475764560063, 6366.076918846648, 6373.745050198126, 6385.489160867027, 6446.0975463434215, 6367.42693157618, 6392.872142557216, 6416.32628573993, 6451.096926406627, 6530.281669016309, 6324.683765811599, 6356.968215158895, 6364.4908407326375, 6404.359564043559, 6421.848680691834, 6542.640154791532, 6546.770863019771, 6574.901376479453, 6579.736810527525, 6597.0, 6386.0304958194665, 6405.743770249138, 6408.58344207625, 6421.422072013541, 6436.841368553705], "value": 5068.568559282112, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.7288704058217, 893.1957445959158, 902.9277657787374, 902.9729108126757, 928.3779867488781, 16888.408905688302, 17991.37030203943, 18030.819691803095, 18044.368447104353, 21865.562688746217, 19639.312624058162, 20414.285500007503, 20681.689774653354, 20751.169953201854, 20762.96185190738, 961.0629636104812, 1006.0291818395248, 1009.6264382178604, 1011.044562888071, 1013.9797204055951, 1022.3712416863613, 1050.6637875879721, 1052.1319911073356, 1057.6456886942883, 1059.0256963593513, 1112.677323576162, 1120.1263014848432, 1123.309164863607, 1125.9324440533553, 1129.2941911305434, 1111.0778054215064, 1119.3843386137592, 1120.7702421003676, 1121.624255874283, 1122.9045531129864, 816.5998660656295, 819.1317203764029, 819.8360327934378, 820.0, 821.1296026212175, 18234.361797337162, 18377.724334135, 18403.907980460146, 18645.906770466197, 20339.593208135902, 20268.79731202684, 20715.482112947146, 21148.365549033555, 21160.153593856227, 21177.0, 15648.608784780357, 16487.34050637973, 16653.50039498817, 16668.166591670368, 16862.578435539086, 3559.5906470755936, 3616.529851119364, 3617.855285788565, 3619.149499867529, 3621.5835178954494, 121921.0, 125395.0, 126994.0, 129004.0, 130835.34582327123, 14850.480233191787, 15540.145292008821, 15620.297086631128, 15832.92083539598, 15947.36210551565, 30579.388412232074, 30597.847010764803, 30629.234269143006, 30656.69343306583, 30931.536026959613, 7516.323530882247, 7731.613419329124, 7792.493487923381, 8245.464044837066, 8389.790255243668, 4135.379693046068, 4314.460692413449, 4319.503257125408, 4321.222180007566, 4324.8486302979245, 5160.7935682573025, 5209.0, 5253.789848406097, 5308.708021058879, 5316.813911513079, 1066.4081434803604, 1083.7832433513329, 1094.6770702642739, 1094.8412480190327, 1098.3355070182424, 649.7433513762038, 653.8267359149829, 678.35217367414, 683.6195015647673, 698.7659134190002, 10552.788944221225, 11071.889281107247, 11773.941130294294, 11840.230385024946, 12448.688782780502, 646.444058110025, 677.1975209376905, 680.5746408494691, 693.0678237770292, 693.9271376505495, 5088.0078384714625, 5135.512126347991, 5147.1507201312115, 5200.76596553156, 5349.358077030781, 97888.0, 98148.0, 99165.50417248011, 100561.0, 106367.0, 83151.0, 85481.57259213789, 89495.10504894998, 90540.0, 90545.54727226454, 78525.60737196277, 79488.20511794924, 81807.0, 82206.58896705478, 85189.0, 11684.591039313584, 12010.399480026139, 12350.320732359807, 12392.938035309766, 12709.491620335082, 33873.15317117092, 35989.527857691144, 36030.8198459006, 36471.90584282476, 36651.0, 66005.33994660185, 66099.67800644056, 66376.0, 67257.66371168113, 71171.0, 17757.378491752726, 17838.464846054638, 18071.367502137364, 18106.18522166532, 18109.547261318574, 15255.923720381326, 18145.183466743765, 18818.24727010959, 20147.597048058662, 20512.38462846117, 18236.17937192803, 18337.449876503728, 18545.0, 18598.81401186025, 18903.43289701311, 17434.5641358962, 17675.292988280326, 17749.556261093578, 17811.732824007908, 18054.5486362842, 17315.567110821827, 17540.12299385, 17734.91132544329, 17781.377651782124, 18002.189901454727, 12077.51689932393, 12894.548690795777, 13126.803097953347, 13336.733265334833, 13591.932040339734, 13021.283829389475, 14397.784033239719, 14398.568042958728, 14403.711925761636, 14511.346989385715], "value": 8982.246121006867, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 111.0, 101.0, 110.0, 108.0, 110.0, 109.0, 100.0, 110.0, 109.0], "value": 106.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 148.0, 171.0, 116.0, 152.0, 141.0, 119.0, 167.0, 118.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 163.0, 164.0, 163.0, 164.0, 164.0, 168.0, 166.0, 163.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 74.0, 181.0, 75.0, 87.0, 75.0, 76.0, 179.0, 74.0], "value": 97.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 131.0, 80.0, 79.0, 80.0, 131.0, 79.0, 82.0, 135.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 115.0, 118.0, 115.0, 114.0, 113.0, 115.0, 114.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 151.0, 150.0, 151.0, 151.0, 151.0, 153.0, 152.0, 150.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 55.0, 54.0, 62.0, 54.0, 55.0, 56.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0, 55.0, 54.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 79.0, 78.0, 78.0, 78.0, 80.0, 78.0, 78.0, 80.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 132.0, 137.0, 130.0, 125.0, 150.0, 136.0, 129.0, 123.0, 157.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 175.0, 193.0, 185.0, 179.0, 181.0, 176.0, 180.0, 174.0, 181.0], "value": 180.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 68.0, 69.0, 75.0, 67.0, 71.0, 70.0, 67.0, 70.0], "value": 69.6, "unit": "score"}]}]} 03:13:30 INFO - Return code: 0 03:13:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:13:30 INFO - # TBPL SUCCESS # 03:13:30 INFO - Running post-action listener: _package_coverage_data 03:13:30 INFO - Running post-action listener: _resource_record_post_action 03:13:30 INFO - [mozharness: 2017-07-26 10:13:30.754617Z] Finished run-tests step (success) 03:13:30 INFO - Running post-run listener: _resource_record_post_run 03:13:30 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 435687424; Read time: 24; Write time: 316280 03:13:30 INFO - TinderboxPrint: CPU usage
13.1% 03:13:30 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 03:13:30 INFO - TinderboxPrint: I/O write bytes / time
435,687,424 / 316,280 03:13:30 INFO - TinderboxPrint: CPU idle
8,260.1 (86.8%) 03:13:30 INFO - TinderboxPrint: CPU user
1,201.2 (12.6%) 03:13:30 INFO - TinderboxPrint: Swap in / out
0 / 0 03:13:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107737088; Read time: 0; Write time: 97036 03:13:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:13:30 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327950336; Read time: 12; Write time: 219244 03:13:31 INFO - Running post-run listener: _upload_blobber_files 03:13:31 INFO - Blob upload gear active. 03:13:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:13:31 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:13:31 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:13:31 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:13:31 INFO - (blobuploader) - INFO - Open directory for files ... 03:13:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:13:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:13:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:13:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:13:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:13:35 INFO - (blobuploader) - INFO - Done attempting. 03:13:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:13:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:13:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:13:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:13:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:13:35 INFO - (blobuploader) - INFO - Done attempting. 03:13:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:13:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:13:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:13:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:13:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:13:36 INFO - (blobuploader) - INFO - Done attempting. 03:13:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:13:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:13:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:13:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:13:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:13:37 INFO - (blobuploader) - INFO - Done attempting. 03:13:37 INFO - (blobuploader) - INFO - Iteration through files over. 03:13:37 INFO - Return code: 0 03:13:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:13:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:13:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0e8cbf05edf691181423f6273a20a777455e49f458210bb7c2d758169b4af270dddd725ea939ec094386bf44ad11cbc9b0824ad078472d2baba65af361e249c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b4d97e975b28193c745e8ba7fc45fcf9118babc0825f8e5b0d3d6012ec061f4f53cac82f4300b00da03459657b9ef8bd69cc56fc13886cc53890db4f81b98ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c948a70e95ca6ecb9491775af1151afd0a97bc7a999812a26586d65597cf0fee9a10392f850b406c1675f2ba81be65fdddff0799cee2fa166e884ff6975ec18e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/05750e858acc65b78be4b194f552241e57a1ad8e87cd5ec6deee0bd55a279881c24388453c7824f462faf77a5e2a6908853aae6cf3ac1c482d853a9ab87a34d2"} 03:13:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:13:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:13:37 INFO - Contents: 03:13:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0e8cbf05edf691181423f6273a20a777455e49f458210bb7c2d758169b4af270dddd725ea939ec094386bf44ad11cbc9b0824ad078472d2baba65af361e249c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b4d97e975b28193c745e8ba7fc45fcf9118babc0825f8e5b0d3d6012ec061f4f53cac82f4300b00da03459657b9ef8bd69cc56fc13886cc53890db4f81b98ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c948a70e95ca6ecb9491775af1151afd0a97bc7a999812a26586d65597cf0fee9a10392f850b406c1675f2ba81be65fdddff0799cee2fa166e884ff6975ec18e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/05750e858acc65b78be4b194f552241e57a1ad8e87cd5ec6deee0bd55a279881c24388453c7824f462faf77a5e2a6908853aae6cf3ac1c482d853a9ab87a34d2"} 03:13:37 INFO - Running post-run listener: copy_logs_to_upload_dir 03:13:37 INFO - Copying logs to upload dir... 03:13:37 INFO - mkdir: /builds/slave/test/build/upload/logs 03:13:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.446839 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-07-26 03:13:36.552444) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-26 03:13:36.559956) ========= 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=51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431 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/d0e8cbf05edf691181423f6273a20a777455e49f458210bb7c2d758169b4af270dddd725ea939ec094386bf44ad11cbc9b0824ad078472d2baba65af361e249c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b4d97e975b28193c745e8ba7fc45fcf9118babc0825f8e5b0d3d6012ec061f4f53cac82f4300b00da03459657b9ef8bd69cc56fc13886cc53890db4f81b98ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c948a70e95ca6ecb9491775af1151afd0a97bc7a999812a26586d65597cf0fee9a10392f850b406c1675f2ba81be65fdddff0799cee2fa166e884ff6975ec18e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/05750e858acc65b78be4b194f552241e57a1ad8e87cd5ec6deee0bd55a279881c24388453c7824f462faf77a5e2a6908853aae6cf3ac1c482d853a9ab87a34d2"} build_url:https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/J3w66787QiyxdpLlfODfTA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0e8cbf05edf691181423f6273a20a777455e49f458210bb7c2d758169b4af270dddd725ea939ec094386bf44ad11cbc9b0824ad078472d2baba65af361e249c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b4d97e975b28193c745e8ba7fc45fcf9118babc0825f8e5b0d3d6012ec061f4f53cac82f4300b00da03459657b9ef8bd69cc56fc13886cc53890db4f81b98ab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c948a70e95ca6ecb9491775af1151afd0a97bc7a999812a26586d65597cf0fee9a10392f850b406c1675f2ba81be65fdddff0799cee2fa166e884ff6975ec18e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/05750e858acc65b78be4b194f552241e57a1ad8e87cd5ec6deee0bd55a279881c24388453c7824f462faf77a5e2a6908853aae6cf3ac1c482d853a9ab87a34d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-26 03:13:36.587177) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 03:13:36.587519) ========= 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=51fc86400edbde9650d2781a000001d0-1501059238.603109-1739209431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004402 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-26 03:13:36.669395) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-26 03:13:36.672744) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-26 03:13:36.673058) ========= ========= Total master_lag: 0.19 =========