builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-014 starttime: 1505913270.46 results: success (0) revision: a20de99fa3c1ba6287fe47d493a859a4e95120b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:30.463137) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:30.463569) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:30.463855) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:30.501260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:30.501513) ========= 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=31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 06:14:30-- 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.1M=0s 2017-09-20 06:14:30 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117326 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:30.635467) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:30.635836) ========= 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=31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015502 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:30.705347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:30.705631) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a20de99fa3c1ba6287fe47d493a859a4e95120b0 --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 a20de99fa3c1ba6287fe47d493a859a4e95120b0 --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=31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 06:14:30,754 truncating revision to first 12 chars 2017-09-20 06:14:30,754 Setting DEBUG logging. 2017-09-20 06:14:30,754 attempt 1/10 2017-09-20 06:14:30,754 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a20de99fa3c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 06:14:31,145 unpacking tar archive at: mozilla-central-a20de99fa3c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.729794 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:31.451765) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:31.452079) ========= script_repo_revision: a20de99fa3c1ba6287fe47d493a859a4e95120b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:31.452450) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:31.452707) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 06:14:31.466262) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-09-20 06:14:31.466627) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:14:31 INFO - MultiFileLogger online at 20170920 06:14:31 in /builds/slave/test 06:14:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 06:14:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:14:31 INFO - {'append_to_log': False, 06:14:31 INFO - 'base_work_dir': '/builds/slave/test', 06:14:31 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 06:14:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:14:31 INFO - 'branch': 'Firefox-Non-PGO', 06:14:31 INFO - 'buildbot_json_path': 'buildprops.json', 06:14:31 INFO - 'code_coverage': False, 06:14:31 INFO - 'config_files': ('talos/linux_config.py',), 06:14:31 INFO - 'default_actions': ('clobber', 06:14:31 INFO - 'read-buildbot-config', 06:14:31 INFO - 'download-and-extract', 06:14:31 INFO - 'populate-webroot', 06:14:31 INFO - 'create-virtualenv', 06:14:31 INFO - 'install', 06:14:31 INFO - 'setup-mitmproxy', 06:14:31 INFO - 'run-tests'), 06:14:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:14:31 INFO - 'disable_ccov_upload': False, 06:14:31 INFO - 'disable_stylo': False, 06:14:31 INFO - 'download_minidump_stackwalk': True, 06:14:31 INFO - 'download_symbols': 'ondemand', 06:14:31 INFO - 'enable_stylo': False, 06:14:31 INFO - 'enable_webrender': False, 06:14:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 06:14:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:14:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:14:31 INFO - 'gecko_profile': False, 06:14:31 INFO - 'gecko_profile_interval': 0, 06:14:31 INFO - 'installer_path': 'installer.exe', 06:14:31 INFO - 'log_level': 'info', 06:14:31 INFO - 'log_name': 'talos', 06:14:31 INFO - 'log_to_console': True, 06:14:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:14:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:14:31 INFO - 'opt_config_files': (), 06:14:31 INFO - 'pip_index': False, 06:14:31 INFO - 'suite': 'dromaeojs-e10s', 06:14:31 INFO - 'system_bits': '32', 06:14:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:14:31 INFO - 'title': 'talos-linux64-ix-014', 06:14:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:14:31 INFO - 'use_talos_json': True, 06:14:31 INFO - 'verify': 'False', 06:14:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:14:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:14:31 INFO - 'work_dir': 'build'} 06:14:31 INFO - [mozharness: 2017-09-20 13:14:31.660011Z] Running clobber step. 06:14:31 INFO - Running pre-action listener: _resource_record_pre_action 06:14:31 INFO - Running main action method: clobber 06:14:31 INFO - rmtree: /builds/slave/test/build 06:14:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:14:32 INFO - Running post-action listener: _resource_record_post_action 06:14:32 INFO - [mozharness: 2017-09-20 13:14:32.664209Z] Finished clobber step (success) 06:14:32 INFO - [mozharness: 2017-09-20 13:14:32.664307Z] Running read-buildbot-config step. 06:14:32 INFO - Running pre-action listener: _resource_record_pre_action 06:14:32 INFO - Running main action method: read_buildbot_config 06:14:32 INFO - Using buildbot properties: 06:14:32 INFO - { 06:14:32 INFO - "project": "", 06:14:32 INFO - "product": "firefox", 06:14:32 INFO - "who": "archaeopteryx@coole-files.de", 06:14:32 INFO - "installer_path": "public/build/target.tar.bz2", 06:14:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 06:14:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 06:14:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 06:14:32 INFO - "stage_platform": "linux64", 06:14:32 INFO - "basedir": "/builds/slave/test", 06:14:32 INFO - "buildnumber": 1162, 06:14:32 INFO - "platform": "ubuntu64_hw", 06:14:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:14:32 INFO - "slavebuilddir": "test", 06:14:32 INFO - "taskId": "Joa0Vc8sTKKxUtMxtWP4pw", 06:14:32 INFO - "branch": "mozilla-central", 06:14:32 INFO - "script_repo_revision": "production", 06:14:32 INFO - "revision": "a20de99fa3c1ba6287fe47d493a859a4e95120b0", 06:14:32 INFO - "slavename": "talos-linux64-ix-014", 06:14:32 INFO - "repo_path": "mozilla-central" 06:14:32 INFO - } 06:14:32 INFO - Finding installer, test and symbols from parent task. 06:14:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Joa0Vc8sTKKxUtMxtWP4pw'}, attempt #1 06:14:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Joa0Vc8sTKKxUtMxtWP4pw'}, attempt #1 06:14:33 INFO - Task dependencies: HBUujFfqTheR_yOD7KSOzg 06:14:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg'}, attempt #1 06:14:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.tar.bz2 06:14:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.test_packages.json 06:14:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.crashreporter-symbols.zip 06:14:34 INFO - Running post-action listener: _resource_record_post_action 06:14:34 INFO - [mozharness: 2017-09-20 13:14:34.014868Z] Finished read-buildbot-config step (success) 06:14:34 INFO - [mozharness: 2017-09-20 13:14:34.015084Z] Running download-and-extract step. 06:14:34 INFO - Running pre-action listener: _resource_record_pre_action 06:14:34 INFO - Running main action method: download_and_extract 06:14:34 INFO - mkdir: /builds/slave/test/build/tests 06:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:34 INFO - trying https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.test_packages.json 06:14:34 INFO - Downloading https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:14:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:14:34 INFO - Downloaded 989 bytes. 06:14:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:14:34 INFO - Using the following test package requirements: 06:14:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 06:14:34 INFO - u'common': [u'target.common.tests.zip'], 06:14:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:14:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:14:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:14:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:14:34 INFO - u'mozbase': [u'target.common.tests.zip'], 06:14:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:14:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:14:34 INFO - u'web-platform': [u'target.common.tests.zip', 06:14:34 INFO - u'target.web-platform.tests.tar.gz'], 06:14:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:14:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 06:14:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.common.tests.zip 06:14:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:14:34 INFO - Fetch https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.common.tests.zip into memory 06:14:35 INFO - Content-Length response header: 54731844 06:14:35 INFO - Bytes received: 54731844 06:14:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.talos.tests.zip 06:14:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:14:46 INFO - Fetch https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.talos.tests.zip into memory 06:14:46 INFO - Content-Length response header: 14508893 06:14:46 INFO - Bytes received: 14508893 06:14:47 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')]} 06:14:47 INFO - trying https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.tar.bz2 06:14:47 INFO - Downloading https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:14:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:14:49 INFO - Downloaded 61677877 bytes. 06:14:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.tar.bz2 06:14:49 INFO - mkdir: /builds/slave/test/properties 06:14:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:14:49 INFO - Writing to file /builds/slave/test/properties/build_url 06:14:49 INFO - Contents: 06:14:49 INFO - build_url:https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.tar.bz2 06:14:49 INFO - Running post-action listener: _resource_record_post_action 06:14:49 INFO - Running post-action listener: find_tests_for_verification 06:14:49 INFO - Running post-action listener: set_extra_try_arguments 06:14:49 INFO - [mozharness: 2017-09-20 13:14:49.181541Z] Finished download-and-extract step (success) 06:14:49 INFO - [mozharness: 2017-09-20 13:14:49.181675Z] Running populate-webroot step. 06:14:49 INFO - Running pre-action listener: _resource_record_pre_action 06:14:49 INFO - Running main action method: populate_webroot 06:14:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:14:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['tresize', 06:14:49 INFO - 'tcanvasmark']}, 06:14:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:14:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['dromaeo_css', 06:14:49 INFO - 'kraken']}, 06:14:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:14:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:14:49 INFO - 'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:14:49 INFO - 'tests': ['damp', 'tps']}, 06:14:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:14:49 INFO - 'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['damp', 'tps']}, 06:14:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:14:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['dromaeo_dom']}, 06:14:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:14:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['basic_compositor_video', 06:14:49 INFO - 'glvideo']}, 06:14:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:14:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:14:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:14:49 INFO - 'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['ts_paint_webext', 06:14:49 INFO - 'tp5o_webext']}, 06:14:49 INFO - 'other-e10s': {'tests': ['a11yr', 06:14:49 INFO - 'ts_paint', 06:14:49 INFO - 'tpaint', 06:14:49 INFO - 'sessionrestore', 06:14:49 INFO - 'sessionrestore_many_windows', 06:14:49 INFO - 'sessionrestore_no_auto_restore', 06:14:49 INFO - 'tabpaint']}, 06:14:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['a11yr', 06:14:49 INFO - 'ts_paint', 06:14:49 INFO - 'tpaint', 06:14:49 INFO - 'sessionrestore', 06:14:49 INFO - 'sessionrestore_many_windows', 06:14:49 INFO - 'sessionrestore_no_auto_restore', 06:14:49 INFO - 'tabpaint']}, 06:14:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 06:14:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 06:14:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['perf_reftest_singletons']}, 06:14:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['bloom_basic']}, 06:14:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:14:49 INFO - 'tsvgr_opacity', 06:14:49 INFO - 'tart', 06:14:49 INFO - 'tscrollx', 06:14:49 INFO - 'tsvg_static']}, 06:14:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['tsvgx', 06:14:49 INFO - 'tsvgr_opacity', 06:14:49 INFO - 'tart', 06:14:49 INFO - 'tscrollx', 06:14:49 INFO - 'tsvg_static']}, 06:14:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:14:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:14:49 INFO - 'talos_options': ['--disable-stylo'], 06:14:49 INFO - 'tests': ['tp5o']}, 06:14:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:14:49 INFO - 'talos_options': ['--mitmproxy', 06:14:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:14:49 INFO - '--firstNonBlankPaint'], 06:14:49 INFO - 'tests': ['tp6_google', 06:14:49 INFO - 'tp6_youtube', 06:14:49 INFO - 'tp6_amazon', 06:14:49 INFO - 'tp6_facebook']}, 06:14:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:14:49 INFO - 'talos_options': ['--disable-stylo', 06:14:49 INFO - '--mitmproxy', 06:14:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:14:49 INFO - '--firstNonBlankPaint'], 06:14:49 INFO - 'tests': ['tp6_google', 06:14:49 INFO - 'tp6_youtube', 06:14:49 INFO - 'tp6_amazon', 06:14:49 INFO - 'tp6_facebook']}, 06:14:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:14:49 INFO - 'talos_options': ['--stylo-threads=1', 06:14:49 INFO - '--mitmproxy', 06:14:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:14:49 INFO - '--firstNonBlankPaint'], 06:14:49 INFO - 'tests': ['tp6_google', 06:14:49 INFO - 'tp6_youtube', 06:14:49 INFO - 'tp6_amazon', 06:14:49 INFO - 'tp6_facebook']}, 06:14:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:14:49 INFO - 'talos_options': ['--xperf_path', 06:14:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:49 INFO - 'tests': ['tp5n']}, 06:14:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:14:49 INFO - 'talos_options': ['--disable-stylo', 06:14:49 INFO - '--xperf_path', 06:14:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:49 INFO - 'tests': ['tp5n']}}} 06:14:49 INFO - Running post-action listener: _resource_record_post_action 06:14:49 INFO - [mozharness: 2017-09-20 13:14:49.197489Z] Finished populate-webroot step (success) 06:14:49 INFO - [mozharness: 2017-09-20 13:14:49.197578Z] Running create-virtualenv step. 06:14:49 INFO - Running pre-action listener: _resource_record_pre_action 06:14:49 INFO - Running main action method: create_virtualenv 06:14:49 INFO - Creating virtualenv /builds/slave/test/build/venv 06:14:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:14:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 06:14:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:14:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:14:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:14:49 INFO - Installing distribute.............................................................................................................................................................................................done. 06:14:50 INFO - Installing pip...............done. 06:14:50 INFO - Return code: 0 06:14:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:14:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:14:50 INFO - Reading from file tmpfile_stdout 06:14:50 INFO - Output received: 06:14:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 06:14:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:14:50 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')]} 06:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:50 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')]} 06:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:50 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 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:14:50 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 06:14:50 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 06:14:50 INFO - Using env: {'DISPLAY': ':0', 06:14:50 INFO - 'HOME': '/home/cltbld', 06:14:50 INFO - 'LANG': 'en_US.UTF-8', 06:14:50 INFO - 'LANGUAGE': 'en_US:en', 06:14:50 INFO - 'LOGNAME': 'cltbld', 06:14:50 INFO - 'MAIL': '/var/mail/cltbld', 06:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:50 INFO - 'MOZ_NO_REMOTE': '1', 06:14:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:50 INFO - 'NO_EM_RESTART': '1', 06:14:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:50 INFO - 'PWD': '/builds/slave/test', 06:14:50 INFO - 'SHELL': '/bin/bash', 06:14:50 INFO - 'SHLVL': '1', 06:14:50 INFO - 'TERM': 'linux', 06:14:50 INFO - 'TMOUT': '86400', 06:14:50 INFO - 'USER': 'cltbld', 06:14:50 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:50 INFO - '_': '/tools/buildbot/bin/python'} 06:14:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:14:51 INFO - Downloading/unpacking pip>=1.5 06:14:51 INFO - Running setup.py egg_info for package pip 06:14:51 INFO - warning: no files found matching 'pip/cacert.pem' 06:14:51 INFO - warning: no files found matching '*.html' under directory 'docs' 06:14:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 06:14:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 06:14:51 INFO - Installing collected packages: pip 06:14:51 INFO - Found existing installation: pip 1.1 06:14:51 INFO - Uninstalling pip: 06:14:51 INFO - Successfully uninstalled pip 06:14:51 INFO - Running setup.py install for pip 06:14:51 INFO - warning: no files found matching 'pip/cacert.pem' 06:14:51 INFO - warning: no files found matching '*.html' under directory 'docs' 06:14:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 06:14:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 06:14:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Successfully installed pip 06:14:51 INFO - Cleaning up... 06:14:51 INFO - Return code: 0 06:14:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:14:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:14:51 INFO - Using env: {'DISPLAY': ':0', 06:14:51 INFO - 'HOME': '/home/cltbld', 06:14:51 INFO - 'LANG': 'en_US.UTF-8', 06:14:51 INFO - 'LANGUAGE': 'en_US:en', 06:14:51 INFO - 'LOGNAME': 'cltbld', 06:14:51 INFO - 'MAIL': '/var/mail/cltbld', 06:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:51 INFO - 'MOZ_NO_REMOTE': '1', 06:14:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:51 INFO - 'NO_EM_RESTART': '1', 06:14:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:51 INFO - 'PWD': '/builds/slave/test', 06:14:51 INFO - 'SHELL': '/bin/bash', 06:14:51 INFO - 'SHLVL': '1', 06:14:51 INFO - 'TERM': 'linux', 06:14:51 INFO - 'TMOUT': '86400', 06:14:51 INFO - 'USER': 'cltbld', 06:14:51 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:51 INFO - '_': '/tools/buildbot/bin/python'} 06:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:51 INFO - Downloading/unpacking psutil>=3.1.1 06:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:14:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:52 INFO - Installing collected packages: psutil 06:14:52 INFO - Running setup.py install for psutil 06:14:52 INFO - building 'psutil._psutil_linux' extension 06:14:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 06:14:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 06:14:53 INFO - building 'psutil._psutil_posix' extension 06:14:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 06:14:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 06:14:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:53 INFO - Successfully installed psutil 06:14:53 INFO - Cleaning up... 06:14:53 INFO - Return code: 0 06:14:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:14:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:14:53 INFO - Using env: {'DISPLAY': ':0', 06:14:53 INFO - 'HOME': '/home/cltbld', 06:14:53 INFO - 'LANG': 'en_US.UTF-8', 06:14:53 INFO - 'LANGUAGE': 'en_US:en', 06:14:53 INFO - 'LOGNAME': 'cltbld', 06:14:53 INFO - 'MAIL': '/var/mail/cltbld', 06:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:53 INFO - 'MOZ_NO_REMOTE': '1', 06:14:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:53 INFO - 'NO_EM_RESTART': '1', 06:14:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:53 INFO - 'PWD': '/builds/slave/test', 06:14:53 INFO - 'SHELL': '/bin/bash', 06:14:53 INFO - 'SHLVL': '1', 06:14:53 INFO - 'TERM': 'linux', 06:14:53 INFO - 'TMOUT': '86400', 06:14:53 INFO - 'USER': 'cltbld', 06:14:53 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:53 INFO - '_': '/tools/buildbot/bin/python'} 06:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:14: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 06:14: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 06:14: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 06:14: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 06:14:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:14:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:14:54 INFO - Installing collected packages: mozsystemmonitor 06:14:54 INFO - Running setup.py install for mozsystemmonitor 06:14:54 INFO - Successfully installed mozsystemmonitor 06:14:54 INFO - Cleaning up... 06:14:55 INFO - Return code: 0 06:14:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14: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')]} 06:14:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:14:55 INFO - Using env: {'DISPLAY': ':0', 06:14:55 INFO - 'HOME': '/home/cltbld', 06:14:55 INFO - 'LANG': 'en_US.UTF-8', 06:14:55 INFO - 'LANGUAGE': 'en_US:en', 06:14:55 INFO - 'LOGNAME': 'cltbld', 06:14:55 INFO - 'MAIL': '/var/mail/cltbld', 06:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:55 INFO - 'MOZ_NO_REMOTE': '1', 06:14:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:55 INFO - 'NO_EM_RESTART': '1', 06:14:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:55 INFO - 'PWD': '/builds/slave/test', 06:14:55 INFO - 'SHELL': '/bin/bash', 06:14:55 INFO - 'SHLVL': '1', 06:14:55 INFO - 'TERM': 'linux', 06:14:55 INFO - 'TMOUT': '86400', 06:14:55 INFO - 'USER': 'cltbld', 06:14:55 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:55 INFO - '_': '/tools/buildbot/bin/python'} 06:14:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:55 INFO - Downloading/unpacking jsonschema==2.5.1 06:14: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 06:14: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 06:14: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 06:14: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 06:14:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:14:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:14:56 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 06:14:56 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 06:14:56 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 06:14:56 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 06:14:56 INFO - Downloading functools32-3.2.3-2.tar.gz 06:14:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:14:57 INFO - warning: no files found matching '*.txt' 06:14:57 INFO - no previously-included directories found matching 'build' 06:14:57 INFO - no previously-included directories found matching 'dist' 06:14:57 INFO - no previously-included directories found matching '.git*' 06:14:57 INFO - Installing collected packages: jsonschema, functools32 06:14:57 INFO - Running setup.py install for functools32 06:14:57 INFO - warning: no files found matching '*.txt' 06:14:57 INFO - no previously-included directories found matching 'build' 06:14:57 INFO - no previously-included directories found matching 'dist' 06:14:57 INFO - no previously-included directories found matching '.git*' 06:14:57 INFO - Successfully installed jsonschema functools32 06:14:57 INFO - Cleaning up... 06:14:57 INFO - Return code: 0 06:14:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14: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')]} 06:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:14:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:14:57 INFO - Using env: {'DISPLAY': ':0', 06:14:57 INFO - 'HOME': '/home/cltbld', 06:14:57 INFO - 'LANG': 'en_US.UTF-8', 06:14:57 INFO - 'LANGUAGE': 'en_US:en', 06:14:57 INFO - 'LOGNAME': 'cltbld', 06:14:57 INFO - 'MAIL': '/var/mail/cltbld', 06:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:57 INFO - 'MOZ_NO_REMOTE': '1', 06:14:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:57 INFO - 'NO_EM_RESTART': '1', 06:14:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:57 INFO - 'PWD': '/builds/slave/test', 06:14:57 INFO - 'SHELL': '/bin/bash', 06:14:57 INFO - 'SHLVL': '1', 06:14:57 INFO - 'TERM': 'linux', 06:14:57 INFO - 'TMOUT': '86400', 06:14:57 INFO - 'USER': 'cltbld', 06:14:57 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:57 INFO - '_': '/tools/buildbot/bin/python'} 06:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:14:57 INFO - Cleaning up... 06:14:57 INFO - Return code: 0 06:14:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14: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')]} 06:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:14:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:14:57 INFO - Using env: {'DISPLAY': ':0', 06:14:57 INFO - 'HOME': '/home/cltbld', 06:14:57 INFO - 'LANG': 'en_US.UTF-8', 06:14:57 INFO - 'LANGUAGE': 'en_US:en', 06:14:57 INFO - 'LOGNAME': 'cltbld', 06:14:57 INFO - 'MAIL': '/var/mail/cltbld', 06:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:57 INFO - 'MOZ_NO_REMOTE': '1', 06:14:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:14:57 INFO - 'NO_EM_RESTART': '1', 06:14:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:57 INFO - 'PWD': '/builds/slave/test', 06:14:57 INFO - 'SHELL': '/bin/bash', 06:14:57 INFO - 'SHLVL': '1', 06:14:57 INFO - 'TERM': 'linux', 06:14:57 INFO - 'TMOUT': '86400', 06:14:57 INFO - 'USER': 'cltbld', 06:14:57 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:57 INFO - '_': '/tools/buildbot/bin/python'} 06:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:58 INFO - Downloading/unpacking blobuploader==1.2.4 06:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:59 INFO - Downloading blobuploader-1.2.4.tar.gz 06:14:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:14:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:14:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:59 INFO - Downloading docopt-0.6.1.tar.gz 06:14:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:14:59 INFO - Installing collected packages: blobuploader, requests, docopt 06:14:59 INFO - Running setup.py install for blobuploader 06:14:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:14:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:14:59 INFO - Running setup.py install for requests 06:15:00 INFO - Running setup.py install for docopt 06:15:00 INFO - Successfully installed blobuploader requests docopt 06:15:00 INFO - Cleaning up... 06:15:00 INFO - Return code: 0 06:15:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:15:00 INFO - Using env: {'DISPLAY': ':0', 06:15:00 INFO - 'HOME': '/home/cltbld', 06:15:00 INFO - 'LANG': 'en_US.UTF-8', 06:15:00 INFO - 'LANGUAGE': 'en_US:en', 06:15:00 INFO - 'LOGNAME': 'cltbld', 06:15:00 INFO - 'MAIL': '/var/mail/cltbld', 06:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:00 INFO - 'MOZ_NO_REMOTE': '1', 06:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:00 INFO - 'NO_EM_RESTART': '1', 06:15:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:00 INFO - 'PWD': '/builds/slave/test', 06:15:00 INFO - 'SHELL': '/bin/bash', 06:15:00 INFO - 'SHLVL': '1', 06:15:00 INFO - 'TERM': 'linux', 06:15:00 INFO - 'TMOUT': '86400', 06:15:00 INFO - 'USER': 'cltbld', 06:15:00 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:00 INFO - '_': '/tools/buildbot/bin/python'} 06:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:00 INFO - Running setup.py (path:/tmp/pip-VVVOj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:00 INFO - Running setup.py (path:/tmp/pip-XlY110-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:00 INFO - Running setup.py (path:/tmp/pip-DWaGIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:00 INFO - Running setup.py (path:/tmp/pip-xvkWUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:00 INFO - Running setup.py (path:/tmp/pip-Lny5io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:00 INFO - Running setup.py (path:/tmp/pip-2zmj5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:01 INFO - Running setup.py (path:/tmp/pip-DZNhlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:01 INFO - Running setup.py (path:/tmp/pip-3njQjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:01 INFO - Running setup.py (path:/tmp/pip-M__rgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:01 INFO - Running setup.py (path:/tmp/pip-IonRt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:01 INFO - Running setup.py (path:/tmp/pip-jIbo0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:01 INFO - Running setup.py (path:/tmp/pip-AgX2uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:01 INFO - Running setup.py (path:/tmp/pip-jBwJdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:01 INFO - Running setup.py (path:/tmp/pip-03dE8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:01 INFO - Running setup.py (path:/tmp/pip-HbQ9Gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:01 INFO - Running setup.py (path:/tmp/pip-uNh5qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:01 INFO - Running setup.py (path:/tmp/pip-mkut9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:15:01 INFO - Running setup.py install for manifestparser 06:15:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:15:01 INFO - Running setup.py install for mozcrash 06:15:01 INFO - Running setup.py install for mozdebug 06:15:02 INFO - Running setup.py install for mozdevice 06:15:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:15:02 INFO - Running setup.py install for mozfile 06:15:02 INFO - Running setup.py install for mozhttpd 06:15:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:15:02 INFO - Running setup.py install for mozinfo 06:15:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:15:02 INFO - Running setup.py install for mozInstall 06:15:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:15:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:15:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:15:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:15:02 INFO - Running setup.py install for mozleak 06:15:02 INFO - Running setup.py install for mozlog 06:15:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:15:02 INFO - Running setup.py install for moznetwork 06:15:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:15:02 INFO - Running setup.py install for mozprocess 06:15:02 INFO - Running setup.py install for mozprofile 06:15:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:15:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:15:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:15:03 INFO - Running setup.py install for mozrunner 06:15:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:15:03 INFO - Running setup.py install for mozscreenshot 06:15:03 INFO - Running setup.py install for moztest 06:15:03 INFO - Running setup.py install for mozversion 06:15:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:15:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:15:03 INFO - Cleaning up... 06:15:03 INFO - Return code: 0 06:15:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:15:03 INFO - Using env: {'DISPLAY': ':0', 06:15:03 INFO - 'HOME': '/home/cltbld', 06:15:03 INFO - 'LANG': 'en_US.UTF-8', 06:15:03 INFO - 'LANGUAGE': 'en_US:en', 06:15:03 INFO - 'LOGNAME': 'cltbld', 06:15:03 INFO - 'MAIL': '/var/mail/cltbld', 06:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:03 INFO - 'MOZ_NO_REMOTE': '1', 06:15:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:03 INFO - 'NO_EM_RESTART': '1', 06:15:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:03 INFO - 'PWD': '/builds/slave/test', 06:15:03 INFO - 'SHELL': '/bin/bash', 06:15:03 INFO - 'SHLVL': '1', 06:15:03 INFO - 'TERM': 'linux', 06:15:03 INFO - 'TMOUT': '86400', 06:15:03 INFO - 'USER': 'cltbld', 06:15:03 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:03 INFO - '_': '/tools/buildbot/bin/python'} 06:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:03 INFO - Running setup.py (path:/tmp/pip-Opuovo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:03 INFO - Running setup.py (path:/tmp/pip-4eRlOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:03 INFO - Running setup.py (path:/tmp/pip-Bw6rSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:03 INFO - Running setup.py (path:/tmp/pip-d1naqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:04 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)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:04 INFO - Running setup.py (path:/tmp/pip-NPlKuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:04 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)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:04 INFO - Running setup.py (path:/tmp/pip-44hdAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:04 INFO - Running setup.py (path:/tmp/pip-DrTmkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:04 INFO - Running setup.py (path:/tmp/pip-IVuC_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:04 INFO - Running setup.py (path:/tmp/pip-t1sHKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:04 INFO - Running setup.py (path:/tmp/pip-g4Unvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:04 INFO - Running setup.py (path:/tmp/pip-flYskv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:04 INFO - Running setup.py (path:/tmp/pip-uz5gEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:04 INFO - Running setup.py (path:/tmp/pip-pBBXfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:04 INFO - Running setup.py (path:/tmp/pip-TytQ0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:04 INFO - Running setup.py (path:/tmp/pip-BoB5n8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:04 INFO - Running setup.py (path:/tmp/pip-yhxfTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:15:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:04 INFO - Running setup.py (path:/tmp/pip-vgHdVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:15:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:06 INFO - Downloading blessings-1.6.tar.gz 06:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:15:06 INFO - Installing collected packages: blessings 06:15:06 INFO - Running setup.py install for blessings 06:15:06 INFO - Successfully installed blessings 06:15:06 INFO - Cleaning up... 06:15:06 INFO - Return code: 0 06:15:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:15:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:06 INFO - Reading from file tmpfile_stdout 06:15:06 INFO - Current package versions: 06:15:06 INFO - argparse == 1.2.1 06:15:06 INFO - blessings == 1.6 06:15:06 INFO - blobuploader == 1.2.4 06:15:06 INFO - docopt == 0.6.1 06:15:06 INFO - functools32 == 3.2.3-2 06:15:06 INFO - jsonschema == 2.5.1 06:15:06 INFO - manifestparser == 1.1 06:15:06 INFO - mozInstall == 1.13 06:15:06 INFO - mozcrash == 1.0 06:15:06 INFO - mozdebug == 0.1 06:15:06 INFO - mozdevice == 0.51 06:15:06 INFO - mozfile == 1.2 06:15:06 INFO - mozhttpd == 0.7 06:15:06 INFO - mozinfo == 0.10 06:15:06 INFO - mozleak == 0.1 06:15:06 INFO - mozlog == 3.5 06:15:06 INFO - moznetwork == 0.27 06:15:06 INFO - mozprocess == 0.25 06:15:06 INFO - mozprofile == 0.28 06:15:06 INFO - mozrunner == 6.13 06:15:06 INFO - mozscreenshot == 0.1 06:15:06 INFO - mozsystemmonitor == 0.3 06:15:06 INFO - moztest == 0.8 06:15:06 INFO - mozversion == 1.4 06:15:06 INFO - psutil == 3.1.1 06:15:06 INFO - requests == 1.2.3 06:15:06 INFO - wsgiref == 0.1.2 06:15:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:15:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:15:06 INFO - Using env: {'DISPLAY': ':0', 06:15:06 INFO - 'HOME': '/home/cltbld', 06:15:06 INFO - 'LANG': 'en_US.UTF-8', 06:15:06 INFO - 'LANGUAGE': 'en_US:en', 06:15:06 INFO - 'LOGNAME': 'cltbld', 06:15:06 INFO - 'MAIL': '/var/mail/cltbld', 06:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:06 INFO - 'MOZ_NO_REMOTE': '1', 06:15:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:06 INFO - 'NO_EM_RESTART': '1', 06:15:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:06 INFO - 'PWD': '/builds/slave/test', 06:15:06 INFO - 'SHELL': '/bin/bash', 06:15:06 INFO - 'SHLVL': '1', 06:15:06 INFO - 'TERM': 'linux', 06:15:06 INFO - 'TMOUT': '86400', 06:15:06 INFO - 'USER': 'cltbld', 06:15:06 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:06 INFO - '_': '/tools/buildbot/bin/python'} 06:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:15:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 06:15:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:15:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:15:07 INFO - Installing collected packages: simplejson 06:15:07 INFO - Running setup.py install for simplejson 06:15:08 INFO - building 'simplejson._speedups' extension 06:15:08 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 06:15:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 06:15:08 INFO - Successfully installed simplejson 06:15:08 INFO - Cleaning up... 06:15:08 INFO - Return code: 0 06:15:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22be1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2487a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, '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': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', '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 06:15:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:15:08 INFO - Using env: {'DISPLAY': ':0', 06:15:08 INFO - 'HOME': '/home/cltbld', 06:15:08 INFO - 'LANG': 'en_US.UTF-8', 06:15:08 INFO - 'LANGUAGE': 'en_US:en', 06:15:08 INFO - 'LOGNAME': 'cltbld', 06:15:08 INFO - 'MAIL': '/var/mail/cltbld', 06:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:08 INFO - 'MOZ_NO_REMOTE': '1', 06:15:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:08 INFO - 'NO_EM_RESTART': '1', 06:15:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:08 INFO - 'PWD': '/builds/slave/test', 06:15:08 INFO - 'SHELL': '/bin/bash', 06:15:08 INFO - 'SHLVL': '1', 06:15:08 INFO - 'TERM': 'linux', 06:15:08 INFO - 'TMOUT': '86400', 06:15:08 INFO - 'USER': 'cltbld', 06:15:08 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:08 INFO - '_': '/tools/buildbot/bin/python'} 06:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:15:09 INFO - Cleaning up... 06:15:09 INFO - Return code: 0 06:15:09 INFO - Running post-action listener: _resource_record_post_action 06:15:09 INFO - Running post-action listener: _start_resource_monitoring 06:15:09 INFO - Starting resource monitoring. 06:15:09 INFO - [mozharness: 2017-09-20 13:15:09.241534Z] Finished create-virtualenv step (success) 06:15:09 INFO - [mozharness: 2017-09-20 13:15:09.242050Z] Running install step. 06:15:09 INFO - Running pre-action listener: _resource_record_pre_action 06:15:09 INFO - Running main action method: install 06:15:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:09 INFO - Reading from file tmpfile_stdout 06:15:09 INFO - Detecting whether we're running mozinstall >=1.0... 06:15:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:15:09 INFO - Reading from file tmpfile_stdout 06:15:09 INFO - Output received: 06:15:09 INFO - Usage: mozinstall [options] installer 06:15:09 INFO - Options: 06:15:09 INFO - -h, --help show this help message and exit 06:15:09 INFO - -d DEST, --destination=DEST 06:15:09 INFO - Directory to install application into. [default: 06:15:09 INFO - "/builds/slave/test"] 06:15:09 INFO - --app=APP Application being installed. [default: firefox] 06:15:09 INFO - mkdir: /builds/slave/test/build/application 06:15:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:15:27 INFO - Reading from file tmpfile_stdout 06:15:27 INFO - Output received: 06:15:27 INFO - /builds/slave/test/build/application/firefox/firefox 06:15:27 INFO - Running post-action listener: _resource_record_post_action 06:15:27 INFO - [mozharness: 2017-09-20 13:15:27.355414Z] Finished install step (success) 06:15:27 INFO - [mozharness: 2017-09-20 13:15:27.355664Z] Running setup-mitmproxy step. 06:15:27 INFO - Running pre-action listener: _resource_record_pre_action 06:15:27 INFO - Running main action method: setup_mitmproxy 06:15:27 INFO - Skipping: mitmproxy is not required 06:15:27 INFO - Running post-action listener: _resource_record_post_action 06:15:27 INFO - [mozharness: 2017-09-20 13:15:27.356849Z] Finished setup-mitmproxy step (success) 06:15:27 INFO - [mozharness: 2017-09-20 13:15:27.357079Z] Running run-tests step. 06:15:27 INFO - Running pre-action listener: _resource_record_pre_action 06:15:27 INFO - Running pre-action listener: _set_gcov_prefix 06:15:27 INFO - Running main action method: run_tests 06:15:27 WARNING - Try message not found. 06:15:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:15:27 INFO - Python 2.7.3 06:15:27 INFO - Return code: 0 06:15:27 INFO - grabbing minidump binary from tooltool 06:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e090>, '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 0x262ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2628f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:15:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:15:27 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:15:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:15:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:15:27 INFO - Return code: 0 06:15:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:15:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:15:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:15:27 INFO - ENV: RUST_BACKTRACE is now full 06:15:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:15:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:15:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 06:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 06:15:27 INFO - Using env: {'DISPLAY': ':0', 06:15:27 INFO - 'HOME': '/home/cltbld', 06:15:27 INFO - 'LANG': 'en_US.UTF-8', 06:15:27 INFO - 'LANGUAGE': 'en_US:en', 06:15:27 INFO - 'LOGNAME': 'cltbld', 06:15:27 INFO - 'MAIL': '/var/mail/cltbld', 06:15:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:15:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:27 INFO - 'MOZ_NO_REMOTE': '1', 06:15:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:15:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:27 INFO - 'NO_EM_RESTART': '1', 06:15:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:27 INFO - 'PWD': '/builds/slave/test', 06:15:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:15:27 INFO - 'RUST_BACKTRACE': 'full', 06:15:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:15:27 INFO - 'SHELL': '/bin/bash', 06:15:27 INFO - 'SHLVL': '1', 06:15:27 INFO - 'TERM': 'linux', 06:15:27 INFO - 'TMOUT': '86400', 06:15:27 INFO - 'USER': 'cltbld', 06:15:27 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:27 INFO - '_': '/tools/buildbot/bin/python'} 06:15:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 06:15:27 INFO - mozversion application_buildid: 20170920094823 06:15:27 INFO - mozversion application_changeset: a20de99fa3c1ba6287fe47d493a859a4e95120b0 06:15:27 INFO - mozversion application_display_name: Nightly 06:15:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:15:27 INFO - mozversion application_name: Firefox 06:15:27 INFO - mozversion application_remotingname: firefox 06:15:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:15:27 INFO - mozversion application_vendor: Mozilla 06:15:27 INFO - mozversion application_version: 57.0a1 06:15:27 INFO - mozversion platform_buildid: 20170920094823 06:15:27 INFO - mozversion platform_changeset: a20de99fa3c1ba6287fe47d493a859a4e95120b0 06:15:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:15:27 INFO - mozversion platform_version: 57.0a1 06:15:27 INFO - using testdate: 1505913327 06:15:27 INFO - actual date: 1505913327 06:15:27 INFO - starting webserver on 'localhost:57370' 06:15:27 INFO - SUITE-START | Running 2 tests 06:15:27 INFO - TEST-START | dromaeo_css 06:15:27 INFO - Initialising browser for dromaeo_css test... 06:15:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57370/getInfo.html -profile /tmp/tmpEuiyo5/profile 06:15:27 INFO - TEST-INFO | started process 21154 (/builds/slave/test/build/application/firefox/firefox http://localhost:57370/getInfo.html) 06:15:32 INFO - TEST-INFO | 21154: exit 0 06:15:33 INFO - Browser initialized. 06:15:33 INFO - Running cycle 1/1 for dromaeo_css test... 06:15:33 INFO - Using env: {'DISPLAY': ':0', 06:15:33 INFO - 'HOME': '/home/cltbld', 06:15:33 INFO - 'JSGC_DISABLE_POISONING': '1', 06:15:33 INFO - 'LANG': 'en_US.UTF-8', 06:15:33 INFO - 'LANGUAGE': 'en_US:en', 06:15:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 06:15:33 INFO - 'LOGNAME': 'cltbld', 06:15:33 INFO - 'MAIL': '/var/mail/cltbld', 06:15:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:15:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:15:33 INFO - 'MOZ_CRASHREPORTER': '1', 06:15:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 06:15:33 INFO - 'MOZ_NO_REMOTE': '1', 06:15:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:15:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:33 INFO - 'NO_EM_RESTART': '1', 06:15:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:33 INFO - 'PWD': '/builds/slave/test', 06:15:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:15:33 INFO - 'RUST_BACKTRACE': 'full', 06:15:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:15:33 INFO - 'SHELL': '/bin/bash', 06:15:33 INFO - 'SHLVL': '1', 06:15:33 INFO - 'TERM': 'linux', 06:15:33 INFO - 'TMOUT': '86400', 06:15:33 INFO - 'USER': 'cltbld', 06:15:33 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:15:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:33 INFO - '_': '/tools/buildbot/bin/python'} 06:15:33 INFO - TEST-INFO | started process 21389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEuiyo5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:15:33 INFO - PID 21389 | 06:15:33 INFO - PID 21389 | (/builds/slave/test/build/application/firefox/firefox:21438): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:15:33 INFO - PID 21389 | 06:15:44 INFO - PID 21389 | RSS: Main: 154402816 06:15:44 INFO - PID 21389 | 06:18:41 INFO - PID 21389 | Cycle 1(1): loaded http://localhost:57370/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57370/tests/dromaeo/cssquery-ext.html) 06:18:42 INFO - PID 21389 | RSS: Main: 155566080 06:18:42 INFO - PID 21389 | 06:21:44 INFO - PID 21389 | Cycle 1(1): loaded http://localhost:57370/tests/dromaeo/cssquery-ext.html (next: http://localhost:57370/tests/dromaeo/cssquery-jquery.html) 06:21:44 INFO - PID 21389 | RSS: Main: 155435008 06:21:44 INFO - PID 21389 | 06:24:46 INFO - PID 21389 | Cycle 1(1): loaded http://localhost:57370/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57370/tests/dromaeo/cssquery-mootools.html) 06:24:47 INFO - PID 21389 | RSS: Main: 155406336 06:24:47 INFO - PID 21389 | 06:27:48 INFO - PID 21389 | Cycle 1(1): loaded http://localhost:57370/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57370/tests/dromaeo/cssquery-prototype.html) 06:27:49 INFO - PID 21389 | RSS: Main: 155295744 06:27:49 INFO - PID 21389 | 06:30:52 INFO - PID 21389 | Cycle 1(1): loaded http://localhost:57370/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57370/tests/dromaeo/cssquery-yui.html) 06:30:53 INFO - PID 21389 | RSS: Main: 157163520 06:30:53 INFO - PID 21389 | 06:33:55 INFO - PID 21389 | Cycle 1(1): loaded http://localhost:57370/tests/dromaeo/cssquery-yui.html (next: http://localhost:57370/tests/dromaeo/cssquery-dojo.html) 06:33:55 INFO - PID 21389 | RSS: Main: 159420416 06:33:55 INFO - PID 21389 | 06:33:55 INFO - PID 21389 | __start_tp_report 06:33:55 INFO - PID 21389 | _x_x_mozilla_page_load 06:33:55 INFO - PID 21389 | _x_x_mozilla_page_load_details 06:33:55 INFO - PID 21389 | |i|pagename|runs| 06:33:55 INFO - PID 21389 | |0;dojo.html;3164.0033389482314;3186.060112266881;3194.4569423198054;3200.9359812803746;3201.311717980635;6286.182796236494;6389.6805159742;6443.35566443356;6475.514336424775;6500.28496865345;6273.623582585048;6321.083442900776;6330.082138089986;6344;6400.295967443578;5851.853703657422;5854.560907931911;5859.355470898197;5890.793822216223;5997.970010149965;5941.910871336933;5992.5505587080925;6010.8497287567725;6015.9398406016035;6041.395860413967;5930.406959304079;5940.792072277472;6002.459778619924;6018.368071352511;6034.849128771794;582.2473285391227;5764.538836893081;5796.159556864273;5924.348321684612;5930.169776231331;5765.827025189209;5846.53343202566;5900.734466948998;5929.555283353722;5931.110333449974;6283.151774510428;6402.167718196652;6412.61524308543;6421.4220720134945;6492.3183065777885;6892.379685828249;6895.069165662621;6902.275261097613;6990.4757143214465;7002.929970700278;6799.388055075067;6964.686589103452;6965.373116419547;7032.75385361515;7046.612436316001;6350.491960643138;6363.968180159116;6374.649394283312;6375.681215939185;6378.4578310843635;6969.16370035599;7033.226345102034;7049.9647501762165;7050.541714788523;7053.329933656294;6688.063671085954;6728.899066514006;6740.359665831738;6742.730290788411;6748.19021717397;6532.297578216846;6841.863162736817;6855.006024126474;7017.157941047005;7031.437485001187;6854.0404343390965;6876.346747059021;6884.380405763505;6976.720931162697;6993.860122797516;6699.899501507482;6804.183497980274;6867.519273650796;6904.516683832184;6980.406665433394;5388.595855310789;5390.811321603804;5406.432324605938;5416.756245968941;5454.12733962572;4718.221493453541;4725.1730947084125;4732.02993386355;4776.665633405698;4782.856514304576;5911.940880591139;5912.763489460373;5990.670513121733;6003.729832157476;6018.428249316308;6734.8989765153565;6842.178938527409;6874;6929.411000065051;7016.403605693471;5916.674582897982;5932.0508718604815;5937;5939.584229104008;6003.099535069687;5844.561657875592;5966.31387390447;5984.820455386345;5993.640381577032;6006.039033754579;6597.076409302702;6611.206655201407;6697.464202863759;6705.832354191184;6742.0224067509935;6714.932850671528;6811.284815094344;6831.077804496329;6905.1713794345;6979.406750426169;6603.042558828847;6609.933900660932;6621.602703837785;6626.204855417188;6638.966805165944;6784.966075169593;6826.795196144124;6883.65581720922;6894.413974812197;6898.793036208922;6898.689558969959;6929.688164032731;6943.51395402317;7053.788386348212;7060.258672839484;6524.119243902012;6649.900251496329;6672.332766723483;6766.492513061442;6778.796636100925;6556.967215163893;6617.867642647024;6626.237982632059;6643.30245324234;6655.900161497679;6585.473162147113;6681.365270299509;6684.331566843472;6717.764878229238;6739.258681545124;6746.966265168643;6842.8289292766085;6870.725170993304;6884.621345826027;6890.586564806128;6537.378948999837;6543.280239173782;6544.411003009562;6639.302873198245;6644.60132392058 06:33:55 INFO - PID 21389 | |1;ext.html;857.4726543175944;862.3273846399809;872.8297981893526;877.9561021948905;894.7181637784098;39517;40746.38880416796;40963.59036409635;41039.58960410399;41049.58950410495;36649.267014659694;36963;36970.63029369705;36995.630043699624;37113.628863711354;7103.609301488416;7193.496455248135;7199.928000720004;7210.711571537132;7216.061911951439;7138.8572228555395;7142.821429464253;7226.421886249113;7228.746993855216;7244.529105608119;7625.970493983325;7821.726239581616;7825.686972521121;7828.295453409192;7867.7639670809685;7761.96119019407;7813.648385822623;7873.409494287951;7881.645325960346;7882.5270483771155;7768.3785297176655;7842.215778422112;7857.8035549111155;7859.68561257549;7861;36628.816855915815;36952.81523592365;36994;37087;37087.629123708684;3516.384632689269;3516.876909308162;3549.2014296783277;3557.875474358385;3559.1280136366754;30619.846900765355;30626.234344141576;30721.539176912363;30765.077047688606;30878.382432351227;5228.320318358627;5238.083335416325;5258.763355649005;5280.287161233261;5284.445133260991;186052;186442;186785;188531.05734471377;189021;46158.538414616094;46284.305735414;46291.30563041557;46328.07343853104;46490.53509464862;47616.76191619089;47678.5232147681;47808.28287575689;47864;47912.28131578029;15704.921475392779;15811.525654230392;15846.049237045814;15899.205039747967;15939.920300398424;12905.419256133497;13099.476020959055;13121.343932803322;13159.210447373189;13219.867801322056;14142.717145657029;14236.28818559068;14295.714085718228;14324.57026289213;14399.568012959628;5496.697681627549;5636.887262254814;5669.659820410789;5679.375268720438;5753.280839895013;999.9500024998866;1010.1867996263079;1020.7295066807302;1027.7944411117805;1042.697617690861;22475.775242247368;22930.65604015941;23068.307950761504;23235.53528929412;23400.765992339857;1111.2499063132516;1116.815725405299;1121.1423261205184;1123.3877536742496;1138.0781566930812;12046.570094576791;12277.045632868052;12304.200226985216;12366.249614301234;12441.00471962259;203977;205545.94454055568;205895;212981;213981;43452.56547434549;44217;44237.77881110574;44255.778721106835;51644;1397.923114228707;1403.3193900958026;1412.552134062594;1414.476643641859;1434.3330351386655;26953;27144.592831107944;27629.447411052068;27783.44433111286;27793.583096253977;35330.29339413249;35587.644123558435;35602.46596301109;35620.109497262776;35745.28509429745;179070;179398;180070;180394;180685;32111.839440802647;32512.83743581267;32668.183295417806;32694.182645434055;32930;40491.39262911118;40676.59323406728;40848.387274191504;40931.1813763729;40936.385954211306;30136.547951780303;30163.547546787253;30657.386852263277;30670.079897603107;30679;30250.394992099595;30755.53866692046;30772.38455230838;30807;31316.37367252688;33779.83110084434;33866.322673545896;34319.828400857834;34399.14002149966;34648.65351346555;29606.111816645534;30302.393952121274;30350.696493034786;30676.539851901794;30859.2285192863;22327.553448931256;22555.323340299816;22754.772452275265;22923.541529169655;23447.17934872271 06:33:55 INFO - PID 21389 | |2;jquery.html;568.5480043365524;572.5820151289561;573.8966390309288;577.2092233639914;587.2747157260784;21758.89120554397;22823.42941426465;22997.425064373398;23001.4249643759;23018.65472017921;23136.190233341837;23880.880595597042;24195.27414177573;24809.50380992379;24904.501909961837;6518.51111166662;6520.4457621102265;6600.141981542392;6612.305707900673;6674.6662666866705;6511.023346498016;6640.369164929324;6640.93359066408;6643.867122657544;6650.733970641193;6255.093011513328;6474.320196379383;6506.70719817607;6509;6512.348765123497;6519.967400162993;6595.70319335631;6597.208334999783;6597.9340206598035;6613.074169616258;5893.941060589382;6092.817215483543;6105.114758360013;6131.448169664752;6139.907901381483;27211.591826122625;28057.859710701316;28063.0177943773;28200.858995705094;28245.152645420465;2035.9389218323474;2073.6474799284006;2073.8340932725346;2076.7092607034874;2078.8856612886284;18570.62858742831;18924.905375473172;19324.420267392;19331.51671208217;19561;2410.710714714228;2447.0945750072588;2447.5349683560066;2467.284487498623;2501.574732295514;121530.3923480377;126796;127384.36307818495;134133;139016;14242.998444367973;14635.900868722434;14766.093562575055;14996.325165367367;15127.319270632848;22858.771412285998;22867.19964801257;22889.198878039188;23036.193733219592;23100.884495577415;10103.141232995278;10140.087392134743;10145.594176232868;10177.491125443697;10209.846852297222;9223.077692230858;9343.159115679486;9343.205827504602;9414.65779648215;9536.856947145798;8601.139886011473;8801.611379618893;8823.250023748053;8910.465372077697;9063.274938004944;2584.831985920909;2593.883275252618;2614.8038897082797;2712.199901029201;2766.5573508238585;926.5737760630051;930.5765876526165;952.9380590261611;957.7078990907701;968.2060710217695;18990.430287091134;19417.417477475698;19442.222311107682;19587.11857966395;19676.90161549183;997.2819569909653;999.0908273471107;1005.9295849290626;1006.8489726540931;1021.3616489693941;10715.517801698943;10828.458577071273;10857.239993200557;10943.069839063768;10958;505276.0246463926;517990;521211;523251;532659;115836;116170;119050.4047479757;119169.40415298041;121930;1011.4790882695418;1032.8192566300868;1060.4644654449453;1117.1174771930084;1140.4696815980774;19809.801901980794;20215.59568808645;20277.69583456279;20493.79506204919;20519.4870128248;55754.72122639523;57378.85242295047;57499.71250143723;57959;59446.405535945814;70203.64898175477;72270;72369;72898.63550682424;73354.63322683566;14328.283585820876;14413.630705083;14670.266486675837;14672.193029383487;14672.926635366755;22786;23367.88316058409;23411.648825267293;23413.648795267745;23449.882750586137;18086.366977155736;18458.538536536693;18743;18815.717764233275;18911.148998295386;16378.67242655164;16663.83336166671;16735.163241837618;16958;17391.47825565235;18960.24159033623;19007.144678489225;19083.522911927314;19128.8087119127;19389.418317450498;13552.728945421233;14341.9282903589;14360;14394.136351818506;14480.493182738976;18627.534311642317;18705.906470467562;18721.438356849318;18769.90615046916;18783.530411739815 06:33:55 INFO - PID 21389 | |3;mootools.html;1339.1429485129515;1371.2663724907177;1412.216219997902;1416.7237388709202;1422.1893520693209;14723.190224537646;14917.552473425787;15428.151451670154;15450.5364839055;15476.303566339513;15459.69080618387;15557.611059723513;15683.215839208022;15695.921520392381;15744.448944286953;2559.872006399682;2717.986410067947;2780.7219278072234;2781.4576157649267;2791.1905547391234;2747.4505098980185;2776.444711057786;2784.276088217068;2785.8049936504453;2789.8186617869874;2725.6184134221226;2725.9182224533197;2736.1244401791437;2755.3524921643398;2757.4071574611453;2635.578307470805;2677.9062732804355;2695.8382497050143;2701.7568418842397;2703.9594406083925;2608.4261462478225;2658.8936442542276;2663.0812369732603;2669.305980445079;2679.946401071987;14686.485972990895;15092.924535377253;15105.697886042328;15111;15119;7396.3343299103335;7575.242475752436;7601.581912994783;7615.961920190363;7616.352610028154;5086.516780905808;5233.947660523384;5311.787528498855;5352.598555108382;5476.452354764531;1925.7881633020356;1944.8638595298335;1965.8132477414765;1997.3209108902897;2003.3689387842928;26450.206493805217;26624.733752662418;26685.332866678295;26707.332316692045;26711.732882671313;6666.733330666719;6718.126643536309;6791.490638202055;6794.8641027180165;6866.313368663094;16808.663826723398;17009.149542522824;17025;17030.48908532746;17071.231794569274;7522.811929701692;7758.689652413953;7767.611619419006;7767.72812951544;7777.066751989684;7036.894446583203;7191.460640451987;7234.927650723531;7284.745033923893;7336.339729424378;5185.118529849935;5384.461553844662;5398.406175320712;5398.811041613603;5406.621536492407;1486.5094518808767;1491.4779827060443;1505.6762795998914;1518.3546992528131;1523.7866698662197;865.3336930563431;894.7852515396258;911.7053783627133;915.6795121707482;918.7197904639015;7615.124260709978;7640.197779233212;7668.309852113337;7693.307602315818;7728.459007869507;691.9965400172966;710.2471380336859;717.666285177395;725.4994054102758;725.677073702208;4927.285543596158;5532.640378375393;5540.085885828874;5540.307461567304;5729.88540229193;21021.789782101983;21573.352799416043;21731.565368692536;21805.781942180376;21915.780842191692;20676.793232067786;21381.144754209967;21388.572228555342;21859.234926777488;21876.23433179831;11362;11525.596604118815;11547.769044619228;11549.76900461986;11567.595134170264;8151.470154439942;8178.427510074238;8244.175582441709;8291.839142520173;8468.153184681483;17784.288628455233;18614.44156675302;18732.6253474927;18833.340833070775;18841.15214815364;18384.724229136307;19004.809951900857;19062.14220360115;19200.61598768045;19241.90379048096;10540.051395374454;10550.788984220426;10624.36253824773;10686.465676715978;10694.572217111228;13761.518346857813;13949.3722782473;13954.651133721332;13969.860301396857;13970.37133329023;11135.83296250573;11226.158038147334;11254.268472549258;11291.983721465107;11536.134789890624;10277.486125693835;10537.315074520438;10570.312929659547;10695.625653102104;10755.784884302113;9167.312451566026;9510.619575216913;9519.857202141835;9521.761955951157;9537.52312384392;7122.394596459152;7171.964140179266;7233.842585186345;7283.308085731847;7289.562626242442;7241.855162896818;7319.963400182965;7329.083864516935;7369.8157546061775;7412.851742965218 06:33:55 INFO - PID 21389 | |4;prototype.html;500.44912100603096;504.7885765653633;519.5791408958743;522.2610006840321;525.2908573425875;6188.226471691039;6328.8101356959305;6357.523185761075;6386.552941294105;6406.1992250968515;6217.875642487147;6241.843953901143;6289;6341.46097581705;6376.617402955814;5218.660787048849;5219.399769026563;5222.556082732954;5224.8693782655355;5267.104592219332;4827.131116399047;4905.264210368456;5151.639385243035;5171.301874246985;5218.686878787266;4852.908095678462;4902.705837649734;4915.950840491603;4925.162722337212;4981.302617633534;4949.208126699728;4949.876253093701;4966.404031516205;4970.980948905466;4991.725455099968;4224.408582798411;4414.426124603813;4451.933221001687;4455.309427038814;4462.799174037176;5985.670788106652;6044.576879618428;6056.394360563952;6064.545159113084;6084;5595.944040559623;5642.351129620064;5649.6327738696855;5655.4061823508355;5670.943290567124;6483.4341112388;6498.5126115541525;6513.641749703765;6518.934810651927;6538.280789113242;4625.4911959684405;4836.056968891067;4842.83050093245;4871.171900776841;4882.779305173706;6462.192225971754;6626.668666566652;6727.596344219362;6828.726850926007;6869.622170780655;4928.408590969107;4950.975245123751;4961.231009193581;4967.236630996017;4973.328600638867;5014.548690617862;5018.519536736671;5019.310806557178;5050;5057.418396884405;5644.068728659725;5668.858278542987;5708.54331653475;5709.68596727184;5726.341470730919;6547.607143571401;6556.213254409501;6596.637184954873;6610.834729131714;6674.399304062657;1701.6766814305242;1702.4041585445243;1705.8485522272417;1712.623222890962;1718.6906356855886;1145.6505765741363;1210.449245593253;1213.8482689663792;1217.926924384522;1220.4385982448173;5435.891282174334;5561.165825126182;5607.439256074442;5631.7747290108755;5732.426757324234;6473.449756770728;6482.57863238888;6522.369407648088;6578.473722102221;6612.570182938093;5584.385717571064;5640;5645.266115404971;5682.971585142048;5720.599558030981;3450.447928331455;3486.2330287336745;3516.49010893419;3536.6993805526304;3541.982290088533;6318.178636777189;6364.777232796922;6366.427021568081;6394;6476.708548115346;6727.62998035103;6761.425278851353;6793.694283757242;6841.897371539431;6877.690503927335;5903.763849445974;6353.807936261982;6364.363563643598;6370.458511026614;6389.329120442287;6604.735810567524;6616;6629.867402651824;6631.469482441489;6633.933660663524;6470.805875823733;6479.222493300834;6558.672066396756;6566.0124643286235;6634.568753031231;6425;6425.453836423956;6432.807015789534;6478.255000674794;6509.153810004669;4854.854354369375;4862.294967229803;4901.583365413837;4913.238448040559;4951.133551628379;6396.552241343062;6453.935460645521;6493.902591461037;6603.735850565923;6613.933860661332;6038.84902877414;6308.14839996613;6383.840403989938;6385.553011289165;6415.743370265137;6337.841553961188;6344.555881088279;6374.649394283359;6417.454516366161;6433.453156481752;6343.714532845941;6395.264544577433;6411.96794016027;6416.294207637063;6454.644994525346;6510.251321098134;6514.3811337922825;6549.377809108127;6559;6565.606063636197;6237.906431403442;6312.3056463788025;6323.494120470259;6325.335839736761;6327.303996560283 06:33:55 INFO - PID 21389 | |5;yui.html;894.4990805149121;902.7066203483869;933.4585940154711;938.8403971324874;943.490515121834;19042.14310356034;19812.60374792507;19826.405207843753;19889.40331790045;19913.103910324033;17197.570060748458;17402.12989350534;17689;18304.81695183048;18670.253189872423;964.1852634523851;965.7682156282511;966.7244835221964;966.8984756600561;967.7870868408974;960.2078285414526;994.6419289055934;996.8604395384652;997.4065431068502;998.4458625462865;1060.01948197917;1062.2670357453371;1062.9468526573678;1064.2550214849598;1064.542246833861;1046.1212581431557;1049.9895001049968;1051.1748277602085;1051.3271506236015;1056.5562463765198;724.7390939261861;739.3936971683222;749.0973377080659;749.1497150733902;751.6504825256227;19757;19982.500437489034;19993.800061999485;20058.89970550138;20081.698774518394;19685.507862303413;19925;20574.074166662536;20754.58490830175;20792.896035519876;17502.824971750375;17569.824301756948;17722.645547089116;17785.110744462727;17979.370722024796;3661.8169091545583;3685.63143685632;3686.152184997439;3688.7602305850032;3689.0777305673582;134654.32672836573;135533;136469;136508;140698.2965085168;15814.130222837854;16042.358305667876;16091.678166436606;16413.42553010639;16430.260638271306;31510.36979260402;32044.359112818078;32251.35497290088;32256.677433225836;32381.676183237865;8452.8732069019;8520.957395213109;8529.360297977677;8555.101714319906;8562.657493700182;4134.64855487287;4240.088380998101;4250.638695710837;4253.510846252659;4265.381519679691;4782.187028205213;4951.702897826142;5107.361579802525;5110.8466745997675;5125.718085505258;942.844430668932;953.6900507335005;954.19370631816;954.8329042417549;957.3777044920718;667.6127845849396;680.3774546290188;686.9043875019336;701.5650296816007;706.6360824175554;11939.88060119405;12020.63938081859;12027.639170824888;12107.75784484323;12129.5148194073;682.1711620381261;682.74738346812;691.0636088100651;697.4246246846373;700.8913618389158;5129.538341549279;5175.689458632494;5186.844394668166;5243.947560524422;5252.238425428368;125204.37397812953;127708;130010.34994824966;130659;134539;110403.44798275958;111268.44365778119;111560.44219779012;112567.43716281366;112741.436292818;92879;94293.5285323569;94394.52802735943;96906;96970;9511.286653500876;9787.657431989846;9846.655367062118;9858.556364963737;9954.651587194414;42448.78775606102;43758.56241437545;44441.777791110835;44498;44942;56643.71678141583;57255.71372143113;57545.71227143837;57634;59341.70329148327;14410.495632652806;14730.926345368205;14798.186099764616;14841.406343746561;14905.403783848524;16506.504804855875;16525.752113718543;16528.173591320145;16559.25483353228;16661.08364039941;14282.42870285177;14403.639909002359;14628.561143165722;14664.046836955564;14893.925530372278;15430.5370838875;15478.5356439307;15505.534833955;15626.218689065274;15644.374225031326;15174.165420901956;15365.231738412811;15440.84559154394;15604.297806598503;15606.453774118305;10731.302465339728;10894.78210435771;10943.835842462211;11178.776424471627;11302.943485282522;11831.940840295743;11979.640610781691;12048.216865903689;12202.633920982385;12318.199317044364 06:33:55 INFO - PID 21389 | __end_tp_report 06:33:55 INFO - PID 21389 | __start_cc_report 06:33:55 INFO - PID 21389 | _x_x_mozilla_cycle_collect,288 06:33:55 INFO - PID 21389 | __end_cc_report 06:33:55 INFO - PID 21389 | __startTimestamp1505914435337__endTimestamp 06:33:55 INFO - PID 21389 | 06:33:55 INFO - PID 21389 | ------- Summary: start ------- 06:33:55 INFO - PID 21389 | Number of tests: 6 06:33:55 INFO - PID 21389 | 06:33:55 INFO - PID 21389 | [#0] dojo.html Cycles:165 Average:6298.19 Median:6597.08 stddev:883.48 (13.4%) stddev-sans-first:851.29 06:33:55 INFO - PID 21389 | Values: 3164.0 3186.1 3194.5 3200.9 3201.3 6286.2 6389.7 6443.4 6475.5 6500.3 6273.6 6321.1 6330.1 6344.0 6400.3 5851.9 5854.6 5859.4 5890.8 5998.0 5941.9 5992.6 6010.8 6015.9 6041.4 5930.4 5940.8 6002.5 6018.4 6034.8 582.2 5764.5 5796.2 5924.3 5930.2 5765.8 5846.5 5900.7 5929.6 5931.1 6283.2 6402.2 6412.6 6421.4 6492.3 6892.4 6895.1 6902.3 6990.5 7002.9 6799.4 6964.7 6965.4 7032.8 7046.6 6350.5 6364.0 6374.6 6375.7 6378.5 6969.2 7033.2 7050.0 7050.5 7053.3 6688.1 6728.9 6740.4 6742.7 6748.2 6532.3 6841.9 6855.0 7017.2 7031.4 6854.0 6876.3 6884.4 6976.7 6993.9 6699.9 6804.2 6867.5 6904.5 6980.4 5388.6 5390.8 5406.4 5416.8 5454.1 4718.2 4725.2 4732.0 4776.7 4782.9 5911.9 5912.8 5990.7 6003.7 6018.4 6734.9 6842.2 6874.0 6929.4 7016.4 5916.7 5932.1 5937.0 5939.6 6003.1 5844.6 5966.3 5984.8 5993.6 6006.0 6597.1 6611.2 6697.5 6705.8 6742.0 6714.9 6811.3 6831.1 6905.2 6979.4 6603.0 6609.9 6621.6 6626.2 6639.0 6785.0 6826.8 6883.7 6894.4 6898.8 6898.7 6929.7 6943.5 7053.8 7060.3 6524.1 6649.9 6672.3 6766.5 6778.8 6557.0 6617.9 6626.2 6643.3 6655.9 6585.5 6681.4 6684.3 6717.8 6739.3 6747.0 6842.8 6870.7 6884.6 6890.6 6537.4 6543.3 6544.4 6639.3 6644.6 06:33:55 INFO - PID 21389 | 06:33:55 INFO - PID 21389 | [#1] ext.html Cycles:180 Average:35581.93 Median:34093.08 stddev:49614.35 (145.5%) stddev-sans-first:49685.02 06:33:55 INFO - PID 21389 | Values: 857.5 862.3 872.8 878.0 894.7 39517.0 40746.4 40963.6 41039.6 41049.6 36649.3 36963.0 36970.6 36995.6 37113.6 7103.6 7193.5 7199.9 7210.7 7216.1 7138.9 7142.8 7226.4 7228.7 7244.5 7626.0 7821.7 7825.7 7828.3 7867.8 7762.0 7813.6 7873.4 7881.6 7882.5 7768.4 7842.2 7857.8 7859.7 7861.0 36628.8 36952.8 36994.0 37087.0 37087.6 3516.4 3516.9 3549.2 3557.9 3559.1 30619.8 30626.2 30721.5 30765.1 30878.4 5228.3 5238.1 5258.8 5280.3 5284.4 186052.0 186442.0 186785.0 188531.1 189021.0 46158.5 46284.3 46291.3 46328.1 46490.5 47616.8 47678.5 47808.3 47864.0 47912.3 15704.9 15811.5 15846.0 15899.2 15939.9 12905.4 13099.5 13121.3 13159.2 13219.9 14142.7 14236.3 14295.7 14324.6 14399.6 5496.7 5636.9 5669.7 5679.4 5753.3 1000.0 1010.2 1020.7 1027.8 1042.7 22475.8 22930.7 23068.3 23235.5 23400.8 1111.2 1116.8 1121.1 1123.4 1138.1 12046.6 12277.0 12304.2 12366.2 12441.0 203977.0 205545.9 205895.0 212981.0 213981.0 43452.6 44217.0 44237.8 44255.8 51644.0 1397.9 1403.3 1412.6 1414.5 1434.3 26953.0 27144.6 27629.4 27783.4 27793.6 35330.3 35587.6 35602.5 35620.1 35745.3 179070.0 179398.0 180070.0 180394.0 180685.0 32111.8 32512.8 32668.2 32694.2 32930.0 40491.4 40676.6 40848.4 40931.2 40936.4 30136.5 30163.5 30657.4 30670.1 30679.0 30250.4 30755.5 30772.4 30807.0 31316.4 33779.8 33866.3 34319.8 34399.1 34648.7 29606.1 30302.4 30350.7 30676.5 30859.2 22327.6 22555.3 22754.8 22923.5 23447.2 06:33:55 INFO - PID 21389 | 06:33:55 INFO - PID 21389 | [#2] jquery.html Cycles:180 Average:35476.28 Median:23010.04 stddev:87068.62 (378.4%) stddev-sans-first:87273.42 06:33:55 INFO - PID 21389 | Values: 568.5 572.6 573.9 577.2 587.3 21758.9 22823.4 22997.4 23001.4 23018.7 23136.2 23880.9 24195.3 24809.5 24904.5 6518.5 6520.4 6600.1 6612.3 6674.7 6511.0 6640.4 6640.9 6643.9 6650.7 6255.1 6474.3 6506.7 6509.0 6512.3 6520.0 6595.7 6597.2 6597.9 6613.1 5893.9 6092.8 6105.1 6131.4 6139.9 27211.6 28057.9 28063.0 28200.9 28245.2 2035.9 2073.6 2073.8 2076.7 2078.9 18570.6 18924.9 19324.4 19331.5 19561.0 2410.7 2447.1 2447.5 2467.3 2501.6 121530.4 126796.0 127384.4 134133.0 139016.0 14243.0 14635.9 14766.1 14996.3 15127.3 22858.8 22867.2 22889.2 23036.2 23100.9 10103.1 10140.1 10145.6 10177.5 10209.8 9223.1 9343.2 9343.2 9414.7 9536.9 8601.1 8801.6 8823.3 8910.5 9063.3 2584.8 2593.9 2614.8 2712.2 2766.6 926.6 930.6 952.9 957.7 968.2 18990.4 19417.4 19442.2 19587.1 19676.9 997.3 999.1 1005.9 1006.8 1021.4 10715.5 10828.5 10857.2 10943.1 10958.0 505276.0 517990.0 521211.0 523251.0 532659.0 115836.0 116170.0 119050.4 119169.4 121930.0 1011.5 1032.8 1060.5 1117.1 1140.5 19809.8 20215.6 20277.7 20493.8 20519.5 55754.7 57378.9 57499.7 57959.0 59446.4 70203.6 72270.0 72369.0 72898.6 73354.6 14328.3 14413.6 14670.3 14672.2 14672.9 22786.0 23367.9 23411.6 23413.6 23449.9 18086.4 18458.5 18743.0 18815.7 18911.1 16378.7 16663.8 16735.2 16958.0 17391.5 18960.2 19007.1 19083.5 19128.8 19389.4 13552.7 14341.9 14360.0 14394.1 14480.5 18627.5 18705.9 18721.4 18769.9 18783.5 06:33:55 INFO - PID 21389 | 06:33:55 INFO - PID 21389 | [#3] mootools.html Cycles:180 Average:9259.39 Median:14694.82 stddev:6669.84 (45.4%) stddev-sans-first:6662.00 06:33:55 INFO - PID 21389 | Values: 1339.1 1371.3 1412.2 1416.7 1422.2 14723.2 14917.6 15428.2 15450.5 15476.3 15459.7 15557.6 15683.2 15695.9 15744.4 2559.9 2718.0 2780.7 2781.5 2791.2 2747.5 2776.4 2784.3 2785.8 2789.8 2725.6 2725.9 2736.1 2755.4 2757.4 2635.6 2677.9 2695.8 2701.8 2704.0 2608.4 2658.9 2663.1 2669.3 2679.9 14686.5 15092.9 15105.7 15111.0 15119.0 7396.3 7575.2 7601.6 7616.0 7616.4 5086.5 5233.9 5311.8 5352.6 5476.5 1925.8 1944.9 1965.8 1997.3 2003.4 26450.2 26624.7 26685.3 26707.3 26711.7 6666.7 6718.1 6791.5 6794.9 6866.3 16808.7 17009.1 17025.0 17030.5 17071.2 7522.8 7758.7 7767.6 7767.7 7777.1 7036.9 7191.5 7234.9 7284.7 7336.3 5185.1 5384.5 5398.4 5398.8 5406.6 1486.5 1491.5 1505.7 1518.4 1523.8 865.3 894.8 911.7 915.7 918.7 7615.1 7640.2 7668.3 7693.3 7728.5 692.0 710.2 717.7 725.5 725.7 4927.3 5532.6 5540.1 5540.3 5729.9 21021.8 21573.4 21731.6 21805.8 21915.8 20676.8 21381.1 21388.6 21859.2 21876.2 11362.0 11525.6 11547.8 11549.8 11567.6 8151.5 8178.4 8244.2 8291.8 8468.2 17784.3 18614.4 18732.6 18833.3 18841.2 18384.7 19004.8 19062.1 19200.6 19241.9 10540.1 10550.8 10624.4 10686.5 10694.6 13761.5 13949.4 13954.7 13969.9 13970.4 11135.8 11226.2 11254.3 11292.0 11536.1 10277.5 10537.3 10570.3 10695.6 10755.8 9167.3 9510.6 9519.9 9521.8 9537.5 7122.4 7172.0 7233.8 7283.3 7289.6 7241.9 7320.0 7329.1 7369.8 7412.9 06:33:55 INFO - PID 21389 | 06:33:55 INFO - PID 21389 | [#4] prototype.html Cycles:180 Average:5453.52 Median:6074.27 stddev:1522.86 (25.1%) stddev-sans-first:1481.06 06:33:55 INFO - PID 21389 | Values: 500.4 504.8 519.6 522.3 525.3 6188.2 6328.8 6357.5 6386.6 6406.2 6217.9 6241.8 6289.0 6341.5 6376.6 5218.7 5219.4 5222.6 5224.9 5267.1 4827.1 4905.3 5151.6 5171.3 5218.7 4852.9 4902.7 4916.0 4925.2 4981.3 4949.2 4949.9 4966.4 4971.0 4991.7 4224.4 4414.4 4451.9 4455.3 4462.8 5985.7 6044.6 6056.4 6064.5 6084.0 5595.9 5642.4 5649.6 5655.4 5670.9 6483.4 6498.5 6513.6 6518.9 6538.3 4625.5 4836.1 4842.8 4871.2 4882.8 6462.2 6626.7 6727.6 6828.7 6869.6 4928.4 4951.0 4961.2 4967.2 4973.3 5014.5 5018.5 5019.3 5050.0 5057.4 5644.1 5668.9 5708.5 5709.7 5726.3 6547.6 6556.2 6596.6 6610.8 6674.4 1701.7 1702.4 1705.8 1712.6 1718.7 1145.7 1210.4 1213.8 1217.9 1220.4 5435.9 5561.2 5607.4 5631.8 5732.4 6473.4 6482.6 6522.4 6578.5 6612.6 5584.4 5640.0 5645.3 5683.0 5720.6 3450.4 3486.2 3516.5 3536.7 3542.0 6318.2 6364.8 6366.4 6394.0 6476.7 6727.6 6761.4 6793.7 6841.9 6877.7 5903.8 6353.8 6364.4 6370.5 6389.3 6604.7 6616.0 6629.9 6631.5 6633.9 6470.8 6479.2 6558.7 6566.0 6634.6 6425.0 6425.5 6432.8 6478.3 6509.2 4854.9 4862.3 4901.6 4913.2 4951.1 6396.6 6453.9 6493.9 6603.7 6613.9 6038.8 6308.1 6383.8 6385.6 6415.7 6337.8 6344.6 6374.6 6417.5 6433.5 6343.7 6395.3 6412.0 6416.3 6454.6 6510.3 6514.4 6549.4 6559.0 6565.6 6237.9 6312.3 6323.5 6325.3 6327.3 06:33:55 INFO - PID 21389 | 06:33:55 INFO - PID 21389 | [#5] yui.html Cycles:180 Average:24311.32 Median:20070.30 stddev:35852.18 (178.6%) stddev-sans-first:35909.64 06:33:55 INFO - PID 21389 | Values: 894.5 902.7 933.5 938.8 943.5 19042.1 19812.6 19826.4 19889.4 19913.1 17197.6 17402.1 17689.0 18304.8 18670.3 964.2 965.8 966.7 966.9 967.8 960.2 994.6 996.9 997.4 998.4 1060.0 1062.3 1062.9 1064.3 1064.5 1046.1 1050.0 1051.2 1051.3 1056.6 724.7 739.4 749.1 749.1 751.7 19757.0 19982.5 19993.8 20058.9 20081.7 19685.5 19925.0 20574.1 20754.6 20792.9 17502.8 17569.8 17722.6 17785.1 17979.4 3661.8 3685.6 3686.2 3688.8 3689.1 134654.3 135533.0 136469.0 136508.0 140698.3 15814.1 16042.4 16091.7 16413.4 16430.3 31510.4 32044.4 32251.4 32256.7 32381.7 8452.9 8521.0 8529.4 8555.1 8562.7 4134.6 4240.1 4250.6 4253.5 4265.4 4782.2 4951.7 5107.4 5110.8 5125.7 942.8 953.7 954.2 954.8 957.4 667.6 680.4 686.9 701.6 706.6 11939.9 12020.6 12027.6 12107.8 12129.5 682.2 682.7 691.1 697.4 700.9 5129.5 5175.7 5186.8 5243.9 5252.2 125204.4 127708.0 130010.3 130659.0 134539.0 110403.4 111268.4 111560.4 112567.4 112741.4 92879.0 94293.5 94394.5 96906.0 96970.0 9511.3 9787.7 9846.7 9858.6 9954.7 42448.8 43758.6 44441.8 44498.0 44942.0 56643.7 57255.7 57545.7 57634.0 59341.7 14410.5 14730.9 14798.2 14841.4 14905.4 16506.5 16525.8 16528.2 16559.3 16661.1 14282.4 14403.6 14628.6 14664.0 14893.9 15430.5 15478.5 15505.5 15626.2 15644.4 15174.2 15365.2 15440.8 15604.3 15606.5 10731.3 10894.8 10943.8 11178.8 11302.9 11831.9 11979.6 12048.2 12202.6 12318.2 06:33:55 INFO - PID 21389 | -------- Summary: end -------- 06:33:55 INFO - PID 21389 | 06:33:56 INFO - TEST-INFO | 21389: exit 0 06:33:56 INFO - TEST-OK | dromaeo_css | took 1108331ms 06:33:56 INFO - TEST-START | kraken 06:33:56 INFO - Initialising browser for kraken test... 06:33:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57370/getInfo.html -profile /tmp/tmpRLI4Pj/profile 06:33:56 INFO - TEST-INFO | started process 21762 (/builds/slave/test/build/application/firefox/firefox http://localhost:57370/getInfo.html) 06:34:00 INFO - TEST-INFO | 21762: exit 0 06:34:01 INFO - Browser initialized. 06:34:01 INFO - Running cycle 1/1 for kraken test... 06:34:01 INFO - Using env: {'DISPLAY': ':0', 06:34:01 INFO - 'HOME': '/home/cltbld', 06:34:01 INFO - 'JSGC_DISABLE_POISONING': '1', 06:34:01 INFO - 'LANG': 'en_US.UTF-8', 06:34:01 INFO - 'LANGUAGE': 'en_US:en', 06:34:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 06:34:01 INFO - 'LOGNAME': 'cltbld', 06:34:01 INFO - 'MAIL': '/var/mail/cltbld', 06:34:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:34:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:34:01 INFO - 'MOZ_CRASHREPORTER': '1', 06:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 06:34:01 INFO - 'MOZ_NO_REMOTE': '1', 06:34:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:34:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:01 INFO - 'NO_EM_RESTART': '1', 06:34:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:01 INFO - 'PWD': '/builds/slave/test', 06:34:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:34:01 INFO - 'RUST_BACKTRACE': 'full', 06:34:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:34:01 INFO - 'SHELL': '/bin/bash', 06:34:01 INFO - 'SHLVL': '1', 06:34:01 INFO - 'TERM': 'linux', 06:34:01 INFO - 'TMOUT': '86400', 06:34:01 INFO - 'USER': 'cltbld', 06:34:01 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192', 06:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:01 INFO - '_': '/tools/buildbot/bin/python'} 06:34:01 INFO - TEST-INFO | started process 21988 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRLI4Pj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:34:01 INFO - PID 21988 | 06:34:01 INFO - PID 21988 | (/builds/slave/test/build/application/firefox/firefox:22037): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:34:01 INFO - PID 21988 | 06:34:12 INFO - PID 21988 | RSS: Main: 155983872 06:34:12 INFO - PID 21988 | 06:34:14 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57370/tests/kraken/driver.html?testName=audio-beat-detection) 06:34:15 INFO - PID 21988 | RSS: Main: 161304576 06:34:15 INFO - PID 21988 | 06:34:18 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57370/tests/kraken/driver.html?testName=audio-dft) 06:34:19 INFO - PID 21988 | RSS: Main: 161722368 06:34:19 INFO - PID 21988 | 06:34:21 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57370/tests/kraken/driver.html?testName=audio-fft) 06:34:22 INFO - PID 21988 | RSS: Main: 158060544 06:34:22 INFO - PID 21988 | 06:34:24 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57370/tests/kraken/driver.html?testName=audio-oscillator) 06:34:25 INFO - PID 21988 | RSS: Main: 159645696 06:34:25 INFO - PID 21988 | 06:34:27 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57370/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:34:28 INFO - PID 21988 | RSS: Main: 159621120 06:34:28 INFO - PID 21988 | 06:34:31 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57370/tests/kraken/driver.html?testName=imaging-darkroom) 06:34:31 INFO - PID 21988 | RSS: Main: 161615872 06:34:31 INFO - PID 21988 | 06:34:35 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57370/tests/kraken/driver.html?testName=imaging-desaturate) 06:34:36 INFO - PID 21988 | RSS: Main: 161878016 06:34:36 INFO - PID 21988 | 06:34:39 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57370/tests/kraken/driver.html?testName=json-parse-financial) 06:34:39 INFO - PID 21988 | RSS: Main: 161943552 06:34:39 INFO - PID 21988 | 06:34:41 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57370/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:34:41 INFO - PID 21988 | RSS: Main: 162234368 06:34:41 INFO - PID 21988 | 06:34:43 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57370/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:34:44 INFO - PID 21988 | RSS: Main: 161628160 06:34:44 INFO - PID 21988 | 06:34:46 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57370/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:34:46 INFO - PID 21988 | RSS: Main: 159633408 06:34:46 INFO - PID 21988 | 06:34:49 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:34:50 INFO - PID 21988 | RSS: Main: 159326208 06:34:50 INFO - PID 21988 | 06:34:53 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:34:53 INFO - PID 21988 | RSS: Main: 159289344 06:34:53 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | Cycle 1(1): loaded http://localhost:57370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57370/tests/kraken/driver.html?testName=ai-astar) 06:34:55 INFO - PID 21988 | RSS: Main: 161087488 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | __start_tp_report 06:34:55 INFO - PID 21988 | _x_x_mozilla_page_load 06:34:55 INFO - PID 21988 | _x_x_mozilla_page_load_details 06:34:55 INFO - PID 21988 | |i|pagename|runs| 06:34:55 INFO - PID 21988 | |0;ai-astar;108;108;108;107;107;106;107;108;108;107 06:34:55 INFO - PID 21988 | |1;audio-beat-detection;121;114;116;117;140;114;144;183;117;143 06:34:55 INFO - PID 21988 | |2;audio-dft;163;165;164;163;161;163;163;164;167;169 06:34:55 INFO - PID 21988 | |3;audio-fft;73;74;75;74;85;75;74;74;76;77 06:34:55 INFO - PID 21988 | |4;audio-oscillator;80;79;79;85;78;80;124;79;112;78 06:34:55 INFO - PID 21988 | |5;imaging-gaussian-blur;113;114;114;113;113;114;113;114;114;113 06:34:55 INFO - PID 21988 | |6;imaging-darkroom;148;148;148;149;148;148;150;147;147;146 06:34:55 INFO - PID 21988 | |7;imaging-desaturate;94;94;94;95;94;96;93;95;94;94 06:34:55 INFO - PID 21988 | |8;json-parse-financial;52;53;53;53;53;53;54;52;54;53 06:34:55 INFO - PID 21988 | |9;json-stringify-tinderbox;38;38;38;39;38;38;37;38;39;38 06:34:55 INFO - PID 21988 | |10;stanford-crypto-aes;76;74;75;75;73;73;77;73;77;73 06:34:55 INFO - PID 21988 | |11;stanford-crypto-ccm;110;112;114;110;113;130;111;113;119;116 06:34:55 INFO - PID 21988 | |12;stanford-crypto-pbkdf2;174;170;171;171;168;171;174;175;179;167 06:34:55 INFO - PID 21988 | |13;stanford-crypto-sha256-iterative;67;67;67;72;66;65;65;65;64;65 06:34:55 INFO - PID 21988 | __end_tp_report 06:34:55 INFO - PID 21988 | __start_cc_report 06:34:55 INFO - PID 21988 | _x_x_mozilla_cycle_collect,750 06:34:55 INFO - PID 21988 | __end_cc_report 06:34:55 INFO - PID 21988 | __startTimestamp1505914495691__endTimestamp 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | ------- Summary: start ------- 06:34:55 INFO - PID 21988 | Number of tests: 14 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#0] ai-astar Cycles:10 Average:107.40 Median:108.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 06:34:55 INFO - PID 21988 | Values: 108.0 108.0 108.0 107.0 107.0 106.0 107.0 108.0 108.0 107.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:130.50 stddev:22.09 (16.9%) stddev-sans-first:23.14 06:34:55 INFO - PID 21988 | Values: 121.0 114.0 116.0 117.0 140.0 114.0 144.0 183.0 117.0 143.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#2] audio-dft Cycles:10 Average:164.20 Median:164.00 stddev:2.30 (1.4%) stddev-sans-first:2.40 06:34:55 INFO - PID 21988 | Values: 163.0 165.0 164.0 163.0 161.0 163.0 163.0 164.0 167.0 169.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#3] audio-fft Cycles:10 Average:75.70 Median:75.00 stddev:3.47 (4.6%) stddev-sans-first:3.54 06:34:55 INFO - PID 21988 | Values: 73.0 74.0 75.0 74.0 85.0 75.0 74.0 74.0 76.0 77.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#4] audio-oscillator Cycles:10 Average:87.40 Median:79.00 stddev:16.49 (20.9%) stddev-sans-first:17.28 06:34:55 INFO - PID 21988 | Values: 80.0 79.0 79.0 85.0 78.0 80.0 124.0 79.0 112.0 78.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 06:34:55 INFO - PID 21988 | Values: 113.0 114.0 114.0 113.0 113.0 114.0 113.0 114.0 114.0 113.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 06:34:55 INFO - PID 21988 | Values: 148.0 148.0 148.0 149.0 148.0 148.0 150.0 147.0 147.0 146.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 06:34:55 INFO - PID 21988 | Values: 94.0 94.0 94.0 95.0 94.0 96.0 93.0 95.0 94.0 94.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.67 (1.3%) stddev-sans-first:0.60 06:34:55 INFO - PID 21988 | Values: 52.0 53.0 53.0 53.0 53.0 53.0 54.0 52.0 54.0 53.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.57 (1.5%) stddev-sans-first:0.60 06:34:55 INFO - PID 21988 | Values: 38.0 38.0 38.0 39.0 38.0 38.0 37.0 38.0 39.0 38.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:75.00 stddev:1.65 (2.2%) stddev-sans-first:1.67 06:34:55 INFO - PID 21988 | Values: 76.0 74.0 75.0 75.0 73.0 73.0 77.0 73.0 77.0 73.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#11] stanford-crypto-ccm Cycles:10 Average:114.80 Median:113.50 stddev:6.01 (5.3%) stddev-sans-first:6.12 06:34:55 INFO - PID 21988 | Values: 110.0 112.0 114.0 110.0 113.0 130.0 111.0 113.0 119.0 116.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:172.50 stddev:3.56 (2.1%) stddev-sans-first:3.70 06:34:55 INFO - PID 21988 | Values: 174.0 170.0 171.0 171.0 168.0 171.0 174.0 175.0 179.0 167.0 06:34:55 INFO - PID 21988 | 06:34:55 INFO - PID 21988 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:2.26 (3.4%) stddev-sans-first:2.39 06:34:55 INFO - PID 21988 | Values: 67.0 67.0 67.0 72.0 66.0 65.0 65.0 65.0 64.0 65.0 06:34:55 INFO - PID 21988 | -------- Summary: end -------- 06:34:55 INFO - PID 21988 | 06:34:56 INFO - PID 21988 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:34:56 INFO - TEST-INFO | 21988: exit 0 06:34:56 INFO - TEST-OK | kraken | took 60287ms 06:34:56 INFO - SUITE-END | took 1168s 06:34:56 INFO - Completed test suite (00:19:28) 06:34:56 INFO - javascript benchmark 06:34:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8435.338956959826, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3164.0033389482314, 3186.060112266881, 3194.4569423198054, 3200.9359812803746, 3201.311717980635, 6286.182796236494, 6389.6805159742, 6443.35566443356, 6475.514336424775, 6500.28496865345, 6273.623582585048, 6321.083442900776, 6330.082138089986, 6344.0, 6400.295967443578, 5851.853703657422, 5854.560907931911, 5859.355470898197, 5890.793822216223, 5997.970010149965, 5941.910871336933, 5992.5505587080925, 6010.8497287567725, 6015.9398406016035, 6041.395860413967, 5930.406959304079, 5940.792072277472, 6002.459778619924, 6018.368071352511, 6034.849128771794, 582.2473285391227, 5764.538836893081, 5796.159556864273, 5924.348321684612, 5930.169776231331, 5765.827025189209, 5846.53343202566, 5900.734466948998, 5929.555283353722, 5931.110333449974, 6283.151774510428, 6402.167718196652, 6412.61524308543, 6421.4220720134945, 6492.3183065777885, 6892.379685828249, 6895.069165662621, 6902.275261097613, 6990.4757143214465, 7002.929970700278, 6799.388055075067, 6964.686589103452, 6965.373116419547, 7032.75385361515, 7046.612436316001, 6350.491960643138, 6363.968180159116, 6374.649394283312, 6375.681215939185, 6378.4578310843635, 6969.16370035599, 7033.226345102034, 7049.9647501762165, 7050.541714788523, 7053.329933656294, 6688.063671085954, 6728.899066514006, 6740.359665831738, 6742.730290788411, 6748.19021717397, 6532.297578216846, 6841.863162736817, 6855.006024126474, 7017.157941047005, 7031.437485001187, 6854.0404343390965, 6876.346747059021, 6884.380405763505, 6976.720931162697, 6993.860122797516, 6699.899501507482, 6804.183497980274, 6867.519273650796, 6904.516683832184, 6980.406665433394, 5388.595855310789, 5390.811321603804, 5406.432324605938, 5416.756245968941, 5454.12733962572, 4718.221493453541, 4725.1730947084125, 4732.02993386355, 4776.665633405698, 4782.856514304576, 5911.940880591139, 5912.763489460373, 5990.670513121733, 6003.729832157476, 6018.428249316308, 6734.8989765153565, 6842.178938527409, 6874.0, 6929.411000065051, 7016.403605693471, 5916.674582897982, 5932.0508718604815, 5937.0, 5939.584229104008, 6003.099535069687, 5844.561657875592, 5966.31387390447, 5984.820455386345, 5993.640381577032, 6006.039033754579, 6597.076409302702, 6611.206655201407, 6697.464202863759, 6705.832354191184, 6742.0224067509935, 6714.932850671528, 6811.284815094344, 6831.077804496329, 6905.1713794345, 6979.406750426169, 6603.042558828847, 6609.933900660932, 6621.602703837785, 6626.204855417188, 6638.966805165944, 6784.966075169593, 6826.795196144124, 6883.65581720922, 6894.413974812197, 6898.793036208922, 6898.689558969959, 6929.688164032731, 6943.51395402317, 7053.788386348212, 7060.258672839484, 6524.119243902012, 6649.900251496329, 6672.332766723483, 6766.492513061442, 6778.796636100925, 6556.967215163893, 6617.867642647024, 6626.237982632059, 6643.30245324234, 6655.900161497679, 6585.473162147113, 6681.365270299509, 6684.331566843472, 6717.764878229238, 6739.258681545124, 6746.966265168643, 6842.8289292766085, 6870.725170993304, 6884.621345826027, 6890.586564806128, 6537.378948999837, 6543.280239173782, 6544.411003009562, 6639.302873198245, 6644.60132392058], "value": 6233.745377708174, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [857.4726543175944, 862.3273846399809, 872.8297981893526, 877.9561021948905, 894.7181637784098, 39517.0, 40746.38880416796, 40963.59036409635, 41039.58960410399, 41049.58950410495, 36649.267014659694, 36963.0, 36970.63029369705, 36995.630043699624, 37113.628863711354, 7103.609301488416, 7193.496455248135, 7199.928000720004, 7210.711571537132, 7216.061911951439, 7138.8572228555395, 7142.821429464253, 7226.421886249113, 7228.746993855216, 7244.529105608119, 7625.970493983325, 7821.726239581616, 7825.686972521121, 7828.295453409192, 7867.7639670809685, 7761.96119019407, 7813.648385822623, 7873.409494287951, 7881.645325960346, 7882.5270483771155, 7768.3785297176655, 7842.215778422112, 7857.8035549111155, 7859.68561257549, 7861.0, 36628.816855915815, 36952.81523592365, 36994.0, 37087.0, 37087.629123708684, 3516.384632689269, 3516.876909308162, 3549.2014296783277, 3557.875474358385, 3559.1280136366754, 30619.846900765355, 30626.234344141576, 30721.539176912363, 30765.077047688606, 30878.382432351227, 5228.320318358627, 5238.083335416325, 5258.763355649005, 5280.287161233261, 5284.445133260991, 186052.0, 186442.0, 186785.0, 188531.05734471377, 189021.0, 46158.538414616094, 46284.305735414, 46291.30563041557, 46328.07343853104, 46490.53509464862, 47616.76191619089, 47678.5232147681, 47808.28287575689, 47864.0, 47912.28131578029, 15704.921475392779, 15811.525654230392, 15846.049237045814, 15899.205039747967, 15939.920300398424, 12905.419256133497, 13099.476020959055, 13121.343932803322, 13159.210447373189, 13219.867801322056, 14142.717145657029, 14236.28818559068, 14295.714085718228, 14324.57026289213, 14399.568012959628, 5496.697681627549, 5636.887262254814, 5669.659820410789, 5679.375268720438, 5753.280839895013, 999.9500024998866, 1010.1867996263079, 1020.7295066807302, 1027.7944411117805, 1042.697617690861, 22475.775242247368, 22930.65604015941, 23068.307950761504, 23235.53528929412, 23400.765992339857, 1111.2499063132516, 1116.815725405299, 1121.1423261205184, 1123.3877536742496, 1138.0781566930812, 12046.570094576791, 12277.045632868052, 12304.200226985216, 12366.249614301234, 12441.00471962259, 203977.0, 205545.94454055568, 205895.0, 212981.0, 213981.0, 43452.56547434549, 44217.0, 44237.77881110574, 44255.778721106835, 51644.0, 1397.923114228707, 1403.3193900958026, 1412.552134062594, 1414.476643641859, 1434.3330351386655, 26953.0, 27144.592831107944, 27629.447411052068, 27783.44433111286, 27793.583096253977, 35330.29339413249, 35587.644123558435, 35602.46596301109, 35620.109497262776, 35745.28509429745, 179070.0, 179398.0, 180070.0, 180394.0, 180685.0, 32111.839440802647, 32512.83743581267, 32668.183295417806, 32694.182645434055, 32930.0, 40491.39262911118, 40676.59323406728, 40848.387274191504, 40931.1813763729, 40936.385954211306, 30136.547951780303, 30163.547546787253, 30657.386852263277, 30670.079897603107, 30679.0, 30250.394992099595, 30755.53866692046, 30772.38455230838, 30807.0, 31316.37367252688, 33779.83110084434, 33866.322673545896, 34319.828400857834, 34399.14002149966, 34648.65351346555, 29606.111816645534, 30302.393952121274, 30350.696493034786, 30676.539851901794, 30859.2285192863, 22327.553448931256, 22555.323340299816, 22754.772452275265, 22923.541529169655, 23447.17934872271], "value": 16715.251028224466, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.5480043365524, 572.5820151289561, 573.8966390309288, 577.2092233639914, 587.2747157260784, 21758.89120554397, 22823.42941426465, 22997.425064373398, 23001.4249643759, 23018.65472017921, 23136.190233341837, 23880.880595597042, 24195.27414177573, 24809.50380992379, 24904.501909961837, 6518.51111166662, 6520.4457621102265, 6600.141981542392, 6612.305707900673, 6674.6662666866705, 6511.023346498016, 6640.369164929324, 6640.93359066408, 6643.867122657544, 6650.733970641193, 6255.093011513328, 6474.320196379383, 6506.70719817607, 6509.0, 6512.348765123497, 6519.967400162993, 6595.70319335631, 6597.208334999783, 6597.9340206598035, 6613.074169616258, 5893.941060589382, 6092.817215483543, 6105.114758360013, 6131.448169664752, 6139.907901381483, 27211.591826122625, 28057.859710701316, 28063.0177943773, 28200.858995705094, 28245.152645420465, 2035.9389218323474, 2073.6474799284006, 2073.8340932725346, 2076.7092607034874, 2078.8856612886284, 18570.62858742831, 18924.905375473172, 19324.420267392, 19331.51671208217, 19561.0, 2410.710714714228, 2447.0945750072588, 2447.5349683560066, 2467.284487498623, 2501.574732295514, 121530.3923480377, 126796.0, 127384.36307818495, 134133.0, 139016.0, 14242.998444367973, 14635.900868722434, 14766.093562575055, 14996.325165367367, 15127.319270632848, 22858.771412285998, 22867.19964801257, 22889.198878039188, 23036.193733219592, 23100.884495577415, 10103.141232995278, 10140.087392134743, 10145.594176232868, 10177.491125443697, 10209.846852297222, 9223.077692230858, 9343.159115679486, 9343.205827504602, 9414.65779648215, 9536.856947145798, 8601.139886011473, 8801.611379618893, 8823.250023748053, 8910.465372077697, 9063.274938004944, 2584.831985920909, 2593.883275252618, 2614.8038897082797, 2712.199901029201, 2766.5573508238585, 926.5737760630051, 930.5765876526165, 952.9380590261611, 957.7078990907701, 968.2060710217695, 18990.430287091134, 19417.417477475698, 19442.222311107682, 19587.11857966395, 19676.90161549183, 997.2819569909653, 999.0908273471107, 1005.9295849290626, 1006.8489726540931, 1021.3616489693941, 10715.517801698943, 10828.458577071273, 10857.239993200557, 10943.069839063768, 10958.0, 505276.0246463926, 517990.0, 521211.0, 523251.0, 532659.0, 115836.0, 116170.0, 119050.4047479757, 119169.40415298041, 121930.0, 1011.4790882695418, 1032.8192566300868, 1060.4644654449453, 1117.1174771930084, 1140.4696815980774, 19809.801901980794, 20215.59568808645, 20277.69583456279, 20493.79506204919, 20519.4870128248, 55754.72122639523, 57378.85242295047, 57499.71250143723, 57959.0, 59446.405535945814, 70203.64898175477, 72270.0, 72369.0, 72898.63550682424, 73354.63322683566, 14328.283585820876, 14413.630705083, 14670.266486675837, 14672.193029383487, 14672.926635366755, 22786.0, 23367.88316058409, 23411.648825267293, 23413.648795267745, 23449.882750586137, 18086.366977155736, 18458.538536536693, 18743.0, 18815.717764233275, 18911.148998295386, 16378.67242655164, 16663.83336166671, 16735.163241837618, 16958.0, 17391.47825565235, 18960.24159033623, 19007.144678489225, 19083.522911927314, 19128.8087119127, 19389.418317450498, 13552.728945421233, 14341.9282903589, 14360.0, 14394.136351818506, 14480.493182738976, 18627.534311642317, 18705.906470467562, 18721.438356849318, 18769.90615046916, 18783.530411739815], "value": 11931.073571300489, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.1429485129515, 1371.2663724907177, 1412.216219997902, 1416.7237388709202, 1422.1893520693209, 14723.190224537646, 14917.552473425787, 15428.151451670154, 15450.5364839055, 15476.303566339513, 15459.69080618387, 15557.611059723513, 15683.215839208022, 15695.921520392381, 15744.448944286953, 2559.872006399682, 2717.986410067947, 2780.7219278072234, 2781.4576157649267, 2791.1905547391234, 2747.4505098980185, 2776.444711057786, 2784.276088217068, 2785.8049936504453, 2789.8186617869874, 2725.6184134221226, 2725.9182224533197, 2736.1244401791437, 2755.3524921643398, 2757.4071574611453, 2635.578307470805, 2677.9062732804355, 2695.8382497050143, 2701.7568418842397, 2703.9594406083925, 2608.4261462478225, 2658.8936442542276, 2663.0812369732603, 2669.305980445079, 2679.946401071987, 14686.485972990895, 15092.924535377253, 15105.697886042328, 15111.0, 15119.0, 7396.3343299103335, 7575.242475752436, 7601.581912994783, 7615.961920190363, 7616.352610028154, 5086.516780905808, 5233.947660523384, 5311.787528498855, 5352.598555108382, 5476.452354764531, 1925.7881633020356, 1944.8638595298335, 1965.8132477414765, 1997.3209108902897, 2003.3689387842928, 26450.206493805217, 26624.733752662418, 26685.332866678295, 26707.332316692045, 26711.732882671313, 6666.733330666719, 6718.126643536309, 6791.490638202055, 6794.8641027180165, 6866.313368663094, 16808.663826723398, 17009.149542522824, 17025.0, 17030.48908532746, 17071.231794569274, 7522.811929701692, 7758.689652413953, 7767.611619419006, 7767.72812951544, 7777.066751989684, 7036.894446583203, 7191.460640451987, 7234.927650723531, 7284.745033923893, 7336.339729424378, 5185.118529849935, 5384.461553844662, 5398.406175320712, 5398.811041613603, 5406.621536492407, 1486.5094518808767, 1491.4779827060443, 1505.6762795998914, 1518.3546992528131, 1523.7866698662197, 865.3336930563431, 894.7852515396258, 911.7053783627133, 915.6795121707482, 918.7197904639015, 7615.124260709978, 7640.197779233212, 7668.309852113337, 7693.307602315818, 7728.459007869507, 691.9965400172966, 710.2471380336859, 717.666285177395, 725.4994054102758, 725.677073702208, 4927.285543596158, 5532.640378375393, 5540.085885828874, 5540.307461567304, 5729.88540229193, 21021.789782101983, 21573.352799416043, 21731.565368692536, 21805.781942180376, 21915.780842191692, 20676.793232067786, 21381.144754209967, 21388.572228555342, 21859.234926777488, 21876.23433179831, 11362.0, 11525.596604118815, 11547.769044619228, 11549.76900461986, 11567.595134170264, 8151.470154439942, 8178.427510074238, 8244.175582441709, 8291.839142520173, 8468.153184681483, 17784.288628455233, 18614.44156675302, 18732.6253474927, 18833.340833070775, 18841.15214815364, 18384.724229136307, 19004.809951900857, 19062.14220360115, 19200.61598768045, 19241.90379048096, 10540.051395374454, 10550.788984220426, 10624.36253824773, 10686.465676715978, 10694.572217111228, 13761.518346857813, 13949.3722782473, 13954.651133721332, 13969.860301396857, 13970.37133329023, 11135.83296250573, 11226.158038147334, 11254.268472549258, 11291.983721465107, 11536.134789890624, 10277.486125693835, 10537.315074520438, 10570.312929659547, 10695.625653102104, 10755.784884302113, 9167.312451566026, 9510.619575216913, 9519.857202141835, 9521.761955951157, 9537.52312384392, 7122.394596459152, 7171.964140179266, 7233.842585186345, 7283.308085731847, 7289.562626242442, 7241.855162896818, 7319.963400182965, 7329.083864516935, 7369.8157546061775, 7412.851742965218], "value": 6585.366543393676, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.44912100603096, 504.7885765653633, 519.5791408958743, 522.2610006840321, 525.2908573425875, 6188.226471691039, 6328.8101356959305, 6357.523185761075, 6386.552941294105, 6406.1992250968515, 6217.875642487147, 6241.843953901143, 6289.0, 6341.46097581705, 6376.617402955814, 5218.660787048849, 5219.399769026563, 5222.556082732954, 5224.8693782655355, 5267.104592219332, 4827.131116399047, 4905.264210368456, 5151.639385243035, 5171.301874246985, 5218.686878787266, 4852.908095678462, 4902.705837649734, 4915.950840491603, 4925.162722337212, 4981.302617633534, 4949.208126699728, 4949.876253093701, 4966.404031516205, 4970.980948905466, 4991.725455099968, 4224.408582798411, 4414.426124603813, 4451.933221001687, 4455.309427038814, 4462.799174037176, 5985.670788106652, 6044.576879618428, 6056.394360563952, 6064.545159113084, 6084.0, 5595.944040559623, 5642.351129620064, 5649.6327738696855, 5655.4061823508355, 5670.943290567124, 6483.4341112388, 6498.5126115541525, 6513.641749703765, 6518.934810651927, 6538.280789113242, 4625.4911959684405, 4836.056968891067, 4842.83050093245, 4871.171900776841, 4882.779305173706, 6462.192225971754, 6626.668666566652, 6727.596344219362, 6828.726850926007, 6869.622170780655, 4928.408590969107, 4950.975245123751, 4961.231009193581, 4967.236630996017, 4973.328600638867, 5014.548690617862, 5018.519536736671, 5019.310806557178, 5050.0, 5057.418396884405, 5644.068728659725, 5668.858278542987, 5708.54331653475, 5709.68596727184, 5726.341470730919, 6547.607143571401, 6556.213254409501, 6596.637184954873, 6610.834729131714, 6674.399304062657, 1701.6766814305242, 1702.4041585445243, 1705.8485522272417, 1712.623222890962, 1718.6906356855886, 1145.6505765741363, 1210.449245593253, 1213.8482689663792, 1217.926924384522, 1220.4385982448173, 5435.891282174334, 5561.165825126182, 5607.439256074442, 5631.7747290108755, 5732.426757324234, 6473.449756770728, 6482.57863238888, 6522.369407648088, 6578.473722102221, 6612.570182938093, 5584.385717571064, 5640.0, 5645.266115404971, 5682.971585142048, 5720.599558030981, 3450.447928331455, 3486.2330287336745, 3516.49010893419, 3536.6993805526304, 3541.982290088533, 6318.178636777189, 6364.777232796922, 6366.427021568081, 6394.0, 6476.708548115346, 6727.62998035103, 6761.425278851353, 6793.694283757242, 6841.897371539431, 6877.690503927335, 5903.763849445974, 6353.807936261982, 6364.363563643598, 6370.458511026614, 6389.329120442287, 6604.735810567524, 6616.0, 6629.867402651824, 6631.469482441489, 6633.933660663524, 6470.805875823733, 6479.222493300834, 6558.672066396756, 6566.0124643286235, 6634.568753031231, 6425.0, 6425.453836423956, 6432.807015789534, 6478.255000674794, 6509.153810004669, 4854.854354369375, 4862.294967229803, 4901.583365413837, 4913.238448040559, 4951.133551628379, 6396.552241343062, 6453.935460645521, 6493.902591461037, 6603.735850565923, 6613.933860661332, 6038.84902877414, 6308.14839996613, 6383.840403989938, 6385.553011289165, 6415.743370265137, 6337.841553961188, 6344.555881088279, 6374.649394283359, 6417.454516366161, 6433.453156481752, 6343.714532845941, 6395.264544577433, 6411.96794016027, 6416.294207637063, 6454.644994525346, 6510.251321098134, 6514.3811337922825, 6549.377809108127, 6559.0, 6565.606063636197, 6237.906431403442, 6312.3056463788025, 6323.494120470259, 6325.335839736761, 6327.303996560283], "value": 5004.6202229626715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [894.4990805149121, 902.7066203483869, 933.4585940154711, 938.8403971324874, 943.490515121834, 19042.14310356034, 19812.60374792507, 19826.405207843753, 19889.40331790045, 19913.103910324033, 17197.570060748458, 17402.12989350534, 17689.0, 18304.81695183048, 18670.253189872423, 964.1852634523851, 965.7682156282511, 966.7244835221964, 966.8984756600561, 967.7870868408974, 960.2078285414526, 994.6419289055934, 996.8604395384652, 997.4065431068502, 998.4458625462865, 1060.01948197917, 1062.2670357453371, 1062.9468526573678, 1064.2550214849598, 1064.542246833861, 1046.1212581431557, 1049.9895001049968, 1051.1748277602085, 1051.3271506236015, 1056.5562463765198, 724.7390939261861, 739.3936971683222, 749.0973377080659, 749.1497150733902, 751.6504825256227, 19757.0, 19982.500437489034, 19993.800061999485, 20058.89970550138, 20081.698774518394, 19685.507862303413, 19925.0, 20574.074166662536, 20754.58490830175, 20792.896035519876, 17502.824971750375, 17569.824301756948, 17722.645547089116, 17785.110744462727, 17979.370722024796, 3661.8169091545583, 3685.63143685632, 3686.152184997439, 3688.7602305850032, 3689.0777305673582, 134654.32672836573, 135533.0, 136469.0, 136508.0, 140698.2965085168, 15814.130222837854, 16042.358305667876, 16091.678166436606, 16413.42553010639, 16430.260638271306, 31510.36979260402, 32044.359112818078, 32251.35497290088, 32256.677433225836, 32381.676183237865, 8452.8732069019, 8520.957395213109, 8529.360297977677, 8555.101714319906, 8562.657493700182, 4134.64855487287, 4240.088380998101, 4250.638695710837, 4253.510846252659, 4265.381519679691, 4782.187028205213, 4951.702897826142, 5107.361579802525, 5110.8466745997675, 5125.718085505258, 942.844430668932, 953.6900507335005, 954.19370631816, 954.8329042417549, 957.3777044920718, 667.6127845849396, 680.3774546290188, 686.9043875019336, 701.5650296816007, 706.6360824175554, 11939.88060119405, 12020.63938081859, 12027.639170824888, 12107.75784484323, 12129.5148194073, 682.1711620381261, 682.74738346812, 691.0636088100651, 697.4246246846373, 700.8913618389158, 5129.538341549279, 5175.689458632494, 5186.844394668166, 5243.947560524422, 5252.238425428368, 125204.37397812953, 127708.0, 130010.34994824966, 130659.0, 134539.0, 110403.44798275958, 111268.44365778119, 111560.44219779012, 112567.43716281366, 112741.436292818, 92879.0, 94293.5285323569, 94394.52802735943, 96906.0, 96970.0, 9511.286653500876, 9787.657431989846, 9846.655367062118, 9858.556364963737, 9954.651587194414, 42448.78775606102, 43758.56241437545, 44441.777791110835, 44498.0, 44942.0, 56643.71678141583, 57255.71372143113, 57545.71227143837, 57634.0, 59341.70329148327, 14410.495632652806, 14730.926345368205, 14798.186099764616, 14841.406343746561, 14905.403783848524, 16506.504804855875, 16525.752113718543, 16528.173591320145, 16559.25483353228, 16661.08364039941, 14282.42870285177, 14403.639909002359, 14628.561143165722, 14664.046836955564, 14893.925530372278, 15430.5370838875, 15478.5356439307, 15505.534833955, 15626.218689065274, 15644.374225031326, 15174.165420901956, 15365.231738412811, 15440.84559154394, 15604.297806598503, 15606.453774118305, 10731.302465339728, 10894.78210435771, 10943.835842462211, 11178.776424471627, 11302.943485282522, 11831.940840295743, 11979.640610781691, 12048.216865903689, 12202.633920982385, 12318.199317044364], "value": 8792.19697706079, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1440.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 108.0, 107.0, 107.0, 106.0, 107.0, 108.0, 108.0, 107.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 114.0, 116.0, 117.0, 140.0, 114.0, 144.0, 183.0, 117.0, 143.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 164.0, 163.0, 161.0, 163.0, 163.0, 164.0, 167.0, 169.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 74.0, 85.0, 75.0, 74.0, 74.0, 76.0, 77.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 85.0, 78.0, 80.0, 124.0, 79.0, 112.0, 78.0], "value": 87.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 114.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 149.0, 148.0, 148.0, 150.0, 147.0, 147.0, 146.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 96.0, 93.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 53.0, 53.0, 53.0, 54.0, 52.0, 54.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 37.0, 38.0, 39.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 75.0, 73.0, 73.0, 77.0, 73.0, 77.0, 73.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 114.0, 110.0, 113.0, 130.0, 111.0, 113.0, 119.0, 116.0], "value": 114.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 171.0, 171.0, 168.0, 171.0, 174.0, 175.0, 179.0, 167.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 72.0, 66.0, 65.0, 65.0, 65.0, 64.0, 65.0], "value": 66.3, "unit": "score"}]}]} 06:34:56 INFO - Return code: 0 06:34:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:34:56 INFO - # TBPL SUCCESS # 06:34:56 INFO - Running post-action listener: _package_coverage_data 06:34:56 INFO - Running post-action listener: _resource_record_post_action 06:34:56 INFO - [mozharness: 2017-09-20 13:34:56.496343Z] Finished run-tests step (success) 06:34:56 INFO - Running post-run listener: _resource_record_post_run 06:34:56 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 338620416; Read time: 12; Write time: 181512 06:34:56 INFO - TinderboxPrint: CPU usage
13.1% 06:34:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 06:34:56 INFO - TinderboxPrint: I/O write bytes / time
338,620,416 / 181,512 06:34:56 INFO - TinderboxPrint: CPU idle
8,223.8 (86.9%) 06:34:56 INFO - TinderboxPrint: CPU user
1,191.4 (12.6%) 06:34:56 INFO - TinderboxPrint: Swap in / out
0 / 0 06:34:56 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9711616; Read time: 0; Write time: 700 06:34:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:34:56 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 0; Write bytes: 327786496; Read time: 0; Write time: 180744 06:34:57 INFO - Running post-run listener: _upload_blobber_files 06:34:57 INFO - Blob upload gear active. 06:34:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:34:57 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 06:34:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:34:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:34:57 INFO - (blobuploader) - INFO - Open directory for files ... 06:34:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:34:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:34:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:35:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:00 INFO - (blobuploader) - INFO - Done attempting. 06:35:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:35:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:35:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:01 INFO - (blobuploader) - INFO - Done attempting. 06:35:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:35:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:35:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:02 INFO - (blobuploader) - INFO - Done attempting. 06:35:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:35:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:35:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:03 INFO - (blobuploader) - INFO - Done attempting. 06:35:03 INFO - (blobuploader) - INFO - Iteration through files over. 06:35:03 INFO - Return code: 0 06:35:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:35:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:35:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/affda01fbacd887ee86f6408c4164c30d12c9f2c09c46349bda728b09cd66b3b5646b9991902f23cbabdd206158ef3eaf635f307f2db5f8b82745aacf53e9668", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ac49aa95b0b1b810fdcd424d81273ecdfc8c11b115e01162c5ce1c995e529e63d7326200704214fca12a8b3ded1d6f0b6a0421ee3bbaf722fbf92442d2ace66", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/57de2e549845c591e6f4828d31d35fc81aa136474227a3c67004f41ace3a59084e8f72c0711ea7864c27f313845ba8aaea8b6bc5b1dd75f0710d0499c2f816d9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/847a0dc7f3c52ae27ce31c7ac086203bd9e9c75bf0c88153034af7c55dae6ff174b4435b1da79103ae353dd5505ab829b901775ac099eb098df8367a268b8b08"} 06:35:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:35:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:35:03 INFO - Contents: 06:35:03 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/affda01fbacd887ee86f6408c4164c30d12c9f2c09c46349bda728b09cd66b3b5646b9991902f23cbabdd206158ef3eaf635f307f2db5f8b82745aacf53e9668", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ac49aa95b0b1b810fdcd424d81273ecdfc8c11b115e01162c5ce1c995e529e63d7326200704214fca12a8b3ded1d6f0b6a0421ee3bbaf722fbf92442d2ace66", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/57de2e549845c591e6f4828d31d35fc81aa136474227a3c67004f41ace3a59084e8f72c0711ea7864c27f313845ba8aaea8b6bc5b1dd75f0710d0499c2f816d9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/847a0dc7f3c52ae27ce31c7ac086203bd9e9c75bf0c88153034af7c55dae6ff174b4435b1da79103ae353dd5505ab829b901775ac099eb098df8367a268b8b08"} 06:35:03 INFO - Running post-run listener: copy_logs_to_upload_dir 06:35:03 INFO - Copying logs to upload dir... 06:35:03 INFO - mkdir: /builds/slave/test/build/upload/logs 06:35:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.319370 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-09-20 06:35:03.830155) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 06:35:03.834587) ========= 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=31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/affda01fbacd887ee86f6408c4164c30d12c9f2c09c46349bda728b09cd66b3b5646b9991902f23cbabdd206158ef3eaf635f307f2db5f8b82745aacf53e9668", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ac49aa95b0b1b810fdcd424d81273ecdfc8c11b115e01162c5ce1c995e529e63d7326200704214fca12a8b3ded1d6f0b6a0421ee3bbaf722fbf92442d2ace66", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/57de2e549845c591e6f4828d31d35fc81aa136474227a3c67004f41ace3a59084e8f72c0711ea7864c27f313845ba8aaea8b6bc5b1dd75f0710d0499c2f816d9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/847a0dc7f3c52ae27ce31c7ac086203bd9e9c75bf0c88153034af7c55dae6ff174b4435b1da79103ae353dd5505ab829b901775ac099eb098df8367a268b8b08"} build_url:https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/HBUujFfqTheR_yOD7KSOzg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/affda01fbacd887ee86f6408c4164c30d12c9f2c09c46349bda728b09cd66b3b5646b9991902f23cbabdd206158ef3eaf635f307f2db5f8b82745aacf53e9668", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ac49aa95b0b1b810fdcd424d81273ecdfc8c11b115e01162c5ce1c995e529e63d7326200704214fca12a8b3ded1d6f0b6a0421ee3bbaf722fbf92442d2ace66", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/57de2e549845c591e6f4828d31d35fc81aa136474227a3c67004f41ace3a59084e8f72c0711ea7864c27f313845ba8aaea8b6bc5b1dd75f0710d0499c2f816d9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/847a0dc7f3c52ae27ce31c7ac086203bd9e9c75bf0c88153034af7c55dae6ff174b4435b1da79103ae353dd5505ab829b901775ac099eb098df8367a268b8b08"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 06:35:03.868105) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 06:35:03.868463) ========= 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=31acecf42b6a79ec49471d4a000001bb-1505910396.512749-455607192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004653 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 06:35:03.953106) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 06:35:03.953455) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 06:35:03.953919) ========= ========= Total master_lag: 0.23 =========