builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-079 starttime: 1506855618.65 results: success (0) revision: f90790f7a34616b1e878f3a6f536546e048e21e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:18.654892) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:18.655297) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:18.655610) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:18.673832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:18.674170) ========= 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=1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-01 04:00:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2017-10-01 04:00:18 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144538 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:18.835802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:18.838185) ========= 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=1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021678 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:18.887870) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:18.888197) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f90790f7a34616b1e878f3a6f536546e048e21e6 --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 f90790f7a34616b1e878f3a6f536546e048e21e6 --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=1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-01 04:00:18,911 truncating revision to first 12 chars 2017-10-01 04:00:18,911 Setting DEBUG logging. 2017-10-01 04:00:18,911 attempt 1/10 2017-10-01 04:00:18,911 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f90790f7a346?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-01 04:00:19,510 unpacking tar archive at: mozilla-central-f90790f7a346/testing/mozharness/ program finished with exit code 0 elapsedTime=0.910237 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:19.817840) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:19.821154) ========= script_repo_revision: f90790f7a34616b1e878f3a6f536546e048e21e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:19.821511) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:19.821763) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 04:00:19.835897) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-01 04:00:19.836252) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:00:19 INFO - MultiFileLogger online at 20171001 04:00:19 in /builds/slave/test 04:00:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:00:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:00:19 INFO - {'append_to_log': False, 04:00:19 INFO - 'base_work_dir': '/builds/slave/test', 04:00:19 INFO - 'blob_upload_branch': 'Firefox', 04:00:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:00:19 INFO - 'branch': 'Firefox', 04:00:19 INFO - 'buildbot_json_path': 'buildprops.json', 04:00:19 INFO - 'code_coverage': False, 04:00:19 INFO - 'config_files': ('talos/linux_config.py',), 04:00:19 INFO - 'default_actions': ('clobber', 04:00:19 INFO - 'read-buildbot-config', 04:00:19 INFO - 'download-and-extract', 04:00:19 INFO - 'populate-webroot', 04:00:19 INFO - 'create-virtualenv', 04:00:19 INFO - 'install', 04:00:19 INFO - 'setup-mitmproxy', 04:00:19 INFO - 'run-tests'), 04:00:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:00:19 INFO - 'disable_ccov_upload': False, 04:00:19 INFO - 'disable_stylo': False, 04:00:19 INFO - 'download_minidump_stackwalk': True, 04:00:19 INFO - 'download_symbols': 'ondemand', 04:00:19 INFO - 'enable_stylo': False, 04:00:19 INFO - 'enable_webrender': False, 04:00:19 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:00:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:00:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:00:19 INFO - 'gecko_profile': False, 04:00:19 INFO - 'gecko_profile_interval': 0, 04:00:19 INFO - 'installer_path': 'installer.exe', 04:00:19 INFO - 'log_level': 'info', 04:00:19 INFO - 'log_name': 'talos', 04:00:19 INFO - 'log_to_console': True, 04:00:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:00:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:00:19 INFO - 'opt_config_files': (), 04:00:19 INFO - 'pip_index': False, 04:00:19 INFO - 'suite': 'dromaeojs-e10s', 04:00:19 INFO - 'system_bits': '32', 04:00:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:00:19 INFO - 'title': 'talos-linux64-ix-079', 04:00:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:00:19 INFO - 'use_talos_json': True, 04:00:19 INFO - 'verify': 'False', 04:00:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:00:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:00:19 INFO - 'work_dir': 'build'} 04:00:19 INFO - [mozharness: 2017-10-01 11:00:19.999377Z] Running clobber step. 04:00:19 INFO - Running pre-action listener: _resource_record_pre_action 04:00:19 INFO - Running main action method: clobber 04:00:19 INFO - rmtree: /builds/slave/test/build 04:00:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:00:20 INFO - Running post-action listener: _resource_record_post_action 04:00:20 INFO - [mozharness: 2017-10-01 11:00:20.993467Z] Finished clobber step (success) 04:00:20 INFO - [mozharness: 2017-10-01 11:00:20.993564Z] Running read-buildbot-config step. 04:00:20 INFO - Running pre-action listener: _resource_record_pre_action 04:00:20 INFO - Running main action method: read_buildbot_config 04:00:20 INFO - Using buildbot properties: 04:00:20 INFO - { 04:00:20 INFO - "project": "", 04:00:20 INFO - "product": "firefox", 04:00:20 INFO - "who": "archaeopteryx@coole-files.de", 04:00:20 INFO - "installer_path": "public/build/target.tar.bz2", 04:00:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:00:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:00:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:00:20 INFO - "stage_platform": "linux64-pgo", 04:00:20 INFO - "basedir": "/builds/slave/test", 04:00:20 INFO - "buildnumber": 225, 04:00:20 INFO - "platform": "ubuntu64_hw", 04:00:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:00:20 INFO - "slavebuilddir": "test", 04:00:20 INFO - "taskId": "aZcLFoRQSIOEnUQL1RZ6Bw", 04:00:20 INFO - "branch": "mozilla-central", 04:00:20 INFO - "script_repo_revision": "production", 04:00:20 INFO - "revision": "f90790f7a34616b1e878f3a6f536546e048e21e6", 04:00:20 INFO - "slavename": "talos-linux64-ix-079", 04:00:20 INFO - "repo_path": "mozilla-central" 04:00:20 INFO - } 04:00:20 INFO - Finding installer, test and symbols from parent task. 04:00:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aZcLFoRQSIOEnUQL1RZ6Bw'}, attempt #1 04:00:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aZcLFoRQSIOEnUQL1RZ6Bw'}, attempt #1 04:00:21 INFO - Task dependencies: LTVQZ2y-So2WoMe30iGkIw 04:00:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw'}, attempt #1 04:00:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.tar.bz2 04:00:22 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.test_packages.json 04:00:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.crashreporter-symbols.zip 04:00:22 INFO - Running post-action listener: _resource_record_post_action 04:00:22 INFO - [mozharness: 2017-10-01 11:00:22.348891Z] Finished read-buildbot-config step (success) 04:00:22 INFO - [mozharness: 2017-10-01 11:00:22.349109Z] Running download-and-extract step. 04:00:22 INFO - Running pre-action listener: _resource_record_pre_action 04:00:22 INFO - Running main action method: download_and_extract 04:00:22 INFO - mkdir: /builds/slave/test/build/tests 04:00:22 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')]} 04:00:22 INFO - trying https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.test_packages.json 04:00:22 INFO - Downloading https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:00:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:00:22 INFO - Downloaded 989 bytes. 04:00:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:00:22 INFO - Using the following test package requirements: 04:00:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:00:22 INFO - u'common': [u'target.common.tests.zip'], 04:00:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:00:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:00:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:00:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:00:22 INFO - u'mozbase': [u'target.common.tests.zip'], 04:00:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:00:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:00:22 INFO - u'web-platform': [u'target.common.tests.zip', 04:00:22 INFO - u'target.web-platform.tests.tar.gz'], 04:00:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:00:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:00:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.common.tests.zip 04:00:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:00:22 INFO - Fetch https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.common.tests.zip into memory 04:00:23 INFO - Content-Length response header: 55913831 04:00:24 INFO - Bytes received: 55913831 04:00:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.talos.tests.zip 04:00:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:00:34 INFO - Fetch https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.talos.tests.zip into memory 04:00:35 INFO - Content-Length response header: 14522385 04:00:35 INFO - Bytes received: 14522385 04:00:35 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')]} 04:00:35 INFO - trying https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.tar.bz2 04:00:35 INFO - Downloading https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:00:36 INFO - Downloaded 62353704 bytes. 04:00:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.tar.bz2 04:00:36 INFO - mkdir: /builds/slave/test/properties 04:00:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:00:36 INFO - Writing to file /builds/slave/test/properties/build_url 04:00:36 INFO - Contents: 04:00:36 INFO - build_url:https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.tar.bz2 04:00:36 INFO - Running post-action listener: _resource_record_post_action 04:00:36 INFO - Running post-action listener: find_tests_for_verification 04:00:36 INFO - Running post-action listener: set_extra_try_arguments 04:00:36 INFO - [mozharness: 2017-10-01 11:00:36.913625Z] Finished download-and-extract step (success) 04:00:36 INFO - [mozharness: 2017-10-01 11:00:36.913764Z] Running populate-webroot step. 04:00:36 INFO - Running pre-action listener: _resource_record_pre_action 04:00:36 INFO - Running main action method: populate_webroot 04:00:36 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:00:36 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['tresize', 04:00:36 INFO - 'tcanvasmark']}, 04:00:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:00:36 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['dromaeo_css', 04:00:36 INFO - 'kraken']}, 04:00:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:00:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:36 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:36 INFO - 'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:00:36 INFO - 'tests': ['damp', 'tps']}, 04:00:36 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:36 INFO - 'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['damp', 'tps']}, 04:00:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:00:36 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['dromaeo_dom']}, 04:00:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:00:36 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['basic_compositor_video', 04:00:36 INFO - 'glvideo']}, 04:00:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:00:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:00:36 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:36 INFO - 'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['ts_paint_webext', 04:00:36 INFO - 'tp5o_webext']}, 04:00:36 INFO - 'other-e10s': {'tests': ['a11yr', 04:00:36 INFO - 'ts_paint', 04:00:36 INFO - 'tpaint', 04:00:36 INFO - 'sessionrestore', 04:00:36 INFO - 'sessionrestore_many_windows', 04:00:36 INFO - 'sessionrestore_no_auto_restore', 04:00:36 INFO - 'tabpaint', 04:00:36 INFO - 'cpstartup']}, 04:00:36 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['a11yr', 04:00:36 INFO - 'ts_paint', 04:00:36 INFO - 'tpaint', 04:00:36 INFO - 'sessionrestore', 04:00:36 INFO - 'sessionrestore_many_windows', 04:00:36 INFO - 'sessionrestore_no_auto_restore', 04:00:36 INFO - 'tabpaint', 04:00:36 INFO - 'cpstartup']}, 04:00:36 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:00:36 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:00:36 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['perf_reftest_singletons']}, 04:00:36 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['perf_reftest']}, 04:00:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:00:36 INFO - 'tsvgr_opacity', 04:00:36 INFO - 'tart', 04:00:36 INFO - 'tscrollx', 04:00:36 INFO - 'tsvg_static']}, 04:00:36 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['tsvgx', 04:00:36 INFO - 'tsvgr_opacity', 04:00:36 INFO - 'tart', 04:00:36 INFO - 'tscrollx', 04:00:36 INFO - 'tsvg_static']}, 04:00:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:00:36 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:36 INFO - 'talos_options': ['--disable-stylo'], 04:00:36 INFO - 'tests': ['tp5o']}, 04:00:36 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:00:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:00:36 INFO - 'talos_options': ['--mitmproxy', 04:00:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:00:36 INFO - '--firstNonBlankPaint'], 04:00:36 INFO - 'tests': ['tp6_google', 04:00:36 INFO - 'tp6_youtube', 04:00:36 INFO - 'tp6_amazon', 04:00:36 INFO - 'tp6_facebook']}, 04:00:36 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:00:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:00:36 INFO - 'talos_options': ['--disable-stylo', 04:00:36 INFO - '--mitmproxy', 04:00:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:00:36 INFO - '--firstNonBlankPaint'], 04:00:36 INFO - 'tests': ['tp6_google', 04:00:36 INFO - 'tp6_youtube', 04:00:36 INFO - 'tp6_amazon', 04:00:36 INFO - 'tp6_facebook']}, 04:00:36 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:00:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:00:36 INFO - 'talos_options': ['--stylo-threads=1', 04:00:36 INFO - '--mitmproxy', 04:00:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:00:36 INFO - '--firstNonBlankPaint'], 04:00:36 INFO - 'tests': ['tp6_google', 04:00:36 INFO - 'tp6_youtube', 04:00:36 INFO - 'tp6_amazon', 04:00:36 INFO - 'tp6_facebook']}, 04:00:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:00:36 INFO - 'talos_options': ['--xperf_path', 04:00:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:36 INFO - 'tests': ['tp5n']}, 04:00:36 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:36 INFO - 'talos_options': ['--disable-stylo', 04:00:36 INFO - '--xperf_path', 04:00:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:36 INFO - 'tests': ['tp5n']}}} 04:00:36 INFO - Running post-action listener: _resource_record_post_action 04:00:36 INFO - [mozharness: 2017-10-01 11:00:36.931128Z] Finished populate-webroot step (success) 04:00:36 INFO - [mozharness: 2017-10-01 11:00:36.931234Z] Running create-virtualenv step. 04:00:36 INFO - Running pre-action listener: _resource_record_pre_action 04:00:36 INFO - Running main action method: create_virtualenv 04:00:36 INFO - Creating virtualenv /builds/slave/test/build/venv 04:00:36 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:00:36 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:00:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:00:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:00:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:00:37 INFO - Installing distribute.............................................................................................................................................................................................done. 04:00:37 INFO - Installing pip...............done. 04:00:37 INFO - Return code: 0 04:00:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:00:37 INFO - Reading from file tmpfile_stdout 04:00:37 INFO - Output received: 04:00:37 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:00:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:00:37 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')]} 04:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:37 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')]} 04:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:37 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 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00:37 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 04:00:37 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 04:00:37 INFO - Using env: {'DISPLAY': ':0', 04:00:37 INFO - 'HOME': '/home/cltbld', 04:00:37 INFO - 'LANG': 'en_US.UTF-8', 04:00:37 INFO - 'LANGUAGE': 'en_US:en', 04:00:37 INFO - 'LOGNAME': 'cltbld', 04:00:37 INFO - 'MAIL': '/var/mail/cltbld', 04:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:37 INFO - 'MOZ_NO_REMOTE': '1', 04:00:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:37 INFO - 'NO_EM_RESTART': '1', 04:00:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:37 INFO - 'PWD': '/builds/slave/test', 04:00:37 INFO - 'SHELL': '/bin/bash', 04:00:37 INFO - 'SHLVL': '1', 04:00:37 INFO - 'TERM': 'linux', 04:00:37 INFO - 'TMOUT': '86400', 04:00:37 INFO - 'USER': 'cltbld', 04:00:37 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:37 INFO - '_': '/tools/buildbot/bin/python'} 04:00:39 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:00:39 INFO - Downloading/unpacking pip>=1.5 04:00:39 INFO - Running setup.py egg_info for package pip 04:00:39 INFO - warning: no files found matching 'pip/cacert.pem' 04:00:39 INFO - warning: no files found matching '*.html' under directory 'docs' 04:00:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:00:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:00:39 INFO - Installing collected packages: pip 04:00:39 INFO - Found existing installation: pip 1.1 04:00:39 INFO - Uninstalling pip: 04:00:39 INFO - Successfully uninstalled pip 04:00:39 INFO - Running setup.py install for pip 04:00:39 INFO - warning: no files found matching 'pip/cacert.pem' 04:00:39 INFO - warning: no files found matching '*.html' under directory 'docs' 04:00:39 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:00:39 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:00:39 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:00:39 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:00:39 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:00:39 INFO - Successfully installed pip 04:00:39 INFO - Cleaning up... 04:00:39 INFO - Return code: 0 04:00:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:00:39 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')]} 04:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:39 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')]} 04:00:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:39 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 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00:39 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 04:00:39 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 04:00:39 INFO - Using env: {'DISPLAY': ':0', 04:00:39 INFO - 'HOME': '/home/cltbld', 04:00:39 INFO - 'LANG': 'en_US.UTF-8', 04:00:39 INFO - 'LANGUAGE': 'en_US:en', 04:00:39 INFO - 'LOGNAME': 'cltbld', 04:00:39 INFO - 'MAIL': '/var/mail/cltbld', 04:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:39 INFO - 'MOZ_NO_REMOTE': '1', 04:00:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:39 INFO - 'NO_EM_RESTART': '1', 04:00:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:39 INFO - 'PWD': '/builds/slave/test', 04:00:39 INFO - 'SHELL': '/bin/bash', 04:00:39 INFO - 'SHLVL': '1', 04:00:39 INFO - 'TERM': 'linux', 04:00:39 INFO - 'TMOUT': '86400', 04:00:39 INFO - 'USER': 'cltbld', 04:00:39 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:39 INFO - '_': '/tools/buildbot/bin/python'} 04:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:39 INFO - Downloading/unpacking psutil>=3.1.1 04:00:39 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 04:00:39 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 04:00:39 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 04:00:39 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 04:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:00:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:40 INFO - Installing collected packages: psutil 04:00:40 INFO - Running setup.py install for psutil 04:00:40 INFO - building 'psutil._psutil_linux' extension 04:00:40 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 04:00:40 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 04:00:40 INFO - building 'psutil._psutil_posix' extension 04:00:40 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 04:00:40 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 04:00:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:41 INFO - Successfully installed psutil 04:00:41 INFO - Cleaning up... 04:00:41 INFO - Return code: 0 04:00:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:00:41 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')]} 04:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:41 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')]} 04:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:41 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 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00:41 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 04:00:41 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 04:00:41 INFO - Using env: {'DISPLAY': ':0', 04:00:41 INFO - 'HOME': '/home/cltbld', 04:00:41 INFO - 'LANG': 'en_US.UTF-8', 04:00:41 INFO - 'LANGUAGE': 'en_US:en', 04:00:41 INFO - 'LOGNAME': 'cltbld', 04:00:41 INFO - 'MAIL': '/var/mail/cltbld', 04:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:41 INFO - 'MOZ_NO_REMOTE': '1', 04:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:41 INFO - 'NO_EM_RESTART': '1', 04:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:41 INFO - 'PWD': '/builds/slave/test', 04:00:41 INFO - 'SHELL': '/bin/bash', 04:00:41 INFO - 'SHLVL': '1', 04:00:41 INFO - 'TERM': 'linux', 04:00:41 INFO - 'TMOUT': '86400', 04:00:41 INFO - 'USER': 'cltbld', 04:00:41 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:41 INFO - '_': '/tools/buildbot/bin/python'} 04:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:00:41 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 04:00:41 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 04:00:41 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 04:00:41 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 04:00:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:00:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:00:42 INFO - Installing collected packages: mozsystemmonitor 04:00:42 INFO - Running setup.py install for mozsystemmonitor 04:00:42 INFO - Successfully installed mozsystemmonitor 04:00:42 INFO - Cleaning up... 04:00:43 INFO - Return code: 0 04:00:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:43 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 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00:43 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 04:00:43 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 04:00:43 INFO - Using env: {'DISPLAY': ':0', 04:00:43 INFO - 'HOME': '/home/cltbld', 04:00:43 INFO - 'LANG': 'en_US.UTF-8', 04:00:43 INFO - 'LANGUAGE': 'en_US:en', 04:00:43 INFO - 'LOGNAME': 'cltbld', 04:00:43 INFO - 'MAIL': '/var/mail/cltbld', 04:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:43 INFO - 'MOZ_NO_REMOTE': '1', 04:00:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:43 INFO - 'NO_EM_RESTART': '1', 04:00:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:43 INFO - 'PWD': '/builds/slave/test', 04:00:43 INFO - 'SHELL': '/bin/bash', 04:00:43 INFO - 'SHLVL': '1', 04:00:43 INFO - 'TERM': 'linux', 04:00:43 INFO - 'TMOUT': '86400', 04:00:43 INFO - 'USER': 'cltbld', 04:00:43 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:43 INFO - '_': '/tools/buildbot/bin/python'} 04:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:43 INFO - Downloading/unpacking jsonschema==2.5.1 04:00:43 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 04:00:43 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 04:00:43 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 04:00:43 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 04:00:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:00:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:00:44 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 04:00:44 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 04:00:44 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 04:00:44 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 04:00:45 INFO - Downloading functools32-3.2.3-2.tar.gz 04:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:00:45 INFO - warning: no files found matching '*.txt' 04:00:45 INFO - no previously-included directories found matching 'build' 04:00:45 INFO - no previously-included directories found matching 'dist' 04:00:45 INFO - no previously-included directories found matching '.git*' 04:00:45 INFO - Installing collected packages: jsonschema, functools32 04:00:45 INFO - Running setup.py install for functools32 04:00:46 INFO - warning: no files found matching '*.txt' 04:00:46 INFO - no previously-included directories found matching 'build' 04:00:46 INFO - no previously-included directories found matching 'dist' 04:00:46 INFO - no previously-included directories found matching '.git*' 04:00:46 INFO - Successfully installed jsonschema functools32 04:00:46 INFO - Cleaning up... 04:00:46 INFO - Return code: 0 04:00:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:00: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')]} 04:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00: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')]} 04:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:46 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 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00:46 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 04:00:46 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 04:00:46 INFO - Using env: {'DISPLAY': ':0', 04:00:46 INFO - 'HOME': '/home/cltbld', 04:00:46 INFO - 'LANG': 'en_US.UTF-8', 04:00:46 INFO - 'LANGUAGE': 'en_US:en', 04:00:46 INFO - 'LOGNAME': 'cltbld', 04:00:46 INFO - 'MAIL': '/var/mail/cltbld', 04:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:46 INFO - 'MOZ_NO_REMOTE': '1', 04:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:46 INFO - 'NO_EM_RESTART': '1', 04:00:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:46 INFO - 'PWD': '/builds/slave/test', 04:00:46 INFO - 'SHELL': '/bin/bash', 04:00:46 INFO - 'SHLVL': '1', 04:00:46 INFO - 'TERM': 'linux', 04:00:46 INFO - 'TMOUT': '86400', 04:00:46 INFO - 'USER': 'cltbld', 04:00:46 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:46 INFO - '_': '/tools/buildbot/bin/python'} 04:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:00:46 INFO - Cleaning up... 04:00:46 INFO - Return code: 0 04:00:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:00: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')]} 04:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00: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')]} 04:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:46 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 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00:46 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 04:00:46 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 04:00:46 INFO - Using env: {'DISPLAY': ':0', 04:00:46 INFO - 'HOME': '/home/cltbld', 04:00:46 INFO - 'LANG': 'en_US.UTF-8', 04:00:46 INFO - 'LANGUAGE': 'en_US:en', 04:00:46 INFO - 'LOGNAME': 'cltbld', 04:00:46 INFO - 'MAIL': '/var/mail/cltbld', 04:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:46 INFO - 'MOZ_NO_REMOTE': '1', 04:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:46 INFO - 'NO_EM_RESTART': '1', 04:00:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:46 INFO - 'PWD': '/builds/slave/test', 04:00:46 INFO - 'SHELL': '/bin/bash', 04:00:46 INFO - 'SHLVL': '1', 04:00:46 INFO - 'TERM': 'linux', 04:00:46 INFO - 'TMOUT': '86400', 04:00:46 INFO - 'USER': 'cltbld', 04:00:46 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:46 INFO - '_': '/tools/buildbot/bin/python'} 04:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:46 INFO - Downloading/unpacking blobuploader==1.2.4 04:00:46 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 04:00:46 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 04:00:46 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 04:00:46 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 04:00:47 INFO - Downloading blobuploader-1.2.4.tar.gz 04:00:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:00:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:00:47 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 04:00:47 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 04:00:47 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 04:00:47 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 04:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:00:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:00:48 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 04:00:48 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 04:00:48 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 04:00:48 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 04:00:48 INFO - Downloading docopt-0.6.1.tar.gz 04:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:00:48 INFO - Installing collected packages: blobuploader, requests, docopt 04:00:48 INFO - Running setup.py install for blobuploader 04:00:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:00:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:00:48 INFO - Running setup.py install for requests 04:00:48 INFO - Running setup.py install for docopt 04:00:48 INFO - Successfully installed blobuploader requests docopt 04:00:48 INFO - Cleaning up... 04:00:49 INFO - Return code: 0 04:00:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:49 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 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00:49 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 04:00:49 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 04:00:49 INFO - Using env: {'DISPLAY': ':0', 04:00:49 INFO - 'HOME': '/home/cltbld', 04:00:49 INFO - 'LANG': 'en_US.UTF-8', 04:00:49 INFO - 'LANGUAGE': 'en_US:en', 04:00:49 INFO - 'LOGNAME': 'cltbld', 04:00:49 INFO - 'MAIL': '/var/mail/cltbld', 04:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:49 INFO - 'MOZ_NO_REMOTE': '1', 04:00:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:49 INFO - 'NO_EM_RESTART': '1', 04:00:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:49 INFO - 'PWD': '/builds/slave/test', 04:00:49 INFO - 'SHELL': '/bin/bash', 04:00:49 INFO - 'SHLVL': '1', 04:00:49 INFO - 'TERM': 'linux', 04:00:49 INFO - 'TMOUT': '86400', 04:00:49 INFO - 'USER': 'cltbld', 04:00:49 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:49 INFO - '_': '/tools/buildbot/bin/python'} 04:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:00:49 INFO - Running setup.py (path:/tmp/pip-kSK80T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:00:49 INFO - Running setup.py (path:/tmp/pip-3BHUHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:00:49 INFO - Running setup.py (path:/tmp/pip-_a_oyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:00:49 INFO - Running setup.py (path:/tmp/pip-JxEq5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:00:49 INFO - Running setup.py (path:/tmp/pip-gAUbqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:00:49 INFO - Running setup.py (path:/tmp/pip-6myBKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:00:49 INFO - Running setup.py (path:/tmp/pip-94Qffi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:00:49 INFO - Running setup.py (path:/tmp/pip-wNTZcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:00:49 INFO - Running setup.py (path:/tmp/pip-Re885h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:00:49 INFO - Running setup.py (path:/tmp/pip-kBjt_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:00:49 INFO - Running setup.py (path:/tmp/pip-afXTSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:00:50 INFO - Running setup.py (path:/tmp/pip-TYzRys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:00:50 INFO - Running setup.py (path:/tmp/pip-r56quN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:00:50 INFO - Running setup.py (path:/tmp/pip-D5FNmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:50 INFO - Running setup.py (path:/tmp/pip-JF5EDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:00:50 INFO - Running setup.py (path:/tmp/pip-Hf3ttg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:00:50 INFO - Running setup.py (path:/tmp/pip-N0IAtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:00:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:00:50 INFO - Running setup.py install for manifestparser 04:00:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:00:50 INFO - Running setup.py install for mozcrash 04:00:50 INFO - Running setup.py install for mozdebug 04:00:50 INFO - Running setup.py install for mozdevice 04:00:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:00:50 INFO - Running setup.py install for mozfile 04:00:50 INFO - Running setup.py install for mozhttpd 04:00:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Running setup.py install for mozinfo 04:00:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Running setup.py install for mozInstall 04:00:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Running setup.py install for mozleak 04:00:51 INFO - Running setup.py install for mozlog 04:00:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Running setup.py install for moznetwork 04:00:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Running setup.py install for mozprocess 04:00:51 INFO - Running setup.py install for mozprofile 04:00:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Running setup.py install for mozrunner 04:00:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:00:51 INFO - Running setup.py install for mozscreenshot 04:00:51 INFO - Running setup.py install for moztest 04:00:52 INFO - Running setup.py install for mozversion 04:00:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:00:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:00:52 INFO - Cleaning up... 04:00:52 INFO - Return code: 0 04:00:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00: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')]} 04:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00: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')]} 04:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:52 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 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00:52 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 04:00:52 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 04:00:52 INFO - Using env: {'DISPLAY': ':0', 04:00:52 INFO - 'HOME': '/home/cltbld', 04:00:52 INFO - 'LANG': 'en_US.UTF-8', 04:00:52 INFO - 'LANGUAGE': 'en_US:en', 04:00:52 INFO - 'LOGNAME': 'cltbld', 04:00:52 INFO - 'MAIL': '/var/mail/cltbld', 04:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:52 INFO - 'MOZ_NO_REMOTE': '1', 04:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:52 INFO - 'NO_EM_RESTART': '1', 04:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:52 INFO - 'PWD': '/builds/slave/test', 04:00:52 INFO - 'SHELL': '/bin/bash', 04:00:52 INFO - 'SHLVL': '1', 04:00:52 INFO - 'TERM': 'linux', 04:00:52 INFO - 'TMOUT': '86400', 04:00:52 INFO - 'USER': 'cltbld', 04:00:52 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:52 INFO - '_': '/tools/buildbot/bin/python'} 04:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:00:52 INFO - Running setup.py (path:/tmp/pip-tWXvY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:00:52 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)) 04:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:00:52 INFO - Running setup.py (path:/tmp/pip-FSDag_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:00:52 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)) 04:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:00:52 INFO - Running setup.py (path:/tmp/pip-CLhRF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:00:52 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)) 04:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:00:52 INFO - Running setup.py (path:/tmp/pip-5bRzyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:00:52 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)) 04:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:00:52 INFO - Running setup.py (path:/tmp/pip-rFZtC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:00:52 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)) 04:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:00:52 INFO - Running setup.py (path:/tmp/pip-YhqPZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:00:52 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)) 04:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:00:52 INFO - Running setup.py (path:/tmp/pip-3Fy83P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:00:52 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)) 04:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:00:52 INFO - Running setup.py (path:/tmp/pip-R4UWUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:00:53 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)) 04:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:00:53 INFO - Running setup.py (path:/tmp/pip-6fQ7ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:00:53 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)) 04:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:00:53 INFO - Running setup.py (path:/tmp/pip-SSVkfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:00:53 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)) 04:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:00:53 INFO - Running setup.py (path:/tmp/pip-aGd8NI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:00:53 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)) 04:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:00:53 INFO - Running setup.py (path:/tmp/pip-_Bhia8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:00:53 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)) 04:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:00:53 INFO - Running setup.py (path:/tmp/pip-28DH2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:00:53 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)) 04:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:00:53 INFO - Running setup.py (path:/tmp/pip-FWxnAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:00:53 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)) 04:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:53 INFO - Running setup.py (path:/tmp/pip-dhCrI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:53 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)) 04:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:00:53 INFO - Running setup.py (path:/tmp/pip-i28zug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:00:53 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)) 04:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:00:53 INFO - Running setup.py (path:/tmp/pip-Vr5TeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:00:53 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)) 04:00:53 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)) 04:00:53 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)) 04:00:53 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)) 04:00:53 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)) 04:00:53 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)) 04:00:53 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)) 04:00:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:00:53 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 04:00:53 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 04:00:53 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 04:00:53 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 04:00:54 INFO - Downloading blessings-1.6.tar.gz 04:00:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:00:54 INFO - Installing collected packages: blessings 04:00:54 INFO - Running setup.py install for blessings 04:00:55 INFO - Successfully installed blessings 04:00:55 INFO - Cleaning up... 04:00:55 INFO - Return code: 0 04:00:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:00:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:00:55 INFO - Reading from file tmpfile_stdout 04:00:55 INFO - Current package versions: 04:00:55 INFO - argparse == 1.2.1 04:00:55 INFO - blessings == 1.6 04:00:55 INFO - blobuploader == 1.2.4 04:00:55 INFO - docopt == 0.6.1 04:00:55 INFO - functools32 == 3.2.3-2 04:00:55 INFO - jsonschema == 2.5.1 04:00:55 INFO - manifestparser == 1.1 04:00:55 INFO - mozInstall == 1.13 04:00:55 INFO - mozcrash == 1.0 04:00:55 INFO - mozdebug == 0.1 04:00:55 INFO - mozdevice == 0.51 04:00:55 INFO - mozfile == 1.2 04:00:55 INFO - mozhttpd == 0.7 04:00:55 INFO - mozinfo == 0.10 04:00:55 INFO - mozleak == 0.1 04:00:55 INFO - mozlog == 3.5 04:00:55 INFO - moznetwork == 0.27 04:00:55 INFO - mozprocess == 0.25 04:00:55 INFO - mozprofile == 0.28 04:00:55 INFO - mozrunner == 6.13 04:00:55 INFO - mozscreenshot == 0.1 04:00:55 INFO - mozsystemmonitor == 0.3 04:00:55 INFO - moztest == 0.8 04:00:55 INFO - mozversion == 1.4 04:00:55 INFO - psutil == 3.1.1 04:00:55 INFO - requests == 1.2.3 04:00:55 INFO - wsgiref == 0.1.2 04:00:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:55 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 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00:55 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 04:00:55 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 04:00:55 INFO - Using env: {'DISPLAY': ':0', 04:00:55 INFO - 'HOME': '/home/cltbld', 04:00:55 INFO - 'LANG': 'en_US.UTF-8', 04:00:55 INFO - 'LANGUAGE': 'en_US:en', 04:00:55 INFO - 'LOGNAME': 'cltbld', 04:00:55 INFO - 'MAIL': '/var/mail/cltbld', 04:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:55 INFO - 'MOZ_NO_REMOTE': '1', 04:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:55 INFO - 'NO_EM_RESTART': '1', 04:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:55 INFO - 'PWD': '/builds/slave/test', 04:00:55 INFO - 'SHELL': '/bin/bash', 04:00:55 INFO - 'SHLVL': '1', 04:00:55 INFO - 'TERM': 'linux', 04:00:55 INFO - 'TMOUT': '86400', 04:00:55 INFO - 'USER': 'cltbld', 04:00:55 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:55 INFO - '_': '/tools/buildbot/bin/python'} 04:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:55 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)) 04:00:55 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)) 04:00:55 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)) 04:00:55 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)) 04:00:55 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)) 04:00:55 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)) 04:00:55 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)) 04:00:55 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)) 04:00:55 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)) 04:00:55 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:00:56 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)) 04:00:56 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)) 04:00:56 INFO - Installing collected packages: simplejson 04:00:56 INFO - Running setup.py install for simplejson 04:00:56 INFO - building 'simplejson._speedups' extension 04:00:56 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 04:00:57 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 04:00:57 INFO - Successfully installed simplejson 04:00:57 INFO - Cleaning up... 04:00:57 INFO - Return code: 0 04:00:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:00: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')]} 04:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00: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')]} 04:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19651f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, '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': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', '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 04:00: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', 'jsonschema'] in /builds/slave/test/build 04:00: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 jsonschema 04:00:57 INFO - Using env: {'DISPLAY': ':0', 04:00:57 INFO - 'HOME': '/home/cltbld', 04:00:57 INFO - 'LANG': 'en_US.UTF-8', 04:00:57 INFO - 'LANGUAGE': 'en_US:en', 04:00:57 INFO - 'LOGNAME': 'cltbld', 04:00:57 INFO - 'MAIL': '/var/mail/cltbld', 04:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:57 INFO - 'MOZ_NO_REMOTE': '1', 04:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:57 INFO - 'NO_EM_RESTART': '1', 04:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:57 INFO - 'PWD': '/builds/slave/test', 04:00:57 INFO - 'SHELL': '/bin/bash', 04:00:57 INFO - 'SHLVL': '1', 04:00:57 INFO - 'TERM': 'linux', 04:00:57 INFO - 'TMOUT': '86400', 04:00:57 INFO - 'USER': 'cltbld', 04:00:57 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:57 INFO - '_': '/tools/buildbot/bin/python'} 04:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:00:57 INFO - Cleaning up... 04:00:57 INFO - Return code: 0 04:00:57 INFO - Running post-action listener: _resource_record_post_action 04:00:57 INFO - Running post-action listener: _start_resource_monitoring 04:00:57 INFO - Starting resource monitoring. 04:00:57 INFO - [mozharness: 2017-10-01 11:00:57.999349Z] Finished create-virtualenv step (success) 04:00:57 INFO - [mozharness: 2017-10-01 11:00:57.999863Z] Running install step. 04:00:58 INFO - Running pre-action listener: _resource_record_pre_action 04:00:58 INFO - Running main action method: install 04:00:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:00:58 INFO - Reading from file tmpfile_stdout 04:00:58 INFO - Detecting whether we're running mozinstall >=1.0... 04:00:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:00:58 INFO - Reading from file tmpfile_stdout 04:00:58 INFO - Output received: 04:00:58 INFO - Usage: mozinstall [options] installer 04:00:58 INFO - Options: 04:00:58 INFO - -h, --help show this help message and exit 04:00:58 INFO - -d DEST, --destination=DEST 04:00:58 INFO - Directory to install application into. [default: 04:00:58 INFO - "/builds/slave/test"] 04:00:58 INFO - --app=APP Application being installed. [default: firefox] 04:00:58 INFO - mkdir: /builds/slave/test/build/application 04:00:58 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'] 04:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:01:15 INFO - Reading from file tmpfile_stdout 04:01:15 INFO - Output received: 04:01:15 INFO - /builds/slave/test/build/application/firefox/firefox 04:01:15 INFO - Running post-action listener: _resource_record_post_action 04:01:15 INFO - [mozharness: 2017-10-01 11:01:15.949930Z] Finished install step (success) 04:01:15 INFO - [mozharness: 2017-10-01 11:01:15.950183Z] Running setup-mitmproxy step. 04:01:15 INFO - Running pre-action listener: _resource_record_pre_action 04:01:15 INFO - Running main action method: setup_mitmproxy 04:01:15 INFO - Skipping: mitmproxy is not required 04:01:15 INFO - Running post-action listener: _resource_record_post_action 04:01:15 INFO - [mozharness: 2017-10-01 11:01:15.951320Z] Finished setup-mitmproxy step (success) 04:01:15 INFO - [mozharness: 2017-10-01 11:01:15.951556Z] Running run-tests step. 04:01:15 INFO - Running pre-action listener: _resource_record_pre_action 04:01:15 INFO - Running pre-action listener: _set_gcov_prefix 04:01:15 INFO - Running main action method: run_tests 04:01:15 WARNING - Try message not found. 04:01:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:01:15 INFO - Python 2.7.3 04:01:15 INFO - Return code: 0 04:01:15 INFO - grabbing minidump binary from tooltool 04:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:15 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 0x1ccfd60>, '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 0x1cd0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:01:15 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 04:01:15 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 04:01:15 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 04:01:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:01:16 INFO - Return code: 0 04:01:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:01:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:01:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:01:16 INFO - ENV: RUST_BACKTRACE is now full 04:01:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:01:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:01:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 04:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --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 04:01:16 INFO - Using env: {'DISPLAY': ':0', 04:01:16 INFO - 'HOME': '/home/cltbld', 04:01:16 INFO - 'LANG': 'en_US.UTF-8', 04:01:16 INFO - 'LANGUAGE': 'en_US:en', 04:01:16 INFO - 'LOGNAME': 'cltbld', 04:01:16 INFO - 'MAIL': '/var/mail/cltbld', 04:01:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:01:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:16 INFO - 'MOZ_NO_REMOTE': '1', 04:01:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:01:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:16 INFO - 'NO_EM_RESTART': '1', 04:01:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:16 INFO - 'PWD': '/builds/slave/test', 04:01:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:01:16 INFO - 'RUST_BACKTRACE': 'full', 04:01:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:01:16 INFO - 'SHELL': '/bin/bash', 04:01:16 INFO - 'SHLVL': '1', 04:01:16 INFO - 'TERM': 'linux', 04:01:16 INFO - 'TMOUT': '86400', 04:01:16 INFO - 'USER': 'cltbld', 04:01:16 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:16 INFO - '_': '/tools/buildbot/bin/python'} 04:01:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 04:01:16 INFO - mozversion application_buildid: 20171001093509 04:01:16 INFO - mozversion application_changeset: f90790f7a34616b1e878f3a6f536546e048e21e6 04:01:16 INFO - mozversion application_display_name: FirefoxNightly 04:01:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:01:16 INFO - mozversion application_name: Firefox 04:01:16 INFO - mozversion application_remotingname: firefox 04:01:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:01:16 INFO - mozversion application_vendor: Mozilla 04:01:16 INFO - mozversion application_version: 58.0a1 04:01:16 INFO - mozversion platform_buildid: 20171001093509 04:01:16 INFO - mozversion platform_changeset: f90790f7a34616b1e878f3a6f536546e048e21e6 04:01:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:01:16 INFO - mozversion platform_version: 58.0a1 04:01:16 INFO - using testdate: 1506855676 04:01:16 INFO - actual date: 1506855676 04:01:16 INFO - starting webserver on 'localhost:58007' 04:01:16 INFO - SUITE-START | Running 2 tests 04:01:16 INFO - TEST-START | dromaeo_css 04:01:16 INFO - Initialising browser for dromaeo_css test... 04:01:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58007/getInfo.html -profile /tmp/tmpTjIBoO/profile 04:01:16 INFO - TEST-INFO | started process 24795 (/builds/slave/test/build/application/firefox/firefox http://localhost:58007/getInfo.html) 04:01:22 INFO - TEST-INFO | 24795: exit 0 04:01:22 INFO - Browser initialized. 04:01:22 INFO - Running cycle 1/1 for dromaeo_css test... 04:01:22 INFO - Using env: {'DISPLAY': ':0', 04:01:22 INFO - 'HOME': '/home/cltbld', 04:01:22 INFO - 'JSGC_DISABLE_POISONING': '1', 04:01:22 INFO - 'LANG': 'en_US.UTF-8', 04:01:22 INFO - 'LANGUAGE': 'en_US:en', 04:01:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:01:22 INFO - 'LOGNAME': 'cltbld', 04:01:22 INFO - 'MAIL': '/var/mail/cltbld', 04:01:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:01:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:01:22 INFO - 'MOZ_CRASHREPORTER': '1', 04:01:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:01:22 INFO - 'MOZ_NO_REMOTE': '1', 04:01:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:01:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:22 INFO - 'NO_EM_RESTART': '1', 04:01:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:22 INFO - 'PWD': '/builds/slave/test', 04:01:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:01:22 INFO - 'RUST_BACKTRACE': 'full', 04:01:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:01:22 INFO - 'SHELL': '/bin/bash', 04:01:22 INFO - 'SHLVL': '1', 04:01:22 INFO - 'TERM': 'linux', 04:01:22 INFO - 'TMOUT': '86400', 04:01:22 INFO - 'USER': 'cltbld', 04:01:22 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:01:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:22 INFO - '_': '/tools/buildbot/bin/python'} 04:01:22 INFO - TEST-INFO | started process 25032 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTjIBoO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:01:22 INFO - PID 25032 | 04:01:22 INFO - PID 25032 | (/builds/slave/test/build/application/firefox/firefox:25082): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:01:22 INFO - PID 25032 | 04:01:33 INFO - PID 25032 | RSS: Main: 144822272 04:01:33 INFO - PID 25032 | 04:04:30 INFO - PID 25032 | Cycle 1(1): loaded http://localhost:58007/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58007/tests/dromaeo/cssquery-ext.html) 04:04:32 INFO - PID 25032 | RSS: Main: 143204352 04:04:32 INFO - PID 25032 | 04:07:33 INFO - PID 25032 | Cycle 1(1): loaded http://localhost:58007/tests/dromaeo/cssquery-ext.html (next: http://localhost:58007/tests/dromaeo/cssquery-jquery.html) 04:07:34 INFO - PID 25032 | RSS: Main: 144265216 04:07:34 INFO - PID 25032 | 04:10:35 INFO - PID 25032 | Cycle 1(1): loaded http://localhost:58007/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58007/tests/dromaeo/cssquery-mootools.html) 04:10:36 INFO - PID 25032 | RSS: Main: 145895424 04:10:36 INFO - PID 25032 | 04:13:37 INFO - PID 25032 | Cycle 1(1): loaded http://localhost:58007/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58007/tests/dromaeo/cssquery-prototype.html) 04:13:38 INFO - PID 25032 | RSS: Main: 145350656 04:13:38 INFO - PID 25032 | 04:16:41 INFO - PID 25032 | Cycle 1(1): loaded http://localhost:58007/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58007/tests/dromaeo/cssquery-yui.html) 04:16:42 INFO - PID 25032 | RSS: Main: 146477056 04:16:42 INFO - PID 25032 | 04:19:43 INFO - PID 25032 | Cycle 1(1): loaded http://localhost:58007/tests/dromaeo/cssquery-yui.html (next: http://localhost:58007/tests/dromaeo/cssquery-dojo.html) 04:19:44 INFO - PID 25032 | RSS: Main: 149184512 04:19:44 INFO - PID 25032 | 04:19:44 INFO - PID 25032 | __start_tp_report 04:19:44 INFO - PID 25032 | _x_x_mozilla_page_load 04:19:44 INFO - PID 25032 | _x_x_mozilla_page_load_details 04:19:44 INFO - PID 25032 | |i|pagename|runs| 04:19:44 INFO - PID 25032 | |0;dojo.html;3860.3051450738863;3864.343061679514;3864.574896761356;3909.863154789583;3971.2256110058534;6230.657313847737;6268.968655156729;6305.6847157642105;6316.368363163681;6400.679966001699;6331.873362532747;6338.556301058916;6345.809625711225;6359.872802543946;6392.296847346788;5594.356648985357;5667.773289068432;5683.715814209283;5689.4879460848515;5718.285214348207;5749.453801888814;5776.971115144418;5789.681567513785;5790;5844.123381492767;5751.166080918264;5769.7115144242825;5783.392743761908;5828.388019257981;5835.941640583582;5293.682379057269;5534.778608855641;5659.801906933238;5664.065429204176;5706.572007099484;5658.122990936411;5667.688277144755;5706.77172913083;5768.78855440358;5772.538196944234;6306.905396419057;6313.526485513605;6319.4944404448;6378.649174295412;6388.872222555569;6735.393814556664;6756.695948682321;6783.457323414115;6784.287649796799;6793.5244532882225;6812.4550035997;6832.863342733118;6840.110785597841;6844.28135045823;6844.965775171142;6375.968120159416;6382.808515744535;6388.744450221986;6429.260635026985;6438.645874476902;6915.135608048994;6981.3367730065565;7089.291070892869;7136.892946605805;7168.068151140423;6695.296993815677;6778.898316525256;6792.8301792454595;6798.354156355138;6845.075914751545;6850.417714494324;6876.346747059021;6930.237673855871;6933.93066069343;6947.89578156328;6717.126773519509;6759.932400676029;6779.72881084751;6807.795766127024;6858.348456896587;6782.830429239309;6799.864002720017;6817.727290908408;6871.244163142051;6883.346082122191;4906.411230652273;4921.434035085939;4999.925001124986;5037.848864534069;5066.746662666852;4507.301368287921;4576.725396476222;4593.977030114828;4600.401947746772;4617;6131.417515336036;6150.538709596798;6167.814965551041;6219.626822390671;6246.312905580383;6648.368405001517;6706;6748.561343512656;6796.694148763318;6811.182658081062;6273.466755325849;6312.747490100436;6321.494280457552;6343.0485427186295;6352.809415717536;5474.808381706621;5532.695701736443;5542.057850165482;5548.195511650788;5552.972235138879;6826.283240259801;6838.14523184602;6859.657017149123;6897.241303456616;6899.137607799025;6636.170478690164;6642.368974947372;6655.700493477708;6656.0348749430095;6665.466762658879;6946.5137440378685;6950.270221626859;6956.513044087068;6966.9651651741415;6971.58170509771;6560.44236239925;6635.402813746593;6639.136912201383;6643.634600097041;6646.867062658816;6722.2605513394465;6765.627890466071;6787.728490860508;6807.5575087619145;6808.7276508939085;6560.507961902972;6564.409203171737;6582.275949645439;6664.166979127609;6666;6272.153259309935;6351.333110023381;6359.650219237987;6564.442022427956;6652.301508341555;6352.428281454691;6380.042993550819;6405.487560995202;6416.550841441054;6417.973124300276;6612.404883560503;6642.771087348856;6653.2348779889;6658.866822663616;6680.465562754871;6351.777687780906;6365.96817015912;6401.487880969605;6453.870922581616;6498.805035848932 04:19:44 INFO - PID 25032 | |1;ext.html;962.6438217859388;981.4749109226562;1011.5650270383734;1013.1894484412468;1017.3031473440689;42708.35937460937;43345;43747.34378984318;43772.56227437725;43868.34197487036;41131.177376452455;42875.78562107185;43934.34098488525;43978.34032489507;44048.339274910904;8432.789180270482;8517.148285171495;8577.142285771404;8601.096884827066;8601.225889669928;8356;8445.704400345989;8475.237228649421;8496.532690702008;8510.957445212765;8495.702650407236;8523.57382130894;8534.487930724177;8546.487210767344;8580.528070956096;8415.83168336636;8428.030776460693;8459.577021148949;8478.406511544194;8486.405951583392;8325.708600199025;8346.916530834735;8375.958120209421;8387.580620968989;8406.579671016425;40713.79643101795;40796.59203407987;40909.590904090874;40946;41164.58835411638;3728.067983004249;3734.925301493955;3751.3435148848844;3752.080740218664;3762.9811850940846;33500.66499335;33528.32943341144;33621.49567756485;33657.66342336594;33853.49219761681;5385.3268341457315;5410.675359478444;5427.185922111676;5453.536449401766;5548.0845660465975;207322;210844;216044;218041;233106.9999999983;53013.46986530085;53484.46515534873;53544.73227633837;53590.196147057824;53654;55630;55700.44299557034;55743.72128139334;55783.16325255125;55796.72101639547;17236.052017139176;17313.3940312091;17348.479545613653;17375.82624173742;17432.738508922375;14347.78478322826;14358;14360.928195358956;14362.569122926328;14362.784558231635;14427.927860360842;14500.419983200554;14506.911981601212;14577.781333280009;14600.85399145995;5630.577706672016;5639.492445679908;5716.942830571641;5856.407131189703;5917.52659787216;1103.2056919018294;1105.2208193223757;1117;1123.5337335006045;1138.5502726423017;25661.3584660385;25792.226233213034;25810.09664661728;25880.482390352463;25947.740522594533;1209.8366720492797;1238.795598726218;1243.3720970909815;1248.15125714515;1248.6004478566954;13512;13596.59210223695;13607.319634018457;13653;13670.931645341909;288044;289461;289678;289713;290500;56388.43611563832;56484;56527;56607;56654.7167264161;1400.66384067823;1525.4981829799137;1538.100211376335;1560.4382422327953;1568.7019466301367;25149.24552263435;25351.366215844013;25359.746402535737;25479;25737.356566085997;35530.467042993856;35577.82211088928;35629.64370356263;35642.64357356497;35667.286654267285;240476;240557;240944;241315;241730;36296;37274.627253727114;38039.61960380361;38041.80979095198;38261;47721;49287.75356123196;49732.502674972784;49812.501874982234;50054.249186261506;36344.63655363518;36615.98549309514;36677.44983825298;37053.629463705016;37448.62551374451;36003.27993440064;36165;36419.27161456809;36734.265314693024;37005.81497092605;40785;41373;41585.168296633296;41750;41982.37026444667;34981.12547186239;35100.82449587736;35199.47200792041;35255.82372088123;35524.28951421009;25934.610980834925;26251.868740656173;26408.339791505365;26507.469850603266;26663.20010399691 04:19:44 INFO - PID 25032 | |2;jquery.html;592.4105515012564;615.7013848283582;615.7967870602702;643.9420452159305;654.6268626882676;23561.293161205147;23925.880370598145;24933.50132997339;24977.50044999099;24991.75008249917;25037.624435633435;26225.47549049018;26243.3439164021;26252.868735656342;26328.60507092395;6602.405783479486;6746.291639377869;6794.796156115324;6797.694103765342;6799.660016999155;6532.5753826001155;6823.7611683591085;6830.59016459014;6836.487263455236;6848.109745733048;6785.050092986986;6801.489888258376;6801.591904485722;6802.591844489347;6806.047153398528;6547.934520654804;6769.356911093463;6772.322767723237;6836.4530837532875;6838.7264509419565;6385.872282554323;6390;6390.6485143317095;6392.296847346788;6400.519961002897;29944.70055299441;30388.54417183744;30396.392072158433;30448.238794030105;30505.542416863766;2248.6064938635673;2265.173211777689;2265.988670056656;2273.7612550682106;2274.0562666493383;20062.197512099498;21438.14247430101;21556.245531406326;21559.676604850938;21583.46041348979;2548.808839337057;2591.2614904752054;2631.4868600622885;2655.0574546036073;2657.0833062593374;144834.27582862123;150113;165716;169668;172545;15102.666380442024;15835.762463563056;16277.348906043626;16494.34022639105;16660.833391666172;24189.51620967571;24235.636465453033;24292.878535607208;24352.75647243505;24435.755642443703;10607.84088238677;10888.40113793749;10905.945470272598;10945.781084378426;10976.835347469794;9848.901510984942;9887.851682224773;10046.191685496535;10107.69676909694;10195.388276703421;9440.622375104918;9461.952690236505;9488.905110948941;9841.409515429097;9955.303128780915;2945.926351841178;2959.955600665992;3022.5012872875727;3025.4251692178414;3132.1699749566383;1056.6037735849056;1066.157735389034;1071.239420011785;1071.4214324264965;1074.3446497636435;21683.56632867334;21757.347279581638;21932;22117.889410553165;22187.66718499224;1116.4194618798178;1117.636768050387;1129.8926601972798;1136.1365362324693;1151.9654410367702;11936.820947685792;12120.636380908587;12140.514379424723;12176.878231217574;12211;613907;628975;645069;656766;659289;134842;136253;138947.30526347304;139200;140771.29614351862;1226.871178526242;1227.3433712963522;1265.1017777378165;1272.5609664665676;1278.9168704034394;19131.90434047821;19394.903025484782;19477.902610486857;19510.21959121619;19687.114079866726;67867;68226;68296.317036829;68477.65761171162;68558.65720671364;86182;90689.54655226681;91796.54101729448;92234;92376.53811730898;15894.841051589336;16136.67726645437;16181.190940452694;16226.594335141715;16261;24194;24381.51236975286;24405.755942441057;24528.01887924463;24665.260042198763;19683.606327873076;19772.30796922101;19794.20823167115;19921.501962451053;19991.000449977153;16889.662206756042;17463.650726985135;17520.649587008444;17825.10874456246;18286.908565457088;19208.327708530695;20398.898005509876;20508.589828203054;20616.48458788542;20628.79371206269;15709.764353534478;15841.68316633684;15953.8404615957;15957.122358269942;15965.201739913191;19497.220111195395;19590.90204548968;19814.60370792605;19829.504262393555;19918.601627967648 04:19:44 INFO - PID 25032 | |3;mootools.html;1391.6033930329859;1496.6034000989732;1497.7229212595678;1512.5916002679278;1514.4018112845426;17102.914485427573;17777.644447111066;17788.73316900246;17803.64392712145;17811.910940445312;17149.82850171498;17249.396271130514;17283;17300.653986920253;17308.307667693276;2716.578930265812;2716.714744951781;2768.1695491352616;2769.3076730817297;2772.50094982903;2646.1003258891974;2670.172246603559;2675.8394496330184;2687.556553168725;2689.905853295135;2802.201372608807;2911.1557648281973;2913.5775312579663;2921.926951826211;2925.0639795265524;2819.0415258812104;2838.162741991097;2847.0889315419076;2881.8270903745843;2882.8846846126235;2688.0591792872337;2873;2895.391967686788;2899;2900.912972610804;16976.490705278862;17226.56933576658;17239.138043097795;17251.13744312792;17251.30994760208;8720.563971801384;8988.77528061797;9002;9018.278537716968;9034.00625931147;5380.973095134499;5517.862053448655;5557.277553918006;5720.856978575527;5816.738246778905;1974.2442268539075;1979.990100049505;1988.5923385705896;1996.650586147433;2018.6265540874986;31222.843885780654;31485.685143148505;31518.684813152035;31548;31665.36669266602;7317.231690672509;7333.88999165013;7349.889751653729;7471.626418679044;7491.363234125051;20589.897050514952;20600.072996715186;20646.380608581767;20707.378778636667;20809;8696.782580435412;8932.508712020772;8954.910450895539;8959.328050396247;8963.596638151299;7988.121306656263;7990.4007199460275;8117.756467305991;8140.430169888169;8167.71413000554;5641.971790141105;5818.854528636733;5860.2381690380835;5891.587588868823;5898.8230353089475;1727.421313859871;1749.1691446562781;1754.3859649122705;1778.1460755487515;1781.8218178182337;962.8266911955915;983.0759086458706;990.4354517924713;996.4419924841967;1021.0096206679509;8494.957525212334;8533.274671652978;8613.052564217931;8700.477971321614;8759.912400876037;783.4359261331832;806.9757907262791;807.2936180841764;813.3615112136944;827.6978902700591;5319.707416092152;5406.78372865081;5494.011078005998;5590.91613625796;5675.631083979528;24504.632430513557;25656.358591035;26097;26100.73899261021;26209;24857.378565535644;24949.750502495106;25408;25427.74572254254;25528.872355638105;12934.67663308406;12972.462001542252;12984.285864277555;13119.540816071392;13190.208587484589;9026.368154229142;9088.59101340428;9140.497272650067;9171.77070573241;9231.353805233837;21463.46341341479;21753.238636647642;21847.453813654785;21985;22205.77794222037;22301;22442.551148977254;22610.660840087083;22619.660705089107;22995.88502057479;12862.67843303925;13099.345032748515;13112.803307950198;13114.082014259291;13115.081944264191;15602.531924042298;15772.763408548652;15835.366585336458;15892.68214635724;16063.518094457186;12188.146829722189;12191.390430478263;12497.625071247878;12576.748465030465;12665.430055647334;12656.430460629477;12883.742325153631;13040.152390094614;13072.5424610142;13239.536616218385;11285.435728213391;11352.148588856035;11429.199956003;11440.199186056896;11446.313221206754;8063.798405039921;8116.107228204774;8162.510249385056;8250.133735957637;8272.17278272168;8384.203500667427;8391.790205244917;8422.157784221774;8455.196756308142;8464.153584641734 04:19:44 INFO - PID 25032 | |4;prototype.html;556.7077284425676;574.5174053794813;577.2524580668035;585.5257241634276;586.9735861886214;6297.874042519158;6330.4935605151595;6377.52168587356;6441.420272175504;6482.448991835694;6191.597546159508;6278.1838361013115;6279.8116056518265;6338.809835704937;6353.587016843902;5148.485151484859;5187.844364669066;5191.195364718475;5203.869903252411;5208.322918020671;5116.411612664554;5117.897642047175;5137.255098010786;5203.219517072451;5241.711705856176;5167.199084141946;5210;5250.553702935255;5266.104762190418;5271.762770675309;5186.455422180655;5202.401723801736;5212.192110222922;5254.21186821976;5281.841544753664;4785.617150627942;4844.612431005511;4856.3201151838775;4899.779509922062;4936.481669424695;6202.906956395657;6225.159603453521;6231.813045608594;6248;6279.309275979638;6125.816225513242;6217.409346112112;6251.687415629246;6256.15541901842;6295.937040629581;6826.658667066627;6827.69275382604;6885.449164066863;6918.257845987869;6926.238113807477;5532.446755324476;5541.168824676291;5555.138953462219;5561.360443549003;5616.297962754656;6872.896906546406;6915.412189963859;7049.577025378442;7147.03456549453;7147.142342918883;5674.4892959633835;5688.146777983336;5728.79949201776;5733.827985160452;5734.39788822176;5773.538116950718;5797.130430435468;5809.477147056785;5862.443067908542;5868.823935281863;6091.939080609226;6124.755009799647;6144.569880108439;6247.687615619201;6258.15514905491;6794.8641027180165;6800.829979250559;6814.284500127415;6843.965780171067;6914.412274956584;2008.1565742388232;2016.354766474714;2023.9190432382834;2031.2788959919017;2039.1435597049274;1461.671123997092;1529.724649563067;1530.5867415797895;1549.1402271739294;1570.418944990338;6250.187445640804;6267.1539342188225;6280.623162610258;6314.368563143649;6328.46208072319;6772.5936443813525;6778.864422711518;6789.25318214996;6835.863282734317;6895.689693963784;6205.069239614004;6228.813135605939;6233.96883015582;6237.662850375028;6278.780242691484;3554.7689400188906;3621.402468592653;3637.8544858205905;3656.2687462507606;3657;7144.392726618192;7171.81665025265;7184.5330053547395;7196.352328290374;7217.566945983152;7008.544444611084;7052.753153639598;7062.552176803743;7077.964610176916;7088.184858741208;6925.238223795379;7061.9293807062295;7110.515847234921;7111.53775004623;7191.280871912767;6842.247352791289;6876.277990810893;6907.6891539879825;6922.5154239204785;6948.513604047668;6772.492063095189;6785.5250132490255;6792.93207067923;6794.320567943166;6803.285655006351;7068.222495525591;7092.219855815753;7102.893456598052;7180.779267524429;7194.532355396883;5672.177534257427;5724.971375143097;5742.138679197987;5748.971255143698;5749.1663708762835;6490.643014634241;6639.170103737033;6857.27998560144;6864.347886950732;6958.965205173941;6619.106420633346;6710;6716.3955244028275;6742.325767423415;6747.493937954575;6654.633995130314;6676.465882729273;6696.430803381572;6697.129373181455;6743.66281685904;6376.298607153302;6506.837329066811;6507.284198738041;6529.314421985623;6552.901706474311;6692.39768420825;6730.192376914802;6752.3922846944015;6777.322267773183;6785.9660701696175;6500.577462464925;6508.251551071688;6571.507136964842;6577.934220657732;6582.868342633216 04:19:44 INFO - PID 25032 | |5;yui.html;970.8252514547379;986.6152200641749;998.560633321339;1004.6232662751469;1015.4463670584005;21517.4620634484;21992.340229793113;22055.889720551415;22057.669134962987;22059.66910496344;18017.63964720705;18098.366557170502;18100.275988960424;19399.515012124702;20441.080151393187;1049.826778581535;1077.159815344033;1078.6170909327193;1078.9190810689229;1081.4214395298518;1062.904338609525;1085.5060947268973;1086.239632257419;1090.983635245472;1099.8900109988977;1147.7073346296684;1160.7214268575565;1161.5818305410046;1161.6224726963728;1162.3665102519146;1135.517405102828;1141.697450175704;1142.1148609827367;1148.8736239013701;1149.8160294352906;837.3343192162246;846.6063280574501;847.635516727813;847.9957600212022;851.6635928808087;20983.685244721342;21556.137754489802;21798;21827.23604673829;21891.452713682287;23438.531229375487;23791.76208237913;23801.523969520687;23941.880290598434;24194;17171.742423863652;17461.65076698459;17652.02913839738;17688.380906668335;17699;3976.522817261918;4022.5776293489057;4034.2536630723116;4043.595640435933;4043.878683639466;157174;160691;166851.1657441717;167264.99999999878;168320;18398.724019139725;18760.437186884414;18798.530036748918;18881.433556993314;18910.62178756417;34400.139996500286;35005.82497087498;35054.64945350565;35121.64878351235;35352.823235883654;9860.556274967643;9877.209823214125;9881.357711748713;9956.303058785816;10051;4753.382060332204;4835.492273311322;4844.515548445198;4862.124817532878;4869.780859861244;6206.510437494969;6258.9374106259265;6265.780697675559;6280.309165991737;6296.244450665949;1252.2361359570837;1271.4786937355639;1274.3182397417338;1282.762688902556;1292.2246652008907;709.0923617769264;715.6815217228284;720.6648908257581;727.0838743183626;740.9851802964018;11472.139589530818;11924.523019079139;12058.336791476377;12087;12223.388830558615;716.5485743981259;716.9462290328246;717.9138503379628;727.1710250314645;737.1080991999634;5794.209780721855;5832.212651292106;5865.395664403422;5971.880562388728;6043.214382130383;158841.31561455273;159474;160349;162345;163763.26828561182;145915;146502;147112;147591;147852.26073869562;123720.38139809243;124902.3754881238;126220;127423;127430;11170.888291116984;11333.659990200307;11460;11489.712757180803;11668.533258669897;58863.822723544436;59788.402115978286;60074.39925600688;60077;61502.69248653728;72970;74454.25545744473;74463.6276818634;74465;74548.62725686337;16224.513264602081;16345.67308653844;16370.918145409196;16397.918010410347;16503;19111.33110341187;19120.52198694988;19493.317733879245;19591.70612440843;19660.901695492004;14877.47928822486;14977.08405179926;14979.850201497846;15058.171800550637;15234.162121083447;17035.574110646838;17460.82539174592;17500.649987000445;17527.298908043536;17691.115444227995;17722;17847.286108555512;17884.55288617795;17964.19161137778;17976.550586235448;12184.634460966185;12324.32216228116;12497.25016499013;12624.11631185845;12645.620631381073;12419.379031048593;12656.430460629477;12700.618981430573;12715.87284127184;12747.61757147287 04:19:44 INFO - PID 25032 | __end_tp_report 04:19:44 INFO - PID 25032 | __start_cc_report 04:19:44 INFO - PID 25032 | _x_x_mozilla_cycle_collect,267 04:19:44 INFO - PID 25032 | __end_cc_report 04:19:44 INFO - PID 25032 | __startTimestamp1506856784027__endTimestamp 04:19:44 INFO - PID 25032 | 04:19:44 INFO - PID 25032 | ------- Summary: start ------- 04:19:44 INFO - PID 25032 | Number of tests: 6 04:19:44 INFO - PID 25032 | 04:19:44 INFO - PID 25032 | [#0] dojo.html Cycles:165 Average:6258.54 Median:6416.55 stddev:715.39 (11.1%) stddev-sans-first:692.40 04:19:44 INFO - PID 25032 | Values: 3860.3 3864.3 3864.6 3909.9 3971.2 6230.7 6269.0 6305.7 6316.4 6400.7 6331.9 6338.6 6345.8 6359.9 6392.3 5594.4 5667.8 5683.7 5689.5 5718.3 5749.5 5777.0 5789.7 5790.0 5844.1 5751.2 5769.7 5783.4 5828.4 5835.9 5293.7 5534.8 5659.8 5664.1 5706.6 5658.1 5667.7 5706.8 5768.8 5772.5 6306.9 6313.5 6319.5 6378.6 6388.9 6735.4 6756.7 6783.5 6784.3 6793.5 6812.5 6832.9 6840.1 6844.3 6845.0 6376.0 6382.8 6388.7 6429.3 6438.6 6915.1 6981.3 7089.3 7136.9 7168.1 6695.3 6778.9 6792.8 6798.4 6845.1 6850.4 6876.3 6930.2 6933.9 6947.9 6717.1 6759.9 6779.7 6807.8 6858.3 6782.8 6799.9 6817.7 6871.2 6883.3 4906.4 4921.4 4999.9 5037.8 5066.7 4507.3 4576.7 4594.0 4600.4 4617.0 6131.4 6150.5 6167.8 6219.6 6246.3 6648.4 6706.0 6748.6 6796.7 6811.2 6273.5 6312.7 6321.5 6343.0 6352.8 5474.8 5532.7 5542.1 5548.2 5553.0 6826.3 6838.1 6859.7 6897.2 6899.1 6636.2 6642.4 6655.7 6656.0 6665.5 6946.5 6950.3 6956.5 6967.0 6971.6 6560.4 6635.4 6639.1 6643.6 6646.9 6722.3 6765.6 6787.7 6807.6 6808.7 6560.5 6564.4 6582.3 6664.2 6666.0 6272.2 6351.3 6359.7 6564.4 6652.3 6352.4 6380.0 6405.5 6416.6 6418.0 6612.4 6642.8 6653.2 6658.9 6680.5 6351.8 6366.0 6401.5 6453.9 6498.8 04:19:44 INFO - PID 25032 | 04:19:44 INFO - PID 25032 | [#1] ext.html Cycles:180 Average:42961.20 Median:36870.04 stddev:65261.37 (177.0%) stddev-sans-first:65368.25 04:19:44 INFO - PID 25032 | Values: 962.6 981.5 1011.6 1013.2 1017.3 42708.4 43345.0 43747.3 43772.6 43868.3 41131.2 42875.8 43934.3 43978.3 44048.3 8432.8 8517.1 8577.1 8601.1 8601.2 8356.0 8445.7 8475.2 8496.5 8511.0 8495.7 8523.6 8534.5 8546.5 8580.5 8415.8 8428.0 8459.6 8478.4 8486.4 8325.7 8346.9 8376.0 8387.6 8406.6 40713.8 40796.6 40909.6 40946.0 41164.6 3728.1 3734.9 3751.3 3752.1 3763.0 33500.7 33528.3 33621.5 33657.7 33853.5 5385.3 5410.7 5427.2 5453.5 5548.1 207322.0 210844.0 216044.0 218041.0 233107.0 53013.5 53484.5 53544.7 53590.2 53654.0 55630.0 55700.4 55743.7 55783.2 55796.7 17236.1 17313.4 17348.5 17375.8 17432.7 14347.8 14358.0 14360.9 14362.6 14362.8 14427.9 14500.4 14506.9 14577.8 14600.9 5630.6 5639.5 5716.9 5856.4 5917.5 1103.2 1105.2 1117.0 1123.5 1138.6 25661.4 25792.2 25810.1 25880.5 25947.7 1209.8 1238.8 1243.4 1248.2 1248.6 13512.0 13596.6 13607.3 13653.0 13670.9 288044.0 289461.0 289678.0 289713.0 290500.0 56388.4 56484.0 56527.0 56607.0 56654.7 1400.7 1525.5 1538.1 1560.4 1568.7 25149.2 25351.4 25359.7 25479.0 25737.4 35530.5 35577.8 35629.6 35642.6 35667.3 240476.0 240557.0 240944.0 241315.0 241730.0 36296.0 37274.6 38039.6 38041.8 38261.0 47721.0 49287.8 49732.5 49812.5 50054.2 36344.6 36616.0 36677.4 37053.6 37448.6 36003.3 36165.0 36419.3 36734.3 37005.8 40785.0 41373.0 41585.2 41750.0 41982.4 34981.1 35100.8 35199.5 35255.8 35524.3 25934.6 26251.9 26408.3 26507.5 26663.2 04:19:44 INFO - PID 25032 | 04:19:44 INFO - PID 25032 | [#2] jquery.html Cycles:180 Average:41781.01 Median:2269.87 stddev:107485.12 (4735.3%) stddev-sans-first:107742.15 04:19:44 INFO - PID 25032 | Values: 592.4 615.7 615.8 643.9 654.6 23561.3 23925.9 24933.5 24977.5 24991.8 25037.6 26225.5 26243.3 26252.9 26328.6 6602.4 6746.3 6794.8 6797.7 6799.7 6532.6 6823.8 6830.6 6836.5 6848.1 6785.1 6801.5 6801.6 6802.6 6806.0 6547.9 6769.4 6772.3 6836.5 6838.7 6385.9 6390.0 6390.6 6392.3 6400.5 29944.7 30388.5 30396.4 30448.2 30505.5 2248.6 2265.2 2266.0 2273.8 2274.1 20062.2 21438.1 21556.2 21559.7 21583.5 2548.8 2591.3 2631.5 2655.1 2657.1 144834.3 150113.0 165716.0 169668.0 172545.0 15102.7 15835.8 16277.3 16494.3 16660.8 24189.5 24235.6 24292.9 24352.8 24435.8 10607.8 10888.4 10905.9 10945.8 10976.8 9848.9 9887.9 10046.2 10107.7 10195.4 9440.6 9462.0 9488.9 9841.4 9955.3 2945.9 2960.0 3022.5 3025.4 3132.2 1056.6 1066.2 1071.2 1071.4 1074.3 21683.6 21757.3 21932.0 22117.9 22187.7 1116.4 1117.6 1129.9 1136.1 1152.0 11936.8 12120.6 12140.5 12176.9 12211.0 613907.0 628975.0 645069.0 656766.0 659289.0 134842.0 136253.0 138947.3 139200.0 140771.3 1226.9 1227.3 1265.1 1272.6 1278.9 19131.9 19394.9 19477.9 19510.2 19687.1 67867.0 68226.0 68296.3 68477.7 68558.7 86182.0 90689.5 91796.5 92234.0 92376.5 15894.8 16136.7 16181.2 16226.6 16261.0 24194.0 24381.5 24405.8 24528.0 24665.3 19683.6 19772.3 19794.2 19921.5 19991.0 16889.7 17463.7 17520.6 17825.1 18286.9 19208.3 20398.9 20508.6 20616.5 20628.8 15709.8 15841.7 15953.8 15957.1 15965.2 19497.2 19590.9 19814.6 19829.5 19918.6 04:19:44 INFO - PID 25032 | 04:19:44 INFO - PID 25032 | [#3] mootools.html Cycles:180 Average:10667.53 Median:2688.98 stddev:7977.67 (296.7%) stddev-sans-first:7969.60 04:19:44 INFO - PID 25032 | Values: 1391.6 1496.6 1497.7 1512.6 1514.4 17102.9 17777.6 17788.7 17803.6 17811.9 17149.8 17249.4 17283.0 17300.7 17308.3 2716.6 2716.7 2768.2 2769.3 2772.5 2646.1 2670.2 2675.8 2687.6 2689.9 2802.2 2911.2 2913.6 2921.9 2925.1 2819.0 2838.2 2847.1 2881.8 2882.9 2688.1 2873.0 2895.4 2899.0 2900.9 16976.5 17226.6 17239.1 17251.1 17251.3 8720.6 8988.8 9002.0 9018.3 9034.0 5381.0 5517.9 5557.3 5720.9 5816.7 1974.2 1980.0 1988.6 1996.7 2018.6 31222.8 31485.7 31518.7 31548.0 31665.4 7317.2 7333.9 7349.9 7471.6 7491.4 20589.9 20600.1 20646.4 20707.4 20809.0 8696.8 8932.5 8954.9 8959.3 8963.6 7988.1 7990.4 8117.8 8140.4 8167.7 5642.0 5818.9 5860.2 5891.6 5898.8 1727.4 1749.2 1754.4 1778.1 1781.8 962.8 983.1 990.4 996.4 1021.0 8495.0 8533.3 8613.1 8700.5 8759.9 783.4 807.0 807.3 813.4 827.7 5319.7 5406.8 5494.0 5590.9 5675.6 24504.6 25656.4 26097.0 26100.7 26209.0 24857.4 24949.8 25408.0 25427.7 25528.9 12934.7 12972.5 12984.3 13119.5 13190.2 9026.4 9088.6 9140.5 9171.8 9231.4 21463.5 21753.2 21847.5 21985.0 22205.8 22301.0 22442.6 22610.7 22619.7 22995.9 12862.7 13099.3 13112.8 13114.1 13115.1 15602.5 15772.8 15835.4 15892.7 16063.5 12188.1 12191.4 12497.6 12576.7 12665.4 12656.4 12883.7 13040.2 13072.5 13239.5 11285.4 11352.1 11429.2 11440.2 11446.3 8063.8 8116.1 8162.5 8250.1 8272.2 8384.2 8391.8 8422.2 8455.2 8464.2 04:19:44 INFO - PID 25032 | 04:19:44 INFO - PID 25032 | [#4] prototype.html Cycles:180 Average:5825.98 Median:6296.91 stddev:1557.45 (24.7%) stddev-sans-first:1510.76 04:19:44 INFO - PID 25032 | Values: 556.7 574.5 577.3 585.5 587.0 6297.9 6330.5 6377.5 6441.4 6482.4 6191.6 6278.2 6279.8 6338.8 6353.6 5148.5 5187.8 5191.2 5203.9 5208.3 5116.4 5117.9 5137.3 5203.2 5241.7 5167.2 5210.0 5250.6 5266.1 5271.8 5186.5 5202.4 5212.2 5254.2 5281.8 4785.6 4844.6 4856.3 4899.8 4936.5 6202.9 6225.2 6231.8 6248.0 6279.3 6125.8 6217.4 6251.7 6256.2 6295.9 6826.7 6827.7 6885.4 6918.3 6926.2 5532.4 5541.2 5555.1 5561.4 5616.3 6872.9 6915.4 7049.6 7147.0 7147.1 5674.5 5688.1 5728.8 5733.8 5734.4 5773.5 5797.1 5809.5 5862.4 5868.8 6091.9 6124.8 6144.6 6247.7 6258.2 6794.9 6800.8 6814.3 6844.0 6914.4 2008.2 2016.4 2023.9 2031.3 2039.1 1461.7 1529.7 1530.6 1549.1 1570.4 6250.2 6267.2 6280.6 6314.4 6328.5 6772.6 6778.9 6789.3 6835.9 6895.7 6205.1 6228.8 6234.0 6237.7 6278.8 3554.8 3621.4 3637.9 3656.3 3657.0 7144.4 7171.8 7184.5 7196.4 7217.6 7008.5 7052.8 7062.6 7078.0 7088.2 6925.2 7061.9 7110.5 7111.5 7191.3 6842.2 6876.3 6907.7 6922.5 6948.5 6772.5 6785.5 6792.9 6794.3 6803.3 7068.2 7092.2 7102.9 7180.8 7194.5 5672.2 5725.0 5742.1 5749.0 5749.2 6490.6 6639.2 6857.3 6864.3 6959.0 6619.1 6710.0 6716.4 6742.3 6747.5 6654.6 6676.5 6696.4 6697.1 6743.7 6376.3 6506.8 6507.3 6529.3 6552.9 6692.4 6730.2 6752.4 6777.3 6786.0 6500.6 6508.3 6571.5 6577.9 6582.9 04:19:44 INFO - PID 25032 | 04:19:44 INFO - PID 25032 | [#5] yui.html Cycles:180 Average:29561.51 Median:17924.37 stddev:45472.42 (253.7%) stddev-sans-first:45549.31 04:19:44 INFO - PID 25032 | Values: 970.8 986.6 998.6 1004.6 1015.4 21517.5 21992.3 22055.9 22057.7 22059.7 18017.6 18098.4 18100.3 19399.5 20441.1 1049.8 1077.2 1078.6 1078.9 1081.4 1062.9 1085.5 1086.2 1091.0 1099.9 1147.7 1160.7 1161.6 1161.6 1162.4 1135.5 1141.7 1142.1 1148.9 1149.8 837.3 846.6 847.6 848.0 851.7 20983.7 21556.1 21798.0 21827.2 21891.5 23438.5 23791.8 23801.5 23941.9 24194.0 17171.7 17461.7 17652.0 17688.4 17699.0 3976.5 4022.6 4034.3 4043.6 4043.9 157174.0 160691.0 166851.2 167265.0 168320.0 18398.7 18760.4 18798.5 18881.4 18910.6 34400.1 35005.8 35054.6 35121.6 35352.8 9860.6 9877.2 9881.4 9956.3 10051.0 4753.4 4835.5 4844.5 4862.1 4869.8 6206.5 6258.9 6265.8 6280.3 6296.2 1252.2 1271.5 1274.3 1282.8 1292.2 709.1 715.7 720.7 727.1 741.0 11472.1 11924.5 12058.3 12087.0 12223.4 716.5 716.9 717.9 727.2 737.1 5794.2 5832.2 5865.4 5971.9 6043.2 158841.3 159474.0 160349.0 162345.0 163763.3 145915.0 146502.0 147112.0 147591.0 147852.3 123720.4 124902.4 126220.0 127423.0 127430.0 11170.9 11333.7 11460.0 11489.7 11668.5 58863.8 59788.4 60074.4 60077.0 61502.7 72970.0 74454.3 74463.6 74465.0 74548.6 16224.5 16345.7 16370.9 16397.9 16503.0 19111.3 19120.5 19493.3 19591.7 19660.9 14877.5 14977.1 14979.9 15058.2 15234.2 17035.6 17460.8 17500.6 17527.3 17691.1 17722.0 17847.3 17884.6 17964.2 17976.6 12184.6 12324.3 12497.3 12624.1 12645.6 12419.4 12656.4 12700.6 12715.9 12747.6 04:19:44 INFO - PID 25032 | -------- Summary: end -------- 04:19:44 INFO - PID 25032 | 04:19:44 INFO - TEST-INFO | 25032: exit 0 04:19:44 INFO - TEST-OK | dromaeo_css | took 1108224ms 04:19:44 INFO - TEST-START | kraken 04:19:44 INFO - Initialising browser for kraken test... 04:19:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58007/getInfo.html -profile /tmp/tmpEDKAqS/profile 04:19:44 INFO - TEST-INFO | started process 25396 (/builds/slave/test/build/application/firefox/firefox http://localhost:58007/getInfo.html) 04:19:50 INFO - TEST-INFO | 25396: exit 0 04:19:50 INFO - Browser initialized. 04:19:50 INFO - Running cycle 1/1 for kraken test... 04:19:50 INFO - Using env: {'DISPLAY': ':0', 04:19:50 INFO - 'HOME': '/home/cltbld', 04:19:50 INFO - 'JSGC_DISABLE_POISONING': '1', 04:19:50 INFO - 'LANG': 'en_US.UTF-8', 04:19:50 INFO - 'LANGUAGE': 'en_US:en', 04:19:50 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:19:50 INFO - 'LOGNAME': 'cltbld', 04:19:50 INFO - 'MAIL': '/var/mail/cltbld', 04:19:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:19:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:19:50 INFO - 'MOZ_CRASHREPORTER': '1', 04:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:50 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:19:50 INFO - 'MOZ_NO_REMOTE': '1', 04:19:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:19:50 INFO - 'NO_EM_RESTART': '1', 04:19:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:50 INFO - 'PWD': '/builds/slave/test', 04:19:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:19:50 INFO - 'RUST_BACKTRACE': 'full', 04:19:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:19:50 INFO - 'SHELL': '/bin/bash', 04:19:50 INFO - 'SHLVL': '1', 04:19:50 INFO - 'TERM': 'linux', 04:19:50 INFO - 'TMOUT': '86400', 04:19:50 INFO - 'USER': 'cltbld', 04:19:50 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546', 04:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:50 INFO - '_': '/tools/buildbot/bin/python'} 04:19:50 INFO - TEST-INFO | started process 25631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEDKAqS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:19:50 INFO - PID 25631 | 04:19:50 INFO - PID 25631 | (/builds/slave/test/build/application/firefox/firefox:25680): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:19:50 INFO - PID 25631 | 04:20:01 INFO - PID 25631 | RSS: Main: 144584704 04:20:01 INFO - PID 25631 | 04:20:03 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58007/tests/kraken/driver.html?testName=audio-beat-detection) 04:20:04 INFO - PID 25631 | RSS: Main: 150130688 04:20:04 INFO - PID 25631 | 04:20:07 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58007/tests/kraken/driver.html?testName=audio-dft) 04:20:07 INFO - PID 25631 | RSS: Main: 150466560 04:20:07 INFO - PID 25631 | 04:20:10 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58007/tests/kraken/driver.html?testName=audio-fft) 04:20:11 INFO - PID 25631 | RSS: Main: 147402752 04:20:11 INFO - PID 25631 | 04:20:13 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58007/tests/kraken/driver.html?testName=audio-oscillator) 04:20:14 INFO - PID 25631 | RSS: Main: 148258816 04:20:14 INFO - PID 25631 | 04:20:16 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58007/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:20:16 INFO - PID 25631 | RSS: Main: 148459520 04:20:16 INFO - PID 25631 | 04:20:19 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58007/tests/kraken/driver.html?testName=imaging-darkroom) 04:20:20 INFO - PID 25631 | RSS: Main: 148738048 04:20:20 INFO - PID 25631 | 04:20:24 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58007/tests/kraken/driver.html?testName=imaging-desaturate) 04:20:24 INFO - PID 25631 | RSS: Main: 149946368 04:20:24 INFO - PID 25631 | 04:20:27 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58007/tests/kraken/driver.html?testName=json-parse-financial) 04:20:28 INFO - PID 25631 | RSS: Main: 151236608 04:20:28 INFO - PID 25631 | 04:20:29 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58007/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:20:30 INFO - PID 25631 | RSS: Main: 151199744 04:20:30 INFO - PID 25631 | 04:20:31 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58007/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:20:32 INFO - PID 25631 | RSS: Main: 151019520 04:20:32 INFO - PID 25631 | 04:20:34 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58007/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:20:34 INFO - PID 25631 | RSS: Main: 147779584 04:20:34 INFO - PID 25631 | 04:20:37 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:20:38 INFO - PID 25631 | RSS: Main: 148164608 04:20:38 INFO - PID 25631 | 04:20:40 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:20:41 INFO - PID 25631 | RSS: Main: 148688896 04:20:41 INFO - PID 25631 | 04:20:42 INFO - PID 25631 | Cycle 1(1): loaded http://localhost:58007/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58007/tests/kraken/driver.html?testName=ai-astar) 04:20:43 INFO - PID 25631 | RSS: Main: 149983232 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | __start_tp_report 04:20:43 INFO - PID 25631 | _x_x_mozilla_page_load 04:20:43 INFO - PID 25631 | _x_x_mozilla_page_load_details 04:20:43 INFO - PID 25631 | |i|pagename|runs| 04:20:43 INFO - PID 25631 | |0;ai-astar;105;105;105;105;105;107;108;107;107;105 04:20:43 INFO - PID 25631 | |1;audio-beat-detection;110;114;111;111;114;113;113;143;113;115 04:20:43 INFO - PID 25631 | |2;audio-dft;162;161;162;160;161;161;159;161;164;167 04:20:43 INFO - PID 25631 | |3;audio-fft;73;75;73;73;74;79;72;74;73;72 04:20:43 INFO - PID 25631 | |4;audio-oscillator;78;78;79;84;82;78;85;82;82;83 04:20:43 INFO - PID 25631 | |5;imaging-gaussian-blur;116;111;111;111;119;112;117;112;111;111 04:20:43 INFO - PID 25631 | |6;imaging-darkroom;149;149;149;148;149;149;148;150;148;150 04:20:43 INFO - PID 25631 | |7;imaging-desaturate;95;94;94;94;93;94;94;94;94;94 04:20:43 INFO - PID 25631 | |8;json-parse-financial;49;43;48;44;42;43;43;43;43;44 04:20:43 INFO - PID 25631 | |9;json-stringify-tinderbox;36;36;35;37;37;36;36;36;39;36 04:20:43 INFO - PID 25631 | |10;stanford-crypto-aes;72;75;70;69;69;71;71;72;70;71 04:20:43 INFO - PID 25631 | |11;stanford-crypto-ccm;104;107;106;106;109;99;105;104;101;108 04:20:43 INFO - PID 25631 | |12;stanford-crypto-pbkdf2;143;147;151;154;148;151;147;147;149;149 04:20:43 INFO - PID 25631 | |13;stanford-crypto-sha256-iterative;59;57;58;58;59;58;59;61;60;58 04:20:43 INFO - PID 25631 | __end_tp_report 04:20:43 INFO - PID 25631 | __start_cc_report 04:20:43 INFO - PID 25631 | _x_x_mozilla_cycle_collect,699 04:20:43 INFO - PID 25631 | __end_cc_report 04:20:43 INFO - PID 25631 | __startTimestamp1506856843180__endTimestamp 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | ------- Summary: start ------- 04:20:43 INFO - PID 25631 | Number of tests: 14 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#0] ai-astar Cycles:10 Average:105.90 Median:106.00 stddev:1.20 (1.1%) stddev-sans-first:1.22 04:20:43 INFO - PID 25631 | Values: 105.0 105.0 105.0 105.0 105.0 107.0 108.0 107.0 107.0 105.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#1] audio-beat-detection Cycles:10 Average:115.70 Median:113.50 stddev:9.72 (8.6%) stddev-sans-first:10.09 04:20:43 INFO - PID 25631 | Values: 110.0 114.0 111.0 111.0 114.0 113.0 113.0 143.0 113.0 115.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#2] audio-dft Cycles:10 Average:161.80 Median:161.50 stddev:2.25 (1.4%) stddev-sans-first:2.39 04:20:43 INFO - PID 25631 | Values: 162.0 161.0 162.0 160.0 161.0 161.0 159.0 161.0 164.0 167.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#3] audio-fft Cycles:10 Average:73.80 Median:73.50 stddev:2.04 (2.8%) stddev-sans-first:2.15 04:20:43 INFO - PID 25631 | Values: 73.0 75.0 73.0 73.0 74.0 79.0 72.0 74.0 73.0 72.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:82.00 stddev:2.64 (3.2%) stddev-sans-first:2.55 04:20:43 INFO - PID 25631 | Values: 78.0 78.0 79.0 84.0 82.0 78.0 85.0 82.0 82.0 83.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:112.00 stddev:3.03 (2.7%) stddev-sans-first:3.03 04:20:43 INFO - PID 25631 | Values: 116.0 111.0 111.0 111.0 119.0 112.0 117.0 112.0 111.0 111.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 04:20:43 INFO - PID 25631 | Values: 149.0 149.0 149.0 148.0 149.0 149.0 148.0 150.0 148.0 150.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 04:20:43 INFO - PID 25631 | Values: 95.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#8] json-parse-financial Cycles:10 Average:44.20 Median:43.50 stddev:2.35 (5.4%) stddev-sans-first:1.73 04:20:43 INFO - PID 25631 | Values: 49.0 43.0 48.0 44.0 42.0 43.0 43.0 43.0 43.0 44.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#9] json-stringify-tinderbox Cycles:10 Average:36.40 Median:36.00 stddev:1.07 (3.0%) stddev-sans-first:1.13 04:20:43 INFO - PID 25631 | Values: 36.0 36.0 35.0 37.0 37.0 36.0 36.0 36.0 39.0 36.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#10] stanford-crypto-aes Cycles:10 Average:71.00 Median:71.00 stddev:1.76 (2.5%) stddev-sans-first:1.83 04:20:43 INFO - PID 25631 | Values: 72.0 75.0 70.0 69.0 69.0 71.0 71.0 72.0 70.0 71.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#11] stanford-crypto-ccm Cycles:10 Average:104.90 Median:106.50 stddev:3.07 (2.9%) stddev-sans-first:3.24 04:20:43 INFO - PID 25631 | Values: 104.0 107.0 106.0 106.0 109.0 99.0 105.0 104.0 101.0 108.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.60 Median:149.00 stddev:2.99 (2.0%) stddev-sans-first:2.39 04:20:43 INFO - PID 25631 | Values: 143.0 147.0 151.0 154.0 148.0 151.0 147.0 147.0 149.0 149.0 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:58.70 Median:59.00 stddev:1.16 (2.0%) stddev-sans-first:1.22 04:20:43 INFO - PID 25631 | Values: 59.0 57.0 58.0 58.0 59.0 58.0 59.0 61.0 60.0 58.0 04:20:43 INFO - PID 25631 | -------- Summary: end -------- 04:20:43 INFO - PID 25631 | 04:20:43 INFO - PID 25631 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:20:43 INFO - TEST-INFO | 25631: exit 0 04:20:43 INFO - TEST-OK | kraken | took 59386ms 04:20:43 INFO - SUITE-END | took 1167s 04:20:44 INFO - Completed test suite (00:19:28) 04:20:44 INFO - javascript benchmark 04:20:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9207.056612870003, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3860.3051450738863, 3864.343061679514, 3864.574896761356, 3909.863154789583, 3971.2256110058534, 6230.657313847737, 6268.968655156729, 6305.6847157642105, 6316.368363163681, 6400.679966001699, 6331.873362532747, 6338.556301058916, 6345.809625711225, 6359.872802543946, 6392.296847346788, 5594.356648985357, 5667.773289068432, 5683.715814209283, 5689.4879460848515, 5718.285214348207, 5749.453801888814, 5776.971115144418, 5789.681567513785, 5790.0, 5844.123381492767, 5751.166080918264, 5769.7115144242825, 5783.392743761908, 5828.388019257981, 5835.941640583582, 5293.682379057269, 5534.778608855641, 5659.801906933238, 5664.065429204176, 5706.572007099484, 5658.122990936411, 5667.688277144755, 5706.77172913083, 5768.78855440358, 5772.538196944234, 6306.905396419057, 6313.526485513605, 6319.4944404448, 6378.649174295412, 6388.872222555569, 6735.393814556664, 6756.695948682321, 6783.457323414115, 6784.287649796799, 6793.5244532882225, 6812.4550035997, 6832.863342733118, 6840.110785597841, 6844.28135045823, 6844.965775171142, 6375.968120159416, 6382.808515744535, 6388.744450221986, 6429.260635026985, 6438.645874476902, 6915.135608048994, 6981.3367730065565, 7089.291070892869, 7136.892946605805, 7168.068151140423, 6695.296993815677, 6778.898316525256, 6792.8301792454595, 6798.354156355138, 6845.075914751545, 6850.417714494324, 6876.346747059021, 6930.237673855871, 6933.93066069343, 6947.89578156328, 6717.126773519509, 6759.932400676029, 6779.72881084751, 6807.795766127024, 6858.348456896587, 6782.830429239309, 6799.864002720017, 6817.727290908408, 6871.244163142051, 6883.346082122191, 4906.411230652273, 4921.434035085939, 4999.925001124986, 5037.848864534069, 5066.746662666852, 4507.301368287921, 4576.725396476222, 4593.977030114828, 4600.401947746772, 4617.0, 6131.417515336036, 6150.538709596798, 6167.814965551041, 6219.626822390671, 6246.312905580383, 6648.368405001517, 6706.0, 6748.561343512656, 6796.694148763318, 6811.182658081062, 6273.466755325849, 6312.747490100436, 6321.494280457552, 6343.0485427186295, 6352.809415717536, 5474.808381706621, 5532.695701736443, 5542.057850165482, 5548.195511650788, 5552.972235138879, 6826.283240259801, 6838.14523184602, 6859.657017149123, 6897.241303456616, 6899.137607799025, 6636.170478690164, 6642.368974947372, 6655.700493477708, 6656.0348749430095, 6665.466762658879, 6946.5137440378685, 6950.270221626859, 6956.513044087068, 6966.9651651741415, 6971.58170509771, 6560.44236239925, 6635.402813746593, 6639.136912201383, 6643.634600097041, 6646.867062658816, 6722.2605513394465, 6765.627890466071, 6787.728490860508, 6807.5575087619145, 6808.7276508939085, 6560.507961902972, 6564.409203171737, 6582.275949645439, 6664.166979127609, 6666.0, 6272.153259309935, 6351.333110023381, 6359.650219237987, 6564.442022427956, 6652.301508341555, 6352.428281454691, 6380.042993550819, 6405.487560995202, 6416.550841441054, 6417.973124300276, 6612.404883560503, 6642.771087348856, 6653.2348779889, 6658.866822663616, 6680.465562754871, 6351.777687780906, 6365.96817015912, 6401.487880969605, 6453.870922581616, 6498.805035848932], "value": 6211.6268503447445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [962.6438217859388, 981.4749109226562, 1011.5650270383734, 1013.1894484412468, 1017.3031473440689, 42708.35937460937, 43345.0, 43747.34378984318, 43772.56227437725, 43868.34197487036, 41131.177376452455, 42875.78562107185, 43934.34098488525, 43978.34032489507, 44048.339274910904, 8432.789180270482, 8517.148285171495, 8577.142285771404, 8601.096884827066, 8601.225889669928, 8356.0, 8445.704400345989, 8475.237228649421, 8496.532690702008, 8510.957445212765, 8495.702650407236, 8523.57382130894, 8534.487930724177, 8546.487210767344, 8580.528070956096, 8415.83168336636, 8428.030776460693, 8459.577021148949, 8478.406511544194, 8486.405951583392, 8325.708600199025, 8346.916530834735, 8375.958120209421, 8387.580620968989, 8406.579671016425, 40713.79643101795, 40796.59203407987, 40909.590904090874, 40946.0, 41164.58835411638, 3728.067983004249, 3734.925301493955, 3751.3435148848844, 3752.080740218664, 3762.9811850940846, 33500.66499335, 33528.32943341144, 33621.49567756485, 33657.66342336594, 33853.49219761681, 5385.3268341457315, 5410.675359478444, 5427.185922111676, 5453.536449401766, 5548.0845660465975, 207322.0, 210844.0, 216044.0, 218041.0, 233106.9999999983, 53013.46986530085, 53484.46515534873, 53544.73227633837, 53590.196147057824, 53654.0, 55630.0, 55700.44299557034, 55743.72128139334, 55783.16325255125, 55796.72101639547, 17236.052017139176, 17313.3940312091, 17348.479545613653, 17375.82624173742, 17432.738508922375, 14347.78478322826, 14358.0, 14360.928195358956, 14362.569122926328, 14362.784558231635, 14427.927860360842, 14500.419983200554, 14506.911981601212, 14577.781333280009, 14600.85399145995, 5630.577706672016, 5639.492445679908, 5716.942830571641, 5856.407131189703, 5917.52659787216, 1103.2056919018294, 1105.2208193223757, 1117.0, 1123.5337335006045, 1138.5502726423017, 25661.3584660385, 25792.226233213034, 25810.09664661728, 25880.482390352463, 25947.740522594533, 1209.8366720492797, 1238.795598726218, 1243.3720970909815, 1248.15125714515, 1248.6004478566954, 13512.0, 13596.59210223695, 13607.319634018457, 13653.0, 13670.931645341909, 288044.0, 289461.0, 289678.0, 289713.0, 290500.0, 56388.43611563832, 56484.0, 56527.0, 56607.0, 56654.7167264161, 1400.66384067823, 1525.4981829799137, 1538.100211376335, 1560.4382422327953, 1568.7019466301367, 25149.24552263435, 25351.366215844013, 25359.746402535737, 25479.0, 25737.356566085997, 35530.467042993856, 35577.82211088928, 35629.64370356263, 35642.64357356497, 35667.286654267285, 240476.0, 240557.0, 240944.0, 241315.0, 241730.0, 36296.0, 37274.627253727114, 38039.61960380361, 38041.80979095198, 38261.0, 47721.0, 49287.75356123196, 49732.502674972784, 49812.501874982234, 50054.249186261506, 36344.63655363518, 36615.98549309514, 36677.44983825298, 37053.629463705016, 37448.62551374451, 36003.27993440064, 36165.0, 36419.27161456809, 36734.265314693024, 37005.81497092605, 40785.0, 41373.0, 41585.168296633296, 41750.0, 41982.37026444667, 34981.12547186239, 35100.82449587736, 35199.47200792041, 35255.82372088123, 35524.28951421009, 25934.610980834925, 26251.868740656173, 26408.339791505365, 26507.469850603266, 26663.20010399691], "value": 18828.229207531087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.4105515012564, 615.7013848283582, 615.7967870602702, 643.9420452159305, 654.6268626882676, 23561.293161205147, 23925.880370598145, 24933.50132997339, 24977.50044999099, 24991.75008249917, 25037.624435633435, 26225.47549049018, 26243.3439164021, 26252.868735656342, 26328.60507092395, 6602.405783479486, 6746.291639377869, 6794.796156115324, 6797.694103765342, 6799.660016999155, 6532.5753826001155, 6823.7611683591085, 6830.59016459014, 6836.487263455236, 6848.109745733048, 6785.050092986986, 6801.489888258376, 6801.591904485722, 6802.591844489347, 6806.047153398528, 6547.934520654804, 6769.356911093463, 6772.322767723237, 6836.4530837532875, 6838.7264509419565, 6385.872282554323, 6390.0, 6390.6485143317095, 6392.296847346788, 6400.519961002897, 29944.70055299441, 30388.54417183744, 30396.392072158433, 30448.238794030105, 30505.542416863766, 2248.6064938635673, 2265.173211777689, 2265.988670056656, 2273.7612550682106, 2274.0562666493383, 20062.197512099498, 21438.14247430101, 21556.245531406326, 21559.676604850938, 21583.46041348979, 2548.808839337057, 2591.2614904752054, 2631.4868600622885, 2655.0574546036073, 2657.0833062593374, 144834.27582862123, 150113.0, 165716.0, 169668.0, 172545.0, 15102.666380442024, 15835.762463563056, 16277.348906043626, 16494.34022639105, 16660.833391666172, 24189.51620967571, 24235.636465453033, 24292.878535607208, 24352.75647243505, 24435.755642443703, 10607.84088238677, 10888.40113793749, 10905.945470272598, 10945.781084378426, 10976.835347469794, 9848.901510984942, 9887.851682224773, 10046.191685496535, 10107.69676909694, 10195.388276703421, 9440.622375104918, 9461.952690236505, 9488.905110948941, 9841.409515429097, 9955.303128780915, 2945.926351841178, 2959.955600665992, 3022.5012872875727, 3025.4251692178414, 3132.1699749566383, 1056.6037735849056, 1066.157735389034, 1071.239420011785, 1071.4214324264965, 1074.3446497636435, 21683.56632867334, 21757.347279581638, 21932.0, 22117.889410553165, 22187.66718499224, 1116.4194618798178, 1117.636768050387, 1129.8926601972798, 1136.1365362324693, 1151.9654410367702, 11936.820947685792, 12120.636380908587, 12140.514379424723, 12176.878231217574, 12211.0, 613907.0, 628975.0, 645069.0, 656766.0, 659289.0, 134842.0, 136253.0, 138947.30526347304, 139200.0, 140771.29614351862, 1226.871178526242, 1227.3433712963522, 1265.1017777378165, 1272.5609664665676, 1278.9168704034394, 19131.90434047821, 19394.903025484782, 19477.902610486857, 19510.21959121619, 19687.114079866726, 67867.0, 68226.0, 68296.317036829, 68477.65761171162, 68558.65720671364, 86182.0, 90689.54655226681, 91796.54101729448, 92234.0, 92376.53811730898, 15894.841051589336, 16136.67726645437, 16181.190940452694, 16226.594335141715, 16261.0, 24194.0, 24381.51236975286, 24405.755942441057, 24528.01887924463, 24665.260042198763, 19683.606327873076, 19772.30796922101, 19794.20823167115, 19921.501962451053, 19991.000449977153, 16889.662206756042, 17463.650726985135, 17520.649587008444, 17825.10874456246, 18286.908565457088, 19208.327708530695, 20398.898005509876, 20508.589828203054, 20616.48458788542, 20628.79371206269, 15709.764353534478, 15841.68316633684, 15953.8404615957, 15957.122358269942, 15965.201739913191, 19497.220111195395, 19590.90204548968, 19814.60370792605, 19829.504262393555, 19918.601627967648], "value": 13067.149722194548, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.6033930329859, 1496.6034000989732, 1497.7229212595678, 1512.5916002679278, 1514.4018112845426, 17102.914485427573, 17777.644447111066, 17788.73316900246, 17803.64392712145, 17811.910940445312, 17149.82850171498, 17249.396271130514, 17283.0, 17300.653986920253, 17308.307667693276, 2716.578930265812, 2716.714744951781, 2768.1695491352616, 2769.3076730817297, 2772.50094982903, 2646.1003258891974, 2670.172246603559, 2675.8394496330184, 2687.556553168725, 2689.905853295135, 2802.201372608807, 2911.1557648281973, 2913.5775312579663, 2921.926951826211, 2925.0639795265524, 2819.0415258812104, 2838.162741991097, 2847.0889315419076, 2881.8270903745843, 2882.8846846126235, 2688.0591792872337, 2873.0, 2895.391967686788, 2899.0, 2900.912972610804, 16976.490705278862, 17226.56933576658, 17239.138043097795, 17251.13744312792, 17251.30994760208, 8720.563971801384, 8988.77528061797, 9002.0, 9018.278537716968, 9034.00625931147, 5380.973095134499, 5517.862053448655, 5557.277553918006, 5720.856978575527, 5816.738246778905, 1974.2442268539075, 1979.990100049505, 1988.5923385705896, 1996.650586147433, 2018.6265540874986, 31222.843885780654, 31485.685143148505, 31518.684813152035, 31548.0, 31665.36669266602, 7317.231690672509, 7333.88999165013, 7349.889751653729, 7471.626418679044, 7491.363234125051, 20589.897050514952, 20600.072996715186, 20646.380608581767, 20707.378778636667, 20809.0, 8696.782580435412, 8932.508712020772, 8954.910450895539, 8959.328050396247, 8963.596638151299, 7988.121306656263, 7990.4007199460275, 8117.756467305991, 8140.430169888169, 8167.71413000554, 5641.971790141105, 5818.854528636733, 5860.2381690380835, 5891.587588868823, 5898.8230353089475, 1727.421313859871, 1749.1691446562781, 1754.3859649122705, 1778.1460755487515, 1781.8218178182337, 962.8266911955915, 983.0759086458706, 990.4354517924713, 996.4419924841967, 1021.0096206679509, 8494.957525212334, 8533.274671652978, 8613.052564217931, 8700.477971321614, 8759.912400876037, 783.4359261331832, 806.9757907262791, 807.2936180841764, 813.3615112136944, 827.6978902700591, 5319.707416092152, 5406.78372865081, 5494.011078005998, 5590.91613625796, 5675.631083979528, 24504.632430513557, 25656.358591035, 26097.0, 26100.73899261021, 26209.0, 24857.378565535644, 24949.750502495106, 25408.0, 25427.74572254254, 25528.872355638105, 12934.67663308406, 12972.462001542252, 12984.285864277555, 13119.540816071392, 13190.208587484589, 9026.368154229142, 9088.59101340428, 9140.497272650067, 9171.77070573241, 9231.353805233837, 21463.46341341479, 21753.238636647642, 21847.453813654785, 21985.0, 22205.77794222037, 22301.0, 22442.551148977254, 22610.660840087083, 22619.660705089107, 22995.88502057479, 12862.67843303925, 13099.345032748515, 13112.803307950198, 13114.082014259291, 13115.081944264191, 15602.531924042298, 15772.763408548652, 15835.366585336458, 15892.68214635724, 16063.518094457186, 12188.146829722189, 12191.390430478263, 12497.625071247878, 12576.748465030465, 12665.430055647334, 12656.430460629477, 12883.742325153631, 13040.152390094614, 13072.5424610142, 13239.536616218385, 11285.435728213391, 11352.148588856035, 11429.199956003, 11440.199186056896, 11446.313221206754, 8063.798405039921, 8116.107228204774, 8162.510249385056, 8250.133735957637, 8272.17278272168, 8384.203500667427, 8391.790205244917, 8422.157784221774, 8455.196756308142, 8464.153584641734], "value": 7383.012857388655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.7077284425676, 574.5174053794813, 577.2524580668035, 585.5257241634276, 586.9735861886214, 6297.874042519158, 6330.4935605151595, 6377.52168587356, 6441.420272175504, 6482.448991835694, 6191.597546159508, 6278.1838361013115, 6279.8116056518265, 6338.809835704937, 6353.587016843902, 5148.485151484859, 5187.844364669066, 5191.195364718475, 5203.869903252411, 5208.322918020671, 5116.411612664554, 5117.897642047175, 5137.255098010786, 5203.219517072451, 5241.711705856176, 5167.199084141946, 5210.0, 5250.553702935255, 5266.104762190418, 5271.762770675309, 5186.455422180655, 5202.401723801736, 5212.192110222922, 5254.21186821976, 5281.841544753664, 4785.617150627942, 4844.612431005511, 4856.3201151838775, 4899.779509922062, 4936.481669424695, 6202.906956395657, 6225.159603453521, 6231.813045608594, 6248.0, 6279.309275979638, 6125.816225513242, 6217.409346112112, 6251.687415629246, 6256.15541901842, 6295.937040629581, 6826.658667066627, 6827.69275382604, 6885.449164066863, 6918.257845987869, 6926.238113807477, 5532.446755324476, 5541.168824676291, 5555.138953462219, 5561.360443549003, 5616.297962754656, 6872.896906546406, 6915.412189963859, 7049.577025378442, 7147.03456549453, 7147.142342918883, 5674.4892959633835, 5688.146777983336, 5728.79949201776, 5733.827985160452, 5734.39788822176, 5773.538116950718, 5797.130430435468, 5809.477147056785, 5862.443067908542, 5868.823935281863, 6091.939080609226, 6124.755009799647, 6144.569880108439, 6247.687615619201, 6258.15514905491, 6794.8641027180165, 6800.829979250559, 6814.284500127415, 6843.965780171067, 6914.412274956584, 2008.1565742388232, 2016.354766474714, 2023.9190432382834, 2031.2788959919017, 2039.1435597049274, 1461.671123997092, 1529.724649563067, 1530.5867415797895, 1549.1402271739294, 1570.418944990338, 6250.187445640804, 6267.1539342188225, 6280.623162610258, 6314.368563143649, 6328.46208072319, 6772.5936443813525, 6778.864422711518, 6789.25318214996, 6835.863282734317, 6895.689693963784, 6205.069239614004, 6228.813135605939, 6233.96883015582, 6237.662850375028, 6278.780242691484, 3554.7689400188906, 3621.402468592653, 3637.8544858205905, 3656.2687462507606, 3657.0, 7144.392726618192, 7171.81665025265, 7184.5330053547395, 7196.352328290374, 7217.566945983152, 7008.544444611084, 7052.753153639598, 7062.552176803743, 7077.964610176916, 7088.184858741208, 6925.238223795379, 7061.9293807062295, 7110.515847234921, 7111.53775004623, 7191.280871912767, 6842.247352791289, 6876.277990810893, 6907.6891539879825, 6922.5154239204785, 6948.513604047668, 6772.492063095189, 6785.5250132490255, 6792.93207067923, 6794.320567943166, 6803.285655006351, 7068.222495525591, 7092.219855815753, 7102.893456598052, 7180.779267524429, 7194.532355396883, 5672.177534257427, 5724.971375143097, 5742.138679197987, 5748.971255143698, 5749.1663708762835, 6490.643014634241, 6639.170103737033, 6857.27998560144, 6864.347886950732, 6958.965205173941, 6619.106420633346, 6710.0, 6716.3955244028275, 6742.325767423415, 6747.493937954575, 6654.633995130314, 6676.465882729273, 6696.430803381572, 6697.129373181455, 6743.66281685904, 6376.298607153302, 6506.837329066811, 6507.284198738041, 6529.314421985623, 6552.901706474311, 6692.39768420825, 6730.192376914802, 6752.3922846944015, 6777.322267773183, 6785.9660701696175, 6500.577462464925, 6508.251551071688, 6571.507136964842, 6577.934220657732, 6582.868342633216], "value": 5389.6625882302405, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [970.8252514547379, 986.6152200641749, 998.560633321339, 1004.6232662751469, 1015.4463670584005, 21517.4620634484, 21992.340229793113, 22055.889720551415, 22057.669134962987, 22059.66910496344, 18017.63964720705, 18098.366557170502, 18100.275988960424, 19399.515012124702, 20441.080151393187, 1049.826778581535, 1077.159815344033, 1078.6170909327193, 1078.9190810689229, 1081.4214395298518, 1062.904338609525, 1085.5060947268973, 1086.239632257419, 1090.983635245472, 1099.8900109988977, 1147.7073346296684, 1160.7214268575565, 1161.5818305410046, 1161.6224726963728, 1162.3665102519146, 1135.517405102828, 1141.697450175704, 1142.1148609827367, 1148.8736239013701, 1149.8160294352906, 837.3343192162246, 846.6063280574501, 847.635516727813, 847.9957600212022, 851.6635928808087, 20983.685244721342, 21556.137754489802, 21798.0, 21827.23604673829, 21891.452713682287, 23438.531229375487, 23791.76208237913, 23801.523969520687, 23941.880290598434, 24194.0, 17171.742423863652, 17461.65076698459, 17652.02913839738, 17688.380906668335, 17699.0, 3976.522817261918, 4022.5776293489057, 4034.2536630723116, 4043.595640435933, 4043.878683639466, 157174.0, 160691.0, 166851.1657441717, 167264.99999999878, 168320.0, 18398.724019139725, 18760.437186884414, 18798.530036748918, 18881.433556993314, 18910.62178756417, 34400.139996500286, 35005.82497087498, 35054.64945350565, 35121.64878351235, 35352.823235883654, 9860.556274967643, 9877.209823214125, 9881.357711748713, 9956.303058785816, 10051.0, 4753.382060332204, 4835.492273311322, 4844.515548445198, 4862.124817532878, 4869.780859861244, 6206.510437494969, 6258.9374106259265, 6265.780697675559, 6280.309165991737, 6296.244450665949, 1252.2361359570837, 1271.4786937355639, 1274.3182397417338, 1282.762688902556, 1292.2246652008907, 709.0923617769264, 715.6815217228284, 720.6648908257581, 727.0838743183626, 740.9851802964018, 11472.139589530818, 11924.523019079139, 12058.336791476377, 12087.0, 12223.388830558615, 716.5485743981259, 716.9462290328246, 717.9138503379628, 727.1710250314645, 737.1080991999634, 5794.209780721855, 5832.212651292106, 5865.395664403422, 5971.880562388728, 6043.214382130383, 158841.31561455273, 159474.0, 160349.0, 162345.0, 163763.26828561182, 145915.0, 146502.0, 147112.0, 147591.0, 147852.26073869562, 123720.38139809243, 124902.3754881238, 126220.0, 127423.0, 127430.0, 11170.888291116984, 11333.659990200307, 11460.0, 11489.712757180803, 11668.533258669897, 58863.822723544436, 59788.402115978286, 60074.39925600688, 60077.0, 61502.69248653728, 72970.0, 74454.25545744473, 74463.6276818634, 74465.0, 74548.62725686337, 16224.513264602081, 16345.67308653844, 16370.918145409196, 16397.918010410347, 16503.0, 19111.33110341187, 19120.52198694988, 19493.317733879245, 19591.70612440843, 19660.901695492004, 14877.47928822486, 14977.08405179926, 14979.850201497846, 15058.171800550637, 15234.162121083447, 17035.574110646838, 17460.82539174592, 17500.649987000445, 17527.298908043536, 17691.115444227995, 17722.0, 17847.286108555512, 17884.55288617795, 17964.19161137778, 17976.550586235448, 12184.634460966185, 12324.32216228116, 12497.25016499013, 12624.11631185845, 12645.620631381073, 12419.379031048593, 12656.430460629477, 12700.618981430573, 12715.87284127184, 12747.61757147287], "value": 10016.316091219343, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1358.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 105.0, 105.0, 105.0, 107.0, 108.0, 107.0, 107.0, 105.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 111.0, 111.0, 114.0, 113.0, 113.0, 143.0, 113.0, 115.0], "value": 115.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 160.0, 161.0, 161.0, 159.0, 161.0, 164.0, 167.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 73.0, 73.0, 74.0, 79.0, 72.0, 74.0, 73.0, 72.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 84.0, 82.0, 78.0, 85.0, 82.0, 82.0, 83.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 111.0, 111.0, 111.0, 119.0, 112.0, 117.0, 112.0, 111.0, 111.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 148.0, 149.0, 149.0, 148.0, 150.0, 148.0, 150.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 43.0, 48.0, 44.0, 42.0, 43.0, 43.0, 43.0, 43.0, 44.0], "value": 44.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 36.0, 35.0, 37.0, 37.0, 36.0, 36.0, 36.0, 39.0, 36.0], "value": 36.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 70.0, 69.0, 69.0, 71.0, 71.0, 72.0, 70.0, 71.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 107.0, 106.0, 106.0, 109.0, 99.0, 105.0, 104.0, 101.0, 108.0], "value": 104.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 147.0, 151.0, 154.0, 148.0, 151.0, 147.0, 147.0, 149.0, 149.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 58.0, 58.0, 59.0, 58.0, 59.0, 61.0, 60.0, 58.0], "value": 58.7, "unit": "score"}]}]} 04:20:44 INFO - Return code: 0 04:20:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:20:44 INFO - # TBPL SUCCESS # 04:20:44 INFO - Running post-action listener: _package_coverage_data 04:20:44 INFO - Running post-action listener: _resource_record_post_action 04:20:44 INFO - [mozharness: 2017-10-01 11:20:44.476845Z] Finished run-tests step (success) 04:20:44 INFO - Running post-run listener: _resource_record_post_run 04:20:44 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 329986048; Read time: 0; Write time: 294696 04:20:44 INFO - TinderboxPrint: CPU usage
13.1% 04:20:44 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:20:44 INFO - TinderboxPrint: I/O write bytes / time
329,986,048 / 294,696 04:20:44 INFO - TinderboxPrint: CPU idle
8,219.8 (86.9%) 04:20:44 INFO - TinderboxPrint: CPU user
1,188.0 (12.6%) 04:20:44 INFO - TinderboxPrint: Swap in / out
0 / 0 04:20:44 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12787712; Read time: 0; Write time: 1408 04:20:44 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:20:44 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 0; Write bytes: 316174336; Read time: 0; Write time: 293264 04:20:45 INFO - Running post-run listener: _upload_blobber_files 04:20:45 INFO - Blob upload gear active. 04:20:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:20:45 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 04:20:45 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:20:45 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:20:45 INFO - (blobuploader) - INFO - Open directory for files ... 04:20:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:20:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:20:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:20:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:20:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:20:46 INFO - (blobuploader) - INFO - Done attempting. 04:20:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:20:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:20:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:20:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:20:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:20:47 INFO - (blobuploader) - INFO - Done attempting. 04:20:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:20:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:20:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:20:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:20:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:20:48 INFO - (blobuploader) - INFO - Done attempting. 04:20:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:20:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:20:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:20:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:20:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:20:54 INFO - (blobuploader) - INFO - Done attempting. 04:20:54 INFO - (blobuploader) - INFO - Iteration through files over. 04:20:54 INFO - Return code: 0 04:20:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:20:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:20:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92aadc588c2c6585fa1bd4f1fba3c56eb23b0a9c05dced486c4c84aa5e7fbd32056ad1f183ef612874dce006247ab6bafc3d0f2101daf9b9cdc8f3a210898450", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/add4b423f692035667e24f445d8611f7645621ad3703b1017c75e517faee5687751e3ca2f2d6402c3cf5dc6a136c9566a158036dcf50cce6e939f88e846f7e17", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3333f73111edec033a7998869798e8fbe7ae53754f71a04fa0c4c3afb54d33e575fb9b49d61671855d30755207b4915c51a910c5c9a418c4087a3d387833c7a2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a834a1a6f7b90d8dc952271a5eee34e23c80594f4d0c03062b92f87db55629b9f660365d6bd6ebd8a8117082f900e39757dc75e1585bdf775406a82bbde64a87"} 04:20:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:20:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:20:54 INFO - Contents: 04:20:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92aadc588c2c6585fa1bd4f1fba3c56eb23b0a9c05dced486c4c84aa5e7fbd32056ad1f183ef612874dce006247ab6bafc3d0f2101daf9b9cdc8f3a210898450", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/add4b423f692035667e24f445d8611f7645621ad3703b1017c75e517faee5687751e3ca2f2d6402c3cf5dc6a136c9566a158036dcf50cce6e939f88e846f7e17", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3333f73111edec033a7998869798e8fbe7ae53754f71a04fa0c4c3afb54d33e575fb9b49d61671855d30755207b4915c51a910c5c9a418c4087a3d387833c7a2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a834a1a6f7b90d8dc952271a5eee34e23c80594f4d0c03062b92f87db55629b9f660365d6bd6ebd8a8117082f900e39757dc75e1585bdf775406a82bbde64a87"} 04:20:54 INFO - Running post-run listener: copy_logs_to_upload_dir 04:20:54 INFO - Copying logs to upload dir... 04:20:54 INFO - mkdir: /builds/slave/test/build/upload/logs 04:20:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.119062 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-01 04:20:55.002232) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 04:20:55.009343) ========= 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=1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92aadc588c2c6585fa1bd4f1fba3c56eb23b0a9c05dced486c4c84aa5e7fbd32056ad1f183ef612874dce006247ab6bafc3d0f2101daf9b9cdc8f3a210898450", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/add4b423f692035667e24f445d8611f7645621ad3703b1017c75e517faee5687751e3ca2f2d6402c3cf5dc6a136c9566a158036dcf50cce6e939f88e846f7e17", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3333f73111edec033a7998869798e8fbe7ae53754f71a04fa0c4c3afb54d33e575fb9b49d61671855d30755207b4915c51a910c5c9a418c4087a3d387833c7a2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a834a1a6f7b90d8dc952271a5eee34e23c80594f4d0c03062b92f87db55629b9f660365d6bd6ebd8a8117082f900e39757dc75e1585bdf775406a82bbde64a87"} build_url:https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/LTVQZ2y-So2WoMe30iGkIw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92aadc588c2c6585fa1bd4f1fba3c56eb23b0a9c05dced486c4c84aa5e7fbd32056ad1f183ef612874dce006247ab6bafc3d0f2101daf9b9cdc8f3a210898450", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/add4b423f692035667e24f445d8611f7645621ad3703b1017c75e517faee5687751e3ca2f2d6402c3cf5dc6a136c9566a158036dcf50cce6e939f88e846f7e17", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3333f73111edec033a7998869798e8fbe7ae53754f71a04fa0c4c3afb54d33e575fb9b49d61671855d30755207b4915c51a910c5c9a418c4087a3d387833c7a2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a834a1a6f7b90d8dc952271a5eee34e23c80594f4d0c03062b92f87db55629b9f660365d6bd6ebd8a8117082f900e39757dc75e1585bdf775406a82bbde64a87"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 04:20:55.039598) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 04:20:55.040040) ========= 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=1258088b6da462a47ba2cd90000001a2-1506854829.361085-1871468546 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 04:20:55.120826) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 04:20:55.121141) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 04:20:55.121436) ========= ========= Total master_lag: 0.21 =========