builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-079 starttime: 1505858491.75 results: success (0) revision: c0d1f9eb2a4090ab8a06ef3560ab5d0e06c5bb1f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:31.746523) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:31.747012) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:31.747358) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:31.810674) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:31.811146) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-19 15:01:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.5M=0s 2017-09-19 15:01:32 (50.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.163771 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:32.021342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:32.021702) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.062401 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:32.100426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-19 15:01:32.100797) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c0d1f9eb2a4090ab8a06ef3560ab5d0e06c5bb1f --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 c0d1f9eb2a4090ab8a06ef3560ab5d0e06c5bb1f --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-19 15:01:32,152 truncating revision to first 12 chars 2017-09-19 15:01:32,152 Setting DEBUG logging. 2017-09-19 15:01:32,152 attempt 1/10 2017-09-19 15:01:32,152 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c0d1f9eb2a40?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-19 15:01:33,296 unpacking tar archive at: mozilla-central-c0d1f9eb2a40/testing/mozharness/ program finished with exit code 0 elapsedTime=1.472407 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-19 15:01:33.587683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:33.588104) ========= script_repo_revision: c0d1f9eb2a4090ab8a06ef3560ab5d0e06c5bb1f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:33.588548) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:33.588821) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-19 15:01:33.601891) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-19 15:01:33.602313) ========= /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=1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:01:33 INFO - MultiFileLogger online at 20170919 15:01:33 in /builds/slave/test 15:01:33 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 15:01:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:01:33 INFO - {'append_to_log': False, 15:01:33 INFO - 'base_work_dir': '/builds/slave/test', 15:01:33 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:01:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:01:33 INFO - 'branch': 'Firefox-Non-PGO', 15:01:33 INFO - 'buildbot_json_path': 'buildprops.json', 15:01:33 INFO - 'code_coverage': False, 15:01:33 INFO - 'config_files': ('talos/linux_config.py',), 15:01:33 INFO - 'default_actions': ('clobber', 15:01:33 INFO - 'read-buildbot-config', 15:01:33 INFO - 'download-and-extract', 15:01:33 INFO - 'populate-webroot', 15:01:33 INFO - 'create-virtualenv', 15:01:33 INFO - 'install', 15:01:33 INFO - 'setup-mitmproxy', 15:01:33 INFO - 'run-tests'), 15:01:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:01:33 INFO - 'disable_ccov_upload': False, 15:01:33 INFO - 'disable_stylo': False, 15:01:33 INFO - 'download_minidump_stackwalk': True, 15:01:33 INFO - 'download_symbols': 'ondemand', 15:01:33 INFO - 'enable_stylo': False, 15:01:33 INFO - 'enable_webrender': False, 15:01:33 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:01:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:01:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:01:33 INFO - 'gecko_profile': False, 15:01:33 INFO - 'gecko_profile_interval': 0, 15:01:33 INFO - 'installer_path': 'installer.exe', 15:01:33 INFO - 'log_level': 'info', 15:01:33 INFO - 'log_name': 'talos', 15:01:33 INFO - 'log_to_console': True, 15:01:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:01:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:01:33 INFO - 'opt_config_files': (), 15:01:33 INFO - 'pip_index': False, 15:01:33 INFO - 'suite': 'dromaeojs-e10s', 15:01:33 INFO - 'system_bits': '32', 15:01:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:01:33 INFO - 'title': 'talos-linux64-ix-079', 15:01:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:01:33 INFO - 'use_talos_json': True, 15:01:33 INFO - 'verify': 'False', 15:01:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:01:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:01:33 INFO - 'work_dir': 'build'} 15:01:33 INFO - [mozharness: 2017-09-19 22:01:33.937017Z] Running clobber step. 15:01:33 INFO - Running pre-action listener: _resource_record_pre_action 15:01:33 INFO - Running main action method: clobber 15:01:33 INFO - rmtree: /builds/slave/test/build 15:01:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:01:38 INFO - Running post-action listener: _resource_record_post_action 15:01:38 INFO - [mozharness: 2017-09-19 22:01:38.854513Z] Finished clobber step (success) 15:01:38 INFO - [mozharness: 2017-09-19 22:01:38.854611Z] Running read-buildbot-config step. 15:01:38 INFO - Running pre-action listener: _resource_record_pre_action 15:01:38 INFO - Running main action method: read_buildbot_config 15:01:38 INFO - Using buildbot properties: 15:01:38 INFO - { 15:01:38 INFO - "project": "", 15:01:38 INFO - "product": "firefox", 15:01:38 INFO - "who": "kwierso@gmail.com", 15:01:38 INFO - "installer_path": "public/build/target.tar.bz2", 15:01:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:01:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:01:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:01:38 INFO - "stage_platform": "linux64", 15:01:38 INFO - "basedir": "/builds/slave/test", 15:01:38 INFO - "buildnumber": 1159, 15:01:38 INFO - "platform": "ubuntu64_hw", 15:01:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:01:38 INFO - "slavebuilddir": "test", 15:01:38 INFO - "taskId": "Xnv8QBFiTcihP0TiWgXcMA", 15:01:38 INFO - "branch": "mozilla-central", 15:01:38 INFO - "script_repo_revision": "production", 15:01:38 INFO - "revision": "c0d1f9eb2a4090ab8a06ef3560ab5d0e06c5bb1f", 15:01:38 INFO - "slavename": "talos-linux64-ix-079", 15:01:38 INFO - "repo_path": "mozilla-central" 15:01:38 INFO - } 15:01:38 INFO - Finding installer, test and symbols from parent task. 15:01:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xnv8QBFiTcihP0TiWgXcMA'}, attempt #1 15:01:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xnv8QBFiTcihP0TiWgXcMA'}, attempt #1 15:01:39 INFO - Task dependencies: AyerD6BRQGKrCIFu0VWbgg 15:01:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg'}, attempt #1 15:01:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.tar.bz2 15:01:40 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.test_packages.json 15:01:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.crashreporter-symbols.zip 15:01:40 INFO - Running post-action listener: _resource_record_post_action 15:01:40 INFO - [mozharness: 2017-09-19 22:01:40.253274Z] Finished read-buildbot-config step (success) 15:01:40 INFO - [mozharness: 2017-09-19 22:01:40.253487Z] Running download-and-extract step. 15:01:40 INFO - Running pre-action listener: _resource_record_pre_action 15:01:40 INFO - Running main action method: download_and_extract 15:01:40 INFO - mkdir: /builds/slave/test/build/tests 15:01:40 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')]} 15:01:40 INFO - trying https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.test_packages.json 15:01:40 INFO - Downloading https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:01:40 INFO - Downloaded 989 bytes. 15:01:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:01:40 INFO - Using the following test package requirements: 15:01:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:01:40 INFO - u'common': [u'target.common.tests.zip'], 15:01:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:01:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:01:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:01:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:01:40 INFO - u'mozbase': [u'target.common.tests.zip'], 15:01:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:01:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:01:40 INFO - u'web-platform': [u'target.common.tests.zip', 15:01:40 INFO - u'target.web-platform.tests.tar.gz'], 15:01:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:01:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:01:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.common.tests.zip 15:01:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:01:40 INFO - Fetch https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.common.tests.zip into memory 15:01:41 INFO - Content-Length response header: 54731597 15:01:42 INFO - Bytes received: 54731597 15:01:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.talos.tests.zip 15:01:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:01:53 INFO - Fetch https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.talos.tests.zip into memory 15:01:53 INFO - Content-Length response header: 14508904 15:01:53 INFO - Bytes received: 14508904 15:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:01:54 INFO - trying https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.tar.bz2 15:01:54 INFO - Downloading https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:01:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:01:55 INFO - Downloaded 61759510 bytes. 15:01:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.tar.bz2 15:01:55 INFO - mkdir: /builds/slave/test/properties 15:01:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:01:55 INFO - Writing to file /builds/slave/test/properties/build_url 15:01:55 INFO - Contents: 15:01:55 INFO - build_url:https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.tar.bz2 15:01:55 INFO - Running post-action listener: _resource_record_post_action 15:01:55 INFO - Running post-action listener: find_tests_for_verification 15:01:55 INFO - Running post-action listener: set_extra_try_arguments 15:01:55 INFO - [mozharness: 2017-09-19 22:01:55.474768Z] Finished download-and-extract step (success) 15:01:55 INFO - [mozharness: 2017-09-19 22:01:55.474866Z] Running populate-webroot step. 15:01:55 INFO - Running pre-action listener: _resource_record_pre_action 15:01:55 INFO - Running main action method: populate_webroot 15:01:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:01:55 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['tresize', 15:01:55 INFO - 'tcanvasmark']}, 15:01:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:01:55 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['dromaeo_css', 15:01:55 INFO - 'kraken']}, 15:01:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:01:55 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:01:55 INFO - 'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:01:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:01:55 INFO - 'tests': ['damp', 'tps']}, 15:01:55 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:01:55 INFO - 'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['damp', 'tps']}, 15:01:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:01:55 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['dromaeo_dom']}, 15:01:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:01:55 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['basic_compositor_video', 15:01:55 INFO - 'glvideo']}, 15:01:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:01:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:01:55 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:01:55 INFO - 'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['ts_paint_webext', 15:01:55 INFO - 'tp5o_webext']}, 15:01:55 INFO - 'other-e10s': {'tests': ['a11yr', 15:01:55 INFO - 'ts_paint', 15:01:55 INFO - 'tpaint', 15:01:55 INFO - 'sessionrestore', 15:01:55 INFO - 'sessionrestore_many_windows', 15:01:55 INFO - 'sessionrestore_no_auto_restore', 15:01:55 INFO - 'tabpaint']}, 15:01:55 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['a11yr', 15:01:55 INFO - 'ts_paint', 15:01:55 INFO - 'tpaint', 15:01:55 INFO - 'sessionrestore', 15:01:55 INFO - 'sessionrestore_many_windows', 15:01:55 INFO - 'sessionrestore_no_auto_restore', 15:01:55 INFO - 'tabpaint']}, 15:01:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:01:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:01:55 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['perf_reftest_singletons']}, 15:01:55 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['bloom_basic']}, 15:01:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:01:55 INFO - 'tsvgr_opacity', 15:01:55 INFO - 'tart', 15:01:55 INFO - 'tscrollx', 15:01:55 INFO - 'tsvg_static']}, 15:01:55 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['tsvgx', 15:01:55 INFO - 'tsvgr_opacity', 15:01:55 INFO - 'tart', 15:01:55 INFO - 'tscrollx', 15:01:55 INFO - 'tsvg_static']}, 15:01:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:01:55 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:01:55 INFO - 'talos_options': ['--disable-stylo'], 15:01:55 INFO - 'tests': ['tp5o']}, 15:01:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:01:55 INFO - 'talos_options': ['--mitmproxy', 15:01:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:01:55 INFO - '--firstNonBlankPaint'], 15:01:55 INFO - 'tests': ['tp6_google', 15:01:55 INFO - 'tp6_youtube', 15:01:55 INFO - 'tp6_amazon', 15:01:55 INFO - 'tp6_facebook']}, 15:01:55 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:01:55 INFO - 'talos_options': ['--disable-stylo', 15:01:55 INFO - '--mitmproxy', 15:01:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:01:55 INFO - '--firstNonBlankPaint'], 15:01:55 INFO - 'tests': ['tp6_google', 15:01:55 INFO - 'tp6_youtube', 15:01:55 INFO - 'tp6_amazon', 15:01:55 INFO - 'tp6_facebook']}, 15:01:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:01:55 INFO - 'talos_options': ['--stylo-threads=1', 15:01:55 INFO - '--mitmproxy', 15:01:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:01:55 INFO - '--firstNonBlankPaint'], 15:01:55 INFO - 'tests': ['tp6_google', 15:01:55 INFO - 'tp6_youtube', 15:01:55 INFO - 'tp6_amazon', 15:01:55 INFO - 'tp6_facebook']}, 15:01:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:01:55 INFO - 'talos_options': ['--xperf_path', 15:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:01:55 INFO - 'tests': ['tp5n']}, 15:01:55 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:01:55 INFO - 'talos_options': ['--disable-stylo', 15:01:55 INFO - '--xperf_path', 15:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:01:55 INFO - 'tests': ['tp5n']}}} 15:01:55 INFO - Running post-action listener: _resource_record_post_action 15:01:55 INFO - [mozharness: 2017-09-19 22:01:55.489948Z] Finished populate-webroot step (success) 15:01:55 INFO - [mozharness: 2017-09-19 22:01:55.490038Z] Running create-virtualenv step. 15:01:55 INFO - Running pre-action listener: _resource_record_pre_action 15:01:55 INFO - Running main action method: create_virtualenv 15:01:55 INFO - Creating virtualenv /builds/slave/test/build/venv 15:01:55 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:01:55 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:01:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:01:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:01:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:01:56 INFO - Installing distribute.............................................................................................................................................................................................done. 15:01:56 INFO - Installing pip...............done. 15:01:56 INFO - Return code: 0 15:01:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:01:56 INFO - Reading from file tmpfile_stdout 15:01:56 INFO - Output received: 15:01:56 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:01:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:01:56 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')]} 15:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:56 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')]} 15:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:56 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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:01:56 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 15:01:56 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 15:01:56 INFO - Using env: {'DISPLAY': ':0', 15:01:56 INFO - 'HOME': '/home/cltbld', 15:01:56 INFO - 'LANG': 'en_US.UTF-8', 15:01:56 INFO - 'LANGUAGE': 'en_US:en', 15:01:56 INFO - 'LOGNAME': 'cltbld', 15:01:56 INFO - 'MAIL': '/var/mail/cltbld', 15:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:56 INFO - 'MOZ_NO_REMOTE': '1', 15:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:01:56 INFO - 'NO_EM_RESTART': '1', 15:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:56 INFO - 'PWD': '/builds/slave/test', 15:01:56 INFO - 'SHELL': '/bin/bash', 15:01:56 INFO - 'SHLVL': '1', 15:01:56 INFO - 'TERM': 'linux', 15:01:56 INFO - 'TMOUT': '86400', 15:01:56 INFO - 'USER': 'cltbld', 15:01:56 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:56 INFO - '_': '/tools/buildbot/bin/python'} 15:01:58 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:01:58 INFO - Downloading/unpacking pip>=1.5 15:01:58 INFO - Running setup.py egg_info for package pip 15:01:58 INFO - warning: no files found matching 'pip/cacert.pem' 15:01:58 INFO - warning: no files found matching '*.html' under directory 'docs' 15:01:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:01:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:01:58 INFO - Installing collected packages: pip 15:01:58 INFO - Found existing installation: pip 1.1 15:01:58 INFO - Uninstalling pip: 15:01:58 INFO - Successfully uninstalled pip 15:01:58 INFO - Running setup.py install for pip 15:01:58 INFO - warning: no files found matching 'pip/cacert.pem' 15:01:58 INFO - warning: no files found matching '*.html' under directory 'docs' 15:01:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:01:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:01:58 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:01:58 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:01:58 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:01:58 INFO - Successfully installed pip 15:01:58 INFO - Cleaning up... 15:01:58 INFO - Return code: 0 15:01:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:01:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:01:58 INFO - Using env: {'DISPLAY': ':0', 15:01:58 INFO - 'HOME': '/home/cltbld', 15:01:58 INFO - 'LANG': 'en_US.UTF-8', 15:01:58 INFO - 'LANGUAGE': 'en_US:en', 15:01:58 INFO - 'LOGNAME': 'cltbld', 15:01:58 INFO - 'MAIL': '/var/mail/cltbld', 15:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:58 INFO - 'MOZ_NO_REMOTE': '1', 15:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:01:58 INFO - 'NO_EM_RESTART': '1', 15:01:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:58 INFO - 'PWD': '/builds/slave/test', 15:01:58 INFO - 'SHELL': '/bin/bash', 15:01:58 INFO - 'SHLVL': '1', 15:01:58 INFO - 'TERM': 'linux', 15:01:58 INFO - 'TMOUT': '86400', 15:01:58 INFO - 'USER': 'cltbld', 15:01:58 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:58 INFO - '_': '/tools/buildbot/bin/python'} 15:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:59 INFO - Downloading/unpacking psutil>=3.1.1 15:01: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 15:01: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 15:01: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 15:01: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 15:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:02:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:02:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:02:00 INFO - Installing collected packages: psutil 15:02:00 INFO - Running setup.py install for psutil 15:02:00 INFO - building 'psutil._psutil_linux' extension 15:02:00 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 15:02:01 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 15:02:01 INFO - building 'psutil._psutil_posix' extension 15:02:01 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 15:02:01 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 15:02:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:02:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:02:01 INFO - Successfully installed psutil 15:02:01 INFO - Cleaning up... 15:02:01 INFO - Return code: 0 15:02:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:02:01 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')]} 15:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:01 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')]} 15:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:01 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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:02:01 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 15:02:01 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 15:02:01 INFO - Using env: {'DISPLAY': ':0', 15:02:01 INFO - 'HOME': '/home/cltbld', 15:02:01 INFO - 'LANG': 'en_US.UTF-8', 15:02:01 INFO - 'LANGUAGE': 'en_US:en', 15:02:01 INFO - 'LOGNAME': 'cltbld', 15:02:01 INFO - 'MAIL': '/var/mail/cltbld', 15:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:01 INFO - 'MOZ_NO_REMOTE': '1', 15:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:01 INFO - 'NO_EM_RESTART': '1', 15:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:01 INFO - 'PWD': '/builds/slave/test', 15:02:01 INFO - 'SHELL': '/bin/bash', 15:02:01 INFO - 'SHLVL': '1', 15:02:01 INFO - 'TERM': 'linux', 15:02:01 INFO - 'TMOUT': '86400', 15:02:01 INFO - 'USER': 'cltbld', 15:02:01 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:01 INFO - '_': '/tools/buildbot/bin/python'} 15:02:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:02:03 INFO - Installing collected packages: mozsystemmonitor 15:02:03 INFO - Running setup.py install for mozsystemmonitor 15:02:03 INFO - Successfully installed mozsystemmonitor 15:02:03 INFO - Cleaning up... 15:02:03 INFO - Return code: 0 15:02:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:02: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')]} 15:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02: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')]} 15:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:03 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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:02:03 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 15:02:03 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 15:02:03 INFO - Using env: {'DISPLAY': ':0', 15:02:03 INFO - 'HOME': '/home/cltbld', 15:02:03 INFO - 'LANG': 'en_US.UTF-8', 15:02:03 INFO - 'LANGUAGE': 'en_US:en', 15:02:03 INFO - 'LOGNAME': 'cltbld', 15:02:03 INFO - 'MAIL': '/var/mail/cltbld', 15:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:03 INFO - 'MOZ_NO_REMOTE': '1', 15:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:03 INFO - 'NO_EM_RESTART': '1', 15:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:03 INFO - 'PWD': '/builds/slave/test', 15:02:03 INFO - 'SHELL': '/bin/bash', 15:02:03 INFO - 'SHLVL': '1', 15:02:03 INFO - 'TERM': 'linux', 15:02:03 INFO - 'TMOUT': '86400', 15:02:03 INFO - 'USER': 'cltbld', 15:02:03 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:03 INFO - '_': '/tools/buildbot/bin/python'} 15:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:03 INFO - Downloading/unpacking jsonschema==2.5.1 15:02:03 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 15:02:03 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 15:02:03 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 15:02:03 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 15:02:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:02:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:02: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 15:02: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 15:02: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 15:02: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 15:02:05 INFO - Downloading functools32-3.2.3-2.tar.gz 15:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:02:05 INFO - warning: no files found matching '*.txt' 15:02:05 INFO - no previously-included directories found matching 'build' 15:02:05 INFO - no previously-included directories found matching 'dist' 15:02:05 INFO - no previously-included directories found matching '.git*' 15:02:05 INFO - Installing collected packages: jsonschema, functools32 15:02:05 INFO - Running setup.py install for functools32 15:02:05 INFO - warning: no files found matching '*.txt' 15:02:05 INFO - no previously-included directories found matching 'build' 15:02:05 INFO - no previously-included directories found matching 'dist' 15:02:05 INFO - no previously-included directories found matching '.git*' 15:02:05 INFO - Successfully installed jsonschema functools32 15:02:05 INFO - Cleaning up... 15:02:05 INFO - Return code: 0 15:02:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:02:05 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')]} 15:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:05 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')]} 15:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:05 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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:02:05 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 15:02:05 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 15:02:05 INFO - Using env: {'DISPLAY': ':0', 15:02:05 INFO - 'HOME': '/home/cltbld', 15:02:05 INFO - 'LANG': 'en_US.UTF-8', 15:02:05 INFO - 'LANGUAGE': 'en_US:en', 15:02:05 INFO - 'LOGNAME': 'cltbld', 15:02:05 INFO - 'MAIL': '/var/mail/cltbld', 15:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:05 INFO - 'MOZ_NO_REMOTE': '1', 15:02:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:05 INFO - 'NO_EM_RESTART': '1', 15:02:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:05 INFO - 'PWD': '/builds/slave/test', 15:02:05 INFO - 'SHELL': '/bin/bash', 15:02:05 INFO - 'SHLVL': '1', 15:02:05 INFO - 'TERM': 'linux', 15:02:05 INFO - 'TMOUT': '86400', 15:02:05 INFO - 'USER': 'cltbld', 15:02:05 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:05 INFO - '_': '/tools/buildbot/bin/python'} 15:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:02:05 INFO - Cleaning up... 15:02:05 INFO - Return code: 0 15:02:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:02:05 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')]} 15:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:05 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')]} 15:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:05 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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:02:05 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 15:02:05 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 15:02:05 INFO - Using env: {'DISPLAY': ':0', 15:02:05 INFO - 'HOME': '/home/cltbld', 15:02:05 INFO - 'LANG': 'en_US.UTF-8', 15:02:05 INFO - 'LANGUAGE': 'en_US:en', 15:02:05 INFO - 'LOGNAME': 'cltbld', 15:02:05 INFO - 'MAIL': '/var/mail/cltbld', 15:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:05 INFO - 'MOZ_NO_REMOTE': '1', 15:02:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:05 INFO - 'NO_EM_RESTART': '1', 15:02:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:05 INFO - 'PWD': '/builds/slave/test', 15:02:05 INFO - 'SHELL': '/bin/bash', 15:02:05 INFO - 'SHLVL': '1', 15:02:05 INFO - 'TERM': 'linux', 15:02:05 INFO - 'TMOUT': '86400', 15:02:05 INFO - 'USER': 'cltbld', 15:02:05 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:05 INFO - '_': '/tools/buildbot/bin/python'} 15:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:05 INFO - Downloading/unpacking blobuploader==1.2.4 15:02:05 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 15:02:05 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 15:02:05 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 15:02:05 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 15:02:06 INFO - Downloading blobuploader-1.2.4.tar.gz 15:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:02:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:02: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 15:02: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 15:02: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 15:02: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 15:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:02:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:07 INFO - Downloading docopt-0.6.1.tar.gz 15:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:02:07 INFO - Installing collected packages: blobuploader, requests, docopt 15:02:07 INFO - Running setup.py install for blobuploader 15:02:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:02:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:02:07 INFO - Running setup.py install for requests 15:02:07 INFO - Running setup.py install for docopt 15:02:08 INFO - Successfully installed blobuploader requests docopt 15:02:08 INFO - Cleaning up... 15:02:08 INFO - Return code: 0 15:02:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02: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')]} 15:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02: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')]} 15:02:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:08 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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:02:08 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 15:02:08 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 15:02:08 INFO - Using env: {'DISPLAY': ':0', 15:02:08 INFO - 'HOME': '/home/cltbld', 15:02:08 INFO - 'LANG': 'en_US.UTF-8', 15:02:08 INFO - 'LANGUAGE': 'en_US:en', 15:02:08 INFO - 'LOGNAME': 'cltbld', 15:02:08 INFO - 'MAIL': '/var/mail/cltbld', 15:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:08 INFO - 'MOZ_NO_REMOTE': '1', 15:02:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:08 INFO - 'NO_EM_RESTART': '1', 15:02:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:08 INFO - 'PWD': '/builds/slave/test', 15:02:08 INFO - 'SHELL': '/bin/bash', 15:02:08 INFO - 'SHLVL': '1', 15:02:08 INFO - 'TERM': 'linux', 15:02:08 INFO - 'TMOUT': '86400', 15:02:08 INFO - 'USER': 'cltbld', 15:02:08 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:08 INFO - '_': '/tools/buildbot/bin/python'} 15:02:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:08 INFO - Running setup.py (path:/tmp/pip-_90mpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:08 INFO - Running setup.py (path:/tmp/pip-jMEUcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:08 INFO - Running setup.py (path:/tmp/pip-U8jNTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:08 INFO - Running setup.py (path:/tmp/pip-L8VXYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:08 INFO - Running setup.py (path:/tmp/pip-R3ATAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:08 INFO - Running setup.py (path:/tmp/pip-7l4fMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:08 INFO - Running setup.py (path:/tmp/pip-AXzsa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:08 INFO - Running setup.py (path:/tmp/pip-mWmTHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:08 INFO - Running setup.py (path:/tmp/pip-CFfa1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:08 INFO - Running setup.py (path:/tmp/pip-EHmWRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:09 INFO - Running setup.py (path:/tmp/pip-edvOfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:09 INFO - Running setup.py (path:/tmp/pip-DAciKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:09 INFO - Running setup.py (path:/tmp/pip-qjtinX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:09 INFO - Running setup.py (path:/tmp/pip-JRetCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:09 INFO - Running setup.py (path:/tmp/pip-efuTAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:09 INFO - Running setup.py (path:/tmp/pip-T84FBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:09 INFO - Running setup.py (path:/tmp/pip-jIJEBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:02:09 INFO - Running setup.py install for manifestparser 15:02:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:02:09 INFO - Running setup.py install for mozcrash 15:02:09 INFO - Running setup.py install for mozdebug 15:02:09 INFO - Running setup.py install for mozdevice 15:02:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:02:09 INFO - Running setup.py install for mozfile 15:02:10 INFO - Running setup.py install for mozhttpd 15:02:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Running setup.py install for mozinfo 15:02:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Running setup.py install for mozInstall 15:02:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Running setup.py install for mozleak 15:02:10 INFO - Running setup.py install for mozlog 15:02:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Running setup.py install for moznetwork 15:02:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Running setup.py install for mozprocess 15:02:10 INFO - Running setup.py install for mozprofile 15:02:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Running setup.py install for mozrunner 15:02:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:02:10 INFO - Running setup.py install for mozscreenshot 15:02:11 INFO - Running setup.py install for moztest 15:02:11 INFO - Running setup.py install for mozversion 15:02:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:02:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:02:11 INFO - Cleaning up... 15:02:11 INFO - Return code: 0 15:02:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:11 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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:02:11 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 15:02:11 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 15:02:11 INFO - Using env: {'DISPLAY': ':0', 15:02:11 INFO - 'HOME': '/home/cltbld', 15:02:11 INFO - 'LANG': 'en_US.UTF-8', 15:02:11 INFO - 'LANGUAGE': 'en_US:en', 15:02:11 INFO - 'LOGNAME': 'cltbld', 15:02:11 INFO - 'MAIL': '/var/mail/cltbld', 15:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:11 INFO - 'MOZ_NO_REMOTE': '1', 15:02:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:11 INFO - 'NO_EM_RESTART': '1', 15:02:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:11 INFO - 'PWD': '/builds/slave/test', 15:02:11 INFO - 'SHELL': '/bin/bash', 15:02:11 INFO - 'SHLVL': '1', 15:02:11 INFO - 'TERM': 'linux', 15:02:11 INFO - 'TMOUT': '86400', 15:02:11 INFO - 'USER': 'cltbld', 15:02:11 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:11 INFO - '_': '/tools/buildbot/bin/python'} 15:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:11 INFO - Running setup.py (path:/tmp/pip-HuAxCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:11 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)) 15:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:11 INFO - Running setup.py (path:/tmp/pip-KLM8Y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:11 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)) 15:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:11 INFO - Running setup.py (path:/tmp/pip-DAmlbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:11 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)) 15:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:11 INFO - Running setup.py (path:/tmp/pip-E2T8mJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:11 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)) 15:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:11 INFO - Running setup.py (path:/tmp/pip-SWV5QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:11 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)) 15:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:11 INFO - Running setup.py (path:/tmp/pip-jP0AFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:11 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)) 15:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:11 INFO - Running setup.py (path:/tmp/pip-NVIJRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:11 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)) 15:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:11 INFO - Running setup.py (path:/tmp/pip-G2GgoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:12 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)) 15:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:12 INFO - Running setup.py (path:/tmp/pip-S7d1Lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:12 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)) 15:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:12 INFO - Running setup.py (path:/tmp/pip-3_FAGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:12 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)) 15:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:12 INFO - Running setup.py (path:/tmp/pip-F4Pjnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:12 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)) 15:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:12 INFO - Running setup.py (path:/tmp/pip-EYx1Fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:12 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)) 15:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:12 INFO - Running setup.py (path:/tmp/pip-djbL1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:12 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)) 15:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:12 INFO - Running setup.py (path:/tmp/pip-RsWRkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:12 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)) 15:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:12 INFO - Running setup.py (path:/tmp/pip-m9B5Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:12 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)) 15:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:12 INFO - Running setup.py (path:/tmp/pip-FoSeMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:12 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)) 15:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:12 INFO - Running setup.py (path:/tmp/pip-wa3LbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:02:12 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 15:02:12 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 15:02:12 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 15:02:12 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 15:02:13 INFO - Downloading blessings-1.6.tar.gz 15:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:02:13 INFO - Installing collected packages: blessings 15:02:13 INFO - Running setup.py install for blessings 15:02:14 INFO - Successfully installed blessings 15:02:14 INFO - Cleaning up... 15:02:14 INFO - Return code: 0 15:02:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:02:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:14 INFO - Reading from file tmpfile_stdout 15:02:14 INFO - Current package versions: 15:02:14 INFO - argparse == 1.2.1 15:02:14 INFO - blessings == 1.6 15:02:14 INFO - blobuploader == 1.2.4 15:02:14 INFO - docopt == 0.6.1 15:02:14 INFO - functools32 == 3.2.3-2 15:02:14 INFO - jsonschema == 2.5.1 15:02:14 INFO - manifestparser == 1.1 15:02:14 INFO - mozInstall == 1.13 15:02:14 INFO - mozcrash == 1.0 15:02:14 INFO - mozdebug == 0.1 15:02:14 INFO - mozdevice == 0.51 15:02:14 INFO - mozfile == 1.2 15:02:14 INFO - mozhttpd == 0.7 15:02:14 INFO - mozinfo == 0.10 15:02:14 INFO - mozleak == 0.1 15:02:14 INFO - mozlog == 3.5 15:02:14 INFO - moznetwork == 0.27 15:02:14 INFO - mozprocess == 0.25 15:02:14 INFO - mozprofile == 0.28 15:02:14 INFO - mozrunner == 6.13 15:02:14 INFO - mozscreenshot == 0.1 15:02:14 INFO - mozsystemmonitor == 0.3 15:02:14 INFO - moztest == 0.8 15:02:14 INFO - mozversion == 1.4 15:02:14 INFO - psutil == 3.1.1 15:02:14 INFO - requests == 1.2.3 15:02:14 INFO - wsgiref == 0.1.2 15:02:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:14 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')]} 15:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:14 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')]} 15:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:14 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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:02:14 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 15:02:14 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 15:02:14 INFO - Using env: {'DISPLAY': ':0', 15:02:14 INFO - 'HOME': '/home/cltbld', 15:02:14 INFO - 'LANG': 'en_US.UTF-8', 15:02:14 INFO - 'LANGUAGE': 'en_US:en', 15:02:14 INFO - 'LOGNAME': 'cltbld', 15:02:14 INFO - 'MAIL': '/var/mail/cltbld', 15:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:14 INFO - 'MOZ_NO_REMOTE': '1', 15:02:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:14 INFO - 'NO_EM_RESTART': '1', 15:02:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:14 INFO - 'PWD': '/builds/slave/test', 15:02:14 INFO - 'SHELL': '/bin/bash', 15:02:14 INFO - 'SHLVL': '1', 15:02:14 INFO - 'TERM': 'linux', 15:02:14 INFO - 'TMOUT': '86400', 15:02:14 INFO - 'USER': 'cltbld', 15:02:14 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:14 INFO - '_': '/tools/buildbot/bin/python'} 15:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:14 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)) 15:02:14 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)) 15:02:14 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)) 15:02:14 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)) 15:02:14 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)) 15:02:14 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)) 15:02:14 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)) 15:02:14 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)) 15:02:14 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)) 15:02:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:02:14 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 15:02:14 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 15:02:14 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 15:02:14 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 15:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:02:15 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)) 15:02:15 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)) 15:02:15 INFO - Installing collected packages: simplejson 15:02:15 INFO - Running setup.py install for simplejson 15:02:15 INFO - building 'simplejson._speedups' extension 15:02:15 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 15:02:16 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 15:02:16 INFO - Successfully installed simplejson 15:02:16 INFO - Cleaning up... 15:02:16 INFO - Return code: 0 15:02:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:02:16 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')]} 15:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:16 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')]} 15:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:16 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 0x1e841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', '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 15:02:16 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 15:02:16 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 15:02:16 INFO - Using env: {'DISPLAY': ':0', 15:02:16 INFO - 'HOME': '/home/cltbld', 15:02:16 INFO - 'LANG': 'en_US.UTF-8', 15:02:16 INFO - 'LANGUAGE': 'en_US:en', 15:02:16 INFO - 'LOGNAME': 'cltbld', 15:02:16 INFO - 'MAIL': '/var/mail/cltbld', 15:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:16 INFO - 'MOZ_NO_REMOTE': '1', 15:02:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:16 INFO - 'NO_EM_RESTART': '1', 15:02:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:16 INFO - 'PWD': '/builds/slave/test', 15:02:16 INFO - 'SHELL': '/bin/bash', 15:02:16 INFO - 'SHLVL': '1', 15:02:16 INFO - 'TERM': 'linux', 15:02:16 INFO - 'TMOUT': '86400', 15:02:16 INFO - 'USER': 'cltbld', 15:02:16 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:16 INFO - '_': '/tools/buildbot/bin/python'} 15:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:02:17 INFO - Cleaning up... 15:02:17 INFO - Return code: 0 15:02:17 INFO - Running post-action listener: _resource_record_post_action 15:02:17 INFO - Running post-action listener: _start_resource_monitoring 15:02:17 INFO - Starting resource monitoring. 15:02:17 INFO - [mozharness: 2017-09-19 22:02:17.050937Z] Finished create-virtualenv step (success) 15:02:17 INFO - [mozharness: 2017-09-19 22:02:17.051936Z] Running install step. 15:02:17 INFO - Running pre-action listener: _resource_record_pre_action 15:02:17 INFO - Running main action method: install 15:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:17 INFO - Reading from file tmpfile_stdout 15:02:17 INFO - Detecting whether we're running mozinstall >=1.0... 15:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:02:17 INFO - Reading from file tmpfile_stdout 15:02:17 INFO - Output received: 15:02:17 INFO - Usage: mozinstall [options] installer 15:02:17 INFO - Options: 15:02:17 INFO - -h, --help show this help message and exit 15:02:17 INFO - -d DEST, --destination=DEST 15:02:17 INFO - Directory to install application into. [default: 15:02:17 INFO - "/builds/slave/test"] 15:02:17 INFO - --app=APP Application being installed. [default: firefox] 15:02:17 INFO - mkdir: /builds/slave/test/build/application 15:02:17 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'] 15:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:02:35 INFO - Reading from file tmpfile_stdout 15:02:35 INFO - Output received: 15:02:35 INFO - /builds/slave/test/build/application/firefox/firefox 15:02:35 INFO - Running post-action listener: _resource_record_post_action 15:02:35 INFO - [mozharness: 2017-09-19 22:02:35.454423Z] Finished install step (success) 15:02:35 INFO - [mozharness: 2017-09-19 22:02:35.454675Z] Running setup-mitmproxy step. 15:02:35 INFO - Running pre-action listener: _resource_record_pre_action 15:02:35 INFO - Running main action method: setup_mitmproxy 15:02:35 INFO - Skipping: mitmproxy is not required 15:02:35 INFO - Running post-action listener: _resource_record_post_action 15:02:35 INFO - [mozharness: 2017-09-19 22:02:35.455811Z] Finished setup-mitmproxy step (success) 15:02:35 INFO - [mozharness: 2017-09-19 22:02:35.456034Z] Running run-tests step. 15:02:35 INFO - Running pre-action listener: _resource_record_pre_action 15:02:35 INFO - Running pre-action listener: _set_gcov_prefix 15:02:35 INFO - Running main action method: run_tests 15:02:35 WARNING - Try message not found. 15:02:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:02:35 INFO - Python 2.7.3 15:02:35 INFO - Return code: 0 15:02:35 INFO - grabbing minidump binary from tooltool 15:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:35 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 0x21f4090>, '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 0x21f4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21eef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:02:35 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 15:02:35 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 15:02:35 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 15:02:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:02:35 INFO - Return code: 0 15:02:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:02:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:02:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:02:35 INFO - ENV: RUST_BACKTRACE is now full 15:02:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:02:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:02:35 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/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:02:35 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/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:02:35 INFO - Using env: {'DISPLAY': ':0', 15:02:35 INFO - 'HOME': '/home/cltbld', 15:02:35 INFO - 'LANG': 'en_US.UTF-8', 15:02:35 INFO - 'LANGUAGE': 'en_US:en', 15:02:35 INFO - 'LOGNAME': 'cltbld', 15:02:35 INFO - 'MAIL': '/var/mail/cltbld', 15:02:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:02:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:35 INFO - 'MOZ_NO_REMOTE': '1', 15:02:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:02:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:35 INFO - 'NO_EM_RESTART': '1', 15:02:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:35 INFO - 'PWD': '/builds/slave/test', 15:02:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:02:35 INFO - 'RUST_BACKTRACE': 'full', 15:02:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:02:35 INFO - 'SHELL': '/bin/bash', 15:02:35 INFO - 'SHLVL': '1', 15:02:35 INFO - 'TERM': 'linux', 15:02:35 INFO - 'TMOUT': '86400', 15:02:35 INFO - 'USER': 'cltbld', 15:02:35 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:35 INFO - '_': '/tools/buildbot/bin/python'} 15:02:35 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/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:02:36 INFO - mozversion application_buildid: 20170919210608 15:02:36 INFO - mozversion application_changeset: c0d1f9eb2a4090ab8a06ef3560ab5d0e06c5bb1f 15:02:36 INFO - mozversion application_display_name: Nightly 15:02:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:02:36 INFO - mozversion application_name: Firefox 15:02:36 INFO - mozversion application_remotingname: firefox 15:02:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:02:36 INFO - mozversion application_vendor: Mozilla 15:02:36 INFO - mozversion application_version: 57.0a1 15:02:36 INFO - mozversion platform_buildid: 20170919210608 15:02:36 INFO - mozversion platform_changeset: c0d1f9eb2a4090ab8a06ef3560ab5d0e06c5bb1f 15:02:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:02:36 INFO - mozversion platform_version: 57.0a1 15:02:36 INFO - using testdate: 1505858556 15:02:36 INFO - actual date: 1505858556 15:02:36 INFO - starting webserver on 'localhost:40367' 15:02:36 INFO - SUITE-START | Running 2 tests 15:02:36 INFO - TEST-START | dromaeo_css 15:02:36 INFO - Initialising browser for dromaeo_css test... 15:02:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40367/getInfo.html -profile /tmp/tmpKRan2X/profile 15:02:36 INFO - TEST-INFO | started process 3551 (/builds/slave/test/build/application/firefox/firefox http://localhost:40367/getInfo.html) 15:02:42 INFO - TEST-INFO | 3551: exit 0 15:02:43 INFO - Browser initialized. 15:02:43 INFO - Running cycle 1/1 for dromaeo_css test... 15:02:43 INFO - Using env: {'DISPLAY': ':0', 15:02:43 INFO - 'HOME': '/home/cltbld', 15:02:43 INFO - 'JSGC_DISABLE_POISONING': '1', 15:02:43 INFO - 'LANG': 'en_US.UTF-8', 15:02:43 INFO - 'LANGUAGE': 'en_US:en', 15:02:43 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:02:43 INFO - 'LOGNAME': 'cltbld', 15:02:43 INFO - 'MAIL': '/var/mail/cltbld', 15:02:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:02:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:02:43 INFO - 'MOZ_CRASHREPORTER': '1', 15:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:43 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:02:43 INFO - 'MOZ_NO_REMOTE': '1', 15:02:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:02:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:43 INFO - 'NO_EM_RESTART': '1', 15:02:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:43 INFO - 'PWD': '/builds/slave/test', 15:02:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:02:43 INFO - 'RUST_BACKTRACE': 'full', 15:02:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:02:43 INFO - 'SHELL': '/bin/bash', 15:02:43 INFO - 'SHLVL': '1', 15:02:43 INFO - 'TERM': 'linux', 15:02:43 INFO - 'TMOUT': '86400', 15:02:43 INFO - 'USER': 'cltbld', 15:02:43 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:43 INFO - '_': '/tools/buildbot/bin/python'} 15:02:43 INFO - TEST-INFO | started process 3787 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKRan2X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:02:43 INFO - PID 3787 | 15:02:43 INFO - PID 3787 | (/builds/slave/test/build/application/firefox/firefox:3836): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:02:43 INFO - PID 3787 | 15:02:54 INFO - PID 3787 | RSS: Main: 154300416 15:02:54 INFO - PID 3787 | 15:05:51 INFO - PID 3787 | Cycle 1(1): loaded http://localhost:40367/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40367/tests/dromaeo/cssquery-ext.html) 15:05:53 INFO - PID 3787 | RSS: Main: 155291648 15:05:53 INFO - PID 3787 | 15:08:54 INFO - PID 3787 | Cycle 1(1): loaded http://localhost:40367/tests/dromaeo/cssquery-ext.html (next: http://localhost:40367/tests/dromaeo/cssquery-jquery.html) 15:08:55 INFO - PID 3787 | RSS: Main: 153665536 15:08:55 INFO - PID 3787 | 15:11:57 INFO - PID 3787 | Cycle 1(1): loaded http://localhost:40367/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40367/tests/dromaeo/cssquery-mootools.html) 15:11:57 INFO - PID 3787 | RSS: Main: 156778496 15:11:57 INFO - PID 3787 | 15:14:59 INFO - PID 3787 | Cycle 1(1): loaded http://localhost:40367/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40367/tests/dromaeo/cssquery-prototype.html) 15:15:00 INFO - PID 3787 | RSS: Main: 155668480 15:15:00 INFO - PID 3787 | 15:18:03 INFO - PID 3787 | Cycle 1(1): loaded http://localhost:40367/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40367/tests/dromaeo/cssquery-yui.html) 15:18:03 INFO - PID 3787 | RSS: Main: 156463104 15:18:03 INFO - PID 3787 | 15:21:05 INFO - PID 3787 | Cycle 1(1): loaded http://localhost:40367/tests/dromaeo/cssquery-yui.html (next: http://localhost:40367/tests/dromaeo/cssquery-dojo.html) 15:21:05 INFO - PID 3787 | RSS: Main: 157175808 15:21:05 INFO - PID 3787 | 15:21:05 INFO - PID 3787 | __start_tp_report 15:21:05 INFO - PID 3787 | _x_x_mozilla_page_load 15:21:05 INFO - PID 3787 | _x_x_mozilla_page_load_details 15:21:05 INFO - PID 3787 | |i|pagename|runs| 15:21:05 INFO - PID 3787 | |0;dojo.html;3086.0587520806152;3099.535069739539;3146.4651009328413;3150.3856747934146;3160.0519844046794;6184.628922264667;6273.435390814831;6338.7464501419945;6349.777757778479;6363.9045414318825;6155.569110162291;6195.6902154892305;6204.503639708812;6205.10026046223;6224.533160013006;5712.542996560275;5805.593608447411;5852.385499522531;5870.0314448116005;5918.852028699274;5764.135379693059;5828.825135245949;5841.620294680833;5850.970745146268;5863.325717542473;5638.154276858463;5772.220750198711;5774;5778.0466223073145;5871.530277577784;5773.422657734235;5840.561957853178;5848.561357898175;5902.3507414184405;5948;5565.666060036411;5643.548516118701;5654.349749778787;5705.400932902027;5756.424357564252;6231.40801623845;6261.123442718023;6302.653354065525;6315.147455093595;6322.304546499881;6723.193216814014;6727.125473688389;6801.4558835293055;6817.727290908358;6826.658667066677;6648.16897887764;6743.898841517381;6761.4590832733265;6844.110265665481;6847.965760171217;6262;6273.278572964122;6274.121622972787;6275.121482992385;6277.7802776903045;6861.382475577222;6913.827154321081;6918.619475928872;6923.89614155778;6926.757563485254;6564.80305590833;6566.343365663395;6577.112089867806;6634.767783127568;6659.134312539339;6745.156855393076;6811.693473793691;6827.453803695693;6835.555688880207;6838;6705.161854768154;6710.765123220664;6792.490563207779;6801.693923773442;6808.080909077211;6570.605763654196;6709;6710.798676039727;6757.3242675732035;6866.141732283465;5300.655457395257;5305.283786688748;5323.733813309319;5349.919751203735;5370.70461124634;4592.88517787058;4653.046125544255;4684.1568517666465;4689.085628302449;4707.246840505502;5746.9712651436475;5764.279465066867;5830.300363956268;5831.766729330864;5851.707414629337;6614.140161778938;6692.564983276072;6747.730090796412;6836;6842.213145488233;5720.2849643794525;5789.565782566325;5812.94187058124;5814.912776308359;5827.446392592697;5543.861403464945;5584.6090773646265;5593.552515798727;5600.047991841316;5609.354924183771;6382.234131904202;6495.935040649533;6559.901601475982;6575.473962083021;6584.967075164689;6590.637514936533;6731.1922569292;6740.494462915203;6825.658717064028;6829.078074459884;6456.741730330922;6476.5466417350335;6527.15147030883;6542.771003014872;6554.672266386757;6637.30308317639;6647.401733843783;6706.195256569243;6713.6307503085845;6738.6967586459705;6762.053312536151;6805.183377994672;6852.691628876612;6875.449964002768;6883.174019117538;6467.126937863328;6509.251436084911;6526.9020964686515;6546.738130474728;6622.304658010839;6501.284858665448;6513;6513.837154071186;6527.608343499405;6538.574992625464;6346.301906790157;6404.743810247538;6419.390157934989;6425.357464253537;6428.453581445442;6520.641364724986;6548.116004339353;6570.047343135314;6587.9670601646685;6633.2040155181685;6322.241331040304;6367.204099487564;6437.7103030362805;6442.838929026624;6448.1295025171 15:21:05 INFO - PID 3787 | |1;ext.html;855.7817756472099;871.6469829718963;874.5408660453261;879.116487929631;880.3529405886676;39091.609083909156;42750.14499710008;42800.78599606998;42852.14295714084;42870.78564607177;39370.40944385829;39720.801395993054;39726.801365993204;39751.60248397515;39804.40293395594;7062.505624606267;7301.634918254093;7933.325667318285;8132.349412047022;8136.837263254732;7975.202479752007;8080.11118776937;8093.1502192269845;8097.757067287961;8098.149694282104;8075.596220188996;8139.593020348989;8139.715109971152;8150.877736833952;8151.388645851555;8016.67933282668;8053.919460805376;8091.554964476952;8092.27169554737;8102.473339232931;7973.800654983614;8028.678852845879;8032.558209298486;8035.437519373646;8043.195680431969;38834;39061.60938390637;39167.21665566673;39296.21407571832;39467.407988880186;3596.1009747563107;3638.199596088856;3641.2353405784816;3641.289948460051;3643.635636436335;32793.83603081969;32820.83589582061;32868.34263314744;32899.50650740241;32919.6708032919;5001.624878134155;5162.6902385856965;5176.378834539842;5230.529252367267;5316.734163291859;189814;190483;190608;191259;191351;43993.34009989853;44352.334714979304;49402.50597494051;49554.25668614974;50130.74934625303;51686;52067.73966130145;52079.47920520822;52104.73947630238;52140.47859521432;16217.108059056865;16373.590660233589;16439.67120657604;16488.17559122063;16495.422660206834;13620.863791361959;13652.249126297951;13658;13660;13784.517541885985;13652.726945461234;13741.312934353242;13753.449862005407;13792.379342929593;13813.585592432042;5459.890802183934;5559.193916882029;5611.859703507445;5634.5210657093785;5636.408177141341;998.7602975285879;1002.8295189817749;1017.3336464615688;1018.1905385218769;1031.9742006449899;23287.65071519495;23357.649635255486;23422.297331080095;23564.175253866033;23671.88164059203;1117.7652692934575;1129.7234125438367;1133.2350663302402;1134.087059916775;1136.8067428537004;12401.689957751129;12425.44085516154;12446.31545265001;12531.43608537618;12634.2419454833;223986;224786;225678;226345;226746;55443.445565544636;55504.72247638736;55616.443835561935;55758;55848.44151558514;1422.132499175502;1478.2239324354584;1482.7108713800706;1484.502691598305;1484.64368551549;22817.657735133653;22958.77041229633;23133;23173.536529268982;23272.06911723512;38691.80654096824;38731;38826;38854.222915540966;38863.61136388713;192225;193727;194384;194962;195515;30819;31323.686763132988;31355.216119597193;33973.49039764455;34173.48739768856;38187;39377.60622393739;43977.34033989429;44239.11521769611;44247;32242.516362255054;32511.83744081359;32524.186895327806;32952.505712414815;32989.83505082459;32693;32763.34473310473;32917.67082329146;33078.338433231685;33272.1681957953;35640.4653930186;35943.82028089843;36213;36304.455433168;36307.63692363042;31260.531092034093;31630.841845790623;31871.36257274888;31952.36095278128;32560.185995350304;23182.956766945867;23234.419139521648;23409.765902340758;23656.645150322416;23678.526429471658 15:21:05 INFO - PID 3787 | |2;jquery.html;558.1766893831599;565.2623326558844;568.2073507457095;575.7553039958017;583.991240131398;22341;23289;23305;23306.533869322604;23314;23389.76610233897;24425.511489770193;24658.260252192416;24807.627885581685;24817.37956551087;6458.806235812933;6513.576617519846;6561.7375304987745;6566.080748695186;6632.535722499427;6452.967735161317;6518.315576864432;6538.673066346688;6564.606123632573;6565.474762019051;6396.392342727433;6881.483888708367;6904.344087311697;6906.654667266642;6907.758228462005;6798.864022719543;6811.693473793666;6837;6846.315368463163;6853.451723862079;6536.150300460909;6996.265392133803;7030.472714546429;7030.964845175792;7038.260982596805;30881.536776948138;31452.52821207684;31471.52792708111;31495.212619684462;31507.739690412356;2127.255460588797;2155.795199456065;2158.53591477833;2159.7192364992607;2162.945926351854;19534.413967580997;20000.699989500168;20286.594268114703;20395.388138355873;20548.17807287707;2404.280939128514;2440.1503005514605;2480.553500369933;2481.1812102006475;2485.391079185593;122705;137635;144988;144997;145702.99999999895;20626.484337891674;21140;21531.89234053851;21618.567628647652;22286.219982300543;41605.375919361235;41713.58286417158;41750.37374439386;41755.95610109771;41822.58177418248;11439.714007149722;11584.304941703525;11609.535618575332;11861.703457413634;11916.761664766656;9556.283278754121;9631.807363852824;9772.413655180711;9824.164945979672;9875.40747555149;10877.184211184192;10955.726106847233;11260.549578016951;11399.145064120225;11407.828882566768;3672.669459748582;4155.771432571177;4168.749875007509;4200;4299.914001719948;965.2085290062222;978.83849164887;986.1519093579516;989.8317286061387;1002.8044531316323;19000.049997500348;19267.325643602686;19341.903290483457;19352.70970935409;19377.4186774397;1020.3673722292226;1045.4459136657583;1055.1506037639774;1057.6668349470046;1060.3849767135043;10640.148788096934;10931.070858976916;10983.286086404358;11056.557737690564;11091.778164436666;467748;474928;482036;484164;501859;112037;112128.4393578027;113416;114150;114550;1151.9424028798526;1168.5150662474978;1176.8940795328463;1178.9410529473491;1179.9469023893946;16730.4144354947;17116.315347385964;17175.65648687044;17288.65422691514;17397.21712522914;53380.10012890891;54216.18675719789;55009.724951374985;55795.72102139463;56837;67390.66304668445;69194.65402672955;69208.65395672989;69289.65355173191;70652.646736766;18743;19739.901300493406;20003.699944501135;20028.799712002692;20422.387328380173;22286.88856555707;22600.09599615997;22604.886975565016;22711.659325109806;22898.65652015188;17886.731699024785;18031.549211269823;18092.185851636445;18179.363722269656;18239.726404104214;15388.307526161574;15737.763933540777;16239.3504259833;16452.259648315616;16463;18587.34944276944;18645.81354186441;18697.345592904185;18717.157727902006;18823.152958117193;19372.4188274352;19428.417147485598;19506.512337191685;19697.81813091162;19700;18017.549561261072;18158.45524634263;18221.088945552936;18322.725159122358;18449.446516604523 15:21:05 INFO - PID 3787 | |3;mootools.html;1286.4339690536162;1391.8816900563452;1392.9164250144988;1392.9233892135935;1396.1762560089546;14975.550733477985;15481.69036619267;15522.146281954501;15525.689486210269;15549.06705597665;15010.249487525634;15219.92390038051;15250.618734531643;15251;15259.084454932712;2711.688155862081;2759.8344099354003;2788.07993362191;2791.2742686901347;2797.930051748702;2716.090109813205;2732.0574401831336;2740.1779466160074;2756.682981457128;2757.1039412191017;2653.9601905971426;2760.2961244882526;2771.80597358185;2778.263760103574;2781.3463835998523;2677.3440507075793;2680.973190268092;2711.8644067796727;2715.389037466564;2725.8364498130077;2712.0236714782664;2713.91858244253;2715.9321016974536;2727.754502094801;2735.2341344423594;14188.574342769733;14197.574072777834;14324.57026289213;14442.133471991714;14446.783298250535;7607.581583012932;7856.450048496607;7869.055713314382;7878.054633443966;7887.921120788776;5272.551833094191;5367.221752845816;5367.490088441592;5454.045542030129;5472.179173124063;1930.449821800794;1931.0923865783059;1944.1834429539485;1976.980230197694;1978.0208796645752;25007.249782506555;25114.121005764893;25176.748232517624;25192.49615007708;25250.116245931305;6421.871562568723;6488.545801793829;6675.532712710161;6678.09845670838;6706.497012723968;16484.752728709078;16486.422975195812;16519.6696066078;16519.752203716955;16545.09002004877;7611.238876112345;7800.765977020698;7813.335866451302;7818.061832580127;7819.687212511436;7077.893831592531;7160.749373771893;7184.892226616605;7282.635868206568;7288.3076107769675;4836.322914791932;4935.333729946483;5001.149804533238;5015.3480047593575;5036.748162591855;1367.9452821887014;1395.4418232706796;1414.3211258595932;1429.0639631041684;1438.8201474815648;865.0830120072743;875.6366108065209;885.0397318909056;887.454215657366;894.1952242981239;7689.308352162515;7879.133295337509;7899.802504937307;7964.761057168294;7987.3610111190965;946.7017889364828;952.7189479103681;955.8422860228123;956.2254573795248;961.8990006049404;4092.3835085141204;4152.626263636287;4174.645155161846;4208.7685177314925;4210.410542524049;19589.412317630493;19914.402567922985;20484;20518.38444846657;20533.58932821335;19388.4183474496;19657;19882.304119355755;19951;19964.3012494562;9984.500774961223;10129.442880641489;10202;10202.744931376776;10203.438810865326;7862.60686965638;7891;7891.763247102596;7993;8020.919790802017;17540.386086486913;17565.912170439067;17866.374676886247;17978;18268.26926922908;18230.17964191641;18336.81663183351;18576;18587.90706046461;18824.7176292353;10497;10548.367097974146;10594.523246454082;10646.414447205478;10649.094826939798;12585.496580136693;13311.134776239513;13329.333533323488;13346.265955372548;13365;10809.13526917829;10855.294405863593;10857.565697372333;10914.23600347968;11034.613788517363;10187.898121018992;10192.082712555906;10307.226957978031;10404.47977601132;10561.89438105609;9662.855057174007;9707.077827606367;9772.31593788428;9812.460314682761;9830.459324737209;6827.351401616838;6909.1018167637885;6918;6955.443564514725;7053.6825842838225;7126.750563730245;7204.747833825791;7264.89102663471;7327.120745510573;7355.926440735524 15:21:05 INFO - PID 3787 | |4;prototype.html;500.9023240468108;503.56693243810315;531.5720844719999;532.2282690099356;536.1796451429315;6308.936910630898;6396.488280937514;6423.229212494495;6430.228372595289;6437.034444833277;6181.814545563629;6201.906971395432;6248.468880145193;6285.277193122793;6305.842353941154;4979.45226025137;5117.413601783441;5121.3342265505435;5128.563932132226;5144.202648589456;5079.644424890259;5146.974265128669;5172.12073947428;5182.170852663575;5186.4813518648025;5040.2439634055;5041.621878359119;5056.823011194609;5091.439941606439;5142.691438513682;5007.92488112675;5043.420006699222;5047.4447810740785;5103.974480127613;5105.719185444799;4588.49526552079;4643.187442197635;4655.837045703418;4655.8593144679435;4669;6310.368963103653;6327.430531252164;6374.553781235315;6391.872162556723;6419.775307864203;5762.827115186509;5788;5824.24284842972;5859.53123750099;5865.912011319833;6712.932870671329;6721.663916804189;6744.629045402501;6751;6756.344695549573;5000.874978125539;5025.296458495814;5030.849074527733;5034.874128146789;5051.848444546669;6922.446204303644;7002.474814388941;7046.471514636422;7051.612161331124;7075.327843854826;5094.465081166498;5113.411957624847;5121.28302037715;5131.512506311894;5134.743262836843;5197.700574856286;5197.7141257231215;5210.713410762444;5214.478552144755;5224.660397074178;5777.855553611109;5787.2187254720075;5792.218050563204;5794.391588883106;5809.128630705428;6487.513436492282;6511.446527045254;6599.109120268702;6632.50256230775;6733.93266067333;1955.1006536993143;1957.4519134642323;1959.6374670685968;1985.2753744883303;2001.4996250937265;1444.812174417319;1454.01206918617;1459.8613131752466;1470.962971105368;1489.7765335199806;5649.830505084854;5736.196932429463;5743.8851222976145;5778.971105144447;5803.274590676166;6598.505112116611;6709.5303328767495;6828;6849.315068493111;6926.272741362185;5631.662100273997;5721.8569535761935;5723.168586052507;5832.445917637818;5865.765369385262;3504.5268888700207;3611.115276757211;3629.9092522686615;3662.1210909381557;3664.706823454117;6519.2072180150835;6569.9343006569325;6591.627014274857;6598.076269322299;6672.366125218096;6960.234374218832;7054.4003759681;7097.751578694824;7136.964315178391;7141.714331426685;6508.299055891846;6611.900821487681;6621.9337806621315;6624.304448032887;6648.7340506379205;6811.727530898708;6858.0398744176855;6872.965635171792;6875.140607424072;6956.782563051344;6720.227173874871;6761.222459417033;6774.762883299061;6862.897056544056;6876.862462750617;6398.712057957497;6438.452731517874;6501.772437964649;6518.967405162944;6527.281998980014;4960.156773348467;4994.900101997867;4997.650164488597;5020.874478138076;5034.14419548685;6745.571594226173;6770.627615480998;6791.456683465212;6864.4165245954655;6916.20463646687;6510.772122975863;6515.869682606416;6578.210614726264;6593;6595.8021259362295;6540.8364790880605;6560.376764207393;6576.210854697467;6600.339966003361;6654.966725166537;6395.808125756234;6449.258335291502;6462;6494;6523.369157710572;6342;6619.536632435684;6677.565958212701;6681.76613818514;6731.899021514583;6484.254310754323;6486.156799616208;6489.967550162219;6499.837504062436;6535 15:21:05 INFO - PID 3787 | |5;yui.html;905.8143080668464;918.068160816771;930.1628534319112;930.7998780262246;953.2516974175273;19196.23215071397;20257.189712411506;20268.29060982866;20269.594608107865;20275.391738247858;16395.4261600844;16757.162141892888;16837.494875153734;18429.17068731907;18498.167582458787;943.7499062748377;955.5795450002012;958.4728399380348;958.6548842416724;958.7315551645515;964.5948701545368;981.4258658684643;981.6318880419842;981.6564202529129;988;1012.559536601577;1014.0164040880335;1015.3653966271081;1016.7661437869294;1023.9744006399825;1014.9340292880976;1015.0407864567986;1015.4110615842793;1018.5518371916407;1019.6023550815188;725.1841678112124;745.9776206713807;750.3921823323126;751.2112282103769;754.0122439604136;20203.696944545696;20277.594448111104;20321.49196270105;20327.881966491837;20442.693359599616;21076.78923210779;21434.892825535928;21775.020124094455;21813.67279490809;21894.452638684;17017.659646806995;17302.394416195497;17487.737683934753;17531.123443827757;17576;3647.3252448296885;3678.797666128362;3679.6136405677553;3684.5762737285286;3689.631036896316;141025.294873526;142264.57735422617;142338.28830855884;143203;145779;16948.067856268022;17283.91358043227;17358.132093395532;17462.91268543649;17514.912425437793;30303.393932121675;30405;30528.694713052584;30773.23066923345;30839.845800770854;7937.444378893422;7978.760637180894;7987.400944929153;8040.4773689711155;8041.758747237593;3880.1657643606195;3887.922241555153;3949.9802500987885;4008.599140086026;4024.8993775155855;5089.8727531812;5175.301334319819;5210.921836172461;5220.817271395483;5236.345456817898;968.5593055159885;985.930984831055;986.7335819328741;992.8262554053011;992.9652462163934;624.1137584629746;642.4378668664918;644.6228956060756;656.4190691238193;663.326723375774;11132;11686.70783230426;11831.408429578487;11961.46173422198;12557.623271301876;631.3497097989078;636.0840389838618;638.3233772201481;639.46285120499;655.2530115668142;5549.18253548669;5603.327600687862;5619.690916999522;5699.401562835843;5749.965006298907;123343;123911;124052;124217;126189;110219;111181;112290;114020;115025;92807;94754.0524594759;94773;94784.52607736917;97389.51305243428;10161.898381016095;10308.639197628047;10333.793324133625;10352.327098738559;10368.22238332113;43356.34965475583;43525.78237108794;44728.32907506325;44734.328985064596;44921.32618010667;56477.71761141168;57175;58503.4149658498;59002.70498647651;59564.106538401094;14162.291885405482;14546.272686365428;14590;14664.046836955564;14803.481878134213;15473.84526154769;15477;15586.376544938528;15642.921785391;16091.436799711953;14938.178400188093;14978.925105374403;15014.099154050791;15231.619209519851;15245.313960871566;14905.10569365798;14956.40174393012;15035.323410446776;15083.698326033636;15265.3893844245;15094.39622415091;15373.3082011312;15414.614634633775;15544;15553.144577047919;10465.633702820365;10800.567977281135;10848.132149428184;10867.565297388015;11089.168312376443;11810.468528916392;11819.527218911147;11958.18829252215;12027.75944481123;12114.697132571757 15:21:05 INFO - PID 3787 | __end_tp_report 15:21:05 INFO - PID 3787 | __start_cc_report 15:21:05 INFO - PID 3787 | _x_x_mozilla_cycle_collect,288 15:21:05 INFO - PID 3787 | __end_cc_report 15:21:05 INFO - PID 3787 | __startTimestamp1505859665812__endTimestamp 15:21:05 INFO - PID 3787 | 15:21:05 INFO - PID 3787 | ------- Summary: start ------- 15:21:05 INFO - PID 3787 | Number of tests: 6 15:21:05 INFO - PID 3787 | 15:21:05 INFO - PID 3787 | [#0] dojo.html Cycles:165 Average:6186.59 Median:6428.45 stddev:748.34 (11.6%) stddev-sans-first:710.01 15:21:05 INFO - PID 3787 | Values: 3086.1 3099.5 3146.5 3150.4 3160.1 6184.6 6273.4 6338.7 6349.8 6363.9 6155.6 6195.7 6204.5 6205.1 6224.5 5712.5 5805.6 5852.4 5870.0 5918.9 5764.1 5828.8 5841.6 5851.0 5863.3 5638.2 5772.2 5774.0 5778.0 5871.5 5773.4 5840.6 5848.6 5902.4 5948.0 5565.7 5643.5 5654.3 5705.4 5756.4 6231.4 6261.1 6302.7 6315.1 6322.3 6723.2 6727.1 6801.5 6817.7 6826.7 6648.2 6743.9 6761.5 6844.1 6848.0 6262.0 6273.3 6274.1 6275.1 6277.8 6861.4 6913.8 6918.6 6923.9 6926.8 6564.8 6566.3 6577.1 6634.8 6659.1 6745.2 6811.7 6827.5 6835.6 6838.0 6705.2 6710.8 6792.5 6801.7 6808.1 6570.6 6709.0 6710.8 6757.3 6866.1 5300.7 5305.3 5323.7 5349.9 5370.7 4592.9 4653.0 4684.2 4689.1 4707.2 5747.0 5764.3 5830.3 5831.8 5851.7 6614.1 6692.6 6747.7 6836.0 6842.2 5720.3 5789.6 5812.9 5814.9 5827.4 5543.9 5584.6 5593.6 5600.0 5609.4 6382.2 6495.9 6559.9 6575.5 6585.0 6590.6 6731.2 6740.5 6825.7 6829.1 6456.7 6476.5 6527.2 6542.8 6554.7 6637.3 6647.4 6706.2 6713.6 6738.7 6762.1 6805.2 6852.7 6875.4 6883.2 6467.1 6509.3 6526.9 6546.7 6622.3 6501.3 6513.0 6513.8 6527.6 6538.6 6346.3 6404.7 6419.4 6425.4 6428.5 6520.6 6548.1 6570.0 6588.0 6633.2 6322.2 6367.2 6437.7 6442.8 6448.1 15:21:05 INFO - PID 3787 | 15:21:05 INFO - PID 3787 | [#1] ext.html Cycles:180 Average:37530.64 Median:19769.96 stddev:52814.78 (267.1%) stddev-sans-first:52891.14 15:21:05 INFO - PID 3787 | Values: 855.8 871.6 874.5 879.1 880.4 39091.6 42750.1 42800.8 42852.1 42870.8 39370.4 39720.8 39726.8 39751.6 39804.4 7062.5 7301.6 7933.3 8132.3 8136.8 7975.2 8080.1 8093.2 8097.8 8098.1 8075.6 8139.6 8139.7 8150.9 8151.4 8016.7 8053.9 8091.6 8092.3 8102.5 7973.8 8028.7 8032.6 8035.4 8043.2 38834.0 39061.6 39167.2 39296.2 39467.4 3596.1 3638.2 3641.2 3641.3 3643.6 32793.8 32820.8 32868.3 32899.5 32919.7 5001.6 5162.7 5176.4 5230.5 5316.7 189814.0 190483.0 190608.0 191259.0 191351.0 43993.3 44352.3 49402.5 49554.3 50130.7 51686.0 52067.7 52079.5 52104.7 52140.5 16217.1 16373.6 16439.7 16488.2 16495.4 13620.9 13652.2 13658.0 13660.0 13784.5 13652.7 13741.3 13753.4 13792.4 13813.6 5459.9 5559.2 5611.9 5634.5 5636.4 998.8 1002.8 1017.3 1018.2 1032.0 23287.7 23357.6 23422.3 23564.2 23671.9 1117.8 1129.7 1133.2 1134.1 1136.8 12401.7 12425.4 12446.3 12531.4 12634.2 223986.0 224786.0 225678.0 226345.0 226746.0 55443.4 55504.7 55616.4 55758.0 55848.4 1422.1 1478.2 1482.7 1484.5 1484.6 22817.7 22958.8 23133.0 23173.5 23272.1 38691.8 38731.0 38826.0 38854.2 38863.6 192225.0 193727.0 194384.0 194962.0 195515.0 30819.0 31323.7 31355.2 33973.5 34173.5 38187.0 39377.6 43977.3 44239.1 44247.0 32242.5 32511.8 32524.2 32952.5 32989.8 32693.0 32763.3 32917.7 33078.3 33272.2 35640.5 35943.8 36213.0 36304.5 36307.6 31260.5 31630.8 31871.4 31952.4 32560.2 23183.0 23234.4 23409.8 23656.6 23678.5 15:21:05 INFO - PID 3787 | 15:21:05 INFO - PID 3787 | [#2] jquery.html Cycles:180 Average:35558.27 Median:22286.55 stddev:81166.21 (364.2%) stddev-sans-first:81351.37 15:21:05 INFO - PID 3787 | Values: 558.2 565.3 568.2 575.8 584.0 22341.0 23289.0 23305.0 23306.5 23314.0 23389.8 24425.5 24658.3 24807.6 24817.4 6458.8 6513.6 6561.7 6566.1 6632.5 6453.0 6518.3 6538.7 6564.6 6565.5 6396.4 6881.5 6904.3 6906.7 6907.8 6798.9 6811.7 6837.0 6846.3 6853.5 6536.2 6996.3 7030.5 7031.0 7038.3 30881.5 31452.5 31471.5 31495.2 31507.7 2127.3 2155.8 2158.5 2159.7 2162.9 19534.4 20000.7 20286.6 20395.4 20548.2 2404.3 2440.2 2480.6 2481.2 2485.4 122705.0 137635.0 144988.0 144997.0 145703.0 20626.5 21140.0 21531.9 21618.6 22286.2 41605.4 41713.6 41750.4 41756.0 41822.6 11439.7 11584.3 11609.5 11861.7 11916.8 9556.3 9631.8 9772.4 9824.2 9875.4 10877.2 10955.7 11260.5 11399.1 11407.8 3672.7 4155.8 4168.7 4200.0 4299.9 965.2 978.8 986.2 989.8 1002.8 19000.0 19267.3 19341.9 19352.7 19377.4 1020.4 1045.4 1055.2 1057.7 1060.4 10640.1 10931.1 10983.3 11056.6 11091.8 467748.0 474928.0 482036.0 484164.0 501859.0 112037.0 112128.4 113416.0 114150.0 114550.0 1151.9 1168.5 1176.9 1178.9 1179.9 16730.4 17116.3 17175.7 17288.7 17397.2 53380.1 54216.2 55009.7 55795.7 56837.0 67390.7 69194.7 69208.7 69289.7 70652.6 18743.0 19739.9 20003.7 20028.8 20422.4 22286.9 22600.1 22604.9 22711.7 22898.7 17886.7 18031.5 18092.2 18179.4 18239.7 15388.3 15737.8 16239.4 16452.3 16463.0 18587.3 18645.8 18697.3 18717.2 18823.2 19372.4 19428.4 19506.5 19697.8 19700.0 18017.5 18158.5 18221.1 18322.7 18449.4 15:21:05 INFO - PID 3787 | 15:21:05 INFO - PID 3787 | [#3] mootools.html Cycles:180 Average:8943.95 Median:2714.65 stddev:6319.34 (232.8%) stddev-sans-first:6310.87 15:21:05 INFO - PID 3787 | Values: 1286.4 1391.9 1392.9 1392.9 1396.2 14975.6 15481.7 15522.1 15525.7 15549.1 15010.2 15219.9 15250.6 15251.0 15259.1 2711.7 2759.8 2788.1 2791.3 2797.9 2716.1 2732.1 2740.2 2756.7 2757.1 2654.0 2760.3 2771.8 2778.3 2781.3 2677.3 2681.0 2711.9 2715.4 2725.8 2712.0 2713.9 2715.9 2727.8 2735.2 14188.6 14197.6 14324.6 14442.1 14446.8 7607.6 7856.5 7869.1 7878.1 7887.9 5272.6 5367.2 5367.5 5454.0 5472.2 1930.4 1931.1 1944.2 1977.0 1978.0 25007.2 25114.1 25176.7 25192.5 25250.1 6421.9 6488.5 6675.5 6678.1 6706.5 16484.8 16486.4 16519.7 16519.8 16545.1 7611.2 7800.8 7813.3 7818.1 7819.7 7077.9 7160.7 7184.9 7282.6 7288.3 4836.3 4935.3 5001.1 5015.3 5036.7 1367.9 1395.4 1414.3 1429.1 1438.8 865.1 875.6 885.0 887.5 894.2 7689.3 7879.1 7899.8 7964.8 7987.4 946.7 952.7 955.8 956.2 961.9 4092.4 4152.6 4174.6 4208.8 4210.4 19589.4 19914.4 20484.0 20518.4 20533.6 19388.4 19657.0 19882.3 19951.0 19964.3 9984.5 10129.4 10202.0 10202.7 10203.4 7862.6 7891.0 7891.8 7993.0 8020.9 17540.4 17565.9 17866.4 17978.0 18268.3 18230.2 18336.8 18576.0 18587.9 18824.7 10497.0 10548.4 10594.5 10646.4 10649.1 12585.5 13311.1 13329.3 13346.3 13365.0 10809.1 10855.3 10857.6 10914.2 11034.6 10187.9 10192.1 10307.2 10404.5 10561.9 9662.9 9707.1 9772.3 9812.5 9830.5 6827.4 6909.1 6918.0 6955.4 7053.7 7126.8 7204.7 7264.9 7327.1 7355.9 15:21:05 INFO - PID 3787 | 15:21:05 INFO - PID 3787 | [#4] prototype.html Cycles:180 Average:5615.85 Median:6309.65 stddev:1536.57 (24.4%) stddev-sans-first:1492.15 15:21:05 INFO - PID 3787 | Values: 500.9 503.6 531.6 532.2 536.2 6308.9 6396.5 6423.2 6430.2 6437.0 6181.8 6201.9 6248.5 6285.3 6305.8 4979.5 5117.4 5121.3 5128.6 5144.2 5079.6 5147.0 5172.1 5182.2 5186.5 5040.2 5041.6 5056.8 5091.4 5142.7 5007.9 5043.4 5047.4 5104.0 5105.7 4588.5 4643.2 4655.8 4655.9 4669.0 6310.4 6327.4 6374.6 6391.9 6419.8 5762.8 5788.0 5824.2 5859.5 5865.9 6712.9 6721.7 6744.6 6751.0 6756.3 5000.9 5025.3 5030.8 5034.9 5051.8 6922.4 7002.5 7046.5 7051.6 7075.3 5094.5 5113.4 5121.3 5131.5 5134.7 5197.7 5197.7 5210.7 5214.5 5224.7 5777.9 5787.2 5792.2 5794.4 5809.1 6487.5 6511.4 6599.1 6632.5 6733.9 1955.1 1957.5 1959.6 1985.3 2001.5 1444.8 1454.0 1459.9 1471.0 1489.8 5649.8 5736.2 5743.9 5779.0 5803.3 6598.5 6709.5 6828.0 6849.3 6926.3 5631.7 5721.9 5723.2 5832.4 5865.8 3504.5 3611.1 3629.9 3662.1 3664.7 6519.2 6569.9 6591.6 6598.1 6672.4 6960.2 7054.4 7097.8 7137.0 7141.7 6508.3 6611.9 6621.9 6624.3 6648.7 6811.7 6858.0 6873.0 6875.1 6956.8 6720.2 6761.2 6774.8 6862.9 6876.9 6398.7 6438.5 6501.8 6519.0 6527.3 4960.2 4994.9 4997.7 5020.9 5034.1 6745.6 6770.6 6791.5 6864.4 6916.2 6510.8 6515.9 6578.2 6593.0 6595.8 6540.8 6560.4 6576.2 6600.3 6655.0 6395.8 6449.3 6462.0 6494.0 6523.4 6342.0 6619.5 6677.6 6681.8 6731.9 6484.3 6486.2 6490.0 6499.8 6535.0 15:21:05 INFO - PID 3787 | 15:21:05 INFO - PID 3787 | [#5] yui.html Cycles:180 Average:24353.56 Median:20276.49 stddev:36037.45 (177.7%) stddev-sans-first:36095.54 15:21:05 INFO - PID 3787 | Values: 905.8 918.1 930.2 930.8 953.3 19196.2 20257.2 20268.3 20269.6 20275.4 16395.4 16757.2 16837.5 18429.2 18498.2 943.7 955.6 958.5 958.7 958.7 964.6 981.4 981.6 981.7 988.0 1012.6 1014.0 1015.4 1016.8 1024.0 1014.9 1015.0 1015.4 1018.6 1019.6 725.2 746.0 750.4 751.2 754.0 20203.7 20277.6 20321.5 20327.9 20442.7 21076.8 21434.9 21775.0 21813.7 21894.5 17017.7 17302.4 17487.7 17531.1 17576.0 3647.3 3678.8 3679.6 3684.6 3689.6 141025.3 142264.6 142338.3 143203.0 145779.0 16948.1 17283.9 17358.1 17462.9 17514.9 30303.4 30405.0 30528.7 30773.2 30839.8 7937.4 7978.8 7987.4 8040.5 8041.8 3880.2 3887.9 3950.0 4008.6 4024.9 5089.9 5175.3 5210.9 5220.8 5236.3 968.6 985.9 986.7 992.8 993.0 624.1 642.4 644.6 656.4 663.3 11132.0 11686.7 11831.4 11961.5 12557.6 631.3 636.1 638.3 639.5 655.3 5549.2 5603.3 5619.7 5699.4 5750.0 123343.0 123911.0 124052.0 124217.0 126189.0 110219.0 111181.0 112290.0 114020.0 115025.0 92807.0 94754.1 94773.0 94784.5 97389.5 10161.9 10308.6 10333.8 10352.3 10368.2 43356.3 43525.8 44728.3 44734.3 44921.3 56477.7 57175.0 58503.4 59002.7 59564.1 14162.3 14546.3 14590.0 14664.0 14803.5 15473.8 15477.0 15586.4 15642.9 16091.4 14938.2 14978.9 15014.1 15231.6 15245.3 14905.1 14956.4 15035.3 15083.7 15265.4 15094.4 15373.3 15414.6 15544.0 15553.1 10465.6 10800.6 10848.1 10867.6 11089.2 11810.5 11819.5 11958.2 12027.8 12114.7 15:21:05 INFO - PID 3787 | -------- Summary: end -------- 15:21:05 INFO - PID 3787 | 15:21:06 INFO - TEST-INFO | 3787: exit 0 15:21:06 INFO - TEST-OK | dromaeo_css | took 1110264ms 15:21:06 INFO - TEST-START | kraken 15:21:06 INFO - Initialising browser for kraken test... 15:21:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40367/getInfo.html -profile /tmp/tmpJ4Eu4Z/profile 15:21:06 INFO - TEST-INFO | started process 4163 (/builds/slave/test/build/application/firefox/firefox http://localhost:40367/getInfo.html) 15:21:11 INFO - TEST-INFO | 4163: exit 0 15:21:11 INFO - Browser initialized. 15:21:11 INFO - Running cycle 1/1 for kraken test... 15:21:11 INFO - Using env: {'DISPLAY': ':0', 15:21:11 INFO - 'HOME': '/home/cltbld', 15:21:11 INFO - 'JSGC_DISABLE_POISONING': '1', 15:21:11 INFO - 'LANG': 'en_US.UTF-8', 15:21:11 INFO - 'LANGUAGE': 'en_US:en', 15:21:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:21:11 INFO - 'LOGNAME': 'cltbld', 15:21:11 INFO - 'MAIL': '/var/mail/cltbld', 15:21:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:21:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:21:11 INFO - 'MOZ_CRASHREPORTER': '1', 15:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:21:11 INFO - 'MOZ_NO_REMOTE': '1', 15:21:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:11 INFO - 'NO_EM_RESTART': '1', 15:21:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:11 INFO - 'PWD': '/builds/slave/test', 15:21:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:21:11 INFO - 'RUST_BACKTRACE': 'full', 15:21:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:21:11 INFO - 'SHELL': '/bin/bash', 15:21:11 INFO - 'SHLVL': '1', 15:21:11 INFO - 'TERM': 'linux', 15:21:11 INFO - 'TMOUT': '86400', 15:21:11 INFO - 'USER': 'cltbld', 15:21:11 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833', 15:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:11 INFO - '_': '/tools/buildbot/bin/python'} 15:21:11 INFO - TEST-INFO | started process 4394 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ4Eu4Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:12 INFO - PID 4394 | 15:21:12 INFO - PID 4394 | (/builds/slave/test/build/application/firefox/firefox:4444): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:21:12 INFO - PID 4394 | 15:21:22 INFO - PID 4394 | RSS: Main: 155787264 15:21:22 INFO - PID 4394 | 15:21:25 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40367/tests/kraken/driver.html?testName=audio-beat-detection) 15:21:26 INFO - PID 4394 | RSS: Main: 161988608 15:21:26 INFO - PID 4394 | 15:21:29 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40367/tests/kraken/driver.html?testName=audio-dft) 15:21:29 INFO - PID 4394 | RSS: Main: 162758656 15:21:29 INFO - PID 4394 | 15:21:32 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40367/tests/kraken/driver.html?testName=audio-fft) 15:21:33 INFO - PID 4394 | RSS: Main: 158666752 15:21:33 INFO - PID 4394 | 15:21:35 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40367/tests/kraken/driver.html?testName=audio-oscillator) 15:21:35 INFO - PID 4394 | RSS: Main: 158892032 15:21:35 INFO - PID 4394 | 15:21:38 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40367/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:21:38 INFO - PID 4394 | RSS: Main: 159121408 15:21:38 INFO - PID 4394 | 15:21:41 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40367/tests/kraken/driver.html?testName=imaging-darkroom) 15:21:42 INFO - PID 4394 | RSS: Main: 161345536 15:21:42 INFO - PID 4394 | 15:21:46 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40367/tests/kraken/driver.html?testName=imaging-desaturate) 15:21:46 INFO - PID 4394 | RSS: Main: 162099200 15:21:46 INFO - PID 4394 | 15:21:49 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40367/tests/kraken/driver.html?testName=json-parse-financial) 15:21:50 INFO - PID 4394 | RSS: Main: 162000896 15:21:50 INFO - PID 4394 | 15:21:51 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40367/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:21:52 INFO - PID 4394 | RSS: Main: 162025472 15:21:52 INFO - PID 4394 | 15:21:54 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40367/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:21:54 INFO - PID 4394 | RSS: Main: 161869824 15:21:54 INFO - PID 4394 | 15:21:56 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40367/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:21:57 INFO - PID 4394 | RSS: Main: 158646272 15:21:57 INFO - PID 4394 | 15:22:00 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:22:00 INFO - PID 4394 | RSS: Main: 159023104 15:22:00 INFO - PID 4394 | 15:22:03 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:22:04 INFO - PID 4394 | RSS: Main: 159006720 15:22:04 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | Cycle 1(1): loaded http://localhost:40367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40367/tests/kraken/driver.html?testName=ai-astar) 15:22:06 INFO - PID 4394 | RSS: Main: 160497664 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | __start_tp_report 15:22:06 INFO - PID 4394 | _x_x_mozilla_page_load 15:22:06 INFO - PID 4394 | _x_x_mozilla_page_load_details 15:22:06 INFO - PID 4394 | |i|pagename|runs| 15:22:06 INFO - PID 4394 | |0;ai-astar;108;109;110;106;106;107;107;107;107;108 15:22:06 INFO - PID 4394 | |1;audio-beat-detection;117;114;115;126;117;116;118;121;149;119 15:22:06 INFO - PID 4394 | |2;audio-dft;163;162;164;162;162;162;163;165;167;166 15:22:06 INFO - PID 4394 | |3;audio-fft;74;73;74;80;74;74;75;74;74;76 15:22:06 INFO - PID 4394 | |4;audio-oscillator;80;79;79;79;79;79;79;77;79;77 15:22:06 INFO - PID 4394 | |5;imaging-gaussian-blur;113;113;115;113;114;115;116;114;114;114 15:22:06 INFO - PID 4394 | |6;imaging-darkroom;148;148;147;147;149;147;149;148;147;149 15:22:06 INFO - PID 4394 | |7;imaging-desaturate;99;103;93;93;94;93;93;95;94;94 15:22:06 INFO - PID 4394 | |8;json-parse-financial;55;52;54;54;52;52;54;54;54;55 15:22:06 INFO - PID 4394 | |9;json-stringify-tinderbox;38;38;39;39;38;38;38;39;39;38 15:22:06 INFO - PID 4394 | |10;stanford-crypto-aes;75;77;75;79;74;73;75;73;79;77 15:22:06 INFO - PID 4394 | |11;stanford-crypto-ccm;112;117;119;114;114;117;114;111;112;117 15:22:06 INFO - PID 4394 | |12;stanford-crypto-pbkdf2;170;175;174;180;179;182;185;171;185;177 15:22:06 INFO - PID 4394 | |13;stanford-crypto-sha256-iterative;75;71;63;65;66;69;69;68;67;68 15:22:06 INFO - PID 4394 | __end_tp_report 15:22:06 INFO - PID 4394 | __start_cc_report 15:22:06 INFO - PID 4394 | _x_x_mozilla_cycle_collect,756 15:22:06 INFO - PID 4394 | __end_cc_report 15:22:06 INFO - PID 4394 | __startTimestamp1505859726568__endTimestamp 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | ------- Summary: start ------- 15:22:06 INFO - PID 4394 | Number of tests: 14 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#0] ai-astar Cycles:10 Average:107.50 Median:107.50 stddev:1.27 (1.2%) stddev-sans-first:1.33 15:22:06 INFO - PID 4394 | Values: 108.0 109.0 110.0 106.0 106.0 107.0 107.0 107.0 107.0 108.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:118.50 stddev:10.35 (8.7%) stddev-sans-first:10.86 15:22:06 INFO - PID 4394 | Values: 117.0 114.0 115.0 126.0 117.0 116.0 118.0 121.0 149.0 119.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.50 stddev:1.84 (1.1%) stddev-sans-first:1.94 15:22:06 INFO - PID 4394 | Values: 163.0 162.0 164.0 162.0 162.0 162.0 163.0 165.0 167.0 166.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#3] audio-fft Cycles:10 Average:74.80 Median:74.00 stddev:1.99 (2.7%) stddev-sans-first:2.09 15:22:06 INFO - PID 4394 | Values: 74.0 73.0 74.0 80.0 74.0 74.0 75.0 74.0 74.0 76.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:79.00 stddev:0.95 (1.2%) stddev-sans-first:0.88 15:22:06 INFO - PID 4394 | Values: 80.0 79.0 79.0 79.0 79.0 79.0 79.0 77.0 79.0 77.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.99 (0.9%) stddev-sans-first:0.97 15:22:06 INFO - PID 4394 | Values: 113.0 113.0 115.0 113.0 114.0 115.0 116.0 114.0 114.0 114.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 15:22:06 INFO - PID 4394 | Values: 148.0 148.0 147.0 147.0 149.0 147.0 149.0 148.0 147.0 149.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:94.00 stddev:3.31 (3.5%) stddev-sans-first:3.20 15:22:06 INFO - PID 4394 | Values: 99.0 103.0 93.0 93.0 94.0 93.0 93.0 95.0 94.0 94.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:1.17 (2.2%) stddev-sans-first:1.13 15:22:06 INFO - PID 4394 | Values: 55.0 52.0 54.0 54.0 52.0 52.0 54.0 54.0 54.0 55.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.53 15:22:06 INFO - PID 4394 | Values: 38.0 38.0 39.0 39.0 38.0 38.0 38.0 39.0 39.0 38.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:76.00 stddev:2.21 (2.9%) stddev-sans-first:2.33 15:22:06 INFO - PID 4394 | Values: 75.0 77.0 75.0 79.0 74.0 73.0 75.0 73.0 79.0 77.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#11] stanford-crypto-ccm Cycles:10 Average:114.70 Median:115.50 stddev:2.67 (2.3%) stddev-sans-first:2.65 15:22:06 INFO - PID 4394 | Values: 112.0 117.0 119.0 114.0 114.0 117.0 114.0 111.0 112.0 117.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:179.50 stddev:5.35 (3.0%) stddev-sans-first:4.87 15:22:06 INFO - PID 4394 | Values: 170.0 175.0 174.0 180.0 179.0 182.0 185.0 171.0 185.0 177.0 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:3.31 (4.8%) stddev-sans-first:2.40 15:22:06 INFO - PID 4394 | Values: 75.0 71.0 63.0 65.0 66.0 69.0 69.0 68.0 67.0 68.0 15:22:06 INFO - PID 4394 | -------- Summary: end -------- 15:22:06 INFO - PID 4394 | 15:22:06 INFO - PID 4394 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:22:07 INFO - TEST-INFO | 4394: exit 0 15:22:07 INFO - TEST-OK | kraken | took 60851ms 15:22:07 INFO - SUITE-END | took 1171s 15:22:07 INFO - Completed test suite (00:19:31) 15:22:07 INFO - javascript benchmark 15:22:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8577.05127870509, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3086.0587520806152, 3099.535069739539, 3146.4651009328413, 3150.3856747934146, 3160.0519844046794, 6184.628922264667, 6273.435390814831, 6338.7464501419945, 6349.777757778479, 6363.9045414318825, 6155.569110162291, 6195.6902154892305, 6204.503639708812, 6205.10026046223, 6224.533160013006, 5712.542996560275, 5805.593608447411, 5852.385499522531, 5870.0314448116005, 5918.852028699274, 5764.135379693059, 5828.825135245949, 5841.620294680833, 5850.970745146268, 5863.325717542473, 5638.154276858463, 5772.220750198711, 5774.0, 5778.0466223073145, 5871.530277577784, 5773.422657734235, 5840.561957853178, 5848.561357898175, 5902.3507414184405, 5948.0, 5565.666060036411, 5643.548516118701, 5654.349749778787, 5705.400932902027, 5756.424357564252, 6231.40801623845, 6261.123442718023, 6302.653354065525, 6315.147455093595, 6322.304546499881, 6723.193216814014, 6727.125473688389, 6801.4558835293055, 6817.727290908358, 6826.658667066677, 6648.16897887764, 6743.898841517381, 6761.4590832733265, 6844.110265665481, 6847.965760171217, 6262.0, 6273.278572964122, 6274.121622972787, 6275.121482992385, 6277.7802776903045, 6861.382475577222, 6913.827154321081, 6918.619475928872, 6923.89614155778, 6926.757563485254, 6564.80305590833, 6566.343365663395, 6577.112089867806, 6634.767783127568, 6659.134312539339, 6745.156855393076, 6811.693473793691, 6827.453803695693, 6835.555688880207, 6838.0, 6705.161854768154, 6710.765123220664, 6792.490563207779, 6801.693923773442, 6808.080909077211, 6570.605763654196, 6709.0, 6710.798676039727, 6757.3242675732035, 6866.141732283465, 5300.655457395257, 5305.283786688748, 5323.733813309319, 5349.919751203735, 5370.70461124634, 4592.88517787058, 4653.046125544255, 4684.1568517666465, 4689.085628302449, 4707.246840505502, 5746.9712651436475, 5764.279465066867, 5830.300363956268, 5831.766729330864, 5851.707414629337, 6614.140161778938, 6692.564983276072, 6747.730090796412, 6836.0, 6842.213145488233, 5720.2849643794525, 5789.565782566325, 5812.94187058124, 5814.912776308359, 5827.446392592697, 5543.861403464945, 5584.6090773646265, 5593.552515798727, 5600.047991841316, 5609.354924183771, 6382.234131904202, 6495.935040649533, 6559.901601475982, 6575.473962083021, 6584.967075164689, 6590.637514936533, 6731.1922569292, 6740.494462915203, 6825.658717064028, 6829.078074459884, 6456.741730330922, 6476.5466417350335, 6527.15147030883, 6542.771003014872, 6554.672266386757, 6637.30308317639, 6647.401733843783, 6706.195256569243, 6713.6307503085845, 6738.6967586459705, 6762.053312536151, 6805.183377994672, 6852.691628876612, 6875.449964002768, 6883.174019117538, 6467.126937863328, 6509.251436084911, 6526.9020964686515, 6546.738130474728, 6622.304658010839, 6501.284858665448, 6513.0, 6513.837154071186, 6527.608343499405, 6538.574992625464, 6346.301906790157, 6404.743810247538, 6419.390157934989, 6425.357464253537, 6428.453581445442, 6520.641364724986, 6548.116004339353, 6570.047343135314, 6587.9670601646685, 6633.2040155181685, 6322.241331040304, 6367.204099487564, 6437.7103030362805, 6442.838929026624, 6448.1295025171], "value": 6128.931525565013, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [855.7817756472099, 871.6469829718963, 874.5408660453261, 879.116487929631, 880.3529405886676, 39091.609083909156, 42750.14499710008, 42800.78599606998, 42852.14295714084, 42870.78564607177, 39370.40944385829, 39720.801395993054, 39726.801365993204, 39751.60248397515, 39804.40293395594, 7062.505624606267, 7301.634918254093, 7933.325667318285, 8132.349412047022, 8136.837263254732, 7975.202479752007, 8080.11118776937, 8093.1502192269845, 8097.757067287961, 8098.149694282104, 8075.596220188996, 8139.593020348989, 8139.715109971152, 8150.877736833952, 8151.388645851555, 8016.67933282668, 8053.919460805376, 8091.554964476952, 8092.27169554737, 8102.473339232931, 7973.800654983614, 8028.678852845879, 8032.558209298486, 8035.437519373646, 8043.195680431969, 38834.0, 39061.60938390637, 39167.21665566673, 39296.21407571832, 39467.407988880186, 3596.1009747563107, 3638.199596088856, 3641.2353405784816, 3641.289948460051, 3643.635636436335, 32793.83603081969, 32820.83589582061, 32868.34263314744, 32899.50650740241, 32919.6708032919, 5001.624878134155, 5162.6902385856965, 5176.378834539842, 5230.529252367267, 5316.734163291859, 189814.0, 190483.0, 190608.0, 191259.0, 191351.0, 43993.34009989853, 44352.334714979304, 49402.50597494051, 49554.25668614974, 50130.74934625303, 51686.0, 52067.73966130145, 52079.47920520822, 52104.73947630238, 52140.47859521432, 16217.108059056865, 16373.590660233589, 16439.67120657604, 16488.17559122063, 16495.422660206834, 13620.863791361959, 13652.249126297951, 13658.0, 13660.0, 13784.517541885985, 13652.726945461234, 13741.312934353242, 13753.449862005407, 13792.379342929593, 13813.585592432042, 5459.890802183934, 5559.193916882029, 5611.859703507445, 5634.5210657093785, 5636.408177141341, 998.7602975285879, 1002.8295189817749, 1017.3336464615688, 1018.1905385218769, 1031.9742006449899, 23287.65071519495, 23357.649635255486, 23422.297331080095, 23564.175253866033, 23671.88164059203, 1117.7652692934575, 1129.7234125438367, 1133.2350663302402, 1134.087059916775, 1136.8067428537004, 12401.689957751129, 12425.44085516154, 12446.31545265001, 12531.43608537618, 12634.2419454833, 223986.0, 224786.0, 225678.0, 226345.0, 226746.0, 55443.445565544636, 55504.72247638736, 55616.443835561935, 55758.0, 55848.44151558514, 1422.132499175502, 1478.2239324354584, 1482.7108713800706, 1484.502691598305, 1484.64368551549, 22817.657735133653, 22958.77041229633, 23133.0, 23173.536529268982, 23272.06911723512, 38691.80654096824, 38731.0, 38826.0, 38854.222915540966, 38863.61136388713, 192225.0, 193727.0, 194384.0, 194962.0, 195515.0, 30819.0, 31323.686763132988, 31355.216119597193, 33973.49039764455, 34173.48739768856, 38187.0, 39377.60622393739, 43977.34033989429, 44239.11521769611, 44247.0, 32242.516362255054, 32511.83744081359, 32524.186895327806, 32952.505712414815, 32989.83505082459, 32693.0, 32763.34473310473, 32917.67082329146, 33078.338433231685, 33272.1681957953, 35640.4653930186, 35943.82028089843, 36213.0, 36304.455433168, 36307.63692363042, 31260.531092034093, 31630.841845790623, 31871.36257274888, 31952.36095278128, 32560.185995350304, 23182.956766945867, 23234.419139521648, 23409.765902340758, 23656.645150322416, 23678.526429471658], "value": 17333.319557940493, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.1766893831599, 565.2623326558844, 568.2073507457095, 575.7553039958017, 583.991240131398, 22341.0, 23289.0, 23305.0, 23306.533869322604, 23314.0, 23389.76610233897, 24425.511489770193, 24658.260252192416, 24807.627885581685, 24817.37956551087, 6458.806235812933, 6513.576617519846, 6561.7375304987745, 6566.080748695186, 6632.535722499427, 6452.967735161317, 6518.315576864432, 6538.673066346688, 6564.606123632573, 6565.474762019051, 6396.392342727433, 6881.483888708367, 6904.344087311697, 6906.654667266642, 6907.758228462005, 6798.864022719543, 6811.693473793666, 6837.0, 6846.315368463163, 6853.451723862079, 6536.150300460909, 6996.265392133803, 7030.472714546429, 7030.964845175792, 7038.260982596805, 30881.536776948138, 31452.52821207684, 31471.52792708111, 31495.212619684462, 31507.739690412356, 2127.255460588797, 2155.795199456065, 2158.53591477833, 2159.7192364992607, 2162.945926351854, 19534.413967580997, 20000.699989500168, 20286.594268114703, 20395.388138355873, 20548.17807287707, 2404.280939128514, 2440.1503005514605, 2480.553500369933, 2481.1812102006475, 2485.391079185593, 122705.0, 137635.0, 144988.0, 144997.0, 145702.99999999895, 20626.484337891674, 21140.0, 21531.89234053851, 21618.567628647652, 22286.219982300543, 41605.375919361235, 41713.58286417158, 41750.37374439386, 41755.95610109771, 41822.58177418248, 11439.714007149722, 11584.304941703525, 11609.535618575332, 11861.703457413634, 11916.761664766656, 9556.283278754121, 9631.807363852824, 9772.413655180711, 9824.164945979672, 9875.40747555149, 10877.184211184192, 10955.726106847233, 11260.549578016951, 11399.145064120225, 11407.828882566768, 3672.669459748582, 4155.771432571177, 4168.749875007509, 4200.0, 4299.914001719948, 965.2085290062222, 978.83849164887, 986.1519093579516, 989.8317286061387, 1002.8044531316323, 19000.049997500348, 19267.325643602686, 19341.903290483457, 19352.70970935409, 19377.4186774397, 1020.3673722292226, 1045.4459136657583, 1055.1506037639774, 1057.6668349470046, 1060.3849767135043, 10640.148788096934, 10931.070858976916, 10983.286086404358, 11056.557737690564, 11091.778164436666, 467748.0, 474928.0, 482036.0, 484164.0, 501859.0, 112037.0, 112128.4393578027, 113416.0, 114150.0, 114550.0, 1151.9424028798526, 1168.5150662474978, 1176.8940795328463, 1178.9410529473491, 1179.9469023893946, 16730.4144354947, 17116.315347385964, 17175.65648687044, 17288.65422691514, 17397.21712522914, 53380.10012890891, 54216.18675719789, 55009.724951374985, 55795.72102139463, 56837.0, 67390.66304668445, 69194.65402672955, 69208.65395672989, 69289.65355173191, 70652.646736766, 18743.0, 19739.901300493406, 20003.699944501135, 20028.799712002692, 20422.387328380173, 22286.88856555707, 22600.09599615997, 22604.886975565016, 22711.659325109806, 22898.65652015188, 17886.731699024785, 18031.549211269823, 18092.185851636445, 18179.363722269656, 18239.726404104214, 15388.307526161574, 15737.763933540777, 16239.3504259833, 16452.259648315616, 16463.0, 18587.34944276944, 18645.81354186441, 18697.345592904185, 18717.157727902006, 18823.152958117193, 19372.4188274352, 19428.417147485598, 19506.512337191685, 19697.81813091162, 19700.0, 18017.549561261072, 18158.45524634263, 18221.088945552936, 18322.725159122358, 18449.446516604523], "value": 12816.55486208375, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.4339690536162, 1391.8816900563452, 1392.9164250144988, 1392.9233892135935, 1396.1762560089546, 14975.550733477985, 15481.69036619267, 15522.146281954501, 15525.689486210269, 15549.06705597665, 15010.249487525634, 15219.92390038051, 15250.618734531643, 15251.0, 15259.084454932712, 2711.688155862081, 2759.8344099354003, 2788.07993362191, 2791.2742686901347, 2797.930051748702, 2716.090109813205, 2732.0574401831336, 2740.1779466160074, 2756.682981457128, 2757.1039412191017, 2653.9601905971426, 2760.2961244882526, 2771.80597358185, 2778.263760103574, 2781.3463835998523, 2677.3440507075793, 2680.973190268092, 2711.8644067796727, 2715.389037466564, 2725.8364498130077, 2712.0236714782664, 2713.91858244253, 2715.9321016974536, 2727.754502094801, 2735.2341344423594, 14188.574342769733, 14197.574072777834, 14324.57026289213, 14442.133471991714, 14446.783298250535, 7607.581583012932, 7856.450048496607, 7869.055713314382, 7878.054633443966, 7887.921120788776, 5272.551833094191, 5367.221752845816, 5367.490088441592, 5454.045542030129, 5472.179173124063, 1930.449821800794, 1931.0923865783059, 1944.1834429539485, 1976.980230197694, 1978.0208796645752, 25007.249782506555, 25114.121005764893, 25176.748232517624, 25192.49615007708, 25250.116245931305, 6421.871562568723, 6488.545801793829, 6675.532712710161, 6678.09845670838, 6706.497012723968, 16484.752728709078, 16486.422975195812, 16519.6696066078, 16519.752203716955, 16545.09002004877, 7611.238876112345, 7800.765977020698, 7813.335866451302, 7818.061832580127, 7819.687212511436, 7077.893831592531, 7160.749373771893, 7184.892226616605, 7282.635868206568, 7288.3076107769675, 4836.322914791932, 4935.333729946483, 5001.149804533238, 5015.3480047593575, 5036.748162591855, 1367.9452821887014, 1395.4418232706796, 1414.3211258595932, 1429.0639631041684, 1438.8201474815648, 865.0830120072743, 875.6366108065209, 885.0397318909056, 887.454215657366, 894.1952242981239, 7689.308352162515, 7879.133295337509, 7899.802504937307, 7964.761057168294, 7987.3610111190965, 946.7017889364828, 952.7189479103681, 955.8422860228123, 956.2254573795248, 961.8990006049404, 4092.3835085141204, 4152.626263636287, 4174.645155161846, 4208.7685177314925, 4210.410542524049, 19589.412317630493, 19914.402567922985, 20484.0, 20518.38444846657, 20533.58932821335, 19388.4183474496, 19657.0, 19882.304119355755, 19951.0, 19964.3012494562, 9984.500774961223, 10129.442880641489, 10202.0, 10202.744931376776, 10203.438810865326, 7862.60686965638, 7891.0, 7891.763247102596, 7993.0, 8020.919790802017, 17540.386086486913, 17565.912170439067, 17866.374676886247, 17978.0, 18268.26926922908, 18230.17964191641, 18336.81663183351, 18576.0, 18587.90706046461, 18824.7176292353, 10497.0, 10548.367097974146, 10594.523246454082, 10646.414447205478, 10649.094826939798, 12585.496580136693, 13311.134776239513, 13329.333533323488, 13346.265955372548, 13365.0, 10809.13526917829, 10855.294405863593, 10857.565697372333, 10914.23600347968, 11034.613788517363, 10187.898121018992, 10192.082712555906, 10307.226957978031, 10404.47977601132, 10561.89438105609, 9662.855057174007, 9707.077827606367, 9772.31593788428, 9812.460314682761, 9830.459324737209, 6827.351401616838, 6909.1018167637885, 6918.0, 6955.443564514725, 7053.6825842838225, 7126.750563730245, 7204.747833825791, 7264.89102663471, 7327.120745510573, 7355.926440735524], "value": 6452.5386934381195, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.9023240468108, 503.56693243810315, 531.5720844719999, 532.2282690099356, 536.1796451429315, 6308.936910630898, 6396.488280937514, 6423.229212494495, 6430.228372595289, 6437.034444833277, 6181.814545563629, 6201.906971395432, 6248.468880145193, 6285.277193122793, 6305.842353941154, 4979.45226025137, 5117.413601783441, 5121.3342265505435, 5128.563932132226, 5144.202648589456, 5079.644424890259, 5146.974265128669, 5172.12073947428, 5182.170852663575, 5186.4813518648025, 5040.2439634055, 5041.621878359119, 5056.823011194609, 5091.439941606439, 5142.691438513682, 5007.92488112675, 5043.420006699222, 5047.4447810740785, 5103.974480127613, 5105.719185444799, 4588.49526552079, 4643.187442197635, 4655.837045703418, 4655.8593144679435, 4669.0, 6310.368963103653, 6327.430531252164, 6374.553781235315, 6391.872162556723, 6419.775307864203, 5762.827115186509, 5788.0, 5824.24284842972, 5859.53123750099, 5865.912011319833, 6712.932870671329, 6721.663916804189, 6744.629045402501, 6751.0, 6756.344695549573, 5000.874978125539, 5025.296458495814, 5030.849074527733, 5034.874128146789, 5051.848444546669, 6922.446204303644, 7002.474814388941, 7046.471514636422, 7051.612161331124, 7075.327843854826, 5094.465081166498, 5113.411957624847, 5121.28302037715, 5131.512506311894, 5134.743262836843, 5197.700574856286, 5197.7141257231215, 5210.713410762444, 5214.478552144755, 5224.660397074178, 5777.855553611109, 5787.2187254720075, 5792.218050563204, 5794.391588883106, 5809.128630705428, 6487.513436492282, 6511.446527045254, 6599.109120268702, 6632.50256230775, 6733.93266067333, 1955.1006536993143, 1957.4519134642323, 1959.6374670685968, 1985.2753744883303, 2001.4996250937265, 1444.812174417319, 1454.01206918617, 1459.8613131752466, 1470.962971105368, 1489.7765335199806, 5649.830505084854, 5736.196932429463, 5743.8851222976145, 5778.971105144447, 5803.274590676166, 6598.505112116611, 6709.5303328767495, 6828.0, 6849.315068493111, 6926.272741362185, 5631.662100273997, 5721.8569535761935, 5723.168586052507, 5832.445917637818, 5865.765369385262, 3504.5268888700207, 3611.115276757211, 3629.9092522686615, 3662.1210909381557, 3664.706823454117, 6519.2072180150835, 6569.9343006569325, 6591.627014274857, 6598.076269322299, 6672.366125218096, 6960.234374218832, 7054.4003759681, 7097.751578694824, 7136.964315178391, 7141.714331426685, 6508.299055891846, 6611.900821487681, 6621.9337806621315, 6624.304448032887, 6648.7340506379205, 6811.727530898708, 6858.0398744176855, 6872.965635171792, 6875.140607424072, 6956.782563051344, 6720.227173874871, 6761.222459417033, 6774.762883299061, 6862.897056544056, 6876.862462750617, 6398.712057957497, 6438.452731517874, 6501.772437964649, 6518.967405162944, 6527.281998980014, 4960.156773348467, 4994.900101997867, 4997.650164488597, 5020.874478138076, 5034.14419548685, 6745.571594226173, 6770.627615480998, 6791.456683465212, 6864.4165245954655, 6916.20463646687, 6510.772122975863, 6515.869682606416, 6578.210614726264, 6593.0, 6595.8021259362295, 6540.8364790880605, 6560.376764207393, 6576.210854697467, 6600.339966003361, 6654.966725166537, 6395.808125756234, 6449.258335291502, 6462.0, 6494.0, 6523.369157710572, 6342.0, 6619.536632435684, 6677.565958212701, 6681.76613818514, 6731.899021514583, 6484.254310754323, 6486.156799616208, 6489.967550162219, 6499.837504062436, 6535.0], "value": 5180.847256172252, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [905.8143080668464, 918.068160816771, 930.1628534319112, 930.7998780262246, 953.2516974175273, 19196.23215071397, 20257.189712411506, 20268.29060982866, 20269.594608107865, 20275.391738247858, 16395.4261600844, 16757.162141892888, 16837.494875153734, 18429.17068731907, 18498.167582458787, 943.7499062748377, 955.5795450002012, 958.4728399380348, 958.6548842416724, 958.7315551645515, 964.5948701545368, 981.4258658684643, 981.6318880419842, 981.6564202529129, 988.0, 1012.559536601577, 1014.0164040880335, 1015.3653966271081, 1016.7661437869294, 1023.9744006399825, 1014.9340292880976, 1015.0407864567986, 1015.4110615842793, 1018.5518371916407, 1019.6023550815188, 725.1841678112124, 745.9776206713807, 750.3921823323126, 751.2112282103769, 754.0122439604136, 20203.696944545696, 20277.594448111104, 20321.49196270105, 20327.881966491837, 20442.693359599616, 21076.78923210779, 21434.892825535928, 21775.020124094455, 21813.67279490809, 21894.452638684, 17017.659646806995, 17302.394416195497, 17487.737683934753, 17531.123443827757, 17576.0, 3647.3252448296885, 3678.797666128362, 3679.6136405677553, 3684.5762737285286, 3689.631036896316, 141025.294873526, 142264.57735422617, 142338.28830855884, 143203.0, 145779.0, 16948.067856268022, 17283.91358043227, 17358.132093395532, 17462.91268543649, 17514.912425437793, 30303.393932121675, 30405.0, 30528.694713052584, 30773.23066923345, 30839.845800770854, 7937.444378893422, 7978.760637180894, 7987.400944929153, 8040.4773689711155, 8041.758747237593, 3880.1657643606195, 3887.922241555153, 3949.9802500987885, 4008.599140086026, 4024.8993775155855, 5089.8727531812, 5175.301334319819, 5210.921836172461, 5220.817271395483, 5236.345456817898, 968.5593055159885, 985.930984831055, 986.7335819328741, 992.8262554053011, 992.9652462163934, 624.1137584629746, 642.4378668664918, 644.6228956060756, 656.4190691238193, 663.326723375774, 11132.0, 11686.70783230426, 11831.408429578487, 11961.46173422198, 12557.623271301876, 631.3497097989078, 636.0840389838618, 638.3233772201481, 639.46285120499, 655.2530115668142, 5549.18253548669, 5603.327600687862, 5619.690916999522, 5699.401562835843, 5749.965006298907, 123343.0, 123911.0, 124052.0, 124217.0, 126189.0, 110219.0, 111181.0, 112290.0, 114020.0, 115025.0, 92807.0, 94754.0524594759, 94773.0, 94784.52607736917, 97389.51305243428, 10161.898381016095, 10308.639197628047, 10333.793324133625, 10352.327098738559, 10368.22238332113, 43356.34965475583, 43525.78237108794, 44728.32907506325, 44734.328985064596, 44921.32618010667, 56477.71761141168, 57175.0, 58503.4149658498, 59002.70498647651, 59564.106538401094, 14162.291885405482, 14546.272686365428, 14590.0, 14664.046836955564, 14803.481878134213, 15473.84526154769, 15477.0, 15586.376544938528, 15642.921785391, 16091.436799711953, 14938.178400188093, 14978.925105374403, 15014.099154050791, 15231.619209519851, 15245.313960871566, 14905.10569365798, 14956.40174393012, 15035.323410446776, 15083.698326033636, 15265.3893844245, 15094.39622415091, 15373.3082011312, 15414.614634633775, 15544.0, 15553.144577047919, 10465.633702820365, 10800.567977281135, 10848.132149428184, 10867.565297388015, 11089.168312376443, 11810.468528916392, 11819.527218911147, 11958.18829252215, 12027.75944481123, 12114.697132571757], "value": 8746.454579977906, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1431.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 109.0, 110.0, 106.0, 106.0, 107.0, 107.0, 107.0, 107.0, 108.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 115.0, 126.0, 117.0, 116.0, 118.0, 121.0, 149.0, 119.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 162.0, 162.0, 162.0, 163.0, 165.0, 167.0, 166.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 80.0, 74.0, 74.0, 75.0, 74.0, 74.0, 76.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 79.0, 79.0, 79.0, 79.0, 77.0, 79.0, 77.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 115.0, 113.0, 114.0, 115.0, 116.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 149.0, 147.0, 149.0, 148.0, 147.0, 149.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 103.0, 93.0, 93.0, 94.0, 93.0, 93.0, 95.0, 94.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 54.0, 54.0, 52.0, 52.0, 54.0, 54.0, 54.0, 55.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 39.0, 38.0, 38.0, 38.0, 39.0, 39.0, 38.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 79.0, 74.0, 73.0, 75.0, 73.0, 79.0, 77.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 119.0, 114.0, 114.0, 117.0, 114.0, 111.0, 112.0, 117.0], "value": 114.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 175.0, 174.0, 180.0, 179.0, 182.0, 185.0, 171.0, 185.0, 177.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 71.0, 63.0, 65.0, 66.0, 69.0, 69.0, 68.0, 67.0, 68.0], "value": 68.1, "unit": "score"}]}]} 15:22:07 INFO - Return code: 0 15:22:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:22:07 INFO - # TBPL SUCCESS # 15:22:07 INFO - Running post-action listener: _package_coverage_data 15:22:07 INFO - Running post-action listener: _resource_record_post_action 15:22:07 INFO - [mozharness: 2017-09-19 22:22:07.836295Z] Finished run-tests step (success) 15:22:07 INFO - Running post-run listener: _resource_record_post_run 15:22:07 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 9121792; Write bytes: 453701632; Read time: 2364; Write time: 459200 15:22:07 INFO - TinderboxPrint: CPU usage
13.1% 15:22:07 INFO - TinderboxPrint: I/O read bytes / time
9,121,792 / 2,364 15:22:07 INFO - TinderboxPrint: I/O write bytes / time
453,701,632 / 459,200 15:22:07 INFO - TinderboxPrint: CPU idle
8,241.1 (86.9%) 15:22:07 INFO - TinderboxPrint: CPU user
1,193.2 (12.6%) 15:22:07 INFO - TinderboxPrint: Swap in / out
0 / 0 15:22:07 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 101015552; Read time: 244; Write time: 103260 15:22:07 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:22:07 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 7073792; Write bytes: 279793664; Read time: 1780; Write time: 230840 15:22:08 INFO - Running post-run listener: _upload_blobber_files 15:22:08 INFO - Blob upload gear active. 15:22:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:22:08 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 15:22:08 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'] 15:22:08 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 15:22:08 INFO - (blobuploader) - INFO - Open directory for files ... 15:22:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:22:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:22:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:09 INFO - (blobuploader) - INFO - Done attempting. 15:22:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:22:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:22:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:10 INFO - (blobuploader) - INFO - Done attempting. 15:22:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:22:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:22:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:12 INFO - (blobuploader) - INFO - Done attempting. 15:22:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:22:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:22:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:18 INFO - (blobuploader) - INFO - Done attempting. 15:22:18 INFO - (blobuploader) - INFO - Iteration through files over. 15:22:18 INFO - Return code: 0 15:22:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:22:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:22:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/577bf2b79274e7c5ef729cf06ae22eda0a8dafdf32d7dc672522d406b57ecc1790593e5f558534dc7fcfae6584fb92828f2855e8ff05a5b17c7097ff9497e3c0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6018e4e1cfe7745d44ba16fbcf20c5eec37b4572fee486596ade0395084bee8bfd05887a16d841ebe957e59d793efdcdd69e9763a04398f0191d50b29fc25f59", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7039d6ddf2aa374fdaed6c54b74de97da5506c4dfe135abcaafd474d62e3a5a32544440e663a8d032c9c0512cf1eacee11a1491ec8ef398c17cd303488c8626", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c6c67a25c51a615bbff90be26664b85e6ad0e4b243425868d78f9016edbb846a16fa90c607f6c3585d817f4b564295641e7da8d7151f0aaaf78766dbc6fcc44"} 15:22:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:22:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:22:18 INFO - Contents: 15:22:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/577bf2b79274e7c5ef729cf06ae22eda0a8dafdf32d7dc672522d406b57ecc1790593e5f558534dc7fcfae6584fb92828f2855e8ff05a5b17c7097ff9497e3c0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6018e4e1cfe7745d44ba16fbcf20c5eec37b4572fee486596ade0395084bee8bfd05887a16d841ebe957e59d793efdcdd69e9763a04398f0191d50b29fc25f59", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7039d6ddf2aa374fdaed6c54b74de97da5506c4dfe135abcaafd474d62e3a5a32544440e663a8d032c9c0512cf1eacee11a1491ec8ef398c17cd303488c8626", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c6c67a25c51a615bbff90be26664b85e6ad0e4b243425868d78f9016edbb846a16fa90c607f6c3585d817f4b564295641e7da8d7151f0aaaf78766dbc6fcc44"} 15:22:18 INFO - Running post-run listener: copy_logs_to_upload_dir 15:22:18 INFO - Copying logs to upload dir... 15:22:18 INFO - mkdir: /builds/slave/test/build/upload/logs 15:22:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.072515 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-19 15:22:18.707845) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-19 15:22:18.711564) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/577bf2b79274e7c5ef729cf06ae22eda0a8dafdf32d7dc672522d406b57ecc1790593e5f558534dc7fcfae6584fb92828f2855e8ff05a5b17c7097ff9497e3c0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6018e4e1cfe7745d44ba16fbcf20c5eec37b4572fee486596ade0395084bee8bfd05887a16d841ebe957e59d793efdcdd69e9763a04398f0191d50b29fc25f59", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7039d6ddf2aa374fdaed6c54b74de97da5506c4dfe135abcaafd474d62e3a5a32544440e663a8d032c9c0512cf1eacee11a1491ec8ef398c17cd303488c8626", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c6c67a25c51a615bbff90be26664b85e6ad0e4b243425868d78f9016edbb846a16fa90c607f6c3585d817f4b564295641e7da8d7151f0aaaf78766dbc6fcc44"} build_url:https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/AyerD6BRQGKrCIFu0VWbgg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/577bf2b79274e7c5ef729cf06ae22eda0a8dafdf32d7dc672522d406b57ecc1790593e5f558534dc7fcfae6584fb92828f2855e8ff05a5b17c7097ff9497e3c0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6018e4e1cfe7745d44ba16fbcf20c5eec37b4572fee486596ade0395084bee8bfd05887a16d841ebe957e59d793efdcdd69e9763a04398f0191d50b29fc25f59", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7039d6ddf2aa374fdaed6c54b74de97da5506c4dfe135abcaafd474d62e3a5a32544440e663a8d032c9c0512cf1eacee11a1491ec8ef398c17cd303488c8626", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5c6c67a25c51a615bbff90be26664b85e6ad0e4b243425868d78f9016edbb846a16fa90c607f6c3585d817f4b564295641e7da8d7151f0aaaf78766dbc6fcc44"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-19 15:22:18.740025) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:22:18.740377) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1258088b6da462a47ba2cd90000001a2-1505857375.756285-1486995833 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:22:18.826689) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-19 15:22:18.827061) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-19 15:22:18.827465) ========= ========= Total master_lag: 0.21 =========