builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-039 starttime: 1505963426.77 results: success (0) revision: 47f7b6c64265bc7bdd22eef7ab71abc97cf3f8bf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:26.770275) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:26.770769) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:26.771061) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:26.823279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:26.823549) ========= 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=5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 20:10:26-- 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% 46.3M=0s 2017-09-20 20:10:27 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.234135 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:27.095298) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:27.095623) ========= 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=5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020165 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:27.182763) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-20 20:10:27.183110) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 47f7b6c64265bc7bdd22eef7ab71abc97cf3f8bf --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 47f7b6c64265bc7bdd22eef7ab71abc97cf3f8bf --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=5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 20:10:27,267 truncating revision to first 12 chars 2017-09-20 20:10:27,267 Setting DEBUG logging. 2017-09-20 20:10:27,267 attempt 1/10 2017-09-20 20:10:27,267 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/47f7b6c64265?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 20:10:28,212 unpacking tar archive at: mozilla-central-47f7b6c64265/testing/mozharness/ program finished with exit code 0 elapsedTime=1.516289 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-20 20:10:28.729978) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:28.730282) ========= script_repo_revision: 47f7b6c64265bc7bdd22eef7ab71abc97cf3f8bf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:28.730704) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:28.731222) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 20:10:28.744471) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-20 20:10:28.744759) ========= /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=5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:10:29 INFO - MultiFileLogger online at 20170920 20:10:29 in /builds/slave/test 20:10:29 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 20:10:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:10:29 INFO - {'append_to_log': False, 20:10:29 INFO - 'base_work_dir': '/builds/slave/test', 20:10:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:10:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:10:29 INFO - 'branch': 'Firefox-Non-PGO', 20:10:29 INFO - 'buildbot_json_path': 'buildprops.json', 20:10:29 INFO - 'code_coverage': False, 20:10:29 INFO - 'config_files': ('talos/linux_config.py',), 20:10:29 INFO - 'default_actions': ('clobber', 20:10:29 INFO - 'read-buildbot-config', 20:10:29 INFO - 'download-and-extract', 20:10:29 INFO - 'populate-webroot', 20:10:29 INFO - 'create-virtualenv', 20:10:29 INFO - 'install', 20:10:29 INFO - 'setup-mitmproxy', 20:10:29 INFO - 'run-tests'), 20:10:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:10:29 INFO - 'disable_ccov_upload': False, 20:10:29 INFO - 'disable_stylo': False, 20:10:29 INFO - 'download_minidump_stackwalk': True, 20:10:29 INFO - 'download_symbols': 'ondemand', 20:10:29 INFO - 'enable_stylo': False, 20:10:29 INFO - 'enable_webrender': False, 20:10:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 20:10:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:10:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:10:29 INFO - 'gecko_profile': False, 20:10:29 INFO - 'gecko_profile_interval': 0, 20:10:29 INFO - 'installer_path': 'installer.exe', 20:10:29 INFO - 'log_level': 'info', 20:10:29 INFO - 'log_name': 'talos', 20:10:29 INFO - 'log_to_console': True, 20:10:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:10:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:10:29 INFO - 'opt_config_files': (), 20:10:29 INFO - 'pip_index': False, 20:10:29 INFO - 'suite': 'dromaeojs-e10s', 20:10:29 INFO - 'system_bits': '32', 20:10:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:10:29 INFO - 'title': 'talos-linux64-ix-039', 20:10:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:10:29 INFO - 'use_talos_json': True, 20:10:29 INFO - 'verify': 'False', 20:10:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:10:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:10:29 INFO - 'work_dir': 'build'} 20:10:29 INFO - [mozharness: 2017-09-21 03:10:29.055558Z] Running clobber step. 20:10:29 INFO - Running pre-action listener: _resource_record_pre_action 20:10:29 INFO - Running main action method: clobber 20:10:29 INFO - rmtree: /builds/slave/test/build 20:10:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:10:30 INFO - Running post-action listener: _resource_record_post_action 20:10:30 INFO - [mozharness: 2017-09-21 03:10:30.007383Z] Finished clobber step (success) 20:10:30 INFO - [mozharness: 2017-09-21 03:10:30.007479Z] Running read-buildbot-config step. 20:10:30 INFO - Running pre-action listener: _resource_record_pre_action 20:10:30 INFO - Running main action method: read_buildbot_config 20:10:30 INFO - Using buildbot properties: 20:10:30 INFO - { 20:10:30 INFO - "project": "", 20:10:30 INFO - "product": "firefox", 20:10:30 INFO - "who": "ryanvm@gmail.com", 20:10:30 INFO - "installer_path": "public/build/target.tar.bz2", 20:10:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:10:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 20:10:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:10:30 INFO - "stage_platform": "linux64", 20:10:30 INFO - "basedir": "/builds/slave/test", 20:10:30 INFO - "buildnumber": 849, 20:10:30 INFO - "platform": "ubuntu64_hw", 20:10:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:10:30 INFO - "slavebuilddir": "test", 20:10:30 INFO - "taskId": "aR3SeOV3SdKXMiCPxxhbfw", 20:10:30 INFO - "branch": "mozilla-central", 20:10:30 INFO - "script_repo_revision": "production", 20:10:30 INFO - "revision": "47f7b6c64265bc7bdd22eef7ab71abc97cf3f8bf", 20:10:30 INFO - "slavename": "talos-linux64-ix-039", 20:10:30 INFO - "repo_path": "mozilla-central" 20:10:30 INFO - } 20:10:30 INFO - Finding installer, test and symbols from parent task. 20:10:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aR3SeOV3SdKXMiCPxxhbfw'}, attempt #1 20:10:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aR3SeOV3SdKXMiCPxxhbfw'}, attempt #1 20:10:30 INFO - Task dependencies: e3qcQ5inSsinAoBQTeRgAQ 20:10:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ'}, attempt #1 20:10:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.tar.bz2 20:10:31 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.test_packages.json 20:10:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.crashreporter-symbols.zip 20:10:31 INFO - Running post-action listener: _resource_record_post_action 20:10:31 INFO - [mozharness: 2017-09-21 03:10:31.337923Z] Finished read-buildbot-config step (success) 20:10:31 INFO - [mozharness: 2017-09-21 03:10:31.338141Z] Running download-and-extract step. 20:10:31 INFO - Running pre-action listener: _resource_record_pre_action 20:10:31 INFO - Running main action method: download_and_extract 20:10:31 INFO - mkdir: /builds/slave/test/build/tests 20:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:31 INFO - trying https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.test_packages.json 20:10:31 INFO - Downloading https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:10:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:10:31 INFO - Downloaded 989 bytes. 20:10:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:10:31 INFO - Using the following test package requirements: 20:10:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 20:10:31 INFO - u'common': [u'target.common.tests.zip'], 20:10:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:10:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:10:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:10:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:10:31 INFO - u'mozbase': [u'target.common.tests.zip'], 20:10:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:10:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:10:31 INFO - u'web-platform': [u'target.common.tests.zip', 20:10:31 INFO - u'target.web-platform.tests.tar.gz'], 20:10:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:10:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 20:10:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.common.tests.zip 20:10:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:10:31 INFO - Fetch https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.common.tests.zip into memory 20:10:34 INFO - Content-Length response header: 54733674 20:10:34 INFO - Bytes received: 54733674 20:10:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.talos.tests.zip 20:10:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:10:45 INFO - Fetch https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.talos.tests.zip into memory 20:10:46 INFO - Content-Length response header: 14509184 20:10:46 INFO - Bytes received: 14509184 20:10:46 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')]} 20:10:46 INFO - trying https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.tar.bz2 20:10:46 INFO - Downloading https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:10:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:10:50 INFO - Downloaded 61500476 bytes. 20:10:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.tar.bz2 20:10:50 INFO - mkdir: /builds/slave/test/properties 20:10:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:10:50 INFO - Writing to file /builds/slave/test/properties/build_url 20:10:50 INFO - Contents: 20:10:50 INFO - build_url:https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.tar.bz2 20:10:50 INFO - Running post-action listener: _resource_record_post_action 20:10:50 INFO - Running post-action listener: find_tests_for_verification 20:10:50 INFO - Running post-action listener: set_extra_try_arguments 20:10:50 INFO - [mozharness: 2017-09-21 03:10:50.890306Z] Finished download-and-extract step (success) 20:10:50 INFO - [mozharness: 2017-09-21 03:10:50.890421Z] Running populate-webroot step. 20:10:50 INFO - Running pre-action listener: _resource_record_pre_action 20:10:50 INFO - Running main action method: populate_webroot 20:10:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:10:50 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['tresize', 20:10:50 INFO - 'tcanvasmark']}, 20:10:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:10:50 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['dromaeo_css', 20:10:50 INFO - 'kraken']}, 20:10:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:10:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:10:50 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:10:50 INFO - 'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:10:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:10:50 INFO - 'tests': ['damp', 'tps']}, 20:10:50 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:10:50 INFO - 'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['damp', 'tps']}, 20:10:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:10:50 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['dromaeo_dom']}, 20:10:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:10:50 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['basic_compositor_video', 20:10:50 INFO - 'glvideo']}, 20:10:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:10:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:10:50 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:10:50 INFO - 'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['ts_paint_webext', 20:10:50 INFO - 'tp5o_webext']}, 20:10:50 INFO - 'other-e10s': {'tests': ['a11yr', 20:10:50 INFO - 'ts_paint', 20:10:50 INFO - 'tpaint', 20:10:50 INFO - 'sessionrestore', 20:10:50 INFO - 'sessionrestore_many_windows', 20:10:50 INFO - 'sessionrestore_no_auto_restore', 20:10:50 INFO - 'tabpaint']}, 20:10:50 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['a11yr', 20:10:50 INFO - 'ts_paint', 20:10:50 INFO - 'tpaint', 20:10:50 INFO - 'sessionrestore', 20:10:50 INFO - 'sessionrestore_many_windows', 20:10:50 INFO - 'sessionrestore_no_auto_restore', 20:10:50 INFO - 'tabpaint']}, 20:10:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 20:10:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 20:10:50 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['perf_reftest_singletons']}, 20:10:50 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['bloom_basic']}, 20:10:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:10:50 INFO - 'tsvgr_opacity', 20:10:50 INFO - 'tart', 20:10:50 INFO - 'tscrollx', 20:10:50 INFO - 'tsvg_static']}, 20:10:50 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['tsvgx', 20:10:50 INFO - 'tsvgr_opacity', 20:10:50 INFO - 'tart', 20:10:50 INFO - 'tscrollx', 20:10:50 INFO - 'tsvg_static']}, 20:10:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:10:50 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:10:50 INFO - 'talos_options': ['--disable-stylo'], 20:10:50 INFO - 'tests': ['tp5o']}, 20:10:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:10:50 INFO - 'talos_options': ['--mitmproxy', 20:10:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:10:50 INFO - '--firstNonBlankPaint'], 20:10:50 INFO - 'tests': ['tp6_google', 20:10:50 INFO - 'tp6_youtube', 20:10:50 INFO - 'tp6_amazon', 20:10:50 INFO - 'tp6_facebook']}, 20:10:50 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:10:50 INFO - 'talos_options': ['--disable-stylo', 20:10:50 INFO - '--mitmproxy', 20:10:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:10:50 INFO - '--firstNonBlankPaint'], 20:10:50 INFO - 'tests': ['tp6_google', 20:10:50 INFO - 'tp6_youtube', 20:10:50 INFO - 'tp6_amazon', 20:10:50 INFO - 'tp6_facebook']}, 20:10:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:10:50 INFO - 'talos_options': ['--stylo-threads=1', 20:10:50 INFO - '--mitmproxy', 20:10:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:10:50 INFO - '--firstNonBlankPaint'], 20:10:50 INFO - 'tests': ['tp6_google', 20:10:50 INFO - 'tp6_youtube', 20:10:50 INFO - 'tp6_amazon', 20:10:50 INFO - 'tp6_facebook']}, 20:10:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:10:50 INFO - 'talos_options': ['--xperf_path', 20:10:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:10:50 INFO - 'tests': ['tp5n']}, 20:10:50 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:10:50 INFO - 'talos_options': ['--disable-stylo', 20:10:50 INFO - '--xperf_path', 20:10:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:10:50 INFO - 'tests': ['tp5n']}}} 20:10:50 INFO - Running post-action listener: _resource_record_post_action 20:10:50 INFO - [mozharness: 2017-09-21 03:10:50.905450Z] Finished populate-webroot step (success) 20:10:50 INFO - [mozharness: 2017-09-21 03:10:50.905539Z] Running create-virtualenv step. 20:10:50 INFO - Running pre-action listener: _resource_record_pre_action 20:10:50 INFO - Running main action method: create_virtualenv 20:10:50 INFO - Creating virtualenv /builds/slave/test/build/venv 20:10:50 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:10:50 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 20:10:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 20:10:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:10:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:10:51 INFO - Installing distribute.............................................................................................................................................................................................done. 20:10:52 INFO - Installing pip...............done. 20:10:52 INFO - Return code: 0 20:10:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:10:52 INFO - Reading from file tmpfile_stdout 20:10:52 INFO - Output received: 20:10:52 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 20:10:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:10:52 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')]} 20:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:52 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')]} 20:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:52 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 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:10:52 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 20:10:52 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 20:10:52 INFO - Using env: {'DISPLAY': ':0', 20:10:52 INFO - 'HOME': '/home/cltbld', 20:10:52 INFO - 'LANG': 'en_US.UTF-8', 20:10:52 INFO - 'LANGUAGE': 'en_US:en', 20:10:52 INFO - 'LOGNAME': 'cltbld', 20:10:52 INFO - 'MAIL': '/var/mail/cltbld', 20:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:52 INFO - 'MOZ_NO_REMOTE': '1', 20:10:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:52 INFO - 'NO_EM_RESTART': '1', 20:10:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:52 INFO - 'PWD': '/builds/slave/test', 20:10:52 INFO - 'SHELL': '/bin/bash', 20:10:52 INFO - 'SHLVL': '1', 20:10:52 INFO - 'TERM': 'linux', 20:10:52 INFO - 'TMOUT': '86400', 20:10:52 INFO - 'USER': 'cltbld', 20:10:52 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:52 INFO - '_': '/tools/buildbot/bin/python'} 20:10:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:10:53 INFO - Downloading/unpacking pip>=1.5 20:10:53 INFO - Running setup.py egg_info for package pip 20:10:53 INFO - warning: no files found matching 'pip/cacert.pem' 20:10:53 INFO - warning: no files found matching '*.html' under directory 'docs' 20:10:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:10:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:10:53 INFO - Installing collected packages: pip 20:10:53 INFO - Found existing installation: pip 1.1 20:10:53 INFO - Uninstalling pip: 20:10:53 INFO - Successfully uninstalled pip 20:10:53 INFO - Running setup.py install for pip 20:10:53 INFO - warning: no files found matching 'pip/cacert.pem' 20:10:53 INFO - warning: no files found matching '*.html' under directory 'docs' 20:10:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:10:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:10:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 20:10:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 20:10:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 20:10:53 INFO - Successfully installed pip 20:10:53 INFO - Cleaning up... 20:10:53 INFO - Return code: 0 20:10:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:10:53 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')]} 20:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:53 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')]} 20:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:53 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 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:10:53 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 20:10:53 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 20:10:53 INFO - Using env: {'DISPLAY': ':0', 20:10:53 INFO - 'HOME': '/home/cltbld', 20:10:53 INFO - 'LANG': 'en_US.UTF-8', 20:10:53 INFO - 'LANGUAGE': 'en_US:en', 20:10:53 INFO - 'LOGNAME': 'cltbld', 20:10:53 INFO - 'MAIL': '/var/mail/cltbld', 20:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:53 INFO - 'MOZ_NO_REMOTE': '1', 20:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:53 INFO - 'NO_EM_RESTART': '1', 20:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:53 INFO - 'PWD': '/builds/slave/test', 20:10:53 INFO - 'SHELL': '/bin/bash', 20:10:53 INFO - 'SHLVL': '1', 20:10:53 INFO - 'TERM': 'linux', 20:10:53 INFO - 'TMOUT': '86400', 20:10:53 INFO - 'USER': 'cltbld', 20:10:53 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:53 INFO - '_': '/tools/buildbot/bin/python'} 20:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:54 INFO - Downloading/unpacking psutil>=3.1.1 20:10: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 20:10: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 20:10: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 20:10: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 20:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:10:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:10:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:10:55 INFO - Installing collected packages: psutil 20:10:55 INFO - Running setup.py install for psutil 20:10:55 INFO - building 'psutil._psutil_linux' extension 20:10:55 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 20:10:56 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 20:10:56 INFO - building 'psutil._psutil_posix' extension 20:10:56 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 20:10:56 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 20:10:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:10:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:10:56 INFO - Successfully installed psutil 20:10:56 INFO - Cleaning up... 20:10:57 INFO - Return code: 0 20:10:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10: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')]} 20:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:10: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:10: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 mozsystemmonitor==0.3 20:10:57 INFO - Using env: {'DISPLAY': ':0', 20:10:57 INFO - 'HOME': '/home/cltbld', 20:10:57 INFO - 'LANG': 'en_US.UTF-8', 20:10:57 INFO - 'LANGUAGE': 'en_US:en', 20:10:57 INFO - 'LOGNAME': 'cltbld', 20:10:57 INFO - 'MAIL': '/var/mail/cltbld', 20:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:57 INFO - 'MOZ_NO_REMOTE': '1', 20:10:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:57 INFO - 'NO_EM_RESTART': '1', 20:10:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:57 INFO - 'PWD': '/builds/slave/test', 20:10:57 INFO - 'SHELL': '/bin/bash', 20:10:57 INFO - 'SHLVL': '1', 20:10:57 INFO - 'TERM': 'linux', 20:10:57 INFO - 'TMOUT': '86400', 20:10:57 INFO - 'USER': 'cltbld', 20:10:57 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:57 INFO - '_': '/tools/buildbot/bin/python'} 20:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:10: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 20:10: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 20:10: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 20:10: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 20:10:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:10:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:10:58 INFO - Installing collected packages: mozsystemmonitor 20:10:58 INFO - Running setup.py install for mozsystemmonitor 20:10:58 INFO - Successfully installed mozsystemmonitor 20:10:58 INFO - Cleaning up... 20:10:58 INFO - Return code: 0 20:10:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:58 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 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:10:58 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 20:10:58 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 20:10:58 INFO - Using env: {'DISPLAY': ':0', 20:10:58 INFO - 'HOME': '/home/cltbld', 20:10:58 INFO - 'LANG': 'en_US.UTF-8', 20:10:58 INFO - 'LANGUAGE': 'en_US:en', 20:10:58 INFO - 'LOGNAME': 'cltbld', 20:10:58 INFO - 'MAIL': '/var/mail/cltbld', 20:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:58 INFO - 'MOZ_NO_REMOTE': '1', 20:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:58 INFO - 'NO_EM_RESTART': '1', 20:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:58 INFO - 'PWD': '/builds/slave/test', 20:10:58 INFO - 'SHELL': '/bin/bash', 20:10:58 INFO - 'SHLVL': '1', 20:10:58 INFO - 'TERM': 'linux', 20:10:58 INFO - 'TMOUT': '86400', 20:10:58 INFO - 'USER': 'cltbld', 20:10:58 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:58 INFO - '_': '/tools/buildbot/bin/python'} 20:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:58 INFO - Downloading/unpacking jsonschema==2.5.1 20:10:58 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 20:10:58 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 20:10:58 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 20:10:58 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 20:10:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:10:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:10: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 20:10: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 20:10: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 20:10: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 20:10:59 INFO - Downloading functools32-3.2.3-2.tar.gz 20:10:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:11:00 INFO - warning: no files found matching '*.txt' 20:11:00 INFO - no previously-included directories found matching 'build' 20:11:00 INFO - no previously-included directories found matching 'dist' 20:11:00 INFO - no previously-included directories found matching '.git*' 20:11:00 INFO - Installing collected packages: jsonschema, functools32 20:11:00 INFO - Running setup.py install for functools32 20:11:00 INFO - warning: no files found matching '*.txt' 20:11:00 INFO - no previously-included directories found matching 'build' 20:11:00 INFO - no previously-included directories found matching 'dist' 20:11:00 INFO - no previously-included directories found matching '.git*' 20:11:00 INFO - Successfully installed jsonschema functools32 20:11:00 INFO - Cleaning up... 20:11:00 INFO - Return code: 0 20:11:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:11: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')]} 20:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:11: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')]} 20:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:11:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:11:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:11:00 INFO - Using env: {'DISPLAY': ':0', 20:11:00 INFO - 'HOME': '/home/cltbld', 20:11:00 INFO - 'LANG': 'en_US.UTF-8', 20:11:00 INFO - 'LANGUAGE': 'en_US:en', 20:11:00 INFO - 'LOGNAME': 'cltbld', 20:11:00 INFO - 'MAIL': '/var/mail/cltbld', 20:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:00 INFO - 'MOZ_NO_REMOTE': '1', 20:11:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:11:00 INFO - 'NO_EM_RESTART': '1', 20:11:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:00 INFO - 'PWD': '/builds/slave/test', 20:11:00 INFO - 'SHELL': '/bin/bash', 20:11:00 INFO - 'SHLVL': '1', 20:11:00 INFO - 'TERM': 'linux', 20:11:00 INFO - 'TMOUT': '86400', 20:11:00 INFO - 'USER': 'cltbld', 20:11:00 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:00 INFO - '_': '/tools/buildbot/bin/python'} 20:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:11:00 INFO - Cleaning up... 20:11:00 INFO - Return code: 0 20:11:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:11: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')]} 20:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:11: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')]} 20:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:11:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:11:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:11:00 INFO - Using env: {'DISPLAY': ':0', 20:11:00 INFO - 'HOME': '/home/cltbld', 20:11:00 INFO - 'LANG': 'en_US.UTF-8', 20:11:00 INFO - 'LANGUAGE': 'en_US:en', 20:11:00 INFO - 'LOGNAME': 'cltbld', 20:11:00 INFO - 'MAIL': '/var/mail/cltbld', 20:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:00 INFO - 'MOZ_NO_REMOTE': '1', 20:11:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:11:00 INFO - 'NO_EM_RESTART': '1', 20:11:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:00 INFO - 'PWD': '/builds/slave/test', 20:11:00 INFO - 'SHELL': '/bin/bash', 20:11:00 INFO - 'SHLVL': '1', 20:11:00 INFO - 'TERM': 'linux', 20:11:00 INFO - 'TMOUT': '86400', 20:11:00 INFO - 'USER': 'cltbld', 20:11:00 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:00 INFO - '_': '/tools/buildbot/bin/python'} 20:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:11:00 INFO - Downloading/unpacking blobuploader==1.2.4 20:11:00 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 20:11:00 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 20:11:00 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 20:11:00 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 20:11:01 INFO - Downloading blobuploader-1.2.4.tar.gz 20:11:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:11:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:11:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:11:02 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 20:11:02 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 20:11:02 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 20:11:02 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 20:11:02 INFO - Downloading docopt-0.6.1.tar.gz 20:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:11:02 INFO - Installing collected packages: blobuploader, requests, docopt 20:11:02 INFO - Running setup.py install for blobuploader 20:11:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:11:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:11:02 INFO - Running setup.py install for requests 20:11:02 INFO - Running setup.py install for docopt 20:11:02 INFO - Successfully installed blobuploader requests docopt 20:11:02 INFO - Cleaning up... 20:11:02 INFO - Return code: 0 20:11:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:11:02 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 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:11:02 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 20:11:02 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 20:11:02 INFO - Using env: {'DISPLAY': ':0', 20:11:02 INFO - 'HOME': '/home/cltbld', 20:11:02 INFO - 'LANG': 'en_US.UTF-8', 20:11:02 INFO - 'LANGUAGE': 'en_US:en', 20:11:02 INFO - 'LOGNAME': 'cltbld', 20:11:02 INFO - 'MAIL': '/var/mail/cltbld', 20:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:02 INFO - 'MOZ_NO_REMOTE': '1', 20:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:11:02 INFO - 'NO_EM_RESTART': '1', 20:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:02 INFO - 'PWD': '/builds/slave/test', 20:11:02 INFO - 'SHELL': '/bin/bash', 20:11:02 INFO - 'SHLVL': '1', 20:11:02 INFO - 'TERM': 'linux', 20:11:02 INFO - 'TMOUT': '86400', 20:11:02 INFO - 'USER': 'cltbld', 20:11:02 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:02 INFO - '_': '/tools/buildbot/bin/python'} 20:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:11:03 INFO - Running setup.py (path:/tmp/pip-VZSS17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:11:03 INFO - Running setup.py (path:/tmp/pip-u7McKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:11:03 INFO - Running setup.py (path:/tmp/pip-Hlv48r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:11:03 INFO - Running setup.py (path:/tmp/pip-60zMO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:11:03 INFO - Running setup.py (path:/tmp/pip-0Zcdgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:11:03 INFO - Running setup.py (path:/tmp/pip-1p2m2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:11:03 INFO - Running setup.py (path:/tmp/pip-E36II2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:11:03 INFO - Running setup.py (path:/tmp/pip-zwgx1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:11:03 INFO - Running setup.py (path:/tmp/pip-rJhUh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:11:03 INFO - Running setup.py (path:/tmp/pip-6IimR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:11:03 INFO - Running setup.py (path:/tmp/pip-laqrgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:11:03 INFO - Running setup.py (path:/tmp/pip-SV0RWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:11:04 INFO - Running setup.py (path:/tmp/pip-5ecepF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:11:04 INFO - Running setup.py (path:/tmp/pip-1HgRNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:11:04 INFO - Running setup.py (path:/tmp/pip-OHnJDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:11:04 INFO - Running setup.py (path:/tmp/pip-7AgYc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:11:04 INFO - Running setup.py (path:/tmp/pip-HMZdPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:11:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:11:04 INFO - Running setup.py install for manifestparser 20:11:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:11:04 INFO - Running setup.py install for mozcrash 20:11:04 INFO - Running setup.py install for mozdebug 20:11:04 INFO - Running setup.py install for mozdevice 20:11:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:11:04 INFO - Running setup.py install for mozfile 20:11:04 INFO - Running setup.py install for mozhttpd 20:11:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:11:04 INFO - Running setup.py install for mozinfo 20:11:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Running setup.py install for mozInstall 20:11:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Running setup.py install for mozleak 20:11:05 INFO - Running setup.py install for mozlog 20:11:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Running setup.py install for moznetwork 20:11:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Running setup.py install for mozprocess 20:11:05 INFO - Running setup.py install for mozprofile 20:11:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Running setup.py install for mozrunner 20:11:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:11:05 INFO - Running setup.py install for mozscreenshot 20:11:05 INFO - Running setup.py install for moztest 20:11:06 INFO - Running setup.py install for mozversion 20:11:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:11:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:11:06 INFO - Cleaning up... 20:11:06 INFO - Return code: 0 20:11:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:11: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')]} 20:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:11: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')]} 20:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:11:06 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 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:11:06 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 20:11:06 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 20:11:06 INFO - Using env: {'DISPLAY': ':0', 20:11:06 INFO - 'HOME': '/home/cltbld', 20:11:06 INFO - 'LANG': 'en_US.UTF-8', 20:11:06 INFO - 'LANGUAGE': 'en_US:en', 20:11:06 INFO - 'LOGNAME': 'cltbld', 20:11:06 INFO - 'MAIL': '/var/mail/cltbld', 20:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:06 INFO - 'MOZ_NO_REMOTE': '1', 20:11:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:11:06 INFO - 'NO_EM_RESTART': '1', 20:11:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:06 INFO - 'PWD': '/builds/slave/test', 20:11:06 INFO - 'SHELL': '/bin/bash', 20:11:06 INFO - 'SHLVL': '1', 20:11:06 INFO - 'TERM': 'linux', 20:11:06 INFO - 'TMOUT': '86400', 20:11:06 INFO - 'USER': 'cltbld', 20:11:06 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:06 INFO - '_': '/tools/buildbot/bin/python'} 20:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:11:06 INFO - Running setup.py (path:/tmp/pip-2Ve1uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:11:06 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)) 20:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:11:06 INFO - Running setup.py (path:/tmp/pip-DKhWKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:11:06 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)) 20:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:11:06 INFO - Running setup.py (path:/tmp/pip-t7wRIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:11:06 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)) 20:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:11:06 INFO - Running setup.py (path:/tmp/pip-M2QzUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:11:06 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)) 20:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:11:06 INFO - Running setup.py (path:/tmp/pip-kaJcaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:11:06 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)) 20:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:11:06 INFO - Running setup.py (path:/tmp/pip-E0Ay0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:11:06 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)) 20:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:11:06 INFO - Running setup.py (path:/tmp/pip-x0S63x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:11:06 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)) 20:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:11:06 INFO - Running setup.py (path:/tmp/pip-ZqGAsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:11:06 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)) 20:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:11:06 INFO - Running setup.py (path:/tmp/pip-BpoWBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:11:07 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)) 20:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:11:07 INFO - Running setup.py (path:/tmp/pip-vzpeRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:11:07 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)) 20:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:11:07 INFO - Running setup.py (path:/tmp/pip-yuZJZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:11:07 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)) 20:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:11:07 INFO - Running setup.py (path:/tmp/pip-fuRyAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:11:07 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)) 20:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:11:07 INFO - Running setup.py (path:/tmp/pip-_dX1pA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:11:07 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)) 20:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:11:07 INFO - Running setup.py (path:/tmp/pip-uobwGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:11:07 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)) 20:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:11:07 INFO - Running setup.py (path:/tmp/pip-PRfVdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:11:07 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)) 20:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:11:07 INFO - Running setup.py (path:/tmp/pip-BEDQ8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:11:07 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)) 20:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:11:07 INFO - Running setup.py (path:/tmp/pip-3GkTam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:11:07 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)) 20:11:07 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)) 20:11:07 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)) 20:11:07 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)) 20:11:07 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)) 20:11:07 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)) 20:11:07 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)) 20:11:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:11:08 INFO - Downloading blessings-1.6.tar.gz 20:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:11:08 INFO - Installing collected packages: blessings 20:11:08 INFO - Running setup.py install for blessings 20:11:08 INFO - Successfully installed blessings 20:11:08 INFO - Cleaning up... 20:11:08 INFO - Return code: 0 20:11:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:11:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:11:09 INFO - Reading from file tmpfile_stdout 20:11:09 INFO - Current package versions: 20:11:09 INFO - argparse == 1.2.1 20:11:09 INFO - blessings == 1.6 20:11:09 INFO - blobuploader == 1.2.4 20:11:09 INFO - docopt == 0.6.1 20:11:09 INFO - functools32 == 3.2.3-2 20:11:09 INFO - jsonschema == 2.5.1 20:11:09 INFO - manifestparser == 1.1 20:11:09 INFO - mozInstall == 1.13 20:11:09 INFO - mozcrash == 1.0 20:11:09 INFO - mozdebug == 0.1 20:11:09 INFO - mozdevice == 0.51 20:11:09 INFO - mozfile == 1.2 20:11:09 INFO - mozhttpd == 0.7 20:11:09 INFO - mozinfo == 0.10 20:11:09 INFO - mozleak == 0.1 20:11:09 INFO - mozlog == 3.5 20:11:09 INFO - moznetwork == 0.27 20:11:09 INFO - mozprocess == 0.25 20:11:09 INFO - mozprofile == 0.28 20:11:09 INFO - mozrunner == 6.13 20:11:09 INFO - mozscreenshot == 0.1 20:11:09 INFO - mozsystemmonitor == 0.3 20:11:09 INFO - moztest == 0.8 20:11:09 INFO - mozversion == 1.4 20:11:09 INFO - psutil == 3.1.1 20:11:09 INFO - requests == 1.2.3 20:11:09 INFO - wsgiref == 0.1.2 20:11:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:11:09 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 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:11:09 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 20:11:09 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 20:11:09 INFO - Using env: {'DISPLAY': ':0', 20:11:09 INFO - 'HOME': '/home/cltbld', 20:11:09 INFO - 'LANG': 'en_US.UTF-8', 20:11:09 INFO - 'LANGUAGE': 'en_US:en', 20:11:09 INFO - 'LOGNAME': 'cltbld', 20:11:09 INFO - 'MAIL': '/var/mail/cltbld', 20:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:09 INFO - 'MOZ_NO_REMOTE': '1', 20:11:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:11:09 INFO - 'NO_EM_RESTART': '1', 20:11:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:09 INFO - 'PWD': '/builds/slave/test', 20:11:09 INFO - 'SHELL': '/bin/bash', 20:11:09 INFO - 'SHLVL': '1', 20:11:09 INFO - 'TERM': 'linux', 20:11:09 INFO - 'TMOUT': '86400', 20:11:09 INFO - 'USER': 'cltbld', 20:11:09 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:09 INFO - '_': '/tools/buildbot/bin/python'} 20:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:11:09 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)) 20:11:09 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)) 20:11:09 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)) 20:11:09 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)) 20:11:09 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)) 20:11:09 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)) 20:11:09 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)) 20:11:09 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)) 20:11:09 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)) 20:11:09 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 20:11:10 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)) 20:11:10 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)) 20:11:10 INFO - Installing collected packages: simplejson 20:11:10 INFO - Running setup.py install for simplejson 20:11:10 INFO - building 'simplejson._speedups' extension 20:11:10 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 20:11:11 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 20:11:11 INFO - Successfully installed simplejson 20:11:11 INFO - Cleaning up... 20:11:11 INFO - Return code: 0 20:11:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:11:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb081f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe74a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', '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 20:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:11:11 INFO - Using env: {'DISPLAY': ':0', 20:11:11 INFO - 'HOME': '/home/cltbld', 20:11:11 INFO - 'LANG': 'en_US.UTF-8', 20:11:11 INFO - 'LANGUAGE': 'en_US:en', 20:11:11 INFO - 'LOGNAME': 'cltbld', 20:11:11 INFO - 'MAIL': '/var/mail/cltbld', 20:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:11 INFO - 'MOZ_NO_REMOTE': '1', 20:11:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:11:11 INFO - 'NO_EM_RESTART': '1', 20:11:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:11 INFO - 'PWD': '/builds/slave/test', 20:11:11 INFO - 'SHELL': '/bin/bash', 20:11:11 INFO - 'SHLVL': '1', 20:11:11 INFO - 'TERM': 'linux', 20:11:11 INFO - 'TMOUT': '86400', 20:11:11 INFO - 'USER': 'cltbld', 20:11:11 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:11 INFO - '_': '/tools/buildbot/bin/python'} 20:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:11:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:11:11 INFO - Cleaning up... 20:11:11 INFO - Return code: 0 20:11:11 INFO - Running post-action listener: _resource_record_post_action 20:11:11 INFO - Running post-action listener: _start_resource_monitoring 20:11:11 INFO - Starting resource monitoring. 20:11:11 INFO - [mozharness: 2017-09-21 03:11:11.917750Z] Finished create-virtualenv step (success) 20:11:11 INFO - [mozharness: 2017-09-21 03:11:11.918864Z] Running install step. 20:11:11 INFO - Running pre-action listener: _resource_record_pre_action 20:11:11 INFO - Running main action method: install 20:11:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:11:12 INFO - Reading from file tmpfile_stdout 20:11:12 INFO - Detecting whether we're running mozinstall >=1.0... 20:11:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:11:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:11:12 INFO - Reading from file tmpfile_stdout 20:11:12 INFO - Output received: 20:11:12 INFO - Usage: mozinstall [options] installer 20:11:12 INFO - Options: 20:11:12 INFO - -h, --help show this help message and exit 20:11:12 INFO - -d DEST, --destination=DEST 20:11:12 INFO - Directory to install application into. [default: 20:11:12 INFO - "/builds/slave/test"] 20:11:12 INFO - --app=APP Application being installed. [default: firefox] 20:11:12 INFO - mkdir: /builds/slave/test/build/application 20:11:12 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'] 20:11:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:11:30 INFO - Reading from file tmpfile_stdout 20:11:30 INFO - Output received: 20:11:30 INFO - /builds/slave/test/build/application/firefox/firefox 20:11:30 INFO - Running post-action listener: _resource_record_post_action 20:11:30 INFO - [mozharness: 2017-09-21 03:11:30.032778Z] Finished install step (success) 20:11:30 INFO - [mozharness: 2017-09-21 03:11:30.032922Z] Running setup-mitmproxy step. 20:11:30 INFO - Running pre-action listener: _resource_record_pre_action 20:11:30 INFO - Running main action method: setup_mitmproxy 20:11:30 INFO - Skipping: mitmproxy is not required 20:11:30 INFO - Running post-action listener: _resource_record_post_action 20:11:30 INFO - [mozharness: 2017-09-21 03:11:30.033446Z] Finished setup-mitmproxy step (success) 20:11:30 INFO - [mozharness: 2017-09-21 03:11:30.033548Z] Running run-tests step. 20:11:30 INFO - Running pre-action listener: _resource_record_pre_action 20:11:30 INFO - Running pre-action listener: _set_gcov_prefix 20:11:30 INFO - Running main action method: run_tests 20:11:30 WARNING - Try message not found. 20:11:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:11:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:11:30 INFO - Python 2.7.3 20:11:30 INFO - Return code: 0 20:11:30 INFO - grabbing minidump binary from tooltool 20:11:30 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')]} 20:11:30 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 0xe78090>, '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 0xe78d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe72f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:11:30 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 20:11:30 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 20:11:30 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 20:11:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:11:30 INFO - Return code: 0 20:11:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:11:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:11:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:11:30 INFO - ENV: RUST_BACKTRACE is now full 20:11:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:11:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:11:30 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/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 20:11:30 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/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 20:11:30 INFO - Using env: {'DISPLAY': ':0', 20:11:30 INFO - 'HOME': '/home/cltbld', 20:11:30 INFO - 'LANG': 'en_US.UTF-8', 20:11:30 INFO - 'LANGUAGE': 'en_US:en', 20:11:30 INFO - 'LOGNAME': 'cltbld', 20:11:30 INFO - 'MAIL': '/var/mail/cltbld', 20:11:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:11:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:30 INFO - 'MOZ_NO_REMOTE': '1', 20:11:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:11:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:11:30 INFO - 'NO_EM_RESTART': '1', 20:11:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:30 INFO - 'PWD': '/builds/slave/test', 20:11:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:11:30 INFO - 'RUST_BACKTRACE': 'full', 20:11:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:11:30 INFO - 'SHELL': '/bin/bash', 20:11:30 INFO - 'SHLVL': '1', 20:11:30 INFO - 'TERM': 'linux', 20:11:30 INFO - 'TMOUT': '86400', 20:11:30 INFO - 'USER': 'cltbld', 20:11:30 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:30 INFO - '_': '/tools/buildbot/bin/python'} 20:11:30 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/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 20:11:30 INFO - mozversion application_buildid: 20170921022004 20:11:30 INFO - mozversion application_changeset: 47f7b6c64265bc7bdd22eef7ab71abc97cf3f8bf 20:11:30 INFO - mozversion application_display_name: Nightly 20:11:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:11:30 INFO - mozversion application_name: Firefox 20:11:30 INFO - mozversion application_remotingname: firefox 20:11:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:11:30 INFO - mozversion application_vendor: Mozilla 20:11:30 INFO - mozversion application_version: 57.0a1 20:11:30 INFO - mozversion platform_buildid: 20170921022004 20:11:30 INFO - mozversion platform_changeset: 47f7b6c64265bc7bdd22eef7ab71abc97cf3f8bf 20:11:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:11:30 INFO - mozversion platform_version: 57.0a1 20:11:30 INFO - using testdate: 1505963490 20:11:30 INFO - actual date: 1505963490 20:11:30 INFO - starting webserver on 'localhost:33296' 20:11:30 INFO - SUITE-START | Running 2 tests 20:11:30 INFO - TEST-START | dromaeo_css 20:11:30 INFO - Initialising browser for dromaeo_css test... 20:11:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33296/getInfo.html -profile /tmp/tmprVPn33/profile 20:11:30 INFO - TEST-INFO | started process 3604 (/builds/slave/test/build/application/firefox/firefox http://localhost:33296/getInfo.html) 20:11:40 INFO - TEST-INFO | 3604: exit 0 20:11:40 INFO - Browser initialized. 20:11:40 INFO - Running cycle 1/1 for dromaeo_css test... 20:11:40 INFO - Using env: {'DISPLAY': ':0', 20:11:40 INFO - 'HOME': '/home/cltbld', 20:11:40 INFO - 'JSGC_DISABLE_POISONING': '1', 20:11:40 INFO - 'LANG': 'en_US.UTF-8', 20:11:40 INFO - 'LANGUAGE': 'en_US:en', 20:11:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:11:40 INFO - 'LOGNAME': 'cltbld', 20:11:40 INFO - 'MAIL': '/var/mail/cltbld', 20:11:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:11:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:11:40 INFO - 'MOZ_CRASHREPORTER': '1', 20:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:11:40 INFO - 'MOZ_NO_REMOTE': '1', 20:11:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:11:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:11:40 INFO - 'NO_EM_RESTART': '1', 20:11:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:40 INFO - 'PWD': '/builds/slave/test', 20:11:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:11:40 INFO - 'RUST_BACKTRACE': 'full', 20:11:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:11:40 INFO - 'SHELL': '/bin/bash', 20:11:40 INFO - 'SHLVL': '1', 20:11:40 INFO - 'TERM': 'linux', 20:11:40 INFO - 'TMOUT': '86400', 20:11:40 INFO - 'USER': 'cltbld', 20:11:40 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:40 INFO - '_': '/tools/buildbot/bin/python'} 20:11:40 INFO - TEST-INFO | started process 3841 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprVPn33/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:11:41 INFO - PID 3841 | 20:11:41 INFO - PID 3841 | (/builds/slave/test/build/application/firefox/firefox:3890): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:11:41 INFO - PID 3841 | 20:11:51 INFO - PID 3841 | RSS: Main: 153645056 20:11:51 INFO - PID 3841 | 20:14:48 INFO - PID 3841 | Cycle 1(1): loaded http://localhost:33296/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33296/tests/dromaeo/cssquery-ext.html) 20:14:49 INFO - PID 3841 | RSS: Main: 154918912 20:14:49 INFO - PID 3841 | 20:17:51 INFO - PID 3841 | Cycle 1(1): loaded http://localhost:33296/tests/dromaeo/cssquery-ext.html (next: http://localhost:33296/tests/dromaeo/cssquery-jquery.html) 20:17:52 INFO - PID 3841 | RSS: Main: 153149440 20:17:52 INFO - PID 3841 | 20:20:54 INFO - PID 3841 | Cycle 1(1): loaded http://localhost:33296/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33296/tests/dromaeo/cssquery-mootools.html) 20:20:54 INFO - PID 3841 | RSS: Main: 155398144 20:20:54 INFO - PID 3841 | 20:23:56 INFO - PID 3841 | Cycle 1(1): loaded http://localhost:33296/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33296/tests/dromaeo/cssquery-prototype.html) 20:23:57 INFO - PID 3841 | RSS: Main: 155262976 20:23:57 INFO - PID 3841 | 20:27:00 INFO - PID 3841 | Cycle 1(1): loaded http://localhost:33296/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33296/tests/dromaeo/cssquery-yui.html) 20:27:01 INFO - PID 3841 | RSS: Main: 157061120 20:27:01 INFO - PID 3841 | 20:30:02 INFO - PID 3841 | Cycle 1(1): loaded http://localhost:33296/tests/dromaeo/cssquery-yui.html (next: http://localhost:33296/tests/dromaeo/cssquery-dojo.html) 20:30:02 INFO - PID 3841 | RSS: Main: 159592448 20:30:02 INFO - PID 3841 | 20:30:02 INFO - PID 3841 | __start_tp_report 20:30:02 INFO - PID 3841 | _x_x_mozilla_page_load 20:30:02 INFO - PID 3841 | _x_x_mozilla_page_load_details 20:30:02 INFO - PID 3841 | |i|pagename|runs| 20:30:02 INFO - PID 3841 | |0;dojo.html;3115.909431698906;3118.0489950565084;3120.5943227380435;3122.2662674271546;3144.05678296511;6236.532260080489;6237.5009999200065;6247.125402443656;6323.810285691431;6358.586691865025;6310.24277086749;6322.620642761438;6345.270293916201;6354.7775827846035;6413.326600706929;5684.346300175492;5736.397678243787;5860.033094539406;5922.378150294222;5955.315138759033;5741.799037033705;5753.050746626802;5777.884442311151;5812.767489300423;5813.854653633672;5749.9712501437225;5766.452187042245;5770.942290577103;5788.710564471759;5795.855103622401;5681.801136960186;5748.97125514374;5794.855128621818;5862.886051650173;5873.911891321633;5623.775048998035;5661.46216109469;5703.201551782754;5720.771169153229;5810.941890581083;6314.147590075373;6341.95357765968;6355.269144048401;6415.8075257741875;6418.8716225675225;6759.5606285591275;6772.356626120511;6812.62530560819;6814.522983391164;6845.14435695538;6763.763268285636;6803.965980170117;6859.965700171517;6870.106886104824;6876.071730316443;6165.537584681122;6235.345288744707;6238.376162383771;6244.3755624437645;6359.4594459471;6804.727810887608;6819.283975182633;6834.453243740489;6923.2038315594355;6983.790486285419;6617.669116544153;6621.602703837785;6668.899966500507;6694.866102677919;6699.832504187337;6736.090627765287;6736.326367363225;6774.72901083961;6828.419584335288;6846.828829279308;6538.051982462516;6603.372679595431;6626.966865165643;6637.303083176197;6649.202095748541;6678.031685405589;6718.361755633207;6752.898706519406;6821.4542836572955;6827;5307.495787900143;5338.652987555796;5343.278657381204;5347.0909945309395;5347.866303342447;4645.419322584677;4652.209124448852;4653.488116307203;4666.427260394174;4684.695494792896;5741.45456181662;5744.540436765049;5806.970965145232;5815.534757219412;5825.563082768725;6575.671216439159;6661.134052573135;6765.898511522331;6788.694508747119;6887.276835932155;5754.367019627837;5818.301803783573;5824.708764561755;5835.183074369592;5853.414658534198;5836.88326233473;5840.675312501568;5911.4975227105315;5918.171455996164;5921.437463440966;6366.586171898812;6555.344465553407;6621.205455345293;6641.800745977628;6644.96677516619;6676.132102826601;6677.399034086859;6745.25802161752;6826.692798823966;6833.180018397824;6451.870962580816;6521.152250207443;6539;6544.9672751635935;6565.474762018932;6569.343065693392;6654.401103900672;6742.123523941857;6760.459163267025;6764.49266305039;6749.358810912955;6815;6836.418904392984;6836.658167091526;6842.384185423336;6613.239477459962;6633.6351500666;6654.101696270942;6661;6669.966650166913;6419.807405777834;6434.67826608677;6498.057781621733;6610.07458955737;6664.166979127609;6497.772577959749;6567.310432404529;6589.703463344065;6590.736370545124;6593.044008618819;6512.05575191604;6575.210974683068;6608.7356505739235;6638.5684930479365;6705.329467053256;6307.942558903027;6319.652419116993;6353.650549219838;6360.204974378203;6361.8727625448155 20:30:02 INFO - PID 3841 | |1;ext.html;801.1147681811598;879.4107947675059;894.5616647842555;903.9864402033969;1155.8786327435623;30122.69877301229;31083.533746993784;40706;41313.38029929546;41567;40462;40854.182916341655;40857.59142408575;40864.59135408645;40885.38671919916;7800;7891.092524359686;8002.119766825645;8208;8222.876656850152;7990.2009799019925;8012;8049.919500805005;8050.718224862131;8068;8003.639836207355;8040.557769322685;8048.557329346883;8050.194980501961;8078.798030049266;8023.438359314821;8098;8100.716474923379;8107.22981316774;8108.472949258279;8019.35845132388;8055.15420880805;8090.433669643127;8094.271515563569;8102.027756669178;36732.081697957765;36777.264454711025;37084.07289817749;37295;37307.25385492302;3535.5757309122814;3578.3022310649435;3595.622459641726;3599.9560127562977;3602.585702644203;30790.846045769627;30868.382632347228;30906;30908.690913090806;30932.845335773403;5114.923276150861;5120.308758317654;5128.230765385185;5144.6655967362;5163.922541161848;184231;184616;185146;186196.99999999863;186817.0659146709;47567;47779;47797.76101119472;47956.5204347959;48587.271190932166;50831;50988.235176472386;51111.488885111416;51136.48863511317;51136.74431627818;14177.645558860904;14401.423943042371;16282.592935176715;16440;16440.095794731165;13099.083064185415;13384.665383365495;13385.263810490516;13391.062625616114;13405.597832065054;13622.523211687543;13626.659333516742;13634.454621815215;13692.31538423075;13768.862311376757;5330.546903513167;5377;5380.784768609212;5391.226141700825;5397.40776470942;973.776031512756;993.3443927008284;998.8202123617818;1005.1556692378438;1008.5209525475487;22251.77748222497;22255.44361390978;22328.55342893133;22719.772802271767;22857.085716571484;1096.2983690438125;1099.115212254127;1099.3239157917824;1114.164376717462;1116.3023110555903;11901.821472677917;12003.519859205533;12029.278243305429;12061.457234424472;12122.75754484923;231509;231895;232553;232765;233559;52541.47458525366;52625.210621840706;52810.73594632079;52823.47176528262;52939;1392.4987004678308;1433.469616241997;1435.253668092586;1437.238263720209;1438.4749566408395;22389.552208955403;22514.887425563422;22664;22683.659745104167;22815.201467949206;36595;36836.26327473489;36858.26283474369;36884.262314754094;36895;181438;182501.08749456168;182869;183205;183783;34523.13692157716;37856.242875142896;38594.61405386023;38626;38903.80548097241;45276.547234527236;46165.61503154911;46200.537994619626;46408.53591464042;46848.531514684415;33691.494627581094;33973.830130850176;34120.82939585286;34381.31237375289;34461.65538344585;33306.66693333036;33572.32855342928;33635.49546756752;33939.321213576084;34048.48927266044;37438.438423423126;37798.43302350412;38128.42807357836;38142.42786358263;38179.61820381872;32629.673703262662;33275.33449331048;33354.332913342085;33586;33625.66374336225;24298.392540186636;24324.526981429037;24449.266522004367;24485.632715508924;24597.87701061555 20:30:02 INFO - PID 3841 | |2;jquery.html;572.7623036439878;584.853488233305;588.0532342927883;596.4423264247928;619.4301242856571;22655.20706775263;23237.3028809136;23377.41556461087;23403.76596234037;23419.648705269414;22853.542929141448;24639.137630182948;24759.504809903792;24927.127550535733;24931.501369972633;6570.835729106775;6652.833679158036;6702.262751097375;6729.798106056826;6739.359760822738;6523.836904077412;6607.1080404145305;6607.504437167232;6625.536212465129;6629.535932484728;6351.523635727316;6632.038354438603;6665.200175978889;6666.899996500057;6668.133142691443;6557.442617377528;6588.93411065888;6608.735650573972;6609.867802643944;6613.173353330858;5836.503897168745;6075.574709770318;6085.665288409135;6136.539759518054;6154.230721159855;28201.294967625767;28819.27951801201;28840.27899302513;28847.85576072127;28859.42281154386;2079.864808787424;2097.4336929029228;2107.8840663763485;2110.0504772852123;2111.8416118790997;18461.261549538;19245.51886202842;19613.509662258413;19613.901930490258;19661.705074423895;2434.8295619306655;2477.7982678400963;2498.362917456055;2534.531111744333;2535.8351707139163;119611;125307;125587.37206314;125644.37177814053;135938;13955.920167687758;14751.557453276419;14832.406703731946;14909.403623854925;14977.775333370008;23736.40658983539;23739.52520949606;23745.643815342784;23795.167169149343;23802.04791808343;10330.948345258226;10355.534000969974;10394.844077338847;10409;10413.895861041445;9542.618295268112;9546.713598592054;9667.033296670277;9732.854007189897;9766.902330976742;8860.601272942733;8972.968108667455;9001.684941027033;9054.86417703735;9389.953050234704;2675.9732402675722;2682.8390296582265;2701.7433343832304;2749.542158814751;2850.144956513038;955.9474228917476;961.2370703165797;976.2141476111798;976.5361453309621;982.9705108846746;18634.440966771017;18838.71741923872;18991.33530326432;19124.235030598622;19170.137343819282;1031.6801791444677;1036.6164519127972;1037.227265687053;1041.9635312764017;1044.4255659387306;10430.739231519121;10611.48653710717;10783.13734901206;10811.945940270249;11038.337699738042;429084;434539;434636;435812;448840;110379;111830;112957.43521282342;112958;115751;1172.1267655596635;1175.905927525796;1206.2159596262325;1245.875412458747;1250.1811313589612;20002.499937501678;20479;20557.280495182596;20676.17295308231;20755;56585.71707141438;57159;58012.70993645005;58276.12585811301;58580.4141958575;72607.63696181485;73470;73771.26228737643;73885.26114738784;74082;14529.927350363181;14608.415663373771;14780.48268310604;14876.776848347066;14904.776428353367;22027.66958495592;22074.88962555177;22396.328110156723;22555.5488890218;22576.661350079434;17585.384511542034;17685.204165812826;17849.73225401646;17885.46343609694;18008.189631466877;15863.603409914844;16084.678306433572;16275.186240688156;16340.101294428921;16358.509244722678;16951.830481695026;17496.562585934946;17616.64766704633;17761.555961101076;17777.844440111352;14477.782833257721;14640.048396854596;14644.780328295297;14659.120452772868;14677.339519721852;17443.738343924597;18080.276788928295;18210.36263730816;18229.270829166686;18438.446846594623 20:30:02 INFO - PID 3841 | |3;mootools.html;1298.5260379961314;1377.4627895120898;1379.917204967702;1383.612588475227;1398.2728980929917;14873.62815929601;15746.133962632051;15772.290246938886;15778.053316801;15806.209689515536;15244.84755152448;15474.922625386886;15493.380264789395;15510.612234694137;15549;2829.137113180479;2842.0479139488243;2898.7246211609863;2898.985505072472;2900.695426980168;2800.48191084649;2849.0598102626186;2865.1118153372418;2870.0959197852712;2879.3089658481917;2838.205302515299;2849.5725641153786;2928.9414211715753;2929.1944715203276;2929.633795775528;2840.119562935486;2859.4995875721875;2881.207667891347;2889.9855500722465;2891.9855400722968;2856.87144078517;2899.4346102510017;2922.1087568291664;2926.1221633509863;2927.458420192271;14745.041572297767;14870.479533216285;15167.924160379238;15226.771598426032;15263.313150908236;8236.917630823735;8494.853194818683;8500.70247541333;8506.744797656018;8563.571821408905;5506.4562131957;5769.740361683693;5805.158252053471;5830.562707796932;5854.20968169296;2023.0794988280297;2031.8679285846372;2043.6934459830995;2049.003515836143;2084.3121769815925;26052;26662.6000609991;26723.19830405091;26735.73264267352;26775.732242677517;7564.9243507565325;7601.7719468414925;7778.183290754502;7808.7657370278985;7866.449348545661;20329.390118296476;20474.692879606817;20477.795222047887;20498.18007279692;20534.897325513575;8408.41141120128;8576.442531235574;8602.655893764178;8607.440516366541;8615.138486151334;7506.962465187639;7550.622468876534;7784.9610751945875;7785.961070194726;7804.570748608768;5727.284089488814;5857.648541087548;5946.405359464019;6001.459868611759;6006.369331220247;1535.1249787621173;1706.87198460116;1721.741738739174;1793.4440323499757;1912.1108684461792;887.8135591525722;904.900460949295;906.40177482861;911.2163539356148;917.821024900138;8485.95757021211;8606.6987655433;8710.346723995726;8726.432781869158;8809.603567839462;960.644561512245;992.8907820139779;1009.6062535611118;1020.6121673763922;1043.2592859069994;5074.517920797518;5178.275041494194;5184.818531351385;5221.921671174935;5323.973380133075;20270.594588108157;22015.889920550293;22316;22518.211862584732;22637.773622263565;20379.694304585442;20430.18279268842;20883.895580522305;21089.78910210878;21090.472738181667;11622.07023438123;11637.88362116368;11910.225835320849;11920;11921.463534140985;8212.507249564806;8252.422330436812;8281.29608983219;8326.583670816555;8356.997160340798;18486.168122434254;18935.526611834815;19098.45256783988;19816.60366792685;20026.499337516678;19316.130774114918;19327.45380369539;19746.40760777179;20085.297014604417;20243;11450.427478626201;11644.126690498417;11792.174547781917;11793.646190614296;11808.350540720343;14285;14378.712425751635;14426.06230595008;14471;14563.563093107225;11659.358735269641;11683.824742629038;11776.11679124052;12041.518339266331;12322.075844311534;11319.151063670422;11426.314421134759;11525.82711259315;11542.076633869438;11741.41292935367;10426.218033647356;10480;10481.213908956706;10485.737856553647;10537.63118290856;7413.888791668021;7486.213947535431;7517;7564.470487066073;7631.855221716787;7354;7494.812629684301;7523.247675232433;7531.548107113591;7553.395728341609 20:30:02 INFO - PID 3841 | |4;prototype.html;488.34410081308806;499.3009786299182;521.4800099329526;523.5183631059425;536.3804805449706;6251.748810808654;6293.653849038301;6304.653244071575;6314.842128946767;6352.682365881699;6109.847253818657;6137.570370074097;6140.508759299257;6186.69066546672;6188.381161883809;4903.950960490403;5097.617678674096;5114.641975061747;5236.842894713146;5247.632665713401;5053.469385714502;5058.468860769622;5102.336696229485;5109.594861413104;5130.51260130287;4990.575801056914;5001.799928002875;5022.623303252252;5048.5203905628905;5051.469595692454;4973.477784832577;5013.423456302499;5041;5060.316857224265;5073.822416215433;4520.954790452086;4559.566841150086;4562.817487300504;4607.9308810367875;4639.257718764981;6204.751809927598;6237.313895471494;6242.376981984717;6284.34014428483;6302.590331628429;5722.37053924068;5742.253507044057;5754.424557544254;5759.452851979097;5794.565407594448;6436.3241859605005;6473.74105035798;6506.41442270198;6506.446952009085;6609.744148611749;4936.481669424695;4947.257911313296;4947.727874966876;4957.628177886637;4973.801047958077;6729.49528785343;6764.966175169093;6787.3212678732225;6822.556533825285;6866.798310295728;5030.270610761419;5031.597472202215;5060.848174554696;5118.692878427306;5132.769025393866;5203.687778733288;5215.947840521622;5221.765020574083;5230.450802665741;5239.056969745407;5642.4357564244065;5683.488486036276;5768.4808367246305;5781.768729250783;5836.241288632451;6244.438000579969;6321.399467050623;6409.359064093646;6493.448056915215;6668.266490686021;1711.4608898197027;1767.3725827331111;1770.7786526684165;1772.1582248431891;1791.6864548703925;1106.645873320546;1239.5909349914507;1252.1047451072527;1255.4789762248565;1275.3368248510722;5522.475364840334;5610.615488417407;5655.688937108416;5709.687576531512;5791.7683292667825;6714.597124172566;6750.32496750321;6770.5599136056;6833.58998460094;6846.486513511507;5700.287464066992;5708.942910570841;5749.613910227293;5762.250907382014;5773.422657734277;3506.964930350715;3556.790691165016;3588.336157810761;3591.1560783215923;3609.3322735294055;6485.5460117792245;6500.507071524554;6546.869062618721;6585.341465853472;6624.403803657598;6851.623160726208;6905.620190889449;6946.097007389007;7021.5787052777;7034.8944765828555;6376.426121649074;6423.710933008026;6474;6475.384838440435;6525.314841941527;6580.21037475506;6658.300878407891;6661.533692641468;6782.118324617768;6813.989872987381;6718.832029199114;6786.151731033621;6805.489588280998;6816.113905192294;6842.178938527409;6315.747370105144;6434.903476447764;6457.354264573505;6548.443382312556;6603.537752357289;4989.825356112518;5010.647125276228;5049.5202955720615;5050.065737838511;5057.266696328938;6765.221999469927;6770.458363330823;6778.762743304158;6783.423409010092;6799.966000169968;6357.523185760994;6445.806625801233;6453.419192272718;6469.611823290617;6508.869822603616;6460.12788273577;6485.481161506974;6518.078850468056;6530.542861999615;6616.867662646624;6351.237851457855;6459.773907913201;6472.902906456501;6503.382178693017;6519.761245363366;6060.636361818305;6426.260979987362;6464.806055818333;6554.541182117206;6588.8352791180605;6192.907106393318;6238.157848690369;6242.625442473466;6303.873922521616;6416.518761092843 20:30:02 INFO - PID 3841 | |5;yui.html;886.5921676029026;938.8075444533871;942.4251206763875;942.9669961551351;946.2099147212077;20088.196472141095;20214.19143234269;20396.69404958925;20410;20416;17669.293228270853;18001.369952051682;18123.546911327223;18130.3654372097;18161.818381816178;933.883264591926;974.2498276327224;976.8925418203992;977.301229620821;978.1685567267837;960.4669408478292;977.0669011094424;997.6907158780765;999.435319044741;999.8350272205078;1055.2560444886337;1059.2161800267822;1059.8516207730925;1063.7234319077056;1066.4081434803682;997.5062344139661;1036.9118624916853;1047.6961681112468;1047.8899715529874;1047.8952104789537;751.3838652305112;752.7252552818237;759.3317880265387;759.4038679636514;759.5366826235992;20680.58638827215;20917.686234706493;20935.162593496243;20963.685544716842;20970.580588388148;22127.557448851094;22422.781993524222;22886.19898303568;22952.311430657108;22994.655080173812;17706.645867082716;17820.287188512444;17849.286028558843;17875.821241787547;17915.910420447814;3738.1589142443004;3803.8858834235016;3807.847686092553;3808.295465338921;3808.7905165215902;142043;145499;145548;146604.99999999892;147476;17255.309787608356;17617.735733964;17655.646887062187;17728.29086836538;17756.3785267515;32104.83947580247;32371.67628323734;32383.352332953207;32387.51418728721;32407.67592324094;8326.375521835887;8565.48607083577;8591.6992905248;8605.225529702357;8652.740417787476;4233.5554766749665;4327.8485253016;4345.152695224401;4354.564543545684;4367;5619.775208991677;5619.971900140555;5665.065264231438;5703.201551782754;5758.2514273144225;1001.0540039662449;1020.6427750287488;1035;1036.6319956415514;1037.2117190934944;678.2911857109234;691.5608588546208;692.2523674431602;706.4489698035541;707.0348973650929;11701.882981170249;12054.638360849189;12146.939265303618;12157.392130393446;12360.140122232247;682.8292926768308;686.7287421468492;690.5028379566704;694.6943344928216;695.5374675840509;5336.306280183551;5341;5387.488188622075;5397;5446.754896029688;125507.74492254961;128441;129630;129810;132600.3369983144;108922.45538772256;111899;112905;113060;115542;94545.5272723632;94775;96417;96432.9999999986;98146.5092674532;9904.603815847286;9935.453550054528;10037.949810250902;10149.594016239267;10197.898021019695;45846.31230531611;45884.541154589366;47105.76447117743;47900;48283;58863.70568147132;59291;60376;61168.38831611627;62131;13536.864631353561;13969.930150349182;14359.569212923629;14455.49405770793;14781.260936952895;16172.67654646924;16231.51305460838;16273.837261627707;16421.096839673934;16506.174691265147;16549.089800060632;16697.749533757247;16709.2480838365;16916.83083169202;16931.492055238363;16228.594285142966;16399.59001024936;16566.088865112535;16574.08842513673;16746.58133546671;15172.620684482976;15398.538043858702;15615.375384984927;15618.297176627306;15807.209639517749;11752.412379380825;12470.189437686522;12523.248605083723;12659.240445573296;12878.35608219604;12196.75606487883;12224.266544007032;12282.877171228174;12380.81428778587;12394.81407778902 20:30:02 INFO - PID 3841 | __end_tp_report 20:30:02 INFO - PID 3841 | __start_cc_report 20:30:02 INFO - PID 3841 | _x_x_mozilla_cycle_collect,304 20:30:02 INFO - PID 3841 | __end_cc_report 20:30:02 INFO - PID 3841 | __startTimestamp1505964602840__endTimestamp 20:30:02 INFO - PID 3841 | 20:30:02 INFO - PID 3841 | ------- Summary: start ------- 20:30:02 INFO - PID 3841 | Number of tests: 6 20:30:02 INFO - PID 3841 | 20:30:02 INFO - PID 3841 | [#0] dojo.html Cycles:165 Average:6207.45 Median:6434.68 stddev:748.89 (11.6%) stddev-sans-first:710.83 20:30:02 INFO - PID 3841 | Values: 3115.9 3118.0 3120.6 3122.3 3144.1 6236.5 6237.5 6247.1 6323.8 6358.6 6310.2 6322.6 6345.3 6354.8 6413.3 5684.3 5736.4 5860.0 5922.4 5955.3 5741.8 5753.1 5777.9 5812.8 5813.9 5750.0 5766.5 5770.9 5788.7 5795.9 5681.8 5749.0 5794.9 5862.9 5873.9 5623.8 5661.5 5703.2 5720.8 5810.9 6314.1 6342.0 6355.3 6415.8 6418.9 6759.6 6772.4 6812.6 6814.5 6845.1 6763.8 6804.0 6860.0 6870.1 6876.1 6165.5 6235.3 6238.4 6244.4 6359.5 6804.7 6819.3 6834.5 6923.2 6983.8 6617.7 6621.6 6668.9 6694.9 6699.8 6736.1 6736.3 6774.7 6828.4 6846.8 6538.1 6603.4 6627.0 6637.3 6649.2 6678.0 6718.4 6752.9 6821.5 6827.0 5307.5 5338.7 5343.3 5347.1 5347.9 4645.4 4652.2 4653.5 4666.4 4684.7 5741.5 5744.5 5807.0 5815.5 5825.6 6575.7 6661.1 6765.9 6788.7 6887.3 5754.4 5818.3 5824.7 5835.2 5853.4 5836.9 5840.7 5911.5 5918.2 5921.4 6366.6 6555.3 6621.2 6641.8 6645.0 6676.1 6677.4 6745.3 6826.7 6833.2 6451.9 6521.2 6539.0 6545.0 6565.5 6569.3 6654.4 6742.1 6760.5 6764.5 6749.4 6815.0 6836.4 6836.7 6842.4 6613.2 6633.6 6654.1 6661.0 6670.0 6419.8 6434.7 6498.1 6610.1 6664.2 6497.8 6567.3 6589.7 6590.7 6593.0 6512.1 6575.2 6608.7 6638.6 6705.3 6307.9 6319.7 6353.7 6360.2 6361.9 20:30:02 INFO - PID 3841 | 20:30:02 INFO - PID 3841 | [#1] ext.html Cycles:180 Average:37240.87 Median:20098.79 stddev:52180.49 (259.6%) stddev-sans-first:52255.13 20:30:02 INFO - PID 3841 | Values: 801.1 879.4 894.6 904.0 1155.9 30122.7 31083.5 40706.0 41313.4 41567.0 40462.0 40854.2 40857.6 40864.6 40885.4 7800.0 7891.1 8002.1 8208.0 8222.9 7990.2 8012.0 8049.9 8050.7 8068.0 8003.6 8040.6 8048.6 8050.2 8078.8 8023.4 8098.0 8100.7 8107.2 8108.5 8019.4 8055.2 8090.4 8094.3 8102.0 36732.1 36777.3 37084.1 37295.0 37307.3 3535.6 3578.3 3595.6 3600.0 3602.6 30790.8 30868.4 30906.0 30908.7 30932.8 5114.9 5120.3 5128.2 5144.7 5163.9 184231.0 184616.0 185146.0 186197.0 186817.1 47567.0 47779.0 47797.8 47956.5 48587.3 50831.0 50988.2 51111.5 51136.5 51136.7 14177.6 14401.4 16282.6 16440.0 16440.1 13099.1 13384.7 13385.3 13391.1 13405.6 13622.5 13626.7 13634.5 13692.3 13768.9 5330.5 5377.0 5380.8 5391.2 5397.4 973.8 993.3 998.8 1005.2 1008.5 22251.8 22255.4 22328.6 22719.8 22857.1 1096.3 1099.1 1099.3 1114.2 1116.3 11901.8 12003.5 12029.3 12061.5 12122.8 231509.0 231895.0 232553.0 232765.0 233559.0 52541.5 52625.2 52810.7 52823.5 52939.0 1392.5 1433.5 1435.3 1437.2 1438.5 22389.6 22514.9 22664.0 22683.7 22815.2 36595.0 36836.3 36858.3 36884.3 36895.0 181438.0 182501.1 182869.0 183205.0 183783.0 34523.1 37856.2 38594.6 38626.0 38903.8 45276.5 46165.6 46200.5 46408.5 46848.5 33691.5 33973.8 34120.8 34381.3 34461.7 33306.7 33572.3 33635.5 33939.3 34048.5 37438.4 37798.4 38128.4 38142.4 38179.6 32629.7 33275.3 33354.3 33586.0 33625.7 24298.4 24324.5 24449.3 24485.6 24597.9 20:30:02 INFO - PID 3841 | 20:30:02 INFO - PID 3841 | [#2] jquery.html Cycles:180 Average:32927.67 Median:22754.37 stddev:73946.66 (325.0%) stddev-sans-first:74114.20 20:30:02 INFO - PID 3841 | Values: 572.8 584.9 588.1 596.4 619.4 22655.2 23237.3 23377.4 23403.8 23419.6 22853.5 24639.1 24759.5 24927.1 24931.5 6570.8 6652.8 6702.3 6729.8 6739.4 6523.8 6607.1 6607.5 6625.5 6629.5 6351.5 6632.0 6665.2 6666.9 6668.1 6557.4 6588.9 6608.7 6609.9 6613.2 5836.5 6075.6 6085.7 6136.5 6154.2 28201.3 28819.3 28840.3 28847.9 28859.4 2079.9 2097.4 2107.9 2110.1 2111.8 18461.3 19245.5 19613.5 19613.9 19661.7 2434.8 2477.8 2498.4 2534.5 2535.8 119611.0 125307.0 125587.4 125644.4 135938.0 13955.9 14751.6 14832.4 14909.4 14977.8 23736.4 23739.5 23745.6 23795.2 23802.0 10330.9 10355.5 10394.8 10409.0 10413.9 9542.6 9546.7 9667.0 9732.9 9766.9 8860.6 8973.0 9001.7 9054.9 9390.0 2676.0 2682.8 2701.7 2749.5 2850.1 955.9 961.2 976.2 976.5 983.0 18634.4 18838.7 18991.3 19124.2 19170.1 1031.7 1036.6 1037.2 1042.0 1044.4 10430.7 10611.5 10783.1 10811.9 11038.3 429084.0 434539.0 434636.0 435812.0 448840.0 110379.0 111830.0 112957.4 112958.0 115751.0 1172.1 1175.9 1206.2 1245.9 1250.2 20002.5 20479.0 20557.3 20676.2 20755.0 56585.7 57159.0 58012.7 58276.1 58580.4 72607.6 73470.0 73771.3 73885.3 74082.0 14529.9 14608.4 14780.5 14876.8 14904.8 22027.7 22074.9 22396.3 22555.5 22576.7 17585.4 17685.2 17849.7 17885.5 18008.2 15863.6 16084.7 16275.2 16340.1 16358.5 16951.8 17496.6 17616.6 17761.6 17777.8 14477.8 14640.0 14644.8 14659.1 14677.3 17443.7 18080.3 18210.4 18229.3 18438.4 20:30:02 INFO - PID 3841 | 20:30:02 INFO - PID 3841 | [#3] mootools.html Cycles:180 Average:9706.48 Median:26729.47 stddev:6798.95 (25.4%) stddev-sans-first:6788.67 20:30:02 INFO - PID 3841 | Values: 1298.5 1377.5 1379.9 1383.6 1398.3 14873.6 15746.1 15772.3 15778.1 15806.2 15244.8 15474.9 15493.4 15510.6 15549.0 2829.1 2842.0 2898.7 2899.0 2900.7 2800.5 2849.1 2865.1 2870.1 2879.3 2838.2 2849.6 2928.9 2929.2 2929.6 2840.1 2859.5 2881.2 2890.0 2892.0 2856.9 2899.4 2922.1 2926.1 2927.5 14745.0 14870.5 15167.9 15226.8 15263.3 8236.9 8494.9 8500.7 8506.7 8563.6 5506.5 5769.7 5805.2 5830.6 5854.2 2023.1 2031.9 2043.7 2049.0 2084.3 26052.0 26662.6 26723.2 26735.7 26775.7 7564.9 7601.8 7778.2 7808.8 7866.4 20329.4 20474.7 20477.8 20498.2 20534.9 8408.4 8576.4 8602.7 8607.4 8615.1 7507.0 7550.6 7785.0 7786.0 7804.6 5727.3 5857.6 5946.4 6001.5 6006.4 1535.1 1706.9 1721.7 1793.4 1912.1 887.8 904.9 906.4 911.2 917.8 8486.0 8606.7 8710.3 8726.4 8809.6 960.6 992.9 1009.6 1020.6 1043.3 5074.5 5178.3 5184.8 5221.9 5324.0 20270.6 22015.9 22316.0 22518.2 22637.8 20379.7 20430.2 20883.9 21089.8 21090.5 11622.1 11637.9 11910.2 11920.0 11921.5 8212.5 8252.4 8281.3 8326.6 8357.0 18486.2 18935.5 19098.5 19816.6 20026.5 19316.1 19327.5 19746.4 20085.3 20243.0 11450.4 11644.1 11792.2 11793.6 11808.4 14285.0 14378.7 14426.1 14471.0 14563.6 11659.4 11683.8 11776.1 12041.5 12322.1 11319.2 11426.3 11525.8 11542.1 11741.4 10426.2 10480.0 10481.2 10485.7 10537.6 7413.9 7486.2 7517.0 7564.5 7631.9 7354.0 7494.8 7523.2 7531.5 7553.4 20:30:02 INFO - PID 3841 | 20:30:02 INFO - PID 3841 | [#4] prototype.html Cycles:180 Average:5531.74 Median:6190.64 stddev:1536.31 (24.8%) stddev-sans-first:1493.26 20:30:02 INFO - PID 3841 | Values: 488.3 499.3 521.5 523.5 536.4 6251.7 6293.7 6304.7 6314.8 6352.7 6109.8 6137.6 6140.5 6186.7 6188.4 4904.0 5097.6 5114.6 5236.8 5247.6 5053.5 5058.5 5102.3 5109.6 5130.5 4990.6 5001.8 5022.6 5048.5 5051.5 4973.5 5013.4 5041.0 5060.3 5073.8 4521.0 4559.6 4562.8 4607.9 4639.3 6204.8 6237.3 6242.4 6284.3 6302.6 5722.4 5742.3 5754.4 5759.5 5794.6 6436.3 6473.7 6506.4 6506.4 6609.7 4936.5 4947.3 4947.7 4957.6 4973.8 6729.5 6765.0 6787.3 6822.6 6866.8 5030.3 5031.6 5060.8 5118.7 5132.8 5203.7 5215.9 5221.8 5230.5 5239.1 5642.4 5683.5 5768.5 5781.8 5836.2 6244.4 6321.4 6409.4 6493.4 6668.3 1711.5 1767.4 1770.8 1772.2 1791.7 1106.6 1239.6 1252.1 1255.5 1275.3 5522.5 5610.6 5655.7 5709.7 5791.8 6714.6 6750.3 6770.6 6833.6 6846.5 5700.3 5708.9 5749.6 5762.3 5773.4 3507.0 3556.8 3588.3 3591.2 3609.3 6485.5 6500.5 6546.9 6585.3 6624.4 6851.6 6905.6 6946.1 7021.6 7034.9 6376.4 6423.7 6474.0 6475.4 6525.3 6580.2 6658.3 6661.5 6782.1 6814.0 6718.8 6786.2 6805.5 6816.1 6842.2 6315.7 6434.9 6457.4 6548.4 6603.5 4989.8 5010.6 5049.5 5050.1 5057.3 6765.2 6770.5 6778.8 6783.4 6800.0 6357.5 6445.8 6453.4 6469.6 6508.9 6460.1 6485.5 6518.1 6530.5 6616.9 6351.2 6459.8 6472.9 6503.4 6519.8 6060.6 6426.3 6464.8 6554.5 6588.8 6192.9 6238.2 6242.6 6303.9 6416.5 20:30:02 INFO - PID 3841 | 20:30:02 INFO - PID 3841 | [#5] yui.html Cycles:180 Average:25063.16 Median:20413.00 stddev:36742.17 (180.0%) stddev-sans-first:36800.40 20:30:02 INFO - PID 3841 | Values: 886.6 938.8 942.4 943.0 946.2 20088.2 20214.2 20396.7 20410.0 20416.0 17669.3 18001.4 18123.5 18130.4 18161.8 933.9 974.2 976.9 977.3 978.2 960.5 977.1 997.7 999.4 999.8 1055.3 1059.2 1059.9 1063.7 1066.4 997.5 1036.9 1047.7 1047.9 1047.9 751.4 752.7 759.3 759.4 759.5 20680.6 20917.7 20935.2 20963.7 20970.6 22127.6 22422.8 22886.2 22952.3 22994.7 17706.6 17820.3 17849.3 17875.8 17915.9 3738.2 3803.9 3807.8 3808.3 3808.8 142043.0 145499.0 145548.0 146605.0 147476.0 17255.3 17617.7 17655.6 17728.3 17756.4 32104.8 32371.7 32383.4 32387.5 32407.7 8326.4 8565.5 8591.7 8605.2 8652.7 4233.6 4327.8 4345.2 4354.6 4367.0 5619.8 5620.0 5665.1 5703.2 5758.3 1001.1 1020.6 1035.0 1036.6 1037.2 678.3 691.6 692.3 706.4 707.0 11701.9 12054.6 12146.9 12157.4 12360.1 682.8 686.7 690.5 694.7 695.5 5336.3 5341.0 5387.5 5397.0 5446.8 125507.7 128441.0 129630.0 129810.0 132600.3 108922.5 111899.0 112905.0 113060.0 115542.0 94545.5 94775.0 96417.0 96433.0 98146.5 9904.6 9935.5 10037.9 10149.6 10197.9 45846.3 45884.5 47105.8 47900.0 48283.0 58863.7 59291.0 60376.0 61168.4 62131.0 13536.9 13969.9 14359.6 14455.5 14781.3 16172.7 16231.5 16273.8 16421.1 16506.2 16549.1 16697.7 16709.2 16916.8 16931.5 16228.6 16399.6 16566.1 16574.1 16746.6 15172.6 15398.5 15615.4 15618.3 15807.2 11752.4 12470.2 12523.2 12659.2 12878.4 12196.8 12224.3 12282.9 12380.8 12394.8 20:30:02 INFO - PID 3841 | -------- Summary: end -------- 20:30:02 INFO - PID 3841 | 20:30:03 INFO - TEST-INFO | 3841: exit 0 20:30:03 INFO - TEST-OK | dromaeo_css | took 1112892ms 20:30:03 INFO - TEST-START | kraken 20:30:03 INFO - Initialising browser for kraken test... 20:30:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33296/getInfo.html -profile /tmp/tmpVmUaJ8/profile 20:30:03 INFO - TEST-INFO | started process 4212 (/builds/slave/test/build/application/firefox/firefox http://localhost:33296/getInfo.html) 20:30:09 INFO - TEST-INFO | 4212: exit 0 20:30:09 INFO - Browser initialized. 20:30:09 INFO - Running cycle 1/1 for kraken test... 20:30:09 INFO - Using env: {'DISPLAY': ':0', 20:30:09 INFO - 'HOME': '/home/cltbld', 20:30:09 INFO - 'JSGC_DISABLE_POISONING': '1', 20:30:09 INFO - 'LANG': 'en_US.UTF-8', 20:30:09 INFO - 'LANGUAGE': 'en_US:en', 20:30:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:30:09 INFO - 'LOGNAME': 'cltbld', 20:30:09 INFO - 'MAIL': '/var/mail/cltbld', 20:30:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:30:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:30:09 INFO - 'MOZ_CRASHREPORTER': '1', 20:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:30:09 INFO - 'MOZ_NO_REMOTE': '1', 20:30:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:09 INFO - 'NO_EM_RESTART': '1', 20:30:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:09 INFO - 'PWD': '/builds/slave/test', 20:30:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:30:09 INFO - 'RUST_BACKTRACE': 'full', 20:30:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:30:09 INFO - 'SHELL': '/bin/bash', 20:30:09 INFO - 'SHLVL': '1', 20:30:09 INFO - 'TERM': 'linux', 20:30:09 INFO - 'TMOUT': '86400', 20:30:09 INFO - 'USER': 'cltbld', 20:30:09 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024', 20:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:09 INFO - '_': '/tools/buildbot/bin/python'} 20:30:09 INFO - TEST-INFO | started process 4445 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVmUaJ8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:30:10 INFO - PID 4445 | 20:30:10 INFO - PID 4445 | (/builds/slave/test/build/application/firefox/firefox:4494): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:30:10 INFO - PID 4445 | 20:30:20 INFO - PID 4445 | RSS: Main: 155156480 20:30:20 INFO - PID 4445 | 20:30:23 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33296/tests/kraken/driver.html?testName=audio-beat-detection) 20:30:24 INFO - PID 4445 | RSS: Main: 161648640 20:30:24 INFO - PID 4445 | 20:30:26 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33296/tests/kraken/driver.html?testName=audio-dft) 20:30:27 INFO - PID 4445 | RSS: Main: 161808384 20:30:27 INFO - PID 4445 | 20:30:30 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33296/tests/kraken/driver.html?testName=audio-fft) 20:30:30 INFO - PID 4445 | RSS: Main: 157261824 20:30:30 INFO - PID 4445 | 20:30:33 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33296/tests/kraken/driver.html?testName=audio-oscillator) 20:30:33 INFO - PID 4445 | RSS: Main: 157892608 20:30:33 INFO - PID 4445 | 20:30:35 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33296/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:30:36 INFO - PID 4445 | RSS: Main: 157827072 20:30:36 INFO - PID 4445 | 20:30:39 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33296/tests/kraken/driver.html?testName=imaging-darkroom) 20:30:40 INFO - PID 4445 | RSS: Main: 160448512 20:30:40 INFO - PID 4445 | 20:30:43 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33296/tests/kraken/driver.html?testName=imaging-desaturate) 20:30:44 INFO - PID 4445 | RSS: Main: 160862208 20:30:44 INFO - PID 4445 | 20:30:47 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33296/tests/kraken/driver.html?testName=json-parse-financial) 20:30:48 INFO - PID 4445 | RSS: Main: 160604160 20:30:48 INFO - PID 4445 | 20:30:49 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33296/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:30:50 INFO - PID 4445 | RSS: Main: 160702464 20:30:50 INFO - PID 4445 | 20:30:51 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33296/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:30:52 INFO - PID 4445 | RSS: Main: 157880320 20:30:52 INFO - PID 4445 | 20:30:55 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33296/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:30:55 INFO - PID 4445 | RSS: Main: 157831168 20:30:55 INFO - PID 4445 | 20:30:58 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33296/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:30:59 INFO - PID 4445 | RSS: Main: 158236672 20:30:59 INFO - PID 4445 | 20:31:02 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33296/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:31:02 INFO - PID 4445 | RSS: Main: 158154752 20:31:02 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | Cycle 1(1): loaded http://localhost:33296/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33296/tests/kraken/driver.html?testName=ai-astar) 20:31:04 INFO - PID 4445 | RSS: Main: 159899648 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | __start_tp_report 20:31:04 INFO - PID 4445 | _x_x_mozilla_page_load 20:31:04 INFO - PID 4445 | _x_x_mozilla_page_load_details 20:31:04 INFO - PID 4445 | |i|pagename|runs| 20:31:04 INFO - PID 4445 | |0;ai-astar;108;107;106;107;107;108;108;107;110;107 20:31:04 INFO - PID 4445 | |1;audio-beat-detection;120;124;119;122;120;116;117;118;116;117 20:31:04 INFO - PID 4445 | |2;audio-dft;166;165;164;163;163;163;163;165;169;165 20:31:04 INFO - PID 4445 | |3;audio-fft;74;74;78;74;74;73;74;74;77;109 20:31:04 INFO - PID 4445 | |4;audio-oscillator;80;79;79;83;79;79;79;81;79;78 20:31:04 INFO - PID 4445 | |5;imaging-gaussian-blur;114;114;113;114;115;114;113;114;115;118 20:31:04 INFO - PID 4445 | |6;imaging-darkroom;148;148;148;147;147;148;148;150;148;148 20:31:04 INFO - PID 4445 | |7;imaging-desaturate;96;94;95;95;94;93;94;96;94;96 20:31:04 INFO - PID 4445 | |8;json-parse-financial;56;57;53;53;54;56;53;54;55;54 20:31:04 INFO - PID 4445 | |9;json-stringify-tinderbox;38;38;38;38;37;38;38;38;38;38 20:31:04 INFO - PID 4445 | |10;stanford-crypto-aes;73;74;74;72;76;74;72;72;75;73 20:31:04 INFO - PID 4445 | |11;stanford-crypto-ccm;114;112;114;117;124;131;119;114;122;117 20:31:04 INFO - PID 4445 | |12;stanford-crypto-pbkdf2;171;174;176;168;169;172;174;184;168;170 20:31:04 INFO - PID 4445 | |13;stanford-crypto-sha256-iterative;66;64;66;69;66;82;66;66;68;74 20:31:04 INFO - PID 4445 | __end_tp_report 20:31:04 INFO - PID 4445 | __start_cc_report 20:31:04 INFO - PID 4445 | _x_x_mozilla_cycle_collect,745 20:31:04 INFO - PID 4445 | __end_cc_report 20:31:04 INFO - PID 4445 | __startTimestamp1505964664821__endTimestamp 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | ------- Summary: start ------- 20:31:04 INFO - PID 4445 | Number of tests: 14 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#0] ai-astar Cycles:10 Average:107.50 Median:107.50 stddev:1.08 (1.0%) stddev-sans-first:1.13 20:31:04 INFO - PID 4445 | Values: 108.0 107.0 106.0 107.0 107.0 108.0 108.0 107.0 110.0 107.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#1] audio-beat-detection Cycles:10 Average:118.90 Median:119.50 stddev:2.64 (2.2%) stddev-sans-first:2.77 20:31:04 INFO - PID 4445 | Values: 120.0 124.0 119.0 122.0 120.0 116.0 117.0 118.0 116.0 117.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#2] audio-dft Cycles:10 Average:164.60 Median:165.00 stddev:1.90 (1.1%) stddev-sans-first:1.94 20:31:04 INFO - PID 4445 | Values: 166.0 165.0 164.0 163.0 163.0 163.0 163.0 165.0 169.0 165.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#3] audio-fft Cycles:10 Average:78.10 Median:74.00 stddev:10.97 (14.8%) stddev-sans-first:11.53 20:31:04 INFO - PID 4445 | Values: 74.0 74.0 78.0 74.0 74.0 73.0 74.0 74.0 77.0 109.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:79.00 stddev:1.43 (1.8%) stddev-sans-first:1.51 20:31:04 INFO - PID 4445 | Values: 80.0 79.0 79.0 83.0 79.0 79.0 79.0 81.0 79.0 78.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:1.43 (1.3%) stddev-sans-first:1.51 20:31:04 INFO - PID 4445 | Values: 114.0 114.0 113.0 114.0 115.0 114.0 113.0 114.0 115.0 118.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 20:31:04 INFO - PID 4445 | Values: 148.0 148.0 148.0 147.0 147.0 148.0 148.0 150.0 148.0 148.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:1.06 (1.1%) stddev-sans-first:1.01 20:31:04 INFO - PID 4445 | Values: 96.0 94.0 95.0 95.0 94.0 93.0 94.0 96.0 94.0 96.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.43 (2.6%) stddev-sans-first:1.41 20:31:04 INFO - PID 4445 | Values: 56.0 57.0 53.0 53.0 54.0 56.0 53.0 54.0 55.0 54.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#9] json-stringify-tinderbox Cycles:10 Average:37.90 Median:38.00 stddev:0.32 (0.8%) stddev-sans-first:0.33 20:31:04 INFO - PID 4445 | Values: 38.0 38.0 38.0 38.0 37.0 38.0 38.0 38.0 38.0 38.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:74.00 stddev:1.35 (1.8%) stddev-sans-first:1.42 20:31:04 INFO - PID 4445 | Values: 73.0 74.0 74.0 72.0 76.0 74.0 72.0 72.0 75.0 73.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#11] stanford-crypto-ccm Cycles:10 Average:118.40 Median:118.00 stddev:5.83 (4.9%) stddev-sans-first:5.97 20:31:04 INFO - PID 4445 | Values: 114.0 112.0 114.0 117.0 124.0 131.0 119.0 114.0 122.0 117.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.00 stddev:4.84 (2.8%) stddev-sans-first:5.09 20:31:04 INFO - PID 4445 | Values: 171.0 174.0 176.0 168.0 169.0 172.0 174.0 184.0 168.0 170.0 20:31:04 INFO - PID 4445 | 20:31:04 INFO - PID 4445 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:67.00 stddev:5.42 (8.1%) stddev-sans-first:5.66 20:31:04 INFO - PID 4445 | Values: 66.0 64.0 66.0 69.0 66.0 82.0 66.0 66.0 68.0 74.0 20:31:04 INFO - PID 4445 | -------- Summary: end -------- 20:31:04 INFO - PID 4445 | 20:31:05 INFO - PID 4445 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 20:31:05 INFO - TEST-INFO | 4445: exit 0 20:31:05 INFO - TEST-OK | kraken | took 61927ms 20:31:05 INFO - SUITE-END | took 1174s 20:31:05 INFO - Completed test suite (00:19:35) 20:31:05 INFO - javascript benchmark 20:31:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8612.898548135236, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3115.909431698906, 3118.0489950565084, 3120.5943227380435, 3122.2662674271546, 3144.05678296511, 6236.532260080489, 6237.5009999200065, 6247.125402443656, 6323.810285691431, 6358.586691865025, 6310.24277086749, 6322.620642761438, 6345.270293916201, 6354.7775827846035, 6413.326600706929, 5684.346300175492, 5736.397678243787, 5860.033094539406, 5922.378150294222, 5955.315138759033, 5741.799037033705, 5753.050746626802, 5777.884442311151, 5812.767489300423, 5813.854653633672, 5749.9712501437225, 5766.452187042245, 5770.942290577103, 5788.710564471759, 5795.855103622401, 5681.801136960186, 5748.97125514374, 5794.855128621818, 5862.886051650173, 5873.911891321633, 5623.775048998035, 5661.46216109469, 5703.201551782754, 5720.771169153229, 5810.941890581083, 6314.147590075373, 6341.95357765968, 6355.269144048401, 6415.8075257741875, 6418.8716225675225, 6759.5606285591275, 6772.356626120511, 6812.62530560819, 6814.522983391164, 6845.14435695538, 6763.763268285636, 6803.965980170117, 6859.965700171517, 6870.106886104824, 6876.071730316443, 6165.537584681122, 6235.345288744707, 6238.376162383771, 6244.3755624437645, 6359.4594459471, 6804.727810887608, 6819.283975182633, 6834.453243740489, 6923.2038315594355, 6983.790486285419, 6617.669116544153, 6621.602703837785, 6668.899966500507, 6694.866102677919, 6699.832504187337, 6736.090627765287, 6736.326367363225, 6774.72901083961, 6828.419584335288, 6846.828829279308, 6538.051982462516, 6603.372679595431, 6626.966865165643, 6637.303083176197, 6649.202095748541, 6678.031685405589, 6718.361755633207, 6752.898706519406, 6821.4542836572955, 6827.0, 5307.495787900143, 5338.652987555796, 5343.278657381204, 5347.0909945309395, 5347.866303342447, 4645.419322584677, 4652.209124448852, 4653.488116307203, 4666.427260394174, 4684.695494792896, 5741.45456181662, 5744.540436765049, 5806.970965145232, 5815.534757219412, 5825.563082768725, 6575.671216439159, 6661.134052573135, 6765.898511522331, 6788.694508747119, 6887.276835932155, 5754.367019627837, 5818.301803783573, 5824.708764561755, 5835.183074369592, 5853.414658534198, 5836.88326233473, 5840.675312501568, 5911.4975227105315, 5918.171455996164, 5921.437463440966, 6366.586171898812, 6555.344465553407, 6621.205455345293, 6641.800745977628, 6644.96677516619, 6676.132102826601, 6677.399034086859, 6745.25802161752, 6826.692798823966, 6833.180018397824, 6451.870962580816, 6521.152250207443, 6539.0, 6544.9672751635935, 6565.474762018932, 6569.343065693392, 6654.401103900672, 6742.123523941857, 6760.459163267025, 6764.49266305039, 6749.358810912955, 6815.0, 6836.418904392984, 6836.658167091526, 6842.384185423336, 6613.239477459962, 6633.6351500666, 6654.101696270942, 6661.0, 6669.966650166913, 6419.807405777834, 6434.67826608677, 6498.057781621733, 6610.07458955737, 6664.166979127609, 6497.772577959749, 6567.310432404529, 6589.703463344065, 6590.736370545124, 6593.044008618819, 6512.05575191604, 6575.210974683068, 6608.7356505739235, 6638.5684930479365, 6705.329467053256, 6307.942558903027, 6319.652419116993, 6353.650549219838, 6360.204974378203, 6361.8727625448155], "value": 6149.5747660821, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [801.1147681811598, 879.4107947675059, 894.5616647842555, 903.9864402033969, 1155.8786327435623, 30122.69877301229, 31083.533746993784, 40706.0, 41313.38029929546, 41567.0, 40462.0, 40854.182916341655, 40857.59142408575, 40864.59135408645, 40885.38671919916, 7800.0, 7891.092524359686, 8002.119766825645, 8208.0, 8222.876656850152, 7990.2009799019925, 8012.0, 8049.919500805005, 8050.718224862131, 8068.0, 8003.639836207355, 8040.557769322685, 8048.557329346883, 8050.194980501961, 8078.798030049266, 8023.438359314821, 8098.0, 8100.716474923379, 8107.22981316774, 8108.472949258279, 8019.35845132388, 8055.15420880805, 8090.433669643127, 8094.271515563569, 8102.027756669178, 36732.081697957765, 36777.264454711025, 37084.07289817749, 37295.0, 37307.25385492302, 3535.5757309122814, 3578.3022310649435, 3595.622459641726, 3599.9560127562977, 3602.585702644203, 30790.846045769627, 30868.382632347228, 30906.0, 30908.690913090806, 30932.845335773403, 5114.923276150861, 5120.308758317654, 5128.230765385185, 5144.6655967362, 5163.922541161848, 184231.0, 184616.0, 185146.0, 186196.99999999863, 186817.0659146709, 47567.0, 47779.0, 47797.76101119472, 47956.5204347959, 48587.271190932166, 50831.0, 50988.235176472386, 51111.488885111416, 51136.48863511317, 51136.74431627818, 14177.645558860904, 14401.423943042371, 16282.592935176715, 16440.0, 16440.095794731165, 13099.083064185415, 13384.665383365495, 13385.263810490516, 13391.062625616114, 13405.597832065054, 13622.523211687543, 13626.659333516742, 13634.454621815215, 13692.31538423075, 13768.862311376757, 5330.546903513167, 5377.0, 5380.784768609212, 5391.226141700825, 5397.40776470942, 973.776031512756, 993.3443927008284, 998.8202123617818, 1005.1556692378438, 1008.5209525475487, 22251.77748222497, 22255.44361390978, 22328.55342893133, 22719.772802271767, 22857.085716571484, 1096.2983690438125, 1099.115212254127, 1099.3239157917824, 1114.164376717462, 1116.3023110555903, 11901.821472677917, 12003.519859205533, 12029.278243305429, 12061.457234424472, 12122.75754484923, 231509.0, 231895.0, 232553.0, 232765.0, 233559.0, 52541.47458525366, 52625.210621840706, 52810.73594632079, 52823.47176528262, 52939.0, 1392.4987004678308, 1433.469616241997, 1435.253668092586, 1437.238263720209, 1438.4749566408395, 22389.552208955403, 22514.887425563422, 22664.0, 22683.659745104167, 22815.201467949206, 36595.0, 36836.26327473489, 36858.26283474369, 36884.262314754094, 36895.0, 181438.0, 182501.08749456168, 182869.0, 183205.0, 183783.0, 34523.13692157716, 37856.242875142896, 38594.61405386023, 38626.0, 38903.80548097241, 45276.547234527236, 46165.61503154911, 46200.537994619626, 46408.53591464042, 46848.531514684415, 33691.494627581094, 33973.830130850176, 34120.82939585286, 34381.31237375289, 34461.65538344585, 33306.66693333036, 33572.32855342928, 33635.49546756752, 33939.321213576084, 34048.48927266044, 37438.438423423126, 37798.43302350412, 38128.42807357836, 38142.42786358263, 38179.61820381872, 32629.673703262662, 33275.33449331048, 33354.332913342085, 33586.0, 33625.66374336225, 24298.392540186636, 24324.526981429037, 24449.266522004367, 24485.632715508924, 24597.87701061555], "value": 17254.842746371345, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.7623036439878, 584.853488233305, 588.0532342927883, 596.4423264247928, 619.4301242856571, 22655.20706775263, 23237.3028809136, 23377.41556461087, 23403.76596234037, 23419.648705269414, 22853.542929141448, 24639.137630182948, 24759.504809903792, 24927.127550535733, 24931.501369972633, 6570.835729106775, 6652.833679158036, 6702.262751097375, 6729.798106056826, 6739.359760822738, 6523.836904077412, 6607.1080404145305, 6607.504437167232, 6625.536212465129, 6629.535932484728, 6351.523635727316, 6632.038354438603, 6665.200175978889, 6666.899996500057, 6668.133142691443, 6557.442617377528, 6588.93411065888, 6608.735650573972, 6609.867802643944, 6613.173353330858, 5836.503897168745, 6075.574709770318, 6085.665288409135, 6136.539759518054, 6154.230721159855, 28201.294967625767, 28819.27951801201, 28840.27899302513, 28847.85576072127, 28859.42281154386, 2079.864808787424, 2097.4336929029228, 2107.8840663763485, 2110.0504772852123, 2111.8416118790997, 18461.261549538, 19245.51886202842, 19613.509662258413, 19613.901930490258, 19661.705074423895, 2434.8295619306655, 2477.7982678400963, 2498.362917456055, 2534.531111744333, 2535.8351707139163, 119611.0, 125307.0, 125587.37206314, 125644.37177814053, 135938.0, 13955.920167687758, 14751.557453276419, 14832.406703731946, 14909.403623854925, 14977.775333370008, 23736.40658983539, 23739.52520949606, 23745.643815342784, 23795.167169149343, 23802.04791808343, 10330.948345258226, 10355.534000969974, 10394.844077338847, 10409.0, 10413.895861041445, 9542.618295268112, 9546.713598592054, 9667.033296670277, 9732.854007189897, 9766.902330976742, 8860.601272942733, 8972.968108667455, 9001.684941027033, 9054.86417703735, 9389.953050234704, 2675.9732402675722, 2682.8390296582265, 2701.7433343832304, 2749.542158814751, 2850.144956513038, 955.9474228917476, 961.2370703165797, 976.2141476111798, 976.5361453309621, 982.9705108846746, 18634.440966771017, 18838.71741923872, 18991.33530326432, 19124.235030598622, 19170.137343819282, 1031.6801791444677, 1036.6164519127972, 1037.227265687053, 1041.9635312764017, 1044.4255659387306, 10430.739231519121, 10611.48653710717, 10783.13734901206, 10811.945940270249, 11038.337699738042, 429084.0, 434539.0, 434636.0, 435812.0, 448840.0, 110379.0, 111830.0, 112957.43521282342, 112958.0, 115751.0, 1172.1267655596635, 1175.905927525796, 1206.2159596262325, 1245.875412458747, 1250.1811313589612, 20002.499937501678, 20479.0, 20557.280495182596, 20676.17295308231, 20755.0, 56585.71707141438, 57159.0, 58012.70993645005, 58276.12585811301, 58580.4141958575, 72607.63696181485, 73470.0, 73771.26228737643, 73885.26114738784, 74082.0, 14529.927350363181, 14608.415663373771, 14780.48268310604, 14876.776848347066, 14904.776428353367, 22027.66958495592, 22074.88962555177, 22396.328110156723, 22555.5488890218, 22576.661350079434, 17585.384511542034, 17685.204165812826, 17849.73225401646, 17885.46343609694, 18008.189631466877, 15863.603409914844, 16084.678306433572, 16275.186240688156, 16340.101294428921, 16358.509244722678, 16951.830481695026, 17496.562585934946, 17616.64766704633, 17761.555961101076, 17777.844440111352, 14477.782833257721, 14640.048396854596, 14644.780328295297, 14659.120452772868, 14677.339519721852, 17443.738343924597, 18080.276788928295, 18210.36263730816, 18229.270829166686, 18438.446846594623], "value": 11934.689972731368, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.5260379961314, 1377.4627895120898, 1379.917204967702, 1383.612588475227, 1398.2728980929917, 14873.62815929601, 15746.133962632051, 15772.290246938886, 15778.053316801, 15806.209689515536, 15244.84755152448, 15474.922625386886, 15493.380264789395, 15510.612234694137, 15549.0, 2829.137113180479, 2842.0479139488243, 2898.7246211609863, 2898.985505072472, 2900.695426980168, 2800.48191084649, 2849.0598102626186, 2865.1118153372418, 2870.0959197852712, 2879.3089658481917, 2838.205302515299, 2849.5725641153786, 2928.9414211715753, 2929.1944715203276, 2929.633795775528, 2840.119562935486, 2859.4995875721875, 2881.207667891347, 2889.9855500722465, 2891.9855400722968, 2856.87144078517, 2899.4346102510017, 2922.1087568291664, 2926.1221633509863, 2927.458420192271, 14745.041572297767, 14870.479533216285, 15167.924160379238, 15226.771598426032, 15263.313150908236, 8236.917630823735, 8494.853194818683, 8500.70247541333, 8506.744797656018, 8563.571821408905, 5506.4562131957, 5769.740361683693, 5805.158252053471, 5830.562707796932, 5854.20968169296, 2023.0794988280297, 2031.8679285846372, 2043.6934459830995, 2049.003515836143, 2084.3121769815925, 26052.0, 26662.6000609991, 26723.19830405091, 26735.73264267352, 26775.732242677517, 7564.9243507565325, 7601.7719468414925, 7778.183290754502, 7808.7657370278985, 7866.449348545661, 20329.390118296476, 20474.692879606817, 20477.795222047887, 20498.18007279692, 20534.897325513575, 8408.41141120128, 8576.442531235574, 8602.655893764178, 8607.440516366541, 8615.138486151334, 7506.962465187639, 7550.622468876534, 7784.9610751945875, 7785.961070194726, 7804.570748608768, 5727.284089488814, 5857.648541087548, 5946.405359464019, 6001.459868611759, 6006.369331220247, 1535.1249787621173, 1706.87198460116, 1721.741738739174, 1793.4440323499757, 1912.1108684461792, 887.8135591525722, 904.900460949295, 906.40177482861, 911.2163539356148, 917.821024900138, 8485.95757021211, 8606.6987655433, 8710.346723995726, 8726.432781869158, 8809.603567839462, 960.644561512245, 992.8907820139779, 1009.6062535611118, 1020.6121673763922, 1043.2592859069994, 5074.517920797518, 5178.275041494194, 5184.818531351385, 5221.921671174935, 5323.973380133075, 20270.594588108157, 22015.889920550293, 22316.0, 22518.211862584732, 22637.773622263565, 20379.694304585442, 20430.18279268842, 20883.895580522305, 21089.78910210878, 21090.472738181667, 11622.07023438123, 11637.88362116368, 11910.225835320849, 11920.0, 11921.463534140985, 8212.507249564806, 8252.422330436812, 8281.29608983219, 8326.583670816555, 8356.997160340798, 18486.168122434254, 18935.526611834815, 19098.45256783988, 19816.60366792685, 20026.499337516678, 19316.130774114918, 19327.45380369539, 19746.40760777179, 20085.297014604417, 20243.0, 11450.427478626201, 11644.126690498417, 11792.174547781917, 11793.646190614296, 11808.350540720343, 14285.0, 14378.712425751635, 14426.06230595008, 14471.0, 14563.563093107225, 11659.358735269641, 11683.824742629038, 11776.11679124052, 12041.518339266331, 12322.075844311534, 11319.151063670422, 11426.314421134759, 11525.82711259315, 11542.076633869438, 11741.41292935367, 10426.218033647356, 10480.0, 10481.213908956706, 10485.737856553647, 10537.63118290856, 7413.888791668021, 7486.213947535431, 7517.0, 7564.470487066073, 7631.855221716787, 7354.0, 7494.812629684301, 7523.247675232433, 7531.548107113591, 7553.395728341609], "value": 6999.465677179156, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.34410081308806, 499.3009786299182, 521.4800099329526, 523.5183631059425, 536.3804805449706, 6251.748810808654, 6293.653849038301, 6304.653244071575, 6314.842128946767, 6352.682365881699, 6109.847253818657, 6137.570370074097, 6140.508759299257, 6186.69066546672, 6188.381161883809, 4903.950960490403, 5097.617678674096, 5114.641975061747, 5236.842894713146, 5247.632665713401, 5053.469385714502, 5058.468860769622, 5102.336696229485, 5109.594861413104, 5130.51260130287, 4990.575801056914, 5001.799928002875, 5022.623303252252, 5048.5203905628905, 5051.469595692454, 4973.477784832577, 5013.423456302499, 5041.0, 5060.316857224265, 5073.822416215433, 4520.954790452086, 4559.566841150086, 4562.817487300504, 4607.9308810367875, 4639.257718764981, 6204.751809927598, 6237.313895471494, 6242.376981984717, 6284.34014428483, 6302.590331628429, 5722.37053924068, 5742.253507044057, 5754.424557544254, 5759.452851979097, 5794.565407594448, 6436.3241859605005, 6473.74105035798, 6506.41442270198, 6506.446952009085, 6609.744148611749, 4936.481669424695, 4947.257911313296, 4947.727874966876, 4957.628177886637, 4973.801047958077, 6729.49528785343, 6764.966175169093, 6787.3212678732225, 6822.556533825285, 6866.798310295728, 5030.270610761419, 5031.597472202215, 5060.848174554696, 5118.692878427306, 5132.769025393866, 5203.687778733288, 5215.947840521622, 5221.765020574083, 5230.450802665741, 5239.056969745407, 5642.4357564244065, 5683.488486036276, 5768.4808367246305, 5781.768729250783, 5836.241288632451, 6244.438000579969, 6321.399467050623, 6409.359064093646, 6493.448056915215, 6668.266490686021, 1711.4608898197027, 1767.3725827331111, 1770.7786526684165, 1772.1582248431891, 1791.6864548703925, 1106.645873320546, 1239.5909349914507, 1252.1047451072527, 1255.4789762248565, 1275.3368248510722, 5522.475364840334, 5610.615488417407, 5655.688937108416, 5709.687576531512, 5791.7683292667825, 6714.597124172566, 6750.32496750321, 6770.5599136056, 6833.58998460094, 6846.486513511507, 5700.287464066992, 5708.942910570841, 5749.613910227293, 5762.250907382014, 5773.422657734277, 3506.964930350715, 3556.790691165016, 3588.336157810761, 3591.1560783215923, 3609.3322735294055, 6485.5460117792245, 6500.507071524554, 6546.869062618721, 6585.341465853472, 6624.403803657598, 6851.623160726208, 6905.620190889449, 6946.097007389007, 7021.5787052777, 7034.8944765828555, 6376.426121649074, 6423.710933008026, 6474.0, 6475.384838440435, 6525.314841941527, 6580.21037475506, 6658.300878407891, 6661.533692641468, 6782.118324617768, 6813.989872987381, 6718.832029199114, 6786.151731033621, 6805.489588280998, 6816.113905192294, 6842.178938527409, 6315.747370105144, 6434.903476447764, 6457.354264573505, 6548.443382312556, 6603.537752357289, 4989.825356112518, 5010.647125276228, 5049.5202955720615, 5050.065737838511, 5057.266696328938, 6765.221999469927, 6770.458363330823, 6778.762743304158, 6783.423409010092, 6799.966000169968, 6357.523185760994, 6445.806625801233, 6453.419192272718, 6469.611823290617, 6508.869822603616, 6460.12788273577, 6485.481161506974, 6518.078850468056, 6530.542861999615, 6616.867662646624, 6351.237851457855, 6459.773907913201, 6472.902906456501, 6503.382178693017, 6519.761245363366, 6060.636361818305, 6426.260979987362, 6464.806055818333, 6554.541182117206, 6588.8352791180605, 6192.907106393318, 6238.157848690369, 6242.625442473466, 6303.873922521616, 6416.518761092843], "value": 5078.541804262115, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.5921676029026, 938.8075444533871, 942.4251206763875, 942.9669961551351, 946.2099147212077, 20088.196472141095, 20214.19143234269, 20396.69404958925, 20410.0, 20416.0, 17669.293228270853, 18001.369952051682, 18123.546911327223, 18130.3654372097, 18161.818381816178, 933.883264591926, 974.2498276327224, 976.8925418203992, 977.301229620821, 978.1685567267837, 960.4669408478292, 977.0669011094424, 997.6907158780765, 999.435319044741, 999.8350272205078, 1055.2560444886337, 1059.2161800267822, 1059.8516207730925, 1063.7234319077056, 1066.4081434803682, 997.5062344139661, 1036.9118624916853, 1047.6961681112468, 1047.8899715529874, 1047.8952104789537, 751.3838652305112, 752.7252552818237, 759.3317880265387, 759.4038679636514, 759.5366826235992, 20680.58638827215, 20917.686234706493, 20935.162593496243, 20963.685544716842, 20970.580588388148, 22127.557448851094, 22422.781993524222, 22886.19898303568, 22952.311430657108, 22994.655080173812, 17706.645867082716, 17820.287188512444, 17849.286028558843, 17875.821241787547, 17915.910420447814, 3738.1589142443004, 3803.8858834235016, 3807.847686092553, 3808.295465338921, 3808.7905165215902, 142043.0, 145499.0, 145548.0, 146604.99999999892, 147476.0, 17255.309787608356, 17617.735733964, 17655.646887062187, 17728.29086836538, 17756.3785267515, 32104.83947580247, 32371.67628323734, 32383.352332953207, 32387.51418728721, 32407.67592324094, 8326.375521835887, 8565.48607083577, 8591.6992905248, 8605.225529702357, 8652.740417787476, 4233.5554766749665, 4327.8485253016, 4345.152695224401, 4354.564543545684, 4367.0, 5619.775208991677, 5619.971900140555, 5665.065264231438, 5703.201551782754, 5758.2514273144225, 1001.0540039662449, 1020.6427750287488, 1035.0, 1036.6319956415514, 1037.2117190934944, 678.2911857109234, 691.5608588546208, 692.2523674431602, 706.4489698035541, 707.0348973650929, 11701.882981170249, 12054.638360849189, 12146.939265303618, 12157.392130393446, 12360.140122232247, 682.8292926768308, 686.7287421468492, 690.5028379566704, 694.6943344928216, 695.5374675840509, 5336.306280183551, 5341.0, 5387.488188622075, 5397.0, 5446.754896029688, 125507.74492254961, 128441.0, 129630.0, 129810.0, 132600.3369983144, 108922.45538772256, 111899.0, 112905.0, 113060.0, 115542.0, 94545.5272723632, 94775.0, 96417.0, 96432.9999999986, 98146.5092674532, 9904.603815847286, 9935.453550054528, 10037.949810250902, 10149.594016239267, 10197.898021019695, 45846.31230531611, 45884.541154589366, 47105.76447117743, 47900.0, 48283.0, 58863.70568147132, 59291.0, 60376.0, 61168.38831611627, 62131.0, 13536.864631353561, 13969.930150349182, 14359.569212923629, 14455.49405770793, 14781.260936952895, 16172.67654646924, 16231.51305460838, 16273.837261627707, 16421.096839673934, 16506.174691265147, 16549.089800060632, 16697.749533757247, 16709.2480838365, 16916.83083169202, 16931.492055238363, 16228.594285142966, 16399.59001024936, 16566.088865112535, 16574.08842513673, 16746.58133546671, 15172.620684482976, 15398.538043858702, 15615.375384984927, 15618.297176627306, 15807.209639517749, 11752.412379380825, 12470.189437686522, 12523.248605083723, 12659.240445573296, 12878.35608219604, 12196.75606487883, 12224.266544007032, 12282.877171228174, 12380.81428778587, 12394.81407778902], "value": 9067.754374063075, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1431.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 106.0, 107.0, 107.0, 108.0, 108.0, 107.0, 110.0, 107.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 124.0, 119.0, 122.0, 120.0, 116.0, 117.0, 118.0, 116.0, 117.0], "value": 118.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 164.0, 163.0, 163.0, 163.0, 163.0, 165.0, 169.0, 165.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 78.0, 74.0, 74.0, 73.0, 74.0, 74.0, 77.0, 109.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 83.0, 79.0, 79.0, 79.0, 81.0, 79.0, 78.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 115.0, 114.0, 113.0, 114.0, 115.0, 118.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 147.0, 148.0, 148.0, 150.0, 148.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 95.0, 94.0, 93.0, 94.0, 96.0, 94.0, 96.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 53.0, 53.0, 54.0, 56.0, 53.0, 54.0, 55.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 37.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 72.0, 76.0, 74.0, 72.0, 72.0, 75.0, 73.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 114.0, 117.0, 124.0, 131.0, 119.0, 114.0, 122.0, 117.0], "value": 118.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 174.0, 176.0, 168.0, 169.0, 172.0, 174.0, 184.0, 168.0, 170.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 69.0, 66.0, 82.0, 66.0, 66.0, 68.0, 74.0], "value": 68.7, "unit": "score"}]}]} 20:31:06 INFO - Return code: 0 20:31:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:31:06 INFO - # TBPL SUCCESS # 20:31:06 INFO - Running post-action listener: _package_coverage_data 20:31:06 INFO - Running post-action listener: _resource_record_post_action 20:31:06 INFO - [mozharness: 2017-09-21 03:31:06.133665Z] Finished run-tests step (success) 20:31:06 INFO - Running post-run listener: _resource_record_post_run 20:31:06 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 9351168; Write bytes: 526905344; Read time: 3744; Write time: 699936 20:31:06 INFO - TinderboxPrint: CPU usage
13.1% 20:31:06 INFO - TinderboxPrint: I/O read bytes / time
9,351,168 / 3,744 20:31:06 INFO - TinderboxPrint: I/O write bytes / time
526,905,344 / 699,936 20:31:06 INFO - TinderboxPrint: CPU idle
8,268.2 (86.8%) 20:31:06 INFO - TinderboxPrint: CPU user
1,193.3 (12.5%) 20:31:06 INFO - TinderboxPrint: Swap in / out
0 / 0 20:31:06 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 121806848; Read time: 160; Write time: 99840 20:31:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 20:31:06 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 6848512; Write bytes: 386342912; Read time: 2948; Write time: 566760 20:31:06 INFO - Running post-run listener: _upload_blobber_files 20:31:06 INFO - Blob upload gear active. 20:31:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:31:06 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 20:31:06 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'] 20:31:06 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 20:31:07 INFO - (blobuploader) - INFO - Open directory for files ... 20:31:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:31:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:31:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:08 INFO - (blobuploader) - INFO - Done attempting. 20:31:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:31:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:31:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:15 INFO - (blobuploader) - INFO - Done attempting. 20:31:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:31:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:31:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:16 INFO - (blobuploader) - INFO - Done attempting. 20:31:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:31:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:31:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:17 INFO - (blobuploader) - INFO - Done attempting. 20:31:17 INFO - (blobuploader) - INFO - Iteration through files over. 20:31:17 INFO - Return code: 0 20:31:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:31:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:31:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0482443e12ec3665eb891fc07da078e2e086f4fb44a1197dcd207ac23b536fe76388f726222f87ba83b8eb3a84e637c4de55c4e0641d141c80cc149d5d2c3dd5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d920ee318bada8461f5f532c603580a26b2dafc3939e86d4e3ac9e1b25762f28bb1030121399f44582e0c37433ac1150d0b2b66c0cc39213e3e6a00a3f23417", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d449377b562eb48c048ebce20635aaf117b09ce0b959bf35fa70e7dcda1948a369cf0f332ea2ec64b8f5bcc48a0e65fa081632ac3d304f08c5189b394ce77cb6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4a0f648327684dd49a4f3b0ad97e1925cf1238ac4101fe60fec0c5fa1c21cb2393c7f11363a7c4d8b51b5cf25b2c9fd12bf15c2deeea9deff09721600586d72"} 20:31:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:31:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:31:17 INFO - Contents: 20:31:17 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0482443e12ec3665eb891fc07da078e2e086f4fb44a1197dcd207ac23b536fe76388f726222f87ba83b8eb3a84e637c4de55c4e0641d141c80cc149d5d2c3dd5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d920ee318bada8461f5f532c603580a26b2dafc3939e86d4e3ac9e1b25762f28bb1030121399f44582e0c37433ac1150d0b2b66c0cc39213e3e6a00a3f23417", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d449377b562eb48c048ebce20635aaf117b09ce0b959bf35fa70e7dcda1948a369cf0f332ea2ec64b8f5bcc48a0e65fa081632ac3d304f08c5189b394ce77cb6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4a0f648327684dd49a4f3b0ad97e1925cf1238ac4101fe60fec0c5fa1c21cb2393c7f11363a7c4d8b51b5cf25b2c9fd12bf15c2deeea9deff09721600586d72"} 20:31:17 INFO - Running post-run listener: copy_logs_to_upload_dir 20:31:17 INFO - Copying logs to upload dir... 20:31:17 INFO - mkdir: /builds/slave/test/build/upload/logs 20:31:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.721129 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-20 20:31:17.570894) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 20:31:17.575380) ========= 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=5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0482443e12ec3665eb891fc07da078e2e086f4fb44a1197dcd207ac23b536fe76388f726222f87ba83b8eb3a84e637c4de55c4e0641d141c80cc149d5d2c3dd5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d920ee318bada8461f5f532c603580a26b2dafc3939e86d4e3ac9e1b25762f28bb1030121399f44582e0c37433ac1150d0b2b66c0cc39213e3e6a00a3f23417", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d449377b562eb48c048ebce20635aaf117b09ce0b959bf35fa70e7dcda1948a369cf0f332ea2ec64b8f5bcc48a0e65fa081632ac3d304f08c5189b394ce77cb6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4a0f648327684dd49a4f3b0ad97e1925cf1238ac4101fe60fec0c5fa1c21cb2393c7f11363a7c4d8b51b5cf25b2c9fd12bf15c2deeea9deff09721600586d72"} build_url:https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/e3qcQ5inSsinAoBQTeRgAQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0482443e12ec3665eb891fc07da078e2e086f4fb44a1197dcd207ac23b536fe76388f726222f87ba83b8eb3a84e637c4de55c4e0641d141c80cc149d5d2c3dd5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9d920ee318bada8461f5f532c603580a26b2dafc3939e86d4e3ac9e1b25762f28bb1030121399f44582e0c37433ac1150d0b2b66c0cc39213e3e6a00a3f23417", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d449377b562eb48c048ebce20635aaf117b09ce0b959bf35fa70e7dcda1948a369cf0f332ea2ec64b8f5bcc48a0e65fa081632ac3d304f08c5189b394ce77cb6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4a0f648327684dd49a4f3b0ad97e1925cf1238ac4101fe60fec0c5fa1c21cb2393c7f11363a7c4d8b51b5cf25b2c9fd12bf15c2deeea9deff09721600586d72"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 20:31:17.610921) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 20:31:17.611238) ========= 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=5bd038846534b2fdfa68eeae000001e2-1505961692.190913-502285024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 20:31:17.691787) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 20:31:17.695116) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 20:31:17.695445) ========= ========= Total master_lag: 0.34 =========