builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-011 starttime: 1506054925.82 results: success (0) revision: 97282b0c985aa1778ced171514d1ae61945c634c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:25.821957) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:25.822343) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:25.822604) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:25.884823) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:25.885112) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 21:35:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.0M=0s 2017-09-21 21:35:26 (37.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.211906 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:26.140695) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:26.140981) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015356 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:26.198892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:26.199214) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 97282b0c985aa1778ced171514d1ae61945c634c --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 97282b0c985aa1778ced171514d1ae61945c634c --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=5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 21:35:26,280 truncating revision to first 12 chars 2017-09-21 21:35:26,281 Setting DEBUG logging. 2017-09-21 21:35:26,281 attempt 1/10 2017-09-21 21:35:26,281 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/97282b0c985a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 21:35:26,583 unpacking tar archive at: mozilla-central-97282b0c985a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.574058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:26.797035) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:26.797310) ========= script_repo_revision: 97282b0c985aa1778ced171514d1ae61945c634c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:26.797667) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:26.797939) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 21:35:26.829269) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-21 21:35:26.829543) ========= /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=5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:35:27 INFO - MultiFileLogger online at 20170921 21:35:27 in /builds/slave/test 21:35:27 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 21:35:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:35:27 INFO - {'append_to_log': False, 21:35:27 INFO - 'base_work_dir': '/builds/slave/test', 21:35:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:35:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:35:27 INFO - 'branch': 'Firefox-Non-PGO', 21:35:27 INFO - 'buildbot_json_path': 'buildprops.json', 21:35:27 INFO - 'code_coverage': False, 21:35:27 INFO - 'config_files': ('talos/linux_config.py',), 21:35:27 INFO - 'default_actions': ('clobber', 21:35:27 INFO - 'read-buildbot-config', 21:35:27 INFO - 'download-and-extract', 21:35:27 INFO - 'populate-webroot', 21:35:27 INFO - 'create-virtualenv', 21:35:27 INFO - 'install', 21:35:27 INFO - 'setup-mitmproxy', 21:35:27 INFO - 'run-tests'), 21:35:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:35:27 INFO - 'disable_ccov_upload': False, 21:35:27 INFO - 'disable_stylo': False, 21:35:27 INFO - 'download_minidump_stackwalk': True, 21:35:27 INFO - 'download_symbols': 'ondemand', 21:35:27 INFO - 'enable_stylo': False, 21:35:27 INFO - 'enable_webrender': False, 21:35:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 21:35:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:35:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:35:27 INFO - 'gecko_profile': False, 21:35:27 INFO - 'gecko_profile_interval': 0, 21:35:27 INFO - 'installer_path': 'installer.exe', 21:35:27 INFO - 'log_level': 'info', 21:35:27 INFO - 'log_name': 'talos', 21:35:27 INFO - 'log_to_console': True, 21:35:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:35:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:35:27 INFO - 'opt_config_files': (), 21:35:27 INFO - 'pip_index': False, 21:35:27 INFO - 'suite': 'dromaeojs-e10s', 21:35:27 INFO - 'system_bits': '32', 21:35:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:35:27 INFO - 'title': 'talos-linux64-ix-011', 21:35:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:35:27 INFO - 'use_talos_json': True, 21:35:27 INFO - 'verify': 'False', 21:35:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:35:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:35:27 INFO - 'work_dir': 'build'} 21:35:27 INFO - [mozharness: 2017-09-22 04:35:27.141130Z] Running clobber step. 21:35:27 INFO - Running pre-action listener: _resource_record_pre_action 21:35:27 INFO - Running main action method: clobber 21:35:27 INFO - rmtree: /builds/slave/test/build 21:35:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:35:28 INFO - Running post-action listener: _resource_record_post_action 21:35:28 INFO - [mozharness: 2017-09-22 04:35:28.093729Z] Finished clobber step (success) 21:35:28 INFO - [mozharness: 2017-09-22 04:35:28.093825Z] Running read-buildbot-config step. 21:35:28 INFO - Running pre-action listener: _resource_record_pre_action 21:35:28 INFO - Running main action method: read_buildbot_config 21:35:28 INFO - Using buildbot properties: 21:35:28 INFO - { 21:35:28 INFO - "project": "", 21:35:28 INFO - "product": "firefox", 21:35:28 INFO - "who": "philringnalda@gmail.com", 21:35:28 INFO - "installer_path": "public/build/target.tar.bz2", 21:35:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:35:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 21:35:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 21:35:28 INFO - "stage_platform": "linux64", 21:35:28 INFO - "basedir": "/builds/slave/test", 21:35:28 INFO - "buildnumber": 853, 21:35:28 INFO - "platform": "ubuntu64_hw", 21:35:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:35:28 INFO - "slavebuilddir": "test", 21:35:28 INFO - "taskId": "bQo6BLUKQKOhDTn6c5WslA", 21:35:28 INFO - "branch": "mozilla-central", 21:35:28 INFO - "script_repo_revision": "production", 21:35:28 INFO - "revision": "97282b0c985aa1778ced171514d1ae61945c634c", 21:35:28 INFO - "slavename": "talos-linux64-ix-011", 21:35:28 INFO - "repo_path": "mozilla-central" 21:35:28 INFO - } 21:35:28 INFO - Finding installer, test and symbols from parent task. 21:35:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bQo6BLUKQKOhDTn6c5WslA'}, attempt #1 21:35:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bQo6BLUKQKOhDTn6c5WslA'}, attempt #1 21:35:29 INFO - Task dependencies: Qc4qapSdREq4to_uIRkeSw 21:35:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw'}, attempt #1 21:35:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.tar.bz2 21:35:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.test_packages.json 21:35:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.crashreporter-symbols.zip 21:35:29 INFO - Running post-action listener: _resource_record_post_action 21:35:29 INFO - [mozharness: 2017-09-22 04:35:29.516468Z] Finished read-buildbot-config step (success) 21:35:29 INFO - [mozharness: 2017-09-22 04:35:29.516685Z] Running download-and-extract step. 21:35:29 INFO - Running pre-action listener: _resource_record_pre_action 21:35:29 INFO - Running main action method: download_and_extract 21:35:29 INFO - mkdir: /builds/slave/test/build/tests 21:35:29 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')]} 21:35:29 INFO - trying https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.test_packages.json 21:35:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:35:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:35:30 INFO - Downloaded 989 bytes. 21:35:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:35:30 INFO - Using the following test package requirements: 21:35:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:35:30 INFO - u'common': [u'target.common.tests.zip'], 21:35:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:35:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:35:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:35:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:35:30 INFO - u'mozbase': [u'target.common.tests.zip'], 21:35:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:35:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:35:30 INFO - u'web-platform': [u'target.common.tests.zip', 21:35:30 INFO - u'target.web-platform.tests.tar.gz'], 21:35:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:35:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 21:35:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.common.tests.zip 21:35:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:35:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.common.tests.zip into memory 21:35:31 INFO - Content-Length response header: 54739760 21:35:31 INFO - Bytes received: 54739760 21:35:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.talos.tests.zip 21:35:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:35:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.talos.tests.zip into memory 21:35:43 INFO - Content-Length response header: 14513414 21:35:43 INFO - Bytes received: 14513414 21:35:43 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')]} 21:35:43 INFO - trying https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.tar.bz2 21:35:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:35:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:35:45 INFO - Downloaded 61709000 bytes. 21:35:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.tar.bz2 21:35:45 INFO - mkdir: /builds/slave/test/properties 21:35:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:35:45 INFO - Writing to file /builds/slave/test/properties/build_url 21:35:45 INFO - Contents: 21:35:45 INFO - build_url:https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.tar.bz2 21:35:45 INFO - Running post-action listener: _resource_record_post_action 21:35:45 INFO - Running post-action listener: find_tests_for_verification 21:35:45 INFO - Running post-action listener: set_extra_try_arguments 21:35:45 INFO - [mozharness: 2017-09-22 04:35:45.579093Z] Finished download-and-extract step (success) 21:35:45 INFO - [mozharness: 2017-09-22 04:35:45.579207Z] Running populate-webroot step. 21:35:45 INFO - Running pre-action listener: _resource_record_pre_action 21:35:45 INFO - Running main action method: populate_webroot 21:35:45 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:35:45 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['tresize', 21:35:45 INFO - 'tcanvasmark']}, 21:35:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:35:45 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['dromaeo_css', 21:35:45 INFO - 'kraken']}, 21:35:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:35:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:35:45 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:35:45 INFO - 'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:35:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:35:45 INFO - 'tests': ['damp', 'tps']}, 21:35:45 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:35:45 INFO - 'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['damp', 'tps']}, 21:35:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:35:45 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['dromaeo_dom']}, 21:35:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:35:45 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['basic_compositor_video', 21:35:45 INFO - 'glvideo']}, 21:35:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:35:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:35:45 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:35:45 INFO - 'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['ts_paint_webext', 21:35:45 INFO - 'tp5o_webext']}, 21:35:45 INFO - 'other-e10s': {'tests': ['a11yr', 21:35:45 INFO - 'ts_paint', 21:35:45 INFO - 'tpaint', 21:35:45 INFO - 'sessionrestore', 21:35:45 INFO - 'sessionrestore_many_windows', 21:35:45 INFO - 'sessionrestore_no_auto_restore', 21:35:45 INFO - 'tabpaint']}, 21:35:45 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['a11yr', 21:35:45 INFO - 'ts_paint', 21:35:45 INFO - 'tpaint', 21:35:45 INFO - 'sessionrestore', 21:35:45 INFO - 'sessionrestore_many_windows', 21:35:45 INFO - 'sessionrestore_no_auto_restore', 21:35:45 INFO - 'tabpaint']}, 21:35:45 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 21:35:45 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 21:35:45 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['perf_reftest_singletons']}, 21:35:45 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['perf_reftest']}, 21:35:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:35:45 INFO - 'tsvgr_opacity', 21:35:45 INFO - 'tart', 21:35:45 INFO - 'tscrollx', 21:35:45 INFO - 'tsvg_static']}, 21:35:45 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['tsvgx', 21:35:45 INFO - 'tsvgr_opacity', 21:35:45 INFO - 'tart', 21:35:45 INFO - 'tscrollx', 21:35:45 INFO - 'tsvg_static']}, 21:35:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:35:45 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:35:45 INFO - 'talos_options': ['--disable-stylo'], 21:35:45 INFO - 'tests': ['tp5o']}, 21:35:45 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:35:45 INFO - 'talos_options': ['--mitmproxy', 21:35:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:35:45 INFO - '--firstNonBlankPaint'], 21:35:45 INFO - 'tests': ['tp6_google', 21:35:45 INFO - 'tp6_youtube', 21:35:45 INFO - 'tp6_amazon', 21:35:45 INFO - 'tp6_facebook']}, 21:35:45 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:35:45 INFO - 'talos_options': ['--disable-stylo', 21:35:45 INFO - '--mitmproxy', 21:35:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:35:45 INFO - '--firstNonBlankPaint'], 21:35:45 INFO - 'tests': ['tp6_google', 21:35:45 INFO - 'tp6_youtube', 21:35:45 INFO - 'tp6_amazon', 21:35:45 INFO - 'tp6_facebook']}, 21:35:45 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:35:45 INFO - 'talos_options': ['--stylo-threads=1', 21:35:45 INFO - '--mitmproxy', 21:35:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:35:45 INFO - '--firstNonBlankPaint'], 21:35:45 INFO - 'tests': ['tp6_google', 21:35:45 INFO - 'tp6_youtube', 21:35:45 INFO - 'tp6_amazon', 21:35:45 INFO - 'tp6_facebook']}, 21:35:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:35:45 INFO - 'talos_options': ['--xperf_path', 21:35:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:35:45 INFO - 'tests': ['tp5n']}, 21:35:45 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:35:45 INFO - 'talos_options': ['--disable-stylo', 21:35:45 INFO - '--xperf_path', 21:35:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:35:45 INFO - 'tests': ['tp5n']}}} 21:35:45 INFO - Running post-action listener: _resource_record_post_action 21:35:45 INFO - [mozharness: 2017-09-22 04:35:45.595917Z] Finished populate-webroot step (success) 21:35:45 INFO - [mozharness: 2017-09-22 04:35:45.596006Z] Running create-virtualenv step. 21:35:45 INFO - Running pre-action listener: _resource_record_pre_action 21:35:45 INFO - Running main action method: create_virtualenv 21:35:45 INFO - Creating virtualenv /builds/slave/test/build/venv 21:35:45 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:35:45 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 21:35:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:35:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:35:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:35:46 INFO - Installing distribute.............................................................................................................................................................................................done. 21:35:48 INFO - Installing pip...............done. 21:35:48 INFO - Return code: 0 21:35:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:35:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:35:49 INFO - Reading from file tmpfile_stdout 21:35:49 INFO - Output received: 21:35:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 21:35:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:35:49 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')]} 21:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:49 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')]} 21:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:35:49 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 21:35:49 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 21:35:49 INFO - Using env: {'DISPLAY': ':0', 21:35:49 INFO - 'HOME': '/home/cltbld', 21:35:49 INFO - 'LANG': 'en_US.UTF-8', 21:35:49 INFO - 'LANGUAGE': 'en_US:en', 21:35:49 INFO - 'LOGNAME': 'cltbld', 21:35:49 INFO - 'MAIL': '/var/mail/cltbld', 21:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:49 INFO - 'MOZ_NO_REMOTE': '1', 21:35:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:49 INFO - 'NO_EM_RESTART': '1', 21:35:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:49 INFO - 'PWD': '/builds/slave/test', 21:35:49 INFO - 'SHELL': '/bin/bash', 21:35:49 INFO - 'SHLVL': '1', 21:35:49 INFO - 'TERM': 'linux', 21:35:49 INFO - 'TMOUT': '86400', 21:35:49 INFO - 'USER': 'cltbld', 21:35:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:49 INFO - '_': '/tools/buildbot/bin/python'} 21:35:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:35:51 INFO - Downloading/unpacking pip>=1.5 21:35:51 INFO - Running setup.py egg_info for package pip 21:35:51 INFO - warning: no files found matching 'pip/cacert.pem' 21:35:51 INFO - warning: no files found matching '*.html' under directory 'docs' 21:35:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:35:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:35:51 INFO - Installing collected packages: pip 21:35:51 INFO - Found existing installation: pip 1.1 21:35:51 INFO - Uninstalling pip: 21:35:51 INFO - Successfully uninstalled pip 21:35:51 INFO - Running setup.py install for pip 21:35:51 INFO - warning: no files found matching 'pip/cacert.pem' 21:35:51 INFO - warning: no files found matching '*.html' under directory 'docs' 21:35:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:35:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:35:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 21:35:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 21:35:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 21:35:51 INFO - Successfully installed pip 21:35:51 INFO - Cleaning up... 21:35:51 INFO - Return code: 0 21:35:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:35:51 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')]} 21:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:51 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')]} 21:35:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:51 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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:35:51 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 21:35:51 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 21:35:51 INFO - Using env: {'DISPLAY': ':0', 21:35:51 INFO - 'HOME': '/home/cltbld', 21:35:51 INFO - 'LANG': 'en_US.UTF-8', 21:35:51 INFO - 'LANGUAGE': 'en_US:en', 21:35:51 INFO - 'LOGNAME': 'cltbld', 21:35:51 INFO - 'MAIL': '/var/mail/cltbld', 21:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:51 INFO - 'MOZ_NO_REMOTE': '1', 21:35:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:51 INFO - 'NO_EM_RESTART': '1', 21:35:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:51 INFO - 'PWD': '/builds/slave/test', 21:35:51 INFO - 'SHELL': '/bin/bash', 21:35:51 INFO - 'SHLVL': '1', 21:35:51 INFO - 'TERM': 'linux', 21:35:51 INFO - 'TMOUT': '86400', 21:35:51 INFO - 'USER': 'cltbld', 21:35:51 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:51 INFO - '_': '/tools/buildbot/bin/python'} 21:35:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:51 INFO - Downloading/unpacking psutil>=3.1.1 21:35:51 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 21:35:51 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 21:35:51 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 21:35:51 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 21:35:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:35:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:35:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:35:52 INFO - Installing collected packages: psutil 21:35:52 INFO - Running setup.py install for psutil 21:35:52 INFO - building 'psutil._psutil_linux' extension 21:35:52 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 21:35:53 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 21:35:53 INFO - building 'psutil._psutil_posix' extension 21:35:53 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 21:35:54 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 21:35:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:35:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:35:54 INFO - Successfully installed psutil 21:35:54 INFO - Cleaning up... 21:35:54 INFO - Return code: 0 21:35:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:35:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:35:54 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 21:35:54 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 21:35:54 INFO - Using env: {'DISPLAY': ':0', 21:35:54 INFO - 'HOME': '/home/cltbld', 21:35:54 INFO - 'LANG': 'en_US.UTF-8', 21:35:54 INFO - 'LANGUAGE': 'en_US:en', 21:35:54 INFO - 'LOGNAME': 'cltbld', 21:35:54 INFO - 'MAIL': '/var/mail/cltbld', 21:35:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:54 INFO - 'MOZ_NO_REMOTE': '1', 21:35:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:54 INFO - 'NO_EM_RESTART': '1', 21:35:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:54 INFO - 'PWD': '/builds/slave/test', 21:35:54 INFO - 'SHELL': '/bin/bash', 21:35:54 INFO - 'SHLVL': '1', 21:35:54 INFO - 'TERM': 'linux', 21:35:54 INFO - 'TMOUT': '86400', 21:35:54 INFO - 'USER': 'cltbld', 21:35:54 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:35:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:54 INFO - '_': '/tools/buildbot/bin/python'} 21:35:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:35:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:35:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:35:55 INFO - Installing collected packages: mozsystemmonitor 21:35:55 INFO - Running setup.py install for mozsystemmonitor 21:35:55 INFO - Successfully installed mozsystemmonitor 21:35:55 INFO - Cleaning up... 21:35:55 INFO - Return code: 0 21:35:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:35: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')]} 21:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35: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')]} 21:35:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:55 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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:35:55 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 21:35:55 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 21:35:55 INFO - Using env: {'DISPLAY': ':0', 21:35:55 INFO - 'HOME': '/home/cltbld', 21:35:55 INFO - 'LANG': 'en_US.UTF-8', 21:35:55 INFO - 'LANGUAGE': 'en_US:en', 21:35:55 INFO - 'LOGNAME': 'cltbld', 21:35:55 INFO - 'MAIL': '/var/mail/cltbld', 21:35:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:55 INFO - 'MOZ_NO_REMOTE': '1', 21:35:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:55 INFO - 'NO_EM_RESTART': '1', 21:35:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:55 INFO - 'PWD': '/builds/slave/test', 21:35:55 INFO - 'SHELL': '/bin/bash', 21:35:55 INFO - 'SHLVL': '1', 21:35:55 INFO - 'TERM': 'linux', 21:35:55 INFO - 'TMOUT': '86400', 21:35:55 INFO - 'USER': 'cltbld', 21:35:55 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:35:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:55 INFO - '_': '/tools/buildbot/bin/python'} 21:35:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:55 INFO - Downloading/unpacking jsonschema==2.5.1 21:35:55 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 21:35:55 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 21:35:55 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 21:35:55 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 21:35:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:35:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:57 INFO - Downloading functools32-3.2.3-2.tar.gz 21:35:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:35:57 INFO - warning: no files found matching '*.txt' 21:35:57 INFO - no previously-included directories found matching 'build' 21:35:57 INFO - no previously-included directories found matching 'dist' 21:35:57 INFO - no previously-included directories found matching '.git*' 21:35:57 INFO - Installing collected packages: jsonschema, functools32 21:35:57 INFO - Running setup.py install for functools32 21:35:57 INFO - warning: no files found matching '*.txt' 21:35:57 INFO - no previously-included directories found matching 'build' 21:35:57 INFO - no previously-included directories found matching 'dist' 21:35:57 INFO - no previously-included directories found matching '.git*' 21:35:57 INFO - Successfully installed jsonschema functools32 21:35:57 INFO - Cleaning up... 21:35:57 INFO - Return code: 0 21:35:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:35:57 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')]} 21:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:57 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')]} 21:35:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:35:57 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 21:35:57 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 21:35:57 INFO - Using env: {'DISPLAY': ':0', 21:35:57 INFO - 'HOME': '/home/cltbld', 21:35:57 INFO - 'LANG': 'en_US.UTF-8', 21:35:57 INFO - 'LANGUAGE': 'en_US:en', 21:35:57 INFO - 'LOGNAME': 'cltbld', 21:35:57 INFO - 'MAIL': '/var/mail/cltbld', 21:35:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:57 INFO - 'MOZ_NO_REMOTE': '1', 21:35:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:57 INFO - 'NO_EM_RESTART': '1', 21:35:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:57 INFO - 'PWD': '/builds/slave/test', 21:35:57 INFO - 'SHELL': '/bin/bash', 21:35:57 INFO - 'SHLVL': '1', 21:35:57 INFO - 'TERM': 'linux', 21:35:57 INFO - 'TMOUT': '86400', 21:35:57 INFO - 'USER': 'cltbld', 21:35:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:35:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:57 INFO - '_': '/tools/buildbot/bin/python'} 21:35:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:35:57 INFO - Cleaning up... 21:35:57 INFO - Return code: 0 21:35:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:35:57 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')]} 21:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:35:57 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')]} 21:35:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:35:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:35:57 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 21:35:57 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 21:35:57 INFO - Using env: {'DISPLAY': ':0', 21:35:57 INFO - 'HOME': '/home/cltbld', 21:35:57 INFO - 'LANG': 'en_US.UTF-8', 21:35:57 INFO - 'LANGUAGE': 'en_US:en', 21:35:57 INFO - 'LOGNAME': 'cltbld', 21:35:57 INFO - 'MAIL': '/var/mail/cltbld', 21:35:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:57 INFO - 'MOZ_NO_REMOTE': '1', 21:35:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:35:57 INFO - 'NO_EM_RESTART': '1', 21:35:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:35:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:57 INFO - 'PWD': '/builds/slave/test', 21:35:57 INFO - 'SHELL': '/bin/bash', 21:35:57 INFO - 'SHLVL': '1', 21:35:57 INFO - 'TERM': 'linux', 21:35:57 INFO - 'TMOUT': '86400', 21:35:57 INFO - 'USER': 'cltbld', 21:35:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:35:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:57 INFO - '_': '/tools/buildbot/bin/python'} 21:35:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:35:57 INFO - Downloading/unpacking blobuploader==1.2.4 21:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:59 INFO - Downloading blobuploader-1.2.4.tar.gz 21:35:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:35:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:35:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:35:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:35:59 INFO - Downloading docopt-0.6.1.tar.gz 21:35:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:35:59 INFO - Installing collected packages: blobuploader, requests, docopt 21:35:59 INFO - Running setup.py install for blobuploader 21:35:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:35:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:35:59 INFO - Running setup.py install for requests 21:36:00 INFO - Running setup.py install for docopt 21:36:00 INFO - Successfully installed blobuploader requests docopt 21:36:00 INFO - Cleaning up... 21:36:00 INFO - Return code: 0 21:36:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:36:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:36:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:36:00 INFO - Using env: {'DISPLAY': ':0', 21:36:00 INFO - 'HOME': '/home/cltbld', 21:36:00 INFO - 'LANG': 'en_US.UTF-8', 21:36:00 INFO - 'LANGUAGE': 'en_US:en', 21:36:00 INFO - 'LOGNAME': 'cltbld', 21:36:00 INFO - 'MAIL': '/var/mail/cltbld', 21:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:00 INFO - 'MOZ_NO_REMOTE': '1', 21:36:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:00 INFO - 'NO_EM_RESTART': '1', 21:36:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:00 INFO - 'PWD': '/builds/slave/test', 21:36:00 INFO - 'SHELL': '/bin/bash', 21:36:00 INFO - 'SHLVL': '1', 21:36:00 INFO - 'TERM': 'linux', 21:36:00 INFO - 'TMOUT': '86400', 21:36:00 INFO - 'USER': 'cltbld', 21:36:00 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:00 INFO - '_': '/tools/buildbot/bin/python'} 21:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:36:00 INFO - Running setup.py (path:/tmp/pip-fe9BzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:36:00 INFO - Running setup.py (path:/tmp/pip-zZiiTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:36:00 INFO - Running setup.py (path:/tmp/pip-7oYphn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:36:00 INFO - Running setup.py (path:/tmp/pip-o5k2MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:36:00 INFO - Running setup.py (path:/tmp/pip-W5IQfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:36:00 INFO - Running setup.py (path:/tmp/pip-mA__9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:36:00 INFO - Running setup.py (path:/tmp/pip-5duHTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:36:00 INFO - Running setup.py (path:/tmp/pip-nRtgvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:36:00 INFO - Running setup.py (path:/tmp/pip-mW1QMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:36:01 INFO - Running setup.py (path:/tmp/pip-DY3YP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:36:01 INFO - Running setup.py (path:/tmp/pip-wIy1LR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:36:01 INFO - Running setup.py (path:/tmp/pip-YntKNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:36:01 INFO - Running setup.py (path:/tmp/pip-i8vQqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:36:01 INFO - Running setup.py (path:/tmp/pip-9EsNBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:01 INFO - Running setup.py (path:/tmp/pip-Vs5uUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:36:01 INFO - Running setup.py (path:/tmp/pip-7UTl0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:36:01 INFO - Running setup.py (path:/tmp/pip-xn7fRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:36:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:36:01 INFO - Running setup.py install for manifestparser 21:36:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:36:01 INFO - Running setup.py install for mozcrash 21:36:01 INFO - Running setup.py install for mozdebug 21:36:01 INFO - Running setup.py install for mozdevice 21:36:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Running setup.py install for mozfile 21:36:02 INFO - Running setup.py install for mozhttpd 21:36:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Running setup.py install for mozinfo 21:36:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Running setup.py install for mozInstall 21:36:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Running setup.py install for mozleak 21:36:02 INFO - Running setup.py install for mozlog 21:36:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Running setup.py install for moznetwork 21:36:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Running setup.py install for mozprocess 21:36:02 INFO - Running setup.py install for mozprofile 21:36:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:36:02 INFO - Running setup.py install for mozrunner 21:36:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:36:03 INFO - Running setup.py install for mozscreenshot 21:36:03 INFO - Running setup.py install for moztest 21:36:03 INFO - Running setup.py install for mozversion 21:36:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:36:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:36:03 INFO - Cleaning up... 21:36:03 INFO - Return code: 0 21:36:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:03 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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:36:03 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 21:36:03 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 21:36:03 INFO - Using env: {'DISPLAY': ':0', 21:36:03 INFO - 'HOME': '/home/cltbld', 21:36:03 INFO - 'LANG': 'en_US.UTF-8', 21:36:03 INFO - 'LANGUAGE': 'en_US:en', 21:36:03 INFO - 'LOGNAME': 'cltbld', 21:36:03 INFO - 'MAIL': '/var/mail/cltbld', 21:36:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:03 INFO - 'MOZ_NO_REMOTE': '1', 21:36:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:03 INFO - 'NO_EM_RESTART': '1', 21:36:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:03 INFO - 'PWD': '/builds/slave/test', 21:36:03 INFO - 'SHELL': '/bin/bash', 21:36:03 INFO - 'SHLVL': '1', 21:36:03 INFO - 'TERM': 'linux', 21:36:03 INFO - 'TMOUT': '86400', 21:36:03 INFO - 'USER': 'cltbld', 21:36:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:36:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:03 INFO - '_': '/tools/buildbot/bin/python'} 21:36:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:36:03 INFO - Running setup.py (path:/tmp/pip-7eWxJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:36:03 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)) 21:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:36:03 INFO - Running setup.py (path:/tmp/pip-tVOUiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:36:03 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)) 21:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:36:03 INFO - Running setup.py (path:/tmp/pip-PZ9fMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:36:03 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)) 21:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:36:03 INFO - Running setup.py (path:/tmp/pip-5dK170-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:36:03 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)) 21:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:36:03 INFO - Running setup.py (path:/tmp/pip-2Wo_tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:36:03 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)) 21:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:36:03 INFO - Running setup.py (path:/tmp/pip-GrkZ_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:36:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:36:04 INFO - Running setup.py (path:/tmp/pip-sJAcy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:36:04 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)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:36:04 INFO - Running setup.py (path:/tmp/pip-461GYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:36:04 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)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:36:04 INFO - Running setup.py (path:/tmp/pip-2u4OGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:36:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:36:04 INFO - Running setup.py (path:/tmp/pip-NxiSQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:36:04 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)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:36:04 INFO - Running setup.py (path:/tmp/pip-ZWM4kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:36:04 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)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:36:04 INFO - Running setup.py (path:/tmp/pip-dzMM9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:36:04 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)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:36:04 INFO - Running setup.py (path:/tmp/pip-g3ftIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:36:04 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)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:36:04 INFO - Running setup.py (path:/tmp/pip-XbdEav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:36:04 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)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:04 INFO - Running setup.py (path:/tmp/pip-bhJs8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:36:04 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)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:36:04 INFO - Running setup.py (path:/tmp/pip-PM2DYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:36:04 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)) 21:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:36:04 INFO - Running setup.py (path:/tmp/pip-p0ka0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:36:04 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)) 21:36:04 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)) 21:36:04 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)) 21:36:04 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)) 21:36:04 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)) 21:36:04 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)) 21:36:04 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)) 21:36:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:05 INFO - Downloading blessings-1.6.tar.gz 21:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:36:06 INFO - Installing collected packages: blessings 21:36:06 INFO - Running setup.py install for blessings 21:36:06 INFO - Successfully installed blessings 21:36:06 INFO - Cleaning up... 21:36:06 INFO - Return code: 0 21:36:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:36:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:36:06 INFO - Reading from file tmpfile_stdout 21:36:06 INFO - Current package versions: 21:36:06 INFO - argparse == 1.2.1 21:36:06 INFO - blessings == 1.6 21:36:06 INFO - blobuploader == 1.2.4 21:36:06 INFO - docopt == 0.6.1 21:36:06 INFO - functools32 == 3.2.3-2 21:36:06 INFO - jsonschema == 2.5.1 21:36:06 INFO - manifestparser == 1.1 21:36:06 INFO - mozInstall == 1.13 21:36:06 INFO - mozcrash == 1.0 21:36:06 INFO - mozdebug == 0.1 21:36:06 INFO - mozdevice == 0.51 21:36:06 INFO - mozfile == 1.2 21:36:06 INFO - mozhttpd == 0.7 21:36:06 INFO - mozinfo == 0.10 21:36:06 INFO - mozleak == 0.1 21:36:06 INFO - mozlog == 3.5 21:36:06 INFO - moznetwork == 0.27 21:36:06 INFO - mozprocess == 0.25 21:36:06 INFO - mozprofile == 0.28 21:36:06 INFO - mozrunner == 6.13 21:36:06 INFO - mozscreenshot == 0.1 21:36:06 INFO - mozsystemmonitor == 0.3 21:36:06 INFO - moztest == 0.8 21:36:06 INFO - mozversion == 1.4 21:36:06 INFO - psutil == 3.1.1 21:36:06 INFO - requests == 1.2.3 21:36:06 INFO - wsgiref == 0.1.2 21:36:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:06 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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:36:06 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 21:36:06 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 21:36:06 INFO - Using env: {'DISPLAY': ':0', 21:36:06 INFO - 'HOME': '/home/cltbld', 21:36:06 INFO - 'LANG': 'en_US.UTF-8', 21:36:06 INFO - 'LANGUAGE': 'en_US:en', 21:36:06 INFO - 'LOGNAME': 'cltbld', 21:36:06 INFO - 'MAIL': '/var/mail/cltbld', 21:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:06 INFO - 'MOZ_NO_REMOTE': '1', 21:36:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:06 INFO - 'NO_EM_RESTART': '1', 21:36:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:06 INFO - 'PWD': '/builds/slave/test', 21:36:06 INFO - 'SHELL': '/bin/bash', 21:36:06 INFO - 'SHLVL': '1', 21:36:06 INFO - 'TERM': 'linux', 21:36:06 INFO - 'TMOUT': '86400', 21:36:06 INFO - 'USER': 'cltbld', 21:36:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:06 INFO - '_': '/tools/buildbot/bin/python'} 21:36:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:06 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)) 21:36:06 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)) 21:36:06 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)) 21:36:06 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)) 21:36:06 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)) 21:36:06 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)) 21:36:06 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)) 21:36:06 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)) 21:36:06 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)) 21:36:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:36:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:36:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 21:36:07 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)) 21:36:07 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)) 21:36:07 INFO - Installing collected packages: simplejson 21:36:07 INFO - Running setup.py install for simplejson 21:36:07 INFO - building 'simplejson._speedups' extension 21:36:07 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 21:36:08 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 21:36:08 INFO - Successfully installed simplejson 21:36:08 INFO - Cleaning up... 21:36:08 INFO - Return code: 0 21:36:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:36:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1a471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c10a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db3a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, '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': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', '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 21:36:08 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 21:36:08 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 21:36:08 INFO - Using env: {'DISPLAY': ':0', 21:36:08 INFO - 'HOME': '/home/cltbld', 21:36:08 INFO - 'LANG': 'en_US.UTF-8', 21:36:08 INFO - 'LANGUAGE': 'en_US:en', 21:36:08 INFO - 'LOGNAME': 'cltbld', 21:36:08 INFO - 'MAIL': '/var/mail/cltbld', 21:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:08 INFO - 'MOZ_NO_REMOTE': '1', 21:36:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:08 INFO - 'NO_EM_RESTART': '1', 21:36:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:08 INFO - 'PWD': '/builds/slave/test', 21:36:08 INFO - 'SHELL': '/bin/bash', 21:36:08 INFO - 'SHLVL': '1', 21:36:08 INFO - 'TERM': 'linux', 21:36:08 INFO - 'TMOUT': '86400', 21:36:08 INFO - 'USER': 'cltbld', 21:36:08 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:08 INFO - '_': '/tools/buildbot/bin/python'} 21:36:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:36:09 INFO - Cleaning up... 21:36:09 INFO - Return code: 0 21:36:09 INFO - Running post-action listener: _resource_record_post_action 21:36:09 INFO - Running post-action listener: _start_resource_monitoring 21:36:09 INFO - Starting resource monitoring. 21:36:09 INFO - [mozharness: 2017-09-22 04:36:09.173411Z] Finished create-virtualenv step (success) 21:36:09 INFO - [mozharness: 2017-09-22 04:36:09.174446Z] Running install step. 21:36:09 INFO - Running pre-action listener: _resource_record_pre_action 21:36:09 INFO - Running main action method: install 21:36:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:36:09 INFO - Reading from file tmpfile_stdout 21:36:09 INFO - Detecting whether we're running mozinstall >=1.0... 21:36:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:36:09 INFO - Reading from file tmpfile_stdout 21:36:09 INFO - Output received: 21:36:09 INFO - Usage: mozinstall [options] installer 21:36:09 INFO - Options: 21:36:09 INFO - -h, --help show this help message and exit 21:36:09 INFO - -d DEST, --destination=DEST 21:36:09 INFO - Directory to install application into. [default: 21:36:09 INFO - "/builds/slave/test"] 21:36:09 INFO - --app=APP Application being installed. [default: firefox] 21:36:09 INFO - mkdir: /builds/slave/test/build/application 21:36:09 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'] 21:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:36:27 INFO - Reading from file tmpfile_stdout 21:36:27 INFO - Output received: 21:36:27 INFO - /builds/slave/test/build/application/firefox/firefox 21:36:27 INFO - Running post-action listener: _resource_record_post_action 21:36:27 INFO - [mozharness: 2017-09-22 04:36:27.314106Z] Finished install step (success) 21:36:27 INFO - [mozharness: 2017-09-22 04:36:27.314290Z] Running setup-mitmproxy step. 21:36:27 INFO - Running pre-action listener: _resource_record_pre_action 21:36:27 INFO - Running main action method: setup_mitmproxy 21:36:27 INFO - Skipping: mitmproxy is not required 21:36:27 INFO - Running post-action listener: _resource_record_post_action 21:36:27 INFO - [mozharness: 2017-09-22 04:36:27.315418Z] Finished setup-mitmproxy step (success) 21:36:27 INFO - [mozharness: 2017-09-22 04:36:27.315639Z] Running run-tests step. 21:36:27 INFO - Running pre-action listener: _resource_record_pre_action 21:36:27 INFO - Running pre-action listener: _set_gcov_prefix 21:36:27 INFO - Running main action method: run_tests 21:36:27 WARNING - Try message not found. 21:36:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:36:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:36:27 INFO - Python 2.7.3 21:36:27 INFO - Return code: 0 21:36:27 INFO - grabbing minidump binary from tooltool 21:36: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')]} 21:36:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x1db7090>, '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 0x1db7d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1db1f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:36:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 21:36:27 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:36:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 21:36:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:36:27 INFO - Return code: 0 21:36:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:36:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:36:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:36:27 INFO - ENV: RUST_BACKTRACE is now full 21:36:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:36:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:36:27 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/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 21:36:27 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/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 21:36:27 INFO - Using env: {'DISPLAY': ':0', 21:36:27 INFO - 'HOME': '/home/cltbld', 21:36:27 INFO - 'LANG': 'en_US.UTF-8', 21:36:27 INFO - 'LANGUAGE': 'en_US:en', 21:36:27 INFO - 'LOGNAME': 'cltbld', 21:36:27 INFO - 'MAIL': '/var/mail/cltbld', 21:36:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:36:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:36:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:27 INFO - 'MOZ_NO_REMOTE': '1', 21:36:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:36:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:27 INFO - 'NO_EM_RESTART': '1', 21:36:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:27 INFO - 'PWD': '/builds/slave/test', 21:36:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:36:27 INFO - 'RUST_BACKTRACE': 'full', 21:36:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:36:27 INFO - 'SHELL': '/bin/bash', 21:36:27 INFO - 'SHLVL': '1', 21:36:27 INFO - 'TERM': 'linux', 21:36:27 INFO - 'TMOUT': '86400', 21:36:27 INFO - 'USER': 'cltbld', 21:36:27 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:36:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:27 INFO - '_': '/tools/buildbot/bin/python'} 21:36:27 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/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 21:36:27 INFO - mozversion application_buildid: 20170922034016 21:36:27 INFO - mozversion application_changeset: 97282b0c985aa1778ced171514d1ae61945c634c 21:36:27 INFO - mozversion application_display_name: Nightly 21:36:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:36:27 INFO - mozversion application_name: Firefox 21:36:27 INFO - mozversion application_remotingname: firefox 21:36:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:36:27 INFO - mozversion application_vendor: Mozilla 21:36:27 INFO - mozversion application_version: 58.0a1 21:36:27 INFO - mozversion platform_buildid: 20170922034016 21:36:27 INFO - mozversion platform_changeset: 97282b0c985aa1778ced171514d1ae61945c634c 21:36:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:36:27 INFO - mozversion platform_version: 58.0a1 21:36:27 INFO - using testdate: 1506054987 21:36:27 INFO - actual date: 1506054987 21:36:27 INFO - starting webserver on 'localhost:54792' 21:36:27 INFO - SUITE-START | Running 2 tests 21:36:27 INFO - TEST-START | dromaeo_css 21:36:27 INFO - Initialising browser for dromaeo_css test... 21:36:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54792/getInfo.html -profile /tmp/tmpgU4vDG/profile 21:36:27 INFO - TEST-INFO | started process 3575 (/builds/slave/test/build/application/firefox/firefox http://localhost:54792/getInfo.html) 21:36:37 INFO - TEST-INFO | 3575: exit 0 21:36:37 INFO - Browser initialized. 21:36:37 INFO - Running cycle 1/1 for dromaeo_css test... 21:36:37 INFO - Using env: {'DISPLAY': ':0', 21:36:37 INFO - 'HOME': '/home/cltbld', 21:36:37 INFO - 'JSGC_DISABLE_POISONING': '1', 21:36:37 INFO - 'LANG': 'en_US.UTF-8', 21:36:37 INFO - 'LANGUAGE': 'en_US:en', 21:36:37 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:36:37 INFO - 'LOGNAME': 'cltbld', 21:36:37 INFO - 'MAIL': '/var/mail/cltbld', 21:36:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:36:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:36:37 INFO - 'MOZ_CRASHREPORTER': '1', 21:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:36:37 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:36:37 INFO - 'MOZ_NO_REMOTE': '1', 21:36:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:36:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:36:37 INFO - 'NO_EM_RESTART': '1', 21:36:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:36:37 INFO - 'PWD': '/builds/slave/test', 21:36:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:36:37 INFO - 'RUST_BACKTRACE': 'full', 21:36:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:36:37 INFO - 'SHELL': '/bin/bash', 21:36:37 INFO - 'SHLVL': '1', 21:36:37 INFO - 'TERM': 'linux', 21:36:37 INFO - 'TMOUT': '86400', 21:36:37 INFO - 'USER': 'cltbld', 21:36:37 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:36:37 INFO - '_': '/tools/buildbot/bin/python'} 21:36:37 INFO - TEST-INFO | started process 3812 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgU4vDG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:36:38 INFO - PID 3812 | 21:36:38 INFO - PID 3812 | (/builds/slave/test/build/application/firefox/firefox:3861): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:36:38 INFO - PID 3812 | 21:36:48 INFO - PID 3812 | RSS: Main: 154284032 21:36:48 INFO - PID 3812 | 21:39:45 INFO - PID 3812 | Cycle 1(1): loaded http://localhost:54792/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54792/tests/dromaeo/cssquery-ext.html) 21:39:46 INFO - PID 3812 | RSS: Main: 156119040 21:39:46 INFO - PID 3812 | 21:42:48 INFO - PID 3812 | Cycle 1(1): loaded http://localhost:54792/tests/dromaeo/cssquery-ext.html (next: http://localhost:54792/tests/dromaeo/cssquery-jquery.html) 21:42:48 INFO - PID 3812 | RSS: Main: 155512832 21:42:48 INFO - PID 3812 | 21:45:50 INFO - PID 3812 | Cycle 1(1): loaded http://localhost:54792/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54792/tests/dromaeo/cssquery-mootools.html) 21:45:51 INFO - PID 3812 | RSS: Main: 157364224 21:45:51 INFO - PID 3812 | 21:48:52 INFO - PID 3812 | Cycle 1(1): loaded http://localhost:54792/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54792/tests/dromaeo/cssquery-prototype.html) 21:48:53 INFO - PID 3812 | RSS: Main: 156553216 21:48:53 INFO - PID 3812 | 21:51:56 INFO - PID 3812 | Cycle 1(1): loaded http://localhost:54792/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54792/tests/dromaeo/cssquery-yui.html) 21:51:57 INFO - PID 3812 | RSS: Main: 157351936 21:51:57 INFO - PID 3812 | 21:54:58 INFO - PID 3812 | Cycle 1(1): loaded http://localhost:54792/tests/dromaeo/cssquery-yui.html (next: http://localhost:54792/tests/dromaeo/cssquery-dojo.html) 21:54:59 INFO - PID 3812 | RSS: Main: 160108544 21:54:59 INFO - PID 3812 | 21:54:59 INFO - PID 3812 | __start_tp_report 21:54:59 INFO - PID 3812 | _x_x_mozilla_page_load 21:54:59 INFO - PID 3812 | _x_x_mozilla_page_load_details 21:54:59 INFO - PID 3812 | |i|pagename|runs| 21:54:59 INFO - PID 3812 | |0;dojo.html;3144.7955882867595;3146.0090071627437;3174.206448387903;3179.952300715488;3188.8246146461943;6164.7534098636115;6277.2153480814895;6288.339724328943;6314.210723659543;6396.808095757123;6222.533310001757;6240.594361366508;6259.718312675929;6291.716872740726;6292.811215663526;5751.338596061444;5835.0663893777;5904.527637788967;5926.644401335912;5988.580799344048;5842.707864606753;5874.5594080443925;5898.705064746767;5908.556858235649;5950.761969521214;499.63289328687233;5689.886202275952;5703.942960570383;5704.914426283609;5789.736960710012;5734.311882574118;5803;5827.155062515912;5828.358880523139;5852.502537284336;5748.42515748426;5790.913136302959;5835.387284335168;5860.150278209636;5872.970635146839;6244.906326405107;6280.403361680633;6302.1177035215105;6306.1171435998995;6378.904316435257;6750.223724271723;6775.898361524581;6841.2132604750595;6841.521093523455;6846.349596788297;6651.068850360954;6743.527953043289;6748.8312792180095;6841.452683785335;6932.584044957319;6218.378162183791;6239.188905442309;6262.655553944532;6274.56078074535;6286.308506064375;6892.310768923117;6919.065926099912;6975.476839236976;7059.2234854166;7079.115110611174;6764.526483146231;6776.28848970851;6777;6779.0509328694025;6792.72829086831;6817.250102488722;6825.658717064127;6857.314268573103;6932.306769323028;6937.9653101735175;6524.575902566318;6574.539782215199;6608.240052394036;6752.459803215731;6853.965730171417;6786.59280443175;6818.965905170442;6837.282085381063;6861.210960739579;6893;5323.627346085815;5395.082835917825;5404.216388623628;5407.378151512547;5410.242566040757;4669.463011753692;4688.578027977498;4697.412823397075;4723.645726570521;4733.644976626767;5752.9424705753245;5781.710914454345;5801.477866991991;5817.970910145506;5843.737031833492;6877.449804015667;6915.930840691629;6921.861562768717;6936.79189624312;6938.757143499954;5842.970785146047;5846.766129354864;5872.354041055481;5921.970390148108;5922.0820772779425;5662.943370566324;5769.826905192851;5868.882622347529;5888.764449422061;5943.375945525744;6511.186101737283;6610.7025183866845;6660.600363978177;6678.7328506859185;6679.565828221247;6515.641639709862;6825.590464572141;6826.556273842184;6891.586504809728;6917.550359226634;6479.676016199265;6491.123698300669;6493.902591461037;6530.640814755234;6543.247526534319;6619.602823830585;6633.568818026813;6636.734530618721;6706.83232919181;6789.524733268624;6897.103376561216;6901.137357830271;6914.965425172842;6947.3747362737595;6953.513254072166;6604.801855944329;6614.70233839488;6635.4359879410795;6683.966580167068;6721.899171512529;6412.487001039813;6526.543141980016;6602.537822352581;6606.735730570724;6707.731690732316;6448.32292609288;6457.644829534421;6480.6435646038035;6482.319356467503;6537.967310163419;6685.2646208916;6694.096296999843;6708;6722.9663851680425;6757.256701762901;6449.484041276781;6463.385978332051;6525.249596296487;6537.346265373425;6550.541462097607 21:54:59 INFO - PID 3812 | |1;ext.html;785.8899754034435;806.8386322735454;809.0493669937822;810.614957895;811.1726039439768;38866.611333886656;42185;42212.788936055316;42409.93975150619;42447.36328955068;38045;38257.61742382575;38299.234015319744;38338;38438.231235375344;7069.399101076402;7951.20487951206;8091.029076510827;8091.4740541864885;8092.514449133071;7893;7910.485818421813;7975.162607926171;7977.720779772709;7989.280964713174;7980.16208298129;7996.200379962015;8010.439269251155;8046.718364857231;8051.758447246562;7936.801579960489;7986.041674999008;7997.1602981686665;8011.0386753589655;8015.719449819287;7904.604769761547;7969.362451003905;7972.561509116997;7972.880406793903;7975.082865470487;38413.80793096044;38609.61390386088;38671.41992870109;38679.806600967095;38829.41755873664;3579.731520136;3618.493410922473;3618.764780289273;3619.457081437779;3621.8189090545366;31821.363572728416;31907.840460797546;31915;31938.20154496133;31946.201344966328;5001.224810154432;5165.66423182492;5180.456052114511;5203.453637368098;5246.737663116829;189712.05143974329;189939.05030474896;189947;190411;190972;42792.3581146283;43709;47917.28124078141;48155.518444815105;49185.26222106671;52191.73904130455;52245.47754522482;52477.95044099173;52492.73753631284;52507.47492525026;15185.924070379577;15350.769738453931;15365.154916479478;15366.078035317692;15366.154861482726;12259.080568957364;12283.201591896499;12297.69255768595;12306.876931230574;12319.93840030812;14300.213488258245;14312.856871431153;14381.78427323591;14426.855731442762;14508.709825803426;5579.581531385163;5648.435156484319;5719.828405147852;5740.637115791945;5857.824265272048;981.5877331520636;1017.4912543728136;1018.3279035836313;1036.7200855769051;1045.8378951262566;22961.85190740456;23012.424689382566;23132.190373336853;23415.41461463477;23531.647025294635;1094.392612100276;1108.8669359676728;1110.3670907582762;1110.8666959964694;1130.3556972525582;12185.268883866996;12271.263724176579;12411.503539858304;12411.689707757378;12467.189632674028;235560;236492;237222;237988;238578;52183.478165217864;52632;52774.73612631912;52898;52919.47080529146;1386.1475192756382;1454.7745099509593;1459.8248210214842;1460.1239256446047;1461.503088949741;22765.203217887294;22844.65733013973;22940;22949.196778113354;23276.418089547897;36882.262354752216;36904.81547592245;36922;36933.26133477369;36949;194124;197365;197719;197907;197969;30765;31262.53106203363;33353.66646333603;34334;34369.14077148091;43646.56353436425;43749.34375984426;43834.78082609694;44146.558534415526;44243;31936.201594959384;32191;32270.677293226767;32314;32388.190295241864;31776.830809658335;31874;32419.837900810344;32468.837655812516;32687.34625307528;36767.08082297964;36768.448473273456;36780.080497987765;36977;37023.444648330835;31133.53299700461;31233.687663123077;31384;31819.681803181673;31823.840880795447;23009.65485517685;23659;24045.639315410634;24060.87969560141;24144.396390090387 21:54:59 INFO - PID 3812 | |2;jquery.html;566.9773209071635;571.3343954293249;582.4670426559699;595.345120367596;599.7091410665827;22155.66766498502;23080.769192308093;23109.884450577767;23116.190933317357;23140.76859231407;20020.399388018384;20289.492762680904;20393.08231129599;21474;23055.30834074976;6523.412892839656;6676.699548520328;6679.56582822115;6692.899606505882;6713.697883595226;6608.438282745972;6685.164354455693;6686.1308029956035;6702.497312701543;6703.296153903843;6330.303666596671;6572.178477690289;6573;6588.3411658833975;6596.274409814917;6555.803325900207;6576.177977752781;6597.703103360361;6602.207735071798;6608.339166083353;5867.76528938842;6098.054801505729;6101.664408457533;6149.723262453201;6181.227346581677;28432.28919277014;28840.855795721098;28932.42135157285;28939.131826045043;28960;2046.9590608187902;2063.1850419084526;2072.057213967641;2079.6048750737314;2085.7392825896763;19318.613627727507;19647.803521964885;19764.60470790576;19923.80076199234;19945.9002704987;2369;2422.733499315074;2445.376429010591;2449.914253001154;2453.4970331082263;116042;121041;141497;147367;147579;14809.407623694931;14909.030912990838;14910;15138.848611513744;15510.146941918078;23772.52454950926;23871.806409679448;23876.880615596812;23890;23892.522149556913;9730.318877678636;9776.755581110529;9868.457234852152;9922.156616687662;9979.401235925867;8954.820903581893;8985;9075.727728168165;9125.45247285165;9137.177654011171;9099.044600317004;9118.908810911938;9348.766280843034;9421.76445588852;9425.528723563795;2646.3119588907025;2745.4509098180442;2758.524259433129;2769.9307517311827;2845.203343063904;940;970.1608108985818;973.7711637765119;974.3812678948776;977.9168770654574;19283.132259048376;19330.03349832503;19422.22311107568;19706.60586788285;20010.29963951255;979.3242662562809;1011.691434112588;1025.139859791189;1033.2973577967055;1036.6423583863555;10682.412467314372;10712.625058122929;10742.624008159833;10755.139588832875;10773.24587278883;474571;482776;484185;484720;486593;107156.46421767841;109053;109071.45464272628;109206.45396773124;115374;1136.255752482142;1144.164759725405;1180.321315243721;1180.7579446213676;1182.1016027818919;16671.49985500437;17116.572085697957;17293.56766080858;17474.82525174783;17545.210465529337;54238.72880635571;55294.170587440414;56289.15566266427;57203.71398142983;57458;69713;69810.65094674494;70061;70104;70190.64904675649;14254.144751314954;14594.562163135122;14601.196934168724;14667.779983300046;14685.55943321702;22218;22317.330480085624;22558.548829023657;22626.773732263126;22644.094236231027;17835.910820445813;17909.820901790816;18002.63994720072;18009.369672061413;18107.18517666682;16108.67782644364;16233.269502872636;16256.918715406347;16286.185690715654;16306.510804675878;17845;18610.906945465187;18720.438386848415;18838.717419238998;18846.811531885054;14319.28403579796;14381.7842732357;14534.927325363305;14552.85447145515;14562.708745825235;17699.82300177033;17784.644307114046;17851.821481785017;17907.73138402899;17973 21:54:59 INFO - PID 3812 | |3;mootools.html;1351.9256440895751;1422.217780220879;1426.486464872646;1427.4789701758848;1431.0984080029586;15251.31369088391;15906.52280431586;15919.522414327588;15920.840791592094;15928.76106858398;15558.766618500704;15682.059076455393;15684.529464116065;15714.371425143008;15771.447999320026;2777.652793400825;2886.090881372361;2892.378138700179;2896.5944767732535;2898.9275268118254;2737.767289780371;2796.049343223304;2819.4783964966446;2821.2664707176077;2827.1942496388533;2836.3901761121356;2867.827930324187;2880.8415537145447;2884.899028534002;2887.768978481727;2677.5849743289823;2678.973210267902;2720.2383332666886;2723.360010397555;2738.137486691696;2526.2421273617842;2537.6320433537035;2560.411105445758;2565.115035312814;2565.1406778729147;14676.779848302283;15022.173780442072;15042.32309546073;15043.323050462646;15087.094774313577;7737.032870891138;8044.235797599219;8048.59757012147;8048.798780030487;8049.718259860935;5421.268128802601;5581.6930068846195;5691.544676425876;5731.254936858182;5762.596618236725;1963.7447131872907;2007.9498012549802;2026.929057482981;2052.887091209983;2055.9486012849648;27203.591946120825;27286.727132728618;27288;27495.9999999998;27569.310767230778;6857.965710171417;6953.721851126;6970;7058.082449281561;7103.218645948941;17849.464516064538;17888.642227155644;17918.372856949944;17955.910220448815;17960.101994900204;7822.765317040498;7869.449138560246;7924.049114106228;7932.286094251545;7960.601969901481;7207.711691532385;7329.963350183321;7360.96319518399;7371.741989030358;7377.926220737724;5010.2735103409805;5206.5314121729225;5244.396894357122;5272.9736351318;5288.180332048539;1373.009136751694;1376.6145479265822;1421.2112277685983;1534.6547026918854;1537.1007960343325;918.6922381002305;935.0882889182992;937.5827756648363;938.8544775559799;944.3861490031397;7968;8049.55727434985;8110.188981101961;8131.83736325282;8133.308668763103;985.3792110970186;987.2102318145455;996.3673067602125;999.48526508848;1007.6120693533043;4866.732329721828;4961.776720047606;4962.156433406258;4975.850724478271;5048.3941926969;21609.243676471247;21747;22538.774612253994;22573.774262257168;22646.320610381714;22260.22089226869;22315.77684223137;22469.10123595038;22568.66147007796;22605;10738.389241613664;11690.298582085103;11745.001674857562;11847.289162650268;11891.881081189078;8475.406721529434;8502.872456913276;8514.74455766328;8527.573621318785;8533.146685331418;18660.906695466434;19107;19134.808651913303;19254.422367329003;19415;19699.606007880047;19781.40655780329;19785.307514236934;19790.802091978894;19831.20675172977;11116.555337786722;11264;11319.434028298387;11332.546698131982;11333.150013749168;14042;14165.43338266499;14202.786958195842;14266.42934282617;14412.567622971328;10107.595696172071;10747;11511.597094101666;11540.019098376397;11808.645740627795;11030.172737044593;11231.66305010851;11294.378809165246;11530;11700.648980530597;10480;10671.839922401325;10680.359178449318;10683.94658026705;10690.41202733857;7644.503107298008;7659.770206893802;7712;7719.5754233515445;7760.805979850549;7795.532268063935;7803.414743894345;7842.176571459914;7887.092984306878;7983.960080199562 21:54:59 INFO - PID 3812 | |4;prototype.html;506.8177881165087;533.2241588488745;534.1518346521037;537.1566640374614;537.6827671673711;6293.276273228568;6332.905006424908;6362.522810789192;6364.840878978028;6381.202349706286;6226.657533835637;6244.56288059836;6248.250209974809;6251.3123556408755;6275.560710750249;5182.896342073166;5189.922151167735;5197.818076367328;5202.193659982709;5216.139337009389;4878.999220639077;5143.717095559743;5172.793088276483;5207.495516378343;5227.3465816772905;4997;5022.246663000543;5022.5730812880765;5024.924626130611;5038.874028149289;5011.316682238552;5035.194368900959;5050.797968081272;5060.190369540893;5063.822766203165;4493.404841281331;4511.729296242236;4519.502854685982;4533.320001999694;4544.659150563721;5993.580449368546;6036.63780173191;6089.117078023662;6098;6112.63324200545;5761.567882408836;5770.740316685759;5807.822066724978;5810.50610698087;5835.183074369592;6465.224173099211;6468.1268028815975;6474.449671777905;6495.902561461582;6507.837304067436;4933.358663373774;4934.1611925972675;4937.8271760488215;4941.950580494221;4956.8760780980765;6667;6709.161354830646;6861.931380686229;6875.1749790025515;6945.02066910928;5004.724740139327;5066.417362003417;5071.163258062378;5072.239164125411;5085.821996230188;5086.287919691246;5105.744712764347;5109.948900510947;5118.462561431071;5122.795088196505;5681.545476361881;5711.485966262973;5713.77153911911;5740.167675687002;5768.913466298009;6511.186101737283;6572.934270657328;6615.503837212228;6636.137302150786;6656.467482601381;1740.3821643316694;1771.406578796092;1778.3597904754279;1787.9195436205403;1789.8210178981997;1139.868915074777;1207.746373261625;1221.1207930290177;1229.956951506693;1239.2626387299542;5512.2558454608115;5574.219609254626;5584.301962254718;5593.580481463907;5677.886442271131;6531.608103513804;6594.77996570636;6613.66931653425;6624.6025238485845;6759.628220447923;5565.693886836263;5569.721513924287;5610.046292130348;5615.719214039282;5622.606417550814;3459.757816952789;3468.5664291963503;3490.2495963367996;3507.859685612547;3513.8594456221977;6258.499320054385;6327.810165695037;6353.428191462791;6422.45409140219;6470.741170353227;6703.597784132968;6739.427148692318;6788.932110678928;6856.766111752049;6882;5994.910076348859;6283.685815709287;6337.302896681362;6431.485481161402;6504.902426463512;6425.261094973951;6460.12788273577;6471.546991710535;6502.512311576556;6666.633335166613;6428.839279017875;6482.935170648422;6509.967450162719;6562.704678289584;6635.156335874742;6453.322401147811;6504.544681872413;6523.282438931618;6525.967370163119;6584;4925.261210818406;4927.359443272351;5002.424236365533;5003.699038250008;5017.37282839645;6640.203175618956;6669.299723529154;6756.189257289157;6775.322467753185;6787.355201255873;6348.174737284308;6386.9680651596445;6389.9680501597195;6393.424591786761;6506.674666266762;6372.024672351586;6440.2915679276175;6470.870582588416;6504.577202481823;6587.473002159912;6333.638267772267;6343.302236754045;6414.358564143548;6497.090407342881;6507.837304067436;6453.774117905851;6511;6553.377429144223;6581.407673309425;6632.801015969529;6308.6436416170745;6318.462930650946;6342.175517182736;6352.936470635419;6401.407429990862 21:54:59 INFO - PID 3812 | |5;yui.html;908.9272858171346;921.7603423109994;929.9721008369743;936.8032713130242;944.0700909604037;18996.33512827051;19901.800981990174;19913.3030343938;19913.50216244593;19942.302019429306;19074.33239836606;19142.808571914276;19280.03599820007;19321.227150913946;19322;958.094600602431;967.7193613851974;977.3305285879195;977.8582105594685;978.3445091788519;983.1348413396202;984.0946329376955;984.266721292638;984.6455276100634;986.9358491698052;949.5062567464914;1029.0327092533032;1035.2908257843364;1041.8333066709329;1042.3433237060642;1014.137982714694;1016.0296916444778;1023.9795204095876;1027.8149933012055;1028.0695970147067;723.4610215389516;745.8210029592859;750.523417629809;754.6264599023518;756.9318761311508;19314.32399866012;19366.322178723676;19442;19482.80517194838;19506;20820.895895520425;21315.893420532953;21324.46688832776;21655.783442165535;21987;17112.144392780312;17260.395886143924;17557.473275801745;17747.73378399325;17847.464576062608;3739.326921154191;3770.26479836432;3771.830267637963;3772.1324095457926;3773.056735816046;102612.48693756558;137247;139919.30040349835;140087;141346;14998.550043498712;16077.598060048358;16086.75869861953;16367.017978921303;16414.507564773077;30913;31790;31813.363732725215;31855.52216716751;32003.359932801213;9256.398334108262;9278.886533616009;9353.298502612195;9356.204722598519;9361.110694484014;4162.604552567501;4239.957600423956;4259.531451540328;4261.701680882308;4261.722988005769;5328.387235467893;5419.512243898068;5422.5119739223655;5431.972840135774;5435.7825686972865;1055.519738518972;1090.3784842639777;1097.4896673047072;1110.7500812317164;1116.8603924509437;633.7243299164878;639.9520035997319;652.3802387731674;653.2455014458309;669.0900375489331;11512.366819824823;11693.239939403911;11699.473523691284;11726.70683232926;12450.81323780144;644.8420137066355;645.854682696399;646.6120327803375;661.6526323680107;662.7315937045504;5390.568754499631;5464.590155738336;5467.152591348347;5475.370332411744;5564.165375193753;116609;118585.40707296408;120365;121017;121054;95145;100757.49621251847;107217;109874.45062774635;115911.42044289724;95478.52260738652;95947;96891;98024;98748.50625746825;11640.243384180001;11957;12135.575254866037;12269.447874845831;12336.62990110298;42529;44191.55808441874;45074.54925450704;45378.77310613426;45917;57475;59115.70442147762;59514.70242648932;59614.40385596262;60063;17200.053997030285;17289.567760805578;17300.13499325054;17329.91335043367;17390.130493475528;18662.34681786131;18789.812101879354;18795.90602046981;18900.05499724981;19070.523236919187;15546.37814487408;16098.11460369691;16121;16173.919130404272;16256.105914174834;17405.82594174042;17425.302987880343;17595.736063959306;17596.472105836845;17640.823591763918;15224.771628425804;15421.84578154204;15491.225438728245;15528.84471155274;15642.608934776721;12890.613281601567;13191.208527488381;13212.933935330262;13276;13378.86621133815;12730.936345318214;13000.934995324962;13139.211647301192;13349;13479.32603369808 21:54:59 INFO - PID 3812 | __end_tp_report 21:54:59 INFO - PID 3812 | __start_cc_report 21:54:59 INFO - PID 3812 | _x_x_mozilla_cycle_collect,291 21:54:59 INFO - PID 3812 | __end_cc_report 21:54:59 INFO - PID 3812 | __startTimestamp1506056099187__endTimestamp 21:54:59 INFO - PID 3812 | 21:54:59 INFO - PID 3812 | ------- Summary: start ------- 21:54:59 INFO - PID 3812 | Number of tests: 6 21:54:59 INFO - PID 3812 | 21:54:59 INFO - PID 3812 | [#0] dojo.html Cycles:165 Average:6214.08 Median:6482.32 stddev:877.03 (13.5%) stddev-sans-first:846.02 21:54:59 INFO - PID 3812 | Values: 3144.8 3146.0 3174.2 3180.0 3188.8 6164.8 6277.2 6288.3 6314.2 6396.8 6222.5 6240.6 6259.7 6291.7 6292.8 5751.3 5835.1 5904.5 5926.6 5988.6 5842.7 5874.6 5898.7 5908.6 5950.8 499.6 5689.9 5703.9 5704.9 5789.7 5734.3 5803.0 5827.2 5828.4 5852.5 5748.4 5790.9 5835.4 5860.2 5873.0 6244.9 6280.4 6302.1 6306.1 6378.9 6750.2 6775.9 6841.2 6841.5 6846.3 6651.1 6743.5 6748.8 6841.5 6932.6 6218.4 6239.2 6262.7 6274.6 6286.3 6892.3 6919.1 6975.5 7059.2 7079.1 6764.5 6776.3 6777.0 6779.1 6792.7 6817.3 6825.7 6857.3 6932.3 6938.0 6524.6 6574.5 6608.2 6752.5 6854.0 6786.6 6819.0 6837.3 6861.2 6893.0 5323.6 5395.1 5404.2 5407.4 5410.2 4669.5 4688.6 4697.4 4723.6 4733.6 5752.9 5781.7 5801.5 5818.0 5843.7 6877.4 6915.9 6921.9 6936.8 6938.8 5843.0 5846.8 5872.4 5922.0 5922.1 5662.9 5769.8 5868.9 5888.8 5943.4 6511.2 6610.7 6660.6 6678.7 6679.6 6515.6 6825.6 6826.6 6891.6 6917.6 6479.7 6491.1 6493.9 6530.6 6543.2 6619.6 6633.6 6636.7 6706.8 6789.5 6897.1 6901.1 6915.0 6947.4 6953.5 6604.8 6614.7 6635.4 6684.0 6721.9 6412.5 6526.5 6602.5 6606.7 6707.7 6448.3 6457.6 6480.6 6482.3 6538.0 6685.3 6694.1 6708.0 6723.0 6757.3 6449.5 6463.4 6525.2 6537.3 6550.5 21:54:59 INFO - PID 3812 | 21:54:59 INFO - PID 3812 | [#1] ext.html Cycles:180 Average:37656.95 Median:3618.63 stddev:54195.10 (1497.7%) stddev-sans-first:54276.42 21:54:59 INFO - PID 3812 | Values: 785.9 806.8 809.0 810.6 811.2 38866.6 42185.0 42212.8 42409.9 42447.4 38045.0 38257.6 38299.2 38338.0 38438.2 7069.4 7951.2 8091.0 8091.5 8092.5 7893.0 7910.5 7975.2 7977.7 7989.3 7980.2 7996.2 8010.4 8046.7 8051.8 7936.8 7986.0 7997.2 8011.0 8015.7 7904.6 7969.4 7972.6 7972.9 7975.1 38413.8 38609.6 38671.4 38679.8 38829.4 3579.7 3618.5 3618.8 3619.5 3621.8 31821.4 31907.8 31915.0 31938.2 31946.2 5001.2 5165.7 5180.5 5203.5 5246.7 189712.1 189939.1 189947.0 190411.0 190972.0 42792.4 43709.0 47917.3 48155.5 49185.3 52191.7 52245.5 52478.0 52492.7 52507.5 15185.9 15350.8 15365.2 15366.1 15366.2 12259.1 12283.2 12297.7 12306.9 12319.9 14300.2 14312.9 14381.8 14426.9 14508.7 5579.6 5648.4 5719.8 5740.6 5857.8 981.6 1017.5 1018.3 1036.7 1045.8 22961.9 23012.4 23132.2 23415.4 23531.6 1094.4 1108.9 1110.4 1110.9 1130.4 12185.3 12271.3 12411.5 12411.7 12467.2 235560.0 236492.0 237222.0 237988.0 238578.0 52183.5 52632.0 52774.7 52898.0 52919.5 1386.1 1454.8 1459.8 1460.1 1461.5 22765.2 22844.7 22940.0 22949.2 23276.4 36882.3 36904.8 36922.0 36933.3 36949.0 194124.0 197365.0 197719.0 197907.0 197969.0 30765.0 31262.5 33353.7 34334.0 34369.1 43646.6 43749.3 43834.8 44146.6 44243.0 31936.2 32191.0 32270.7 32314.0 32388.2 31776.8 31874.0 32419.8 32468.8 32687.3 36767.1 36768.4 36780.1 36977.0 37023.4 31133.5 31233.7 31384.0 31819.7 31823.8 23009.7 23659.0 24045.6 24060.9 24144.4 21:54:59 INFO - PID 3812 | 21:54:59 INFO - PID 3812 | [#2] jquery.html Cycles:180 Average:34058.04 Median:23095.33 stddev:81202.68 (351.6%) stddev-sans-first:81391.54 21:54:59 INFO - PID 3812 | Values: 567.0 571.3 582.5 595.3 599.7 22155.7 23080.8 23109.9 23116.2 23140.8 20020.4 20289.5 20393.1 21474.0 23055.3 6523.4 6676.7 6679.6 6692.9 6713.7 6608.4 6685.2 6686.1 6702.5 6703.3 6330.3 6572.2 6573.0 6588.3 6596.3 6555.8 6576.2 6597.7 6602.2 6608.3 5867.8 6098.1 6101.7 6149.7 6181.2 28432.3 28840.9 28932.4 28939.1 28960.0 2047.0 2063.2 2072.1 2079.6 2085.7 19318.6 19647.8 19764.6 19923.8 19945.9 2369.0 2422.7 2445.4 2449.9 2453.5 116042.0 121041.0 141497.0 147367.0 147579.0 14809.4 14909.0 14910.0 15138.8 15510.1 23772.5 23871.8 23876.9 23890.0 23892.5 9730.3 9776.8 9868.5 9922.2 9979.4 8954.8 8985.0 9075.7 9125.5 9137.2 9099.0 9118.9 9348.8 9421.8 9425.5 2646.3 2745.5 2758.5 2769.9 2845.2 940.0 970.2 973.8 974.4 977.9 19283.1 19330.0 19422.2 19706.6 20010.3 979.3 1011.7 1025.1 1033.3 1036.6 10682.4 10712.6 10742.6 10755.1 10773.2 474571.0 482776.0 484185.0 484720.0 486593.0 107156.5 109053.0 109071.5 109206.5 115374.0 1136.3 1144.2 1180.3 1180.8 1182.1 16671.5 17116.6 17293.6 17474.8 17545.2 54238.7 55294.2 56289.2 57203.7 57458.0 69713.0 69810.7 70061.0 70104.0 70190.6 14254.1 14594.6 14601.2 14667.8 14685.6 22218.0 22317.3 22558.5 22626.8 22644.1 17835.9 17909.8 18002.6 18009.4 18107.2 16108.7 16233.3 16256.9 16286.2 16306.5 17845.0 18610.9 18720.4 18838.7 18846.8 14319.3 14381.8 14534.9 14552.9 14562.7 17699.8 17784.6 17851.8 17907.7 17973.0 21:54:59 INFO - PID 3812 | 21:54:59 INFO - PID 3812 | [#3] mootools.html Cycles:180 Average:9563.17 Median:2621.36 stddev:6893.59 (263.0%) stddev-sans-first:6885.32 21:54:59 INFO - PID 3812 | Values: 1351.9 1422.2 1426.5 1427.5 1431.1 15251.3 15906.5 15919.5 15920.8 15928.8 15558.8 15682.1 15684.5 15714.4 15771.4 2777.7 2886.1 2892.4 2896.6 2898.9 2737.8 2796.0 2819.5 2821.3 2827.2 2836.4 2867.8 2880.8 2884.9 2887.8 2677.6 2679.0 2720.2 2723.4 2738.1 2526.2 2537.6 2560.4 2565.1 2565.1 14676.8 15022.2 15042.3 15043.3 15087.1 7737.0 8044.2 8048.6 8048.8 8049.7 5421.3 5581.7 5691.5 5731.3 5762.6 1963.7 2007.9 2026.9 2052.9 2055.9 27203.6 27286.7 27288.0 27496.0 27569.3 6858.0 6953.7 6970.0 7058.1 7103.2 17849.5 17888.6 17918.4 17955.9 17960.1 7822.8 7869.4 7924.0 7932.3 7960.6 7207.7 7330.0 7361.0 7371.7 7377.9 5010.3 5206.5 5244.4 5273.0 5288.2 1373.0 1376.6 1421.2 1534.7 1537.1 918.7 935.1 937.6 938.9 944.4 7968.0 8049.6 8110.2 8131.8 8133.3 985.4 987.2 996.4 999.5 1007.6 4866.7 4961.8 4962.2 4975.9 5048.4 21609.2 21747.0 22538.8 22573.8 22646.3 22260.2 22315.8 22469.1 22568.7 22605.0 10738.4 11690.3 11745.0 11847.3 11891.9 8475.4 8502.9 8514.7 8527.6 8533.1 18660.9 19107.0 19134.8 19254.4 19415.0 19699.6 19781.4 19785.3 19790.8 19831.2 11116.6 11264.0 11319.4 11332.5 11333.2 14042.0 14165.4 14202.8 14266.4 14412.6 10107.6 10747.0 11511.6 11540.0 11808.6 11030.2 11231.7 11294.4 11530.0 11700.6 10480.0 10671.8 10680.4 10683.9 10690.4 7644.5 7659.8 7712.0 7719.6 7760.8 7795.5 7803.4 7842.2 7887.1 7984.0 21:54:59 INFO - PID 3812 | 21:54:59 INFO - PID 3812 | [#4] prototype.html Cycles:180 Average:5494.82 Median:6169.65 stddev:1520.48 (24.6%) stddev-sans-first:1477.94 21:54:59 INFO - PID 3812 | Values: 506.8 533.2 534.2 537.2 537.7 6293.3 6332.9 6362.5 6364.8 6381.2 6226.7 6244.6 6248.3 6251.3 6275.6 5182.9 5189.9 5197.8 5202.2 5216.1 4879.0 5143.7 5172.8 5207.5 5227.3 4997.0 5022.2 5022.6 5024.9 5038.9 5011.3 5035.2 5050.8 5060.2 5063.8 4493.4 4511.7 4519.5 4533.3 4544.7 5993.6 6036.6 6089.1 6098.0 6112.6 5761.6 5770.7 5807.8 5810.5 5835.2 6465.2 6468.1 6474.4 6495.9 6507.8 4933.4 4934.2 4937.8 4942.0 4956.9 6667.0 6709.2 6861.9 6875.2 6945.0 5004.7 5066.4 5071.2 5072.2 5085.8 5086.3 5105.7 5109.9 5118.5 5122.8 5681.5 5711.5 5713.8 5740.2 5768.9 6511.2 6572.9 6615.5 6636.1 6656.5 1740.4 1771.4 1778.4 1787.9 1789.8 1139.9 1207.7 1221.1 1230.0 1239.3 5512.3 5574.2 5584.3 5593.6 5677.9 6531.6 6594.8 6613.7 6624.6 6759.6 5565.7 5569.7 5610.0 5615.7 5622.6 3459.8 3468.6 3490.2 3507.9 3513.9 6258.5 6327.8 6353.4 6422.5 6470.7 6703.6 6739.4 6788.9 6856.8 6882.0 5994.9 6283.7 6337.3 6431.5 6504.9 6425.3 6460.1 6471.5 6502.5 6666.6 6428.8 6482.9 6510.0 6562.7 6635.2 6453.3 6504.5 6523.3 6526.0 6584.0 4925.3 4927.4 5002.4 5003.7 5017.4 6640.2 6669.3 6756.2 6775.3 6787.4 6348.2 6387.0 6390.0 6393.4 6506.7 6372.0 6440.3 6470.9 6504.6 6587.5 6333.6 6343.3 6414.4 6497.1 6507.8 6453.8 6511.0 6553.4 6581.4 6632.8 6308.6 6318.5 6342.2 6352.9 6401.4 21:54:59 INFO - PID 3812 | 21:54:59 INFO - PID 3812 | [#5] yui.html Cycles:180 Average:24305.05 Median:19404.16 stddev:34381.11 (177.2%) stddev-sans-first:34432.68 21:54:59 INFO - PID 3812 | Values: 908.9 921.8 930.0 936.8 944.1 18996.3 19901.8 19913.3 19913.5 19942.3 19074.3 19142.8 19280.0 19321.2 19322.0 958.1 967.7 977.3 977.9 978.3 983.1 984.1 984.3 984.6 986.9 949.5 1029.0 1035.3 1041.8 1042.3 1014.1 1016.0 1024.0 1027.8 1028.1 723.5 745.8 750.5 754.6 756.9 19314.3 19366.3 19442.0 19482.8 19506.0 20820.9 21315.9 21324.5 21655.8 21987.0 17112.1 17260.4 17557.5 17747.7 17847.5 3739.3 3770.3 3771.8 3772.1 3773.1 102612.5 137247.0 139919.3 140087.0 141346.0 14998.6 16077.6 16086.8 16367.0 16414.5 30913.0 31790.0 31813.4 31855.5 32003.4 9256.4 9278.9 9353.3 9356.2 9361.1 4162.6 4240.0 4259.5 4261.7 4261.7 5328.4 5419.5 5422.5 5432.0 5435.8 1055.5 1090.4 1097.5 1110.8 1116.9 633.7 640.0 652.4 653.2 669.1 11512.4 11693.2 11699.5 11726.7 12450.8 644.8 645.9 646.6 661.7 662.7 5390.6 5464.6 5467.2 5475.4 5564.2 116609.0 118585.4 120365.0 121017.0 121054.0 95145.0 100757.5 107217.0 109874.5 115911.4 95478.5 95947.0 96891.0 98024.0 98748.5 11640.2 11957.0 12135.6 12269.4 12336.6 42529.0 44191.6 45074.5 45378.8 45917.0 57475.0 59115.7 59514.7 59614.4 60063.0 17200.1 17289.6 17300.1 17329.9 17390.1 18662.3 18789.8 18795.9 18900.1 19070.5 15546.4 16098.1 16121.0 16173.9 16256.1 17405.8 17425.3 17595.7 17596.5 17640.8 15224.8 15421.8 15491.2 15528.8 15642.6 12890.6 13191.2 13212.9 13276.0 13378.9 12730.9 13000.9 13139.2 13349.0 13479.3 21:54:59 INFO - PID 3812 | -------- Summary: end -------- 21:54:59 INFO - PID 3812 | 21:54:59 INFO - TEST-INFO | 3812: exit 0 21:54:59 INFO - TEST-OK | dromaeo_css | took 1112110ms 21:54:59 INFO - TEST-START | kraken 21:54:59 INFO - Initialising browser for kraken test... 21:54:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54792/getInfo.html -profile /tmp/tmp82Gjpv/profile 21:54:59 INFO - TEST-INFO | started process 4179 (/builds/slave/test/build/application/firefox/firefox http://localhost:54792/getInfo.html) 21:55:05 INFO - TEST-INFO | 4179: exit 0 21:55:05 INFO - Browser initialized. 21:55:05 INFO - Running cycle 1/1 for kraken test... 21:55:05 INFO - Using env: {'DISPLAY': ':0', 21:55:05 INFO - 'HOME': '/home/cltbld', 21:55:05 INFO - 'JSGC_DISABLE_POISONING': '1', 21:55:05 INFO - 'LANG': 'en_US.UTF-8', 21:55:05 INFO - 'LANGUAGE': 'en_US:en', 21:55:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:55:05 INFO - 'LOGNAME': 'cltbld', 21:55:05 INFO - 'MAIL': '/var/mail/cltbld', 21:55:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:55:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:55:05 INFO - 'MOZ_CRASHREPORTER': '1', 21:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:55:05 INFO - 'MOZ_NO_REMOTE': '1', 21:55:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:05 INFO - 'NO_EM_RESTART': '1', 21:55:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:05 INFO - 'PWD': '/builds/slave/test', 21:55:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:55:05 INFO - 'RUST_BACKTRACE': 'full', 21:55:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:55:05 INFO - 'SHELL': '/bin/bash', 21:55:05 INFO - 'SHLVL': '1', 21:55:05 INFO - 'TERM': 'linux', 21:55:05 INFO - 'TMOUT': '86400', 21:55:05 INFO - 'USER': 'cltbld', 21:55:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214', 21:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:05 INFO - '_': '/tools/buildbot/bin/python'} 21:55:05 INFO - TEST-INFO | started process 4413 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp82Gjpv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:55:06 INFO - PID 4413 | 21:55:06 INFO - PID 4413 | (/builds/slave/test/build/application/firefox/firefox:4462): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:55:06 INFO - PID 4413 | 21:55:16 INFO - PID 4413 | RSS: Main: 157044736 21:55:16 INFO - PID 4413 | 21:55:19 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54792/tests/kraken/driver.html?testName=audio-beat-detection) 21:55:20 INFO - PID 4413 | RSS: Main: 163225600 21:55:20 INFO - PID 4413 | 21:55:22 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54792/tests/kraken/driver.html?testName=audio-dft) 21:55:23 INFO - PID 4413 | RSS: Main: 163516416 21:55:23 INFO - PID 4413 | 21:55:26 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54792/tests/kraken/driver.html?testName=audio-fft) 21:55:26 INFO - PID 4413 | RSS: Main: 159076352 21:55:26 INFO - PID 4413 | 21:55:28 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54792/tests/kraken/driver.html?testName=audio-oscillator) 21:55:29 INFO - PID 4413 | RSS: Main: 160030720 21:55:29 INFO - PID 4413 | 21:55:31 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54792/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:55:32 INFO - PID 4413 | RSS: Main: 160034816 21:55:32 INFO - PID 4413 | 21:55:35 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54792/tests/kraken/driver.html?testName=imaging-darkroom) 21:55:36 INFO - PID 4413 | RSS: Main: 162553856 21:55:36 INFO - PID 4413 | 21:55:39 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54792/tests/kraken/driver.html?testName=imaging-desaturate) 21:55:40 INFO - PID 4413 | RSS: Main: 162451456 21:55:40 INFO - PID 4413 | 21:55:43 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54792/tests/kraken/driver.html?testName=json-parse-financial) 21:55:44 INFO - PID 4413 | RSS: Main: 163004416 21:55:44 INFO - PID 4413 | 21:55:45 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54792/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:55:46 INFO - PID 4413 | RSS: Main: 162705408 21:55:46 INFO - PID 4413 | 21:55:47 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54792/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:55:48 INFO - PID 4413 | RSS: Main: 162734080 21:55:48 INFO - PID 4413 | 21:55:50 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54792/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:55:51 INFO - PID 4413 | RSS: Main: 159834112 21:55:51 INFO - PID 4413 | 21:55:53 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54792/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:55:54 INFO - PID 4413 | RSS: Main: 159920128 21:55:54 INFO - PID 4413 | 21:55:57 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54792/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:55:57 INFO - PID 4413 | RSS: Main: 160141312 21:55:57 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | Cycle 1(1): loaded http://localhost:54792/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54792/tests/kraken/driver.html?testName=ai-astar) 21:55:59 INFO - PID 4413 | RSS: Main: 161759232 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | __start_tp_report 21:55:59 INFO - PID 4413 | _x_x_mozilla_page_load 21:55:59 INFO - PID 4413 | _x_x_mozilla_page_load_details 21:55:59 INFO - PID 4413 | |i|pagename|runs| 21:55:59 INFO - PID 4413 | |0;ai-astar;107;107;106;105;106;108;107;107;105;105 21:55:59 INFO - PID 4413 | |1;audio-beat-detection;120;118;116;116;116;115;121;115;122;116 21:55:59 INFO - PID 4413 | |2;audio-dft;163;161;164;161;160;160;162;166;169;165 21:55:59 INFO - PID 4413 | |3;audio-fft;74;75;73;74;72;74;75;74;73;77 21:55:59 INFO - PID 4413 | |4;audio-oscillator;79;79;80;80;79;79;79;79;79;79 21:55:59 INFO - PID 4413 | |5;imaging-gaussian-blur;110;113;110;113;114;114;110;113;114;114 21:55:59 INFO - PID 4413 | |6;imaging-darkroom;148;150;146;148;148;147;147;149;150;149 21:55:59 INFO - PID 4413 | |7;imaging-desaturate;98;94;94;93;94;94;94;93;94;94 21:55:59 INFO - PID 4413 | |8;json-parse-financial;52;53;58;51;51;50;51;50;50;52 21:55:59 INFO - PID 4413 | |9;json-stringify-tinderbox;38;38;38;38;38;39;38;39;38;38 21:55:59 INFO - PID 4413 | |10;stanford-crypto-aes;73;84;73;75;73;73;71;75;72;72 21:55:59 INFO - PID 4413 | |11;stanford-crypto-ccm;116;111;120;110;116;110;112;111;118;106 21:55:59 INFO - PID 4413 | |12;stanford-crypto-pbkdf2;166;167;168;166;173;169;181;164;165;180 21:55:59 INFO - PID 4413 | |13;stanford-crypto-sha256-iterative;63;62;63;68;66;62;64;63;66;62 21:55:59 INFO - PID 4413 | __end_tp_report 21:55:59 INFO - PID 4413 | __start_cc_report 21:55:59 INFO - PID 4413 | _x_x_mozilla_cycle_collect,719 21:55:59 INFO - PID 4413 | __end_cc_report 21:55:59 INFO - PID 4413 | __startTimestamp1506056159924__endTimestamp 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | ------- Summary: start ------- 21:55:59 INFO - PID 4413 | Number of tests: 14 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#0] ai-astar Cycles:10 Average:106.30 Median:107.00 stddev:1.06 (1.0%) stddev-sans-first:1.09 21:55:59 INFO - PID 4413 | Values: 107.0 107.0 106.0 105.0 106.0 108.0 107.0 107.0 105.0 105.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#1] audio-beat-detection Cycles:10 Average:117.50 Median:117.00 stddev:2.59 (2.2%) stddev-sans-first:2.59 21:55:59 INFO - PID 4413 | Values: 120.0 118.0 116.0 116.0 116.0 115.0 121.0 115.0 122.0 116.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.50 stddev:2.92 (1.8%) stddev-sans-first:3.10 21:55:59 INFO - PID 4413 | Values: 163.0 161.0 164.0 161.0 160.0 160.0 162.0 166.0 169.0 165.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#3] audio-fft Cycles:10 Average:74.10 Median:74.00 stddev:1.37 (1.9%) stddev-sans-first:1.45 21:55:59 INFO - PID 4413 | Values: 74.0 75.0 73.0 74.0 72.0 74.0 75.0 74.0 73.0 77.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#4] audio-oscillator Cycles:10 Average:79.20 Median:79.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 21:55:59 INFO - PID 4413 | Values: 79.0 79.0 80.0 80.0 79.0 79.0 79.0 79.0 79.0 79.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#5] imaging-gaussian-blur Cycles:10 Average:112.50 Median:113.50 stddev:1.78 (1.6%) stddev-sans-first:1.64 21:55:59 INFO - PID 4413 | Values: 110.0 113.0 110.0 113.0 114.0 114.0 110.0 113.0 114.0 114.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.50 stddev:1.32 (0.9%) stddev-sans-first:1.39 21:55:59 INFO - PID 4413 | Values: 148.0 150.0 146.0 148.0 148.0 147.0 147.0 149.0 150.0 149.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:1.40 (1.5%) stddev-sans-first:0.44 21:55:59 INFO - PID 4413 | Values: 98.0 94.0 94.0 93.0 94.0 94.0 94.0 93.0 94.0 94.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:51.50 stddev:2.39 (4.6%) stddev-sans-first:2.54 21:55:59 INFO - PID 4413 | Values: 52.0 53.0 58.0 51.0 51.0 50.0 51.0 50.0 50.0 52.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 21:55:59 INFO - PID 4413 | Values: 38.0 38.0 38.0 38.0 38.0 39.0 38.0 39.0 38.0 38.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:73.00 stddev:3.70 (5.1%) stddev-sans-first:3.90 21:55:59 INFO - PID 4413 | Values: 73.0 84.0 73.0 75.0 73.0 73.0 71.0 75.0 72.0 72.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#11] stanford-crypto-ccm Cycles:10 Average:113.00 Median:114.00 stddev:4.32 (3.8%) stddev-sans-first:4.44 21:55:59 INFO - PID 4413 | Values: 116.0 111.0 120.0 110.0 116.0 110.0 112.0 111.0 118.0 106.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:168.50 stddev:6.12 (3.6%) stddev-sans-first:6.32 21:55:59 INFO - PID 4413 | Values: 166.0 167.0 168.0 166.0 173.0 169.0 181.0 164.0 165.0 180.0 21:55:59 INFO - PID 4413 | 21:55:59 INFO - PID 4413 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:63.50 stddev:2.08 (3.3%) stddev-sans-first:2.18 21:55:59 INFO - PID 4413 | Values: 63.0 62.0 63.0 68.0 66.0 62.0 64.0 63.0 66.0 62.0 21:55:59 INFO - PID 4413 | -------- Summary: end -------- 21:55:59 INFO - PID 4413 | 21:56:00 INFO - PID 4413 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:56:00 INFO - TEST-INFO | 4413: exit 0 21:56:00 INFO - TEST-OK | kraken | took 60747ms 21:56:00 INFO - SUITE-END | took 1172s 21:56:01 INFO - Completed test suite (00:19:33) 21:56:01 INFO - javascript benchmark 21:56:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8545.437942256009, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.7955882867595, 3146.0090071627437, 3174.206448387903, 3179.952300715488, 3188.8246146461943, 6164.7534098636115, 6277.2153480814895, 6288.339724328943, 6314.210723659543, 6396.808095757123, 6222.533310001757, 6240.594361366508, 6259.718312675929, 6291.716872740726, 6292.811215663526, 5751.338596061444, 5835.0663893777, 5904.527637788967, 5926.644401335912, 5988.580799344048, 5842.707864606753, 5874.5594080443925, 5898.705064746767, 5908.556858235649, 5950.761969521214, 499.63289328687233, 5689.886202275952, 5703.942960570383, 5704.914426283609, 5789.736960710012, 5734.311882574118, 5803.0, 5827.155062515912, 5828.358880523139, 5852.502537284336, 5748.42515748426, 5790.913136302959, 5835.387284335168, 5860.150278209636, 5872.970635146839, 6244.906326405107, 6280.403361680633, 6302.1177035215105, 6306.1171435998995, 6378.904316435257, 6750.223724271723, 6775.898361524581, 6841.2132604750595, 6841.521093523455, 6846.349596788297, 6651.068850360954, 6743.527953043289, 6748.8312792180095, 6841.452683785335, 6932.584044957319, 6218.378162183791, 6239.188905442309, 6262.655553944532, 6274.56078074535, 6286.308506064375, 6892.310768923117, 6919.065926099912, 6975.476839236976, 7059.2234854166, 7079.115110611174, 6764.526483146231, 6776.28848970851, 6777.0, 6779.0509328694025, 6792.72829086831, 6817.250102488722, 6825.658717064127, 6857.314268573103, 6932.306769323028, 6937.9653101735175, 6524.575902566318, 6574.539782215199, 6608.240052394036, 6752.459803215731, 6853.965730171417, 6786.59280443175, 6818.965905170442, 6837.282085381063, 6861.210960739579, 6893.0, 5323.627346085815, 5395.082835917825, 5404.216388623628, 5407.378151512547, 5410.242566040757, 4669.463011753692, 4688.578027977498, 4697.412823397075, 4723.645726570521, 4733.644976626767, 5752.9424705753245, 5781.710914454345, 5801.477866991991, 5817.970910145506, 5843.737031833492, 6877.449804015667, 6915.930840691629, 6921.861562768717, 6936.79189624312, 6938.757143499954, 5842.970785146047, 5846.766129354864, 5872.354041055481, 5921.970390148108, 5922.0820772779425, 5662.943370566324, 5769.826905192851, 5868.882622347529, 5888.764449422061, 5943.375945525744, 6511.186101737283, 6610.7025183866845, 6660.600363978177, 6678.7328506859185, 6679.565828221247, 6515.641639709862, 6825.590464572141, 6826.556273842184, 6891.586504809728, 6917.550359226634, 6479.676016199265, 6491.123698300669, 6493.902591461037, 6530.640814755234, 6543.247526534319, 6619.602823830585, 6633.568818026813, 6636.734530618721, 6706.83232919181, 6789.524733268624, 6897.103376561216, 6901.137357830271, 6914.965425172842, 6947.3747362737595, 6953.513254072166, 6604.801855944329, 6614.70233839488, 6635.4359879410795, 6683.966580167068, 6721.899171512529, 6412.487001039813, 6526.543141980016, 6602.537822352581, 6606.735730570724, 6707.731690732316, 6448.32292609288, 6457.644829534421, 6480.6435646038035, 6482.319356467503, 6537.967310163419, 6685.2646208916, 6694.096296999843, 6708.0, 6722.9663851680425, 6757.256701762901, 6449.484041276781, 6463.385978332051, 6525.249596296487, 6537.346265373425, 6550.541462097607], "value": 6150.086425666226, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.8899754034435, 806.8386322735454, 809.0493669937822, 810.614957895, 811.1726039439768, 38866.611333886656, 42185.0, 42212.788936055316, 42409.93975150619, 42447.36328955068, 38045.0, 38257.61742382575, 38299.234015319744, 38338.0, 38438.231235375344, 7069.399101076402, 7951.20487951206, 8091.029076510827, 8091.4740541864885, 8092.514449133071, 7893.0, 7910.485818421813, 7975.162607926171, 7977.720779772709, 7989.280964713174, 7980.16208298129, 7996.200379962015, 8010.439269251155, 8046.718364857231, 8051.758447246562, 7936.801579960489, 7986.041674999008, 7997.1602981686665, 8011.0386753589655, 8015.719449819287, 7904.604769761547, 7969.362451003905, 7972.561509116997, 7972.880406793903, 7975.082865470487, 38413.80793096044, 38609.61390386088, 38671.41992870109, 38679.806600967095, 38829.41755873664, 3579.731520136, 3618.493410922473, 3618.764780289273, 3619.457081437779, 3621.8189090545366, 31821.363572728416, 31907.840460797546, 31915.0, 31938.20154496133, 31946.201344966328, 5001.224810154432, 5165.66423182492, 5180.456052114511, 5203.453637368098, 5246.737663116829, 189712.05143974329, 189939.05030474896, 189947.0, 190411.0, 190972.0, 42792.3581146283, 43709.0, 47917.28124078141, 48155.518444815105, 49185.26222106671, 52191.73904130455, 52245.47754522482, 52477.95044099173, 52492.73753631284, 52507.47492525026, 15185.924070379577, 15350.769738453931, 15365.154916479478, 15366.078035317692, 15366.154861482726, 12259.080568957364, 12283.201591896499, 12297.69255768595, 12306.876931230574, 12319.93840030812, 14300.213488258245, 14312.856871431153, 14381.78427323591, 14426.855731442762, 14508.709825803426, 5579.581531385163, 5648.435156484319, 5719.828405147852, 5740.637115791945, 5857.824265272048, 981.5877331520636, 1017.4912543728136, 1018.3279035836313, 1036.7200855769051, 1045.8378951262566, 22961.85190740456, 23012.424689382566, 23132.190373336853, 23415.41461463477, 23531.647025294635, 1094.392612100276, 1108.8669359676728, 1110.3670907582762, 1110.8666959964694, 1130.3556972525582, 12185.268883866996, 12271.263724176579, 12411.503539858304, 12411.689707757378, 12467.189632674028, 235560.0, 236492.0, 237222.0, 237988.0, 238578.0, 52183.478165217864, 52632.0, 52774.73612631912, 52898.0, 52919.47080529146, 1386.1475192756382, 1454.7745099509593, 1459.8248210214842, 1460.1239256446047, 1461.503088949741, 22765.203217887294, 22844.65733013973, 22940.0, 22949.196778113354, 23276.418089547897, 36882.262354752216, 36904.81547592245, 36922.0, 36933.26133477369, 36949.0, 194124.0, 197365.0, 197719.0, 197907.0, 197969.0, 30765.0, 31262.53106203363, 33353.66646333603, 34334.0, 34369.14077148091, 43646.56353436425, 43749.34375984426, 43834.78082609694, 44146.558534415526, 44243.0, 31936.201594959384, 32191.0, 32270.677293226767, 32314.0, 32388.190295241864, 31776.830809658335, 31874.0, 32419.837900810344, 32468.837655812516, 32687.34625307528, 36767.08082297964, 36768.448473273456, 36780.080497987765, 36977.0, 37023.444648330835, 31133.53299700461, 31233.687663123077, 31384.0, 31819.681803181673, 31823.840880795447, 23009.65485517685, 23659.0, 24045.639315410634, 24060.87969560141, 24144.396390090387], "value": 17151.568134690573, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.9773209071635, 571.3343954293249, 582.4670426559699, 595.345120367596, 599.7091410665827, 22155.66766498502, 23080.769192308093, 23109.884450577767, 23116.190933317357, 23140.76859231407, 20020.399388018384, 20289.492762680904, 20393.08231129599, 21474.0, 23055.30834074976, 6523.412892839656, 6676.699548520328, 6679.56582822115, 6692.899606505882, 6713.697883595226, 6608.438282745972, 6685.164354455693, 6686.1308029956035, 6702.497312701543, 6703.296153903843, 6330.303666596671, 6572.178477690289, 6573.0, 6588.3411658833975, 6596.274409814917, 6555.803325900207, 6576.177977752781, 6597.703103360361, 6602.207735071798, 6608.339166083353, 5867.76528938842, 6098.054801505729, 6101.664408457533, 6149.723262453201, 6181.227346581677, 28432.28919277014, 28840.855795721098, 28932.42135157285, 28939.131826045043, 28960.0, 2046.9590608187902, 2063.1850419084526, 2072.057213967641, 2079.6048750737314, 2085.7392825896763, 19318.613627727507, 19647.803521964885, 19764.60470790576, 19923.80076199234, 19945.9002704987, 2369.0, 2422.733499315074, 2445.376429010591, 2449.914253001154, 2453.4970331082263, 116042.0, 121041.0, 141497.0, 147367.0, 147579.0, 14809.407623694931, 14909.030912990838, 14910.0, 15138.848611513744, 15510.146941918078, 23772.52454950926, 23871.806409679448, 23876.880615596812, 23890.0, 23892.522149556913, 9730.318877678636, 9776.755581110529, 9868.457234852152, 9922.156616687662, 9979.401235925867, 8954.820903581893, 8985.0, 9075.727728168165, 9125.45247285165, 9137.177654011171, 9099.044600317004, 9118.908810911938, 9348.766280843034, 9421.76445588852, 9425.528723563795, 2646.3119588907025, 2745.4509098180442, 2758.524259433129, 2769.9307517311827, 2845.203343063904, 940.0, 970.1608108985818, 973.7711637765119, 974.3812678948776, 977.9168770654574, 19283.132259048376, 19330.03349832503, 19422.22311107568, 19706.60586788285, 20010.29963951255, 979.3242662562809, 1011.691434112588, 1025.139859791189, 1033.2973577967055, 1036.6423583863555, 10682.412467314372, 10712.625058122929, 10742.624008159833, 10755.139588832875, 10773.24587278883, 474571.0, 482776.0, 484185.0, 484720.0, 486593.0, 107156.46421767841, 109053.0, 109071.45464272628, 109206.45396773124, 115374.0, 1136.255752482142, 1144.164759725405, 1180.321315243721, 1180.7579446213676, 1182.1016027818919, 16671.49985500437, 17116.572085697957, 17293.56766080858, 17474.82525174783, 17545.210465529337, 54238.72880635571, 55294.170587440414, 56289.15566266427, 57203.71398142983, 57458.0, 69713.0, 69810.65094674494, 70061.0, 70104.0, 70190.64904675649, 14254.144751314954, 14594.562163135122, 14601.196934168724, 14667.779983300046, 14685.55943321702, 22218.0, 22317.330480085624, 22558.548829023657, 22626.773732263126, 22644.094236231027, 17835.910820445813, 17909.820901790816, 18002.63994720072, 18009.369672061413, 18107.18517666682, 16108.67782644364, 16233.269502872636, 16256.918715406347, 16286.185690715654, 16306.510804675878, 17845.0, 18610.906945465187, 18720.438386848415, 18838.717419238998, 18846.811531885054, 14319.28403579796, 14381.7842732357, 14534.927325363305, 14552.85447145515, 14562.708745825235, 17699.82300177033, 17784.644307114046, 17851.821481785017, 17907.73138402899, 17973.0], "value": 11818.413908240458, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.9256440895751, 1422.217780220879, 1426.486464872646, 1427.4789701758848, 1431.0984080029586, 15251.31369088391, 15906.52280431586, 15919.522414327588, 15920.840791592094, 15928.76106858398, 15558.766618500704, 15682.059076455393, 15684.529464116065, 15714.371425143008, 15771.447999320026, 2777.652793400825, 2886.090881372361, 2892.378138700179, 2896.5944767732535, 2898.9275268118254, 2737.767289780371, 2796.049343223304, 2819.4783964966446, 2821.2664707176077, 2827.1942496388533, 2836.3901761121356, 2867.827930324187, 2880.8415537145447, 2884.899028534002, 2887.768978481727, 2677.5849743289823, 2678.973210267902, 2720.2383332666886, 2723.360010397555, 2738.137486691696, 2526.2421273617842, 2537.6320433537035, 2560.411105445758, 2565.115035312814, 2565.1406778729147, 14676.779848302283, 15022.173780442072, 15042.32309546073, 15043.323050462646, 15087.094774313577, 7737.032870891138, 8044.235797599219, 8048.59757012147, 8048.798780030487, 8049.718259860935, 5421.268128802601, 5581.6930068846195, 5691.544676425876, 5731.254936858182, 5762.596618236725, 1963.7447131872907, 2007.9498012549802, 2026.929057482981, 2052.887091209983, 2055.9486012849648, 27203.591946120825, 27286.727132728618, 27288.0, 27495.9999999998, 27569.310767230778, 6857.965710171417, 6953.721851126, 6970.0, 7058.082449281561, 7103.218645948941, 17849.464516064538, 17888.642227155644, 17918.372856949944, 17955.910220448815, 17960.101994900204, 7822.765317040498, 7869.449138560246, 7924.049114106228, 7932.286094251545, 7960.601969901481, 7207.711691532385, 7329.963350183321, 7360.96319518399, 7371.741989030358, 7377.926220737724, 5010.2735103409805, 5206.5314121729225, 5244.396894357122, 5272.9736351318, 5288.180332048539, 1373.009136751694, 1376.6145479265822, 1421.2112277685983, 1534.6547026918854, 1537.1007960343325, 918.6922381002305, 935.0882889182992, 937.5827756648363, 938.8544775559799, 944.3861490031397, 7968.0, 8049.55727434985, 8110.188981101961, 8131.83736325282, 8133.308668763103, 985.3792110970186, 987.2102318145455, 996.3673067602125, 999.48526508848, 1007.6120693533043, 4866.732329721828, 4961.776720047606, 4962.156433406258, 4975.850724478271, 5048.3941926969, 21609.243676471247, 21747.0, 22538.774612253994, 22573.774262257168, 22646.320610381714, 22260.22089226869, 22315.77684223137, 22469.10123595038, 22568.66147007796, 22605.0, 10738.389241613664, 11690.298582085103, 11745.001674857562, 11847.289162650268, 11891.881081189078, 8475.406721529434, 8502.872456913276, 8514.74455766328, 8527.573621318785, 8533.146685331418, 18660.906695466434, 19107.0, 19134.808651913303, 19254.422367329003, 19415.0, 19699.606007880047, 19781.40655780329, 19785.307514236934, 19790.802091978894, 19831.20675172977, 11116.555337786722, 11264.0, 11319.434028298387, 11332.546698131982, 11333.150013749168, 14042.0, 14165.43338266499, 14202.786958195842, 14266.42934282617, 14412.567622971328, 10107.595696172071, 10747.0, 11511.597094101666, 11540.019098376397, 11808.645740627795, 11030.172737044593, 11231.66305010851, 11294.378809165246, 11530.0, 11700.648980530597, 10480.0, 10671.839922401325, 10680.359178449318, 10683.94658026705, 10690.41202733857, 7644.503107298008, 7659.770206893802, 7712.0, 7719.5754233515445, 7760.805979850549, 7795.532268063935, 7803.414743894345, 7842.176571459914, 7887.092984306878, 7983.960080199562], "value": 6818.969025835279, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.8177881165087, 533.2241588488745, 534.1518346521037, 537.1566640374614, 537.6827671673711, 6293.276273228568, 6332.905006424908, 6362.522810789192, 6364.840878978028, 6381.202349706286, 6226.657533835637, 6244.56288059836, 6248.250209974809, 6251.3123556408755, 6275.560710750249, 5182.896342073166, 5189.922151167735, 5197.818076367328, 5202.193659982709, 5216.139337009389, 4878.999220639077, 5143.717095559743, 5172.793088276483, 5207.495516378343, 5227.3465816772905, 4997.0, 5022.246663000543, 5022.5730812880765, 5024.924626130611, 5038.874028149289, 5011.316682238552, 5035.194368900959, 5050.797968081272, 5060.190369540893, 5063.822766203165, 4493.404841281331, 4511.729296242236, 4519.502854685982, 4533.320001999694, 4544.659150563721, 5993.580449368546, 6036.63780173191, 6089.117078023662, 6098.0, 6112.63324200545, 5761.567882408836, 5770.740316685759, 5807.822066724978, 5810.50610698087, 5835.183074369592, 6465.224173099211, 6468.1268028815975, 6474.449671777905, 6495.902561461582, 6507.837304067436, 4933.358663373774, 4934.1611925972675, 4937.8271760488215, 4941.950580494221, 4956.8760780980765, 6667.0, 6709.161354830646, 6861.931380686229, 6875.1749790025515, 6945.02066910928, 5004.724740139327, 5066.417362003417, 5071.163258062378, 5072.239164125411, 5085.821996230188, 5086.287919691246, 5105.744712764347, 5109.948900510947, 5118.462561431071, 5122.795088196505, 5681.545476361881, 5711.485966262973, 5713.77153911911, 5740.167675687002, 5768.913466298009, 6511.186101737283, 6572.934270657328, 6615.503837212228, 6636.137302150786, 6656.467482601381, 1740.3821643316694, 1771.406578796092, 1778.3597904754279, 1787.9195436205403, 1789.8210178981997, 1139.868915074777, 1207.746373261625, 1221.1207930290177, 1229.956951506693, 1239.2626387299542, 5512.2558454608115, 5574.219609254626, 5584.301962254718, 5593.580481463907, 5677.886442271131, 6531.608103513804, 6594.77996570636, 6613.66931653425, 6624.6025238485845, 6759.628220447923, 5565.693886836263, 5569.721513924287, 5610.046292130348, 5615.719214039282, 5622.606417550814, 3459.757816952789, 3468.5664291963503, 3490.2495963367996, 3507.859685612547, 3513.8594456221977, 6258.499320054385, 6327.810165695037, 6353.428191462791, 6422.45409140219, 6470.741170353227, 6703.597784132968, 6739.427148692318, 6788.932110678928, 6856.766111752049, 6882.0, 5994.910076348859, 6283.685815709287, 6337.302896681362, 6431.485481161402, 6504.902426463512, 6425.261094973951, 6460.12788273577, 6471.546991710535, 6502.512311576556, 6666.633335166613, 6428.839279017875, 6482.935170648422, 6509.967450162719, 6562.704678289584, 6635.156335874742, 6453.322401147811, 6504.544681872413, 6523.282438931618, 6525.967370163119, 6584.0, 4925.261210818406, 4927.359443272351, 5002.424236365533, 5003.699038250008, 5017.37282839645, 6640.203175618956, 6669.299723529154, 6756.189257289157, 6775.322467753185, 6787.355201255873, 6348.174737284308, 6386.9680651596445, 6389.9680501597195, 6393.424591786761, 6506.674666266762, 6372.024672351586, 6440.2915679276175, 6470.870582588416, 6504.577202481823, 6587.473002159912, 6333.638267772267, 6343.302236754045, 6414.358564143548, 6497.090407342881, 6507.837304067436, 6453.774117905851, 6511.0, 6553.377429144223, 6581.407673309425, 6632.801015969529, 6308.6436416170745, 6318.462930650946, 6342.175517182736, 6352.936470635419, 6401.407429990862], "value": 5049.886303511377, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [908.9272858171346, 921.7603423109994, 929.9721008369743, 936.8032713130242, 944.0700909604037, 18996.33512827051, 19901.800981990174, 19913.3030343938, 19913.50216244593, 19942.302019429306, 19074.33239836606, 19142.808571914276, 19280.03599820007, 19321.227150913946, 19322.0, 958.094600602431, 967.7193613851974, 977.3305285879195, 977.8582105594685, 978.3445091788519, 983.1348413396202, 984.0946329376955, 984.266721292638, 984.6455276100634, 986.9358491698052, 949.5062567464914, 1029.0327092533032, 1035.2908257843364, 1041.8333066709329, 1042.3433237060642, 1014.137982714694, 1016.0296916444778, 1023.9795204095876, 1027.8149933012055, 1028.0695970147067, 723.4610215389516, 745.8210029592859, 750.523417629809, 754.6264599023518, 756.9318761311508, 19314.32399866012, 19366.322178723676, 19442.0, 19482.80517194838, 19506.0, 20820.895895520425, 21315.893420532953, 21324.46688832776, 21655.783442165535, 21987.0, 17112.144392780312, 17260.395886143924, 17557.473275801745, 17747.73378399325, 17847.464576062608, 3739.326921154191, 3770.26479836432, 3771.830267637963, 3772.1324095457926, 3773.056735816046, 102612.48693756558, 137247.0, 139919.30040349835, 140087.0, 141346.0, 14998.550043498712, 16077.598060048358, 16086.75869861953, 16367.017978921303, 16414.507564773077, 30913.0, 31790.0, 31813.363732725215, 31855.52216716751, 32003.359932801213, 9256.398334108262, 9278.886533616009, 9353.298502612195, 9356.204722598519, 9361.110694484014, 4162.604552567501, 4239.957600423956, 4259.531451540328, 4261.701680882308, 4261.722988005769, 5328.387235467893, 5419.512243898068, 5422.5119739223655, 5431.972840135774, 5435.7825686972865, 1055.519738518972, 1090.3784842639777, 1097.4896673047072, 1110.7500812317164, 1116.8603924509437, 633.7243299164878, 639.9520035997319, 652.3802387731674, 653.2455014458309, 669.0900375489331, 11512.366819824823, 11693.239939403911, 11699.473523691284, 11726.70683232926, 12450.81323780144, 644.8420137066355, 645.854682696399, 646.6120327803375, 661.6526323680107, 662.7315937045504, 5390.568754499631, 5464.590155738336, 5467.152591348347, 5475.370332411744, 5564.165375193753, 116609.0, 118585.40707296408, 120365.0, 121017.0, 121054.0, 95145.0, 100757.49621251847, 107217.0, 109874.45062774635, 115911.42044289724, 95478.52260738652, 95947.0, 96891.0, 98024.0, 98748.50625746825, 11640.243384180001, 11957.0, 12135.575254866037, 12269.447874845831, 12336.62990110298, 42529.0, 44191.55808441874, 45074.54925450704, 45378.77310613426, 45917.0, 57475.0, 59115.70442147762, 59514.70242648932, 59614.40385596262, 60063.0, 17200.053997030285, 17289.567760805578, 17300.13499325054, 17329.91335043367, 17390.130493475528, 18662.34681786131, 18789.812101879354, 18795.90602046981, 18900.05499724981, 19070.523236919187, 15546.37814487408, 16098.11460369691, 16121.0, 16173.919130404272, 16256.105914174834, 17405.82594174042, 17425.302987880343, 17595.736063959306, 17596.472105836845, 17640.823591763918, 15224.771628425804, 15421.84578154204, 15491.225438728245, 15528.84471155274, 15642.608934776721, 12890.613281601567, 13191.208527488381, 13212.933935330262, 13276.0, 13378.86621133815, 12730.936345318214, 13000.934995324962, 13139.211647301192, 13349.0, 13479.32603369808], "value": 9070.601038925886, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1406.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 106.0, 105.0, 106.0, 108.0, 107.0, 107.0, 105.0, 105.0], "value": 106.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 118.0, 116.0, 116.0, 116.0, 115.0, 121.0, 115.0, 122.0, 116.0], "value": 117.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 164.0, 161.0, 160.0, 160.0, 162.0, 166.0, 169.0, 165.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 74.0, 72.0, 74.0, 75.0, 74.0, 73.0, 77.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 80.0, 79.0, 79.0, 79.0, 79.0, 79.0, 79.0], "value": 79.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 110.0, 113.0, 114.0, 114.0, 110.0, 113.0, 114.0, 114.0], "value": 112.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 146.0, 148.0, 148.0, 147.0, 147.0, 149.0, 150.0, 149.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 58.0, 51.0, 51.0, 50.0, 51.0, 50.0, 50.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 39.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 84.0, 73.0, 75.0, 73.0, 73.0, 71.0, 75.0, 72.0, 72.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 111.0, 120.0, 110.0, 116.0, 110.0, 112.0, 111.0, 118.0, 106.0], "value": 113.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 168.0, 166.0, 173.0, 169.0, 181.0, 164.0, 165.0, 180.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 68.0, 66.0, 62.0, 64.0, 63.0, 66.0, 62.0], "value": 63.9, "unit": "score"}]}]} 21:56:01 INFO - Return code: 0 21:56:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:56:01 INFO - # TBPL SUCCESS # 21:56:01 INFO - Running post-action listener: _package_coverage_data 21:56:01 INFO - Running post-action listener: _resource_record_post_action 21:56:01 INFO - [mozharness: 2017-09-22 04:56:01.244181Z] Finished run-tests step (success) 21:56:01 INFO - Running post-run listener: _resource_record_post_run 21:56:01 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 9412608; Write bytes: 461611008; Read time: 4180; Write time: 664788 21:56:01 INFO - TinderboxPrint: CPU usage
13.1% 21:56:01 INFO - TinderboxPrint: I/O read bytes / time
9,412,608 / 4,180 21:56:01 INFO - TinderboxPrint: I/O write bytes / time
461,611,008 / 664,788 21:56:01 INFO - TinderboxPrint: CPU idle
8,257.0 (86.9%) 21:56:01 INFO - TinderboxPrint: CPU user
1,190.5 (12.5%) 21:56:01 INFO - TinderboxPrint: Swap in / out
0 / 0 21:56:01 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 92569600; Read time: 0; Write time: 114992 21:56:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:56:01 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 7041024; Write bytes: 369041408; Read time: 3680; Write time: 549796 21:56:01 INFO - Running post-run listener: _upload_blobber_files 21:56:01 INFO - Blob upload gear active. 21:56:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:56:01 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 21:56:01 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'] 21:56:01 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 21:56:02 INFO - (blobuploader) - INFO - Open directory for files ... 21:56:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:56:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:56:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:03 INFO - (blobuploader) - INFO - Done attempting. 21:56:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:56:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:56:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:04 INFO - (blobuploader) - INFO - Done attempting. 21:56:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:56:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:56:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:07 INFO - (blobuploader) - INFO - Done attempting. 21:56:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:56:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:56:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:56:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:56:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:56:09 INFO - (blobuploader) - INFO - Done attempting. 21:56:09 INFO - (blobuploader) - INFO - Iteration through files over. 21:56:09 INFO - Return code: 0 21:56:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:56:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:56:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34feae79766f7d49430cf578da5706157f7416a79f57a025cf9c51295522af2c23359dfb7718108504e7d9b54cf519372cdd8f1c07189ca4e716a78293dff042", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43ea70c882cd7d08bd03aad72c14a6c53eb91302d115e8d1d967e3932ad9773a37866c514b0c281a4f97d3097e083192ec33f2858556a44026685220d8319922", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/024d8d1e645ed2d8756910390db2111de640dbcee645628f50f31f3ed262d8097124d63f47582852ae85e38c19bee1737c28d599deb939170846e551feb2d2c3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1c17b071b255b02d1e346e999e8cb1ac7b3441175b5e77fe7ab02ecd62a16ea8eed2f8c27d57b5e3b8ea085db98a7903d48d5d580ef51e84005433e1f945da5"} 21:56:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:56:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:56:09 INFO - Contents: 21:56:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34feae79766f7d49430cf578da5706157f7416a79f57a025cf9c51295522af2c23359dfb7718108504e7d9b54cf519372cdd8f1c07189ca4e716a78293dff042", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43ea70c882cd7d08bd03aad72c14a6c53eb91302d115e8d1d967e3932ad9773a37866c514b0c281a4f97d3097e083192ec33f2858556a44026685220d8319922", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/024d8d1e645ed2d8756910390db2111de640dbcee645628f50f31f3ed262d8097124d63f47582852ae85e38c19bee1737c28d599deb939170846e551feb2d2c3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1c17b071b255b02d1e346e999e8cb1ac7b3441175b5e77fe7ab02ecd62a16ea8eed2f8c27d57b5e3b8ea085db98a7903d48d5d580ef51e84005433e1f945da5"} 21:56:09 INFO - Running post-run listener: copy_logs_to_upload_dir 21:56:09 INFO - Copying logs to upload dir... 21:56:09 INFO - mkdir: /builds/slave/test/build/upload/logs 21:56:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.250873 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-21 21:56:09.164427) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 21:56:09.172337) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34feae79766f7d49430cf578da5706157f7416a79f57a025cf9c51295522af2c23359dfb7718108504e7d9b54cf519372cdd8f1c07189ca4e716a78293dff042", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43ea70c882cd7d08bd03aad72c14a6c53eb91302d115e8d1d967e3932ad9773a37866c514b0c281a4f97d3097e083192ec33f2858556a44026685220d8319922", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/024d8d1e645ed2d8756910390db2111de640dbcee645628f50f31f3ed262d8097124d63f47582852ae85e38c19bee1737c28d599deb939170846e551feb2d2c3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1c17b071b255b02d1e346e999e8cb1ac7b3441175b5e77fe7ab02ecd62a16ea8eed2f8c27d57b5e3b8ea085db98a7903d48d5d580ef51e84005433e1f945da5"} build_url:https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011199 build_url: 'https://queue.taskcluster.net/v1/task/Qc4qapSdREq4to_uIRkeSw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34feae79766f7d49430cf578da5706157f7416a79f57a025cf9c51295522af2c23359dfb7718108504e7d9b54cf519372cdd8f1c07189ca4e716a78293dff042", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43ea70c882cd7d08bd03aad72c14a6c53eb91302d115e8d1d967e3932ad9773a37866c514b0c281a4f97d3097e083192ec33f2858556a44026685220d8319922", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/024d8d1e645ed2d8756910390db2111de640dbcee645628f50f31f3ed262d8097124d63f47582852ae85e38c19bee1737c28d599deb939170846e551feb2d2c3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1c17b071b255b02d1e346e999e8cb1ac7b3441175b5e77fe7ab02ecd62a16ea8eed2f8c27d57b5e3b8ea085db98a7903d48d5d580ef51e84005433e1f945da5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 21:56:09.203391) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 21:56:09.203757) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506054759.145508-1906422214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 21:56:09.287946) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 21:56:09.288264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 21:56:09.288579) ========= ========= Total master_lag: 0.29 =========