builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-015 starttime: 1507071963.54 results: success (0) revision: c97190c389c4cfef20fe55b4bacade95a36ae6ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:03.545160) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:03.545607) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:03.545896) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:03.641853) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:03.642369) ========= 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=20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 16:06:03-- 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% 225M=0s 2017-10-03 16:06:03 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.072811 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:03.743945) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:03.744224) ========= 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=20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021813 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:03.796474) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-10-03 16:06:03.796810) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c97190c389c4cfef20fe55b4bacade95a36ae6ef --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 c97190c389c4cfef20fe55b4bacade95a36ae6ef --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=20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 16:06:03,856 truncating revision to first 12 chars 2017-10-03 16:06:03,856 Setting DEBUG logging. 2017-10-03 16:06:03,856 attempt 1/10 2017-10-03 16:06:03,856 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c97190c389c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 16:06:05,053 unpacking tar archive at: mozilla-central-c97190c389c4/testing/mozharness/ program finished with exit code 0 elapsedTime=3.760040 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-10-03 16:06:07.573536) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:07.573873) ========= script_repo_revision: c97190c389c4cfef20fe55b4bacade95a36ae6ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:07.574262) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:07.574528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 16:06:07.588332) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-10-03 16:06:07.588688) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:06:07 INFO - MultiFileLogger online at 20171003 16:06:07 in /builds/slave/test 16:06:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:06:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:06:07 INFO - {'append_to_log': False, 16:06:07 INFO - 'base_work_dir': '/builds/slave/test', 16:06:07 INFO - 'blob_upload_branch': 'Firefox', 16:06:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:06:07 INFO - 'branch': 'Firefox', 16:06:07 INFO - 'buildbot_json_path': 'buildprops.json', 16:06:07 INFO - 'code_coverage': False, 16:06:07 INFO - 'config_files': ('talos/linux_config.py',), 16:06:07 INFO - 'default_actions': ('clobber', 16:06:07 INFO - 'read-buildbot-config', 16:06:07 INFO - 'download-and-extract', 16:06:07 INFO - 'populate-webroot', 16:06:07 INFO - 'create-virtualenv', 16:06:07 INFO - 'install', 16:06:07 INFO - 'setup-mitmproxy', 16:06:07 INFO - 'run-tests'), 16:06:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:06:07 INFO - 'disable_ccov_upload': False, 16:06:07 INFO - 'disable_stylo': False, 16:06:07 INFO - 'download_minidump_stackwalk': True, 16:06:07 INFO - 'download_symbols': 'ondemand', 16:06:07 INFO - 'enable_stylo': False, 16:06:07 INFO - 'enable_webrender': False, 16:06:07 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:06:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:06:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:06:07 INFO - 'gecko_profile': False, 16:06:07 INFO - 'gecko_profile_interval': 0, 16:06:07 INFO - 'installer_path': 'installer.exe', 16:06:07 INFO - 'log_level': 'info', 16:06:07 INFO - 'log_name': 'talos', 16:06:07 INFO - 'log_to_console': True, 16:06:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:06:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:06:07 INFO - 'opt_config_files': (), 16:06:07 INFO - 'pip_index': False, 16:06:07 INFO - 'suite': 'dromaeojs-e10s', 16:06:07 INFO - 'system_bits': '32', 16:06:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:06:07 INFO - 'title': 'talos-linux64-ix-015', 16:06:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:06:07 INFO - 'use_talos_json': True, 16:06:07 INFO - 'verify': 'False', 16:06:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:06:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:06:07 INFO - 'work_dir': 'build'} 16:06:07 INFO - [mozharness: 2017-10-03 23:06:07.793740Z] Running clobber step. 16:06:07 INFO - Running pre-action listener: _resource_record_pre_action 16:06:07 INFO - Running main action method: clobber 16:06:07 INFO - rmtree: /builds/slave/test/build 16:06:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:06:09 INFO - Running post-action listener: _resource_record_post_action 16:06:09 INFO - [mozharness: 2017-10-03 23:06:09.397802Z] Finished clobber step (success) 16:06:09 INFO - [mozharness: 2017-10-03 23:06:09.397898Z] Running read-buildbot-config step. 16:06:09 INFO - Running pre-action listener: _resource_record_pre_action 16:06:09 INFO - Running main action method: read_buildbot_config 16:06:09 INFO - Using buildbot properties: 16:06:09 INFO - { 16:06:09 INFO - "project": "", 16:06:09 INFO - "product": "firefox", 16:06:09 INFO - "who": "kwierso@gmail.com", 16:06:09 INFO - "installer_path": "public/build/target.tar.bz2", 16:06:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:06:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:06:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:06:09 INFO - "stage_platform": "linux64-pgo", 16:06:09 INFO - "basedir": "/builds/slave/test", 16:06:09 INFO - "buildnumber": 13, 16:06:09 INFO - "platform": "ubuntu64_hw", 16:06:09 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:06:09 INFO - "slavebuilddir": "test", 16:06:09 INFO - "taskId": "CiStkHiFS_OyOCPoZ16Ogg", 16:06:09 INFO - "branch": "mozilla-central", 16:06:09 INFO - "script_repo_revision": "production", 16:06:09 INFO - "revision": "c97190c389c4cfef20fe55b4bacade95a36ae6ef", 16:06:09 INFO - "slavename": "talos-linux64-ix-015", 16:06:09 INFO - "repo_path": "mozilla-central" 16:06:09 INFO - } 16:06:09 INFO - Finding installer, test and symbols from parent task. 16:06:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CiStkHiFS_OyOCPoZ16Ogg'}, attempt #1 16:06:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CiStkHiFS_OyOCPoZ16Ogg'}, attempt #1 16:06:10 INFO - Task dependencies: Ffxf4E-mT86Y4EN0dsL5BA 16:06:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA'}, attempt #1 16:06:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.tar.bz2 16:06:10 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.test_packages.json 16:06:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.crashreporter-symbols.zip 16:06:10 INFO - Running post-action listener: _resource_record_post_action 16:06:10 INFO - [mozharness: 2017-10-03 23:06:10.793497Z] Finished read-buildbot-config step (success) 16:06:10 INFO - [mozharness: 2017-10-03 23:06:10.793717Z] Running download-and-extract step. 16:06:10 INFO - Running pre-action listener: _resource_record_pre_action 16:06:10 INFO - Running main action method: download_and_extract 16:06:10 INFO - mkdir: /builds/slave/test/build/tests 16:06:10 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')]} 16:06:10 INFO - trying https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.test_packages.json 16:06:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:06:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:06:11 INFO - Downloaded 989 bytes. 16:06:11 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:06:11 INFO - Using the following test package requirements: 16:06:11 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:06:11 INFO - u'common': [u'target.common.tests.zip'], 16:06:11 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:06:11 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:06:11 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:06:11 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:06:11 INFO - u'mozbase': [u'target.common.tests.zip'], 16:06:11 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:06:11 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:06:11 INFO - u'web-platform': [u'target.common.tests.zip', 16:06:11 INFO - u'target.web-platform.tests.tar.gz'], 16:06:11 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:06:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:06:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.common.tests.zip 16:06:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:06:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.common.tests.zip into memory 16:06:13 INFO - Content-Length response header: 55878624 16:06:13 INFO - Bytes received: 55878624 16:06:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.talos.tests.zip 16:06:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:06:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.talos.tests.zip into memory 16:06:25 INFO - Content-Length response header: 14533844 16:06:25 INFO - Bytes received: 14533844 16:06:25 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')]} 16:06:25 INFO - trying https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.tar.bz2 16:06:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:06:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:06:27 INFO - Downloaded 62422288 bytes. 16:06:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.tar.bz2 16:06:27 INFO - mkdir: /builds/slave/test/properties 16:06:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:06:27 INFO - Writing to file /builds/slave/test/properties/build_url 16:06:27 INFO - Contents: 16:06:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.tar.bz2 16:06:27 INFO - Running post-action listener: _resource_record_post_action 16:06:27 INFO - Running post-action listener: find_tests_for_verification 16:06:27 INFO - Running post-action listener: set_extra_try_arguments 16:06:27 INFO - [mozharness: 2017-10-03 23:06:27.535457Z] Finished download-and-extract step (success) 16:06:27 INFO - [mozharness: 2017-10-03 23:06:27.535557Z] Running populate-webroot step. 16:06:27 INFO - Running pre-action listener: _resource_record_pre_action 16:06:27 INFO - Running main action method: populate_webroot 16:06:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:06:27 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['tresize', 16:06:27 INFO - 'tcanvasmark']}, 16:06:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:06:27 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['dromaeo_css', 16:06:27 INFO - 'kraken']}, 16:06:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:06:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:06:27 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:06:27 INFO - 'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:06:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:06:27 INFO - 'tests': ['damp', 'tps']}, 16:06:27 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:06:27 INFO - 'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['damp', 'tps']}, 16:06:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:06:27 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['dromaeo_dom']}, 16:06:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:06:27 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['basic_compositor_video', 16:06:27 INFO - 'glvideo']}, 16:06:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:06:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:06:27 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:06:27 INFO - 'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['ts_paint_webext', 16:06:27 INFO - 'tp5o_webext']}, 16:06:27 INFO - 'other-e10s': {'tests': ['a11yr', 16:06:27 INFO - 'ts_paint', 16:06:27 INFO - 'tpaint', 16:06:27 INFO - 'sessionrestore', 16:06:27 INFO - 'sessionrestore_many_windows', 16:06:27 INFO - 'sessionrestore_no_auto_restore', 16:06:27 INFO - 'tabpaint', 16:06:27 INFO - 'cpstartup']}, 16:06:27 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['a11yr', 16:06:27 INFO - 'ts_paint', 16:06:27 INFO - 'tpaint', 16:06:27 INFO - 'sessionrestore', 16:06:27 INFO - 'sessionrestore_many_windows', 16:06:27 INFO - 'sessionrestore_no_auto_restore', 16:06:27 INFO - 'tabpaint', 16:06:27 INFO - 'cpstartup']}, 16:06:27 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 16:06:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:06:27 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['perf_reftest_singletons']}, 16:06:27 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['perf_reftest']}, 16:06:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:06:27 INFO - 'tsvgr_opacity', 16:06:27 INFO - 'tart', 16:06:27 INFO - 'tscrollx', 16:06:27 INFO - 'tsvg_static']}, 16:06:27 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['tsvgx', 16:06:27 INFO - 'tsvgr_opacity', 16:06:27 INFO - 'tart', 16:06:27 INFO - 'tscrollx', 16:06:27 INFO - 'tsvg_static']}, 16:06:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:06:27 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:06:27 INFO - 'talos_options': ['--disable-stylo'], 16:06:27 INFO - 'tests': ['tp5o']}, 16:06:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:06:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:06:27 INFO - 'talos_options': ['--mitmproxy', 16:06:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:06:27 INFO - '--firstNonBlankPaint'], 16:06:27 INFO - 'tests': ['tp6_google', 16:06:27 INFO - 'tp6_youtube', 16:06:27 INFO - 'tp6_amazon', 16:06:27 INFO - 'tp6_facebook']}, 16:06:27 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:06:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:06:27 INFO - 'talos_options': ['--disable-stylo', 16:06:27 INFO - '--mitmproxy', 16:06:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:06:27 INFO - '--firstNonBlankPaint'], 16:06:27 INFO - 'tests': ['tp6_google', 16:06:27 INFO - 'tp6_youtube', 16:06:27 INFO - 'tp6_amazon', 16:06:27 INFO - 'tp6_facebook']}, 16:06:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:06:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:06:27 INFO - 'talos_options': ['--stylo-threads=1', 16:06:27 INFO - '--mitmproxy', 16:06:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:06:27 INFO - '--firstNonBlankPaint'], 16:06:27 INFO - 'tests': ['tp6_google', 16:06:27 INFO - 'tp6_youtube', 16:06:27 INFO - 'tp6_amazon', 16:06:27 INFO - 'tp6_facebook']}, 16:06:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:06:27 INFO - 'talos_options': ['--xperf_path', 16:06:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:06:27 INFO - 'tests': ['tp5n']}, 16:06:27 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:06:27 INFO - 'talos_options': ['--disable-stylo', 16:06:27 INFO - '--xperf_path', 16:06:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:06:27 INFO - 'tests': ['tp5n']}}} 16:06:27 INFO - Running post-action listener: _resource_record_post_action 16:06:27 INFO - [mozharness: 2017-10-03 23:06:27.562702Z] Finished populate-webroot step (success) 16:06:27 INFO - [mozharness: 2017-10-03 23:06:27.562850Z] Running create-virtualenv step. 16:06:27 INFO - Running pre-action listener: _resource_record_pre_action 16:06:27 INFO - Running main action method: create_virtualenv 16:06:27 INFO - Creating virtualenv /builds/slave/test/build/venv 16:06:27 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:06:27 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:06:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:06:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:06:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:06:28 INFO - Installing distribute.............................................................................................................................................................................................done. 16:06:28 INFO - Installing pip...............done. 16:06:28 INFO - Return code: 0 16:06:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:06:28 INFO - Reading from file tmpfile_stdout 16:06:28 INFO - Output received: 16:06:28 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:06:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:06:28 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')]} 16:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:28 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')]} 16:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:28 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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:28 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 16:06:28 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 16:06:28 INFO - Using env: {'DISPLAY': ':0', 16:06:28 INFO - 'HOME': '/home/cltbld', 16:06:28 INFO - 'LANG': 'en_US.UTF-8', 16:06:28 INFO - 'LANGUAGE': 'en_US:en', 16:06:28 INFO - 'LOGNAME': 'cltbld', 16:06:28 INFO - 'MAIL': '/var/mail/cltbld', 16:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:28 INFO - 'MOZ_NO_REMOTE': '1', 16:06:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:28 INFO - 'NO_EM_RESTART': '1', 16:06:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:28 INFO - 'PWD': '/builds/slave/test', 16:06:28 INFO - 'SHELL': '/bin/bash', 16:06:28 INFO - 'SHLVL': '1', 16:06:28 INFO - 'TERM': 'linux', 16:06:28 INFO - 'TMOUT': '86400', 16:06:28 INFO - 'USER': 'cltbld', 16:06:28 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:28 INFO - '_': '/tools/buildbot/bin/python'} 16:06:29 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:06:29 INFO - Downloading/unpacking pip>=1.5 16:06:29 INFO - Running setup.py egg_info for package pip 16:06:29 INFO - warning: no files found matching 'pip/cacert.pem' 16:06:29 INFO - warning: no files found matching '*.html' under directory 'docs' 16:06:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:06:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:06:29 INFO - Installing collected packages: pip 16:06:29 INFO - Found existing installation: pip 1.1 16:06:29 INFO - Uninstalling pip: 16:06:29 INFO - Successfully uninstalled pip 16:06:29 INFO - Running setup.py install for pip 16:06:29 INFO - warning: no files found matching 'pip/cacert.pem' 16:06:29 INFO - warning: no files found matching '*.html' under directory 'docs' 16:06:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:06:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:06:29 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:06:29 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:06:29 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:06:29 INFO - Successfully installed pip 16:06:29 INFO - Cleaning up... 16:06:29 INFO - Return code: 0 16:06:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:29 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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:29 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 16:06:29 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 16:06:29 INFO - Using env: {'DISPLAY': ':0', 16:06:29 INFO - 'HOME': '/home/cltbld', 16:06:29 INFO - 'LANG': 'en_US.UTF-8', 16:06:29 INFO - 'LANGUAGE': 'en_US:en', 16:06:29 INFO - 'LOGNAME': 'cltbld', 16:06:29 INFO - 'MAIL': '/var/mail/cltbld', 16:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:29 INFO - 'MOZ_NO_REMOTE': '1', 16:06:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:29 INFO - 'NO_EM_RESTART': '1', 16:06:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:29 INFO - 'PWD': '/builds/slave/test', 16:06:29 INFO - 'SHELL': '/bin/bash', 16:06:29 INFO - 'SHLVL': '1', 16:06:29 INFO - 'TERM': 'linux', 16:06:29 INFO - 'TMOUT': '86400', 16:06:29 INFO - 'USER': 'cltbld', 16:06:29 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:29 INFO - '_': '/tools/buildbot/bin/python'} 16:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:30 INFO - Downloading/unpacking psutil>=3.1.1 16:06:30 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 16:06:30 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 16:06:30 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 16:06:30 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 16:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:06:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:06:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:06:31 INFO - Installing collected packages: psutil 16:06:31 INFO - Running setup.py install for psutil 16:06:31 INFO - building 'psutil._psutil_linux' extension 16:06:31 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 16:06:31 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 16:06:31 INFO - building 'psutil._psutil_posix' extension 16:06:31 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 16:06:31 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 16:06:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:06:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:06:31 INFO - Successfully installed psutil 16:06:31 INFO - Cleaning up... 16:06:31 INFO - Return code: 0 16:06:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:31 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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:31 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 16:06:31 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 16:06:31 INFO - Using env: {'DISPLAY': ':0', 16:06:31 INFO - 'HOME': '/home/cltbld', 16:06:31 INFO - 'LANG': 'en_US.UTF-8', 16:06:31 INFO - 'LANGUAGE': 'en_US:en', 16:06:31 INFO - 'LOGNAME': 'cltbld', 16:06:31 INFO - 'MAIL': '/var/mail/cltbld', 16:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:31 INFO - 'MOZ_NO_REMOTE': '1', 16:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:31 INFO - 'NO_EM_RESTART': '1', 16:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:31 INFO - 'PWD': '/builds/slave/test', 16:06:31 INFO - 'SHELL': '/bin/bash', 16:06:31 INFO - 'SHLVL': '1', 16:06:31 INFO - 'TERM': 'linux', 16:06:31 INFO - 'TMOUT': '86400', 16:06:31 INFO - 'USER': 'cltbld', 16:06:31 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:31 INFO - '_': '/tools/buildbot/bin/python'} 16:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:06:32 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 16:06:32 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 16:06:32 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 16:06:32 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 16:06:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:06:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:06:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:06:33 INFO - Installing collected packages: mozsystemmonitor 16:06:33 INFO - Running setup.py install for mozsystemmonitor 16:06:33 INFO - Successfully installed mozsystemmonitor 16:06:33 INFO - Cleaning up... 16:06:34 INFO - Return code: 0 16:06:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:06:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:34 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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:34 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 16:06:34 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 16:06:34 INFO - Using env: {'DISPLAY': ':0', 16:06:34 INFO - 'HOME': '/home/cltbld', 16:06:34 INFO - 'LANG': 'en_US.UTF-8', 16:06:34 INFO - 'LANGUAGE': 'en_US:en', 16:06:34 INFO - 'LOGNAME': 'cltbld', 16:06:34 INFO - 'MAIL': '/var/mail/cltbld', 16:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:34 INFO - 'MOZ_NO_REMOTE': '1', 16:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:34 INFO - 'NO_EM_RESTART': '1', 16:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:34 INFO - 'PWD': '/builds/slave/test', 16:06:34 INFO - 'SHELL': '/bin/bash', 16:06:34 INFO - 'SHLVL': '1', 16:06:34 INFO - 'TERM': 'linux', 16:06:34 INFO - 'TMOUT': '86400', 16:06:34 INFO - 'USER': 'cltbld', 16:06:34 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:34 INFO - '_': '/tools/buildbot/bin/python'} 16:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:35 INFO - Downloading/unpacking jsonschema==2.5.1 16:06:35 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 16:06:35 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 16:06:35 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 16:06:35 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 16:06:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:06:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:06:36 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 16:06:36 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 16:06:36 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 16:06:36 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 16:06:36 INFO - Downloading functools32-3.2.3-2.tar.gz 16:06:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:06:36 INFO - warning: no files found matching '*.txt' 16:06:36 INFO - no previously-included directories found matching 'build' 16:06:36 INFO - no previously-included directories found matching 'dist' 16:06:36 INFO - no previously-included directories found matching '.git*' 16:06:37 INFO - Installing collected packages: jsonschema, functools32 16:06:37 INFO - Running setup.py install for functools32 16:06:37 INFO - warning: no files found matching '*.txt' 16:06:37 INFO - no previously-included directories found matching 'build' 16:06:37 INFO - no previously-included directories found matching 'dist' 16:06:37 INFO - no previously-included directories found matching '.git*' 16:06:37 INFO - Successfully installed jsonschema functools32 16:06:37 INFO - Cleaning up... 16:06:37 INFO - Return code: 0 16:06:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:06:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:06:37 INFO - Using env: {'DISPLAY': ':0', 16:06:37 INFO - 'HOME': '/home/cltbld', 16:06:37 INFO - 'LANG': 'en_US.UTF-8', 16:06:37 INFO - 'LANGUAGE': 'en_US:en', 16:06:37 INFO - 'LOGNAME': 'cltbld', 16:06:37 INFO - 'MAIL': '/var/mail/cltbld', 16:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:37 INFO - 'MOZ_NO_REMOTE': '1', 16:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:37 INFO - 'NO_EM_RESTART': '1', 16:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:37 INFO - 'PWD': '/builds/slave/test', 16:06:37 INFO - 'SHELL': '/bin/bash', 16:06:37 INFO - 'SHLVL': '1', 16:06:37 INFO - 'TERM': 'linux', 16:06:37 INFO - 'TMOUT': '86400', 16:06:37 INFO - 'USER': 'cltbld', 16:06:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:37 INFO - '_': '/tools/buildbot/bin/python'} 16:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:06:37 INFO - Cleaning up... 16:06:37 INFO - Return code: 0 16:06:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:06:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:06:37 INFO - Using env: {'DISPLAY': ':0', 16:06:37 INFO - 'HOME': '/home/cltbld', 16:06:37 INFO - 'LANG': 'en_US.UTF-8', 16:06:37 INFO - 'LANGUAGE': 'en_US:en', 16:06:37 INFO - 'LOGNAME': 'cltbld', 16:06:37 INFO - 'MAIL': '/var/mail/cltbld', 16:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:37 INFO - 'MOZ_NO_REMOTE': '1', 16:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:37 INFO - 'NO_EM_RESTART': '1', 16:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:37 INFO - 'PWD': '/builds/slave/test', 16:06:37 INFO - 'SHELL': '/bin/bash', 16:06:37 INFO - 'SHLVL': '1', 16:06:37 INFO - 'TERM': 'linux', 16:06:37 INFO - 'TMOUT': '86400', 16:06:37 INFO - 'USER': 'cltbld', 16:06:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:37 INFO - '_': '/tools/buildbot/bin/python'} 16:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:37 INFO - Downloading/unpacking blobuploader==1.2.4 16:06:37 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 16:06:37 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 16:06:37 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 16:06:37 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 16:06:38 INFO - Downloading blobuploader-1.2.4.tar.gz 16:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:06:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:06:38 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 16:06:38 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 16:06:38 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 16:06:38 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 16:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:06:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:39 INFO - Downloading docopt-0.6.1.tar.gz 16:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:06:39 INFO - Installing collected packages: blobuploader, requests, docopt 16:06:39 INFO - Running setup.py install for blobuploader 16:06:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:06:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:06:39 INFO - Running setup.py install for requests 16:06:39 INFO - Running setup.py install for docopt 16:06:39 INFO - Successfully installed blobuploader requests docopt 16:06:39 INFO - Cleaning up... 16:06:39 INFO - Return code: 0 16:06:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:39 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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:39 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 16:06:39 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 16:06:39 INFO - Using env: {'DISPLAY': ':0', 16:06:39 INFO - 'HOME': '/home/cltbld', 16:06:39 INFO - 'LANG': 'en_US.UTF-8', 16:06:39 INFO - 'LANGUAGE': 'en_US:en', 16:06:39 INFO - 'LOGNAME': 'cltbld', 16:06:39 INFO - 'MAIL': '/var/mail/cltbld', 16:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:39 INFO - 'MOZ_NO_REMOTE': '1', 16:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:39 INFO - 'NO_EM_RESTART': '1', 16:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:39 INFO - 'PWD': '/builds/slave/test', 16:06:39 INFO - 'SHELL': '/bin/bash', 16:06:39 INFO - 'SHLVL': '1', 16:06:39 INFO - 'TERM': 'linux', 16:06:39 INFO - 'TMOUT': '86400', 16:06:39 INFO - 'USER': 'cltbld', 16:06:39 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:39 INFO - '_': '/tools/buildbot/bin/python'} 16:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:06:40 INFO - Running setup.py (path:/tmp/pip-c0NU2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:06:40 INFO - Running setup.py (path:/tmp/pip-BLekt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:06:40 INFO - Running setup.py (path:/tmp/pip-3yxK3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:06:40 INFO - Running setup.py (path:/tmp/pip-gw51VR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:06:40 INFO - Running setup.py (path:/tmp/pip-tK9oTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:06:40 INFO - Running setup.py (path:/tmp/pip-lrgJPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:06:40 INFO - Running setup.py (path:/tmp/pip-t8F_0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:06:40 INFO - Running setup.py (path:/tmp/pip-V3NUfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:06:40 INFO - Running setup.py (path:/tmp/pip-ojgvth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:06:40 INFO - Running setup.py (path:/tmp/pip-zfkU3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:06:40 INFO - Running setup.py (path:/tmp/pip-Oh2gn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:06:40 INFO - Running setup.py (path:/tmp/pip-pf4yoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:06:40 INFO - Running setup.py (path:/tmp/pip-ExEVdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:06:40 INFO - Running setup.py (path:/tmp/pip-QcAduL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:41 INFO - Running setup.py (path:/tmp/pip-uOtQny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:06:41 INFO - Running setup.py (path:/tmp/pip-fYOooc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:06:41 INFO - Running setup.py (path:/tmp/pip-gBHOij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:06:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:06:41 INFO - Running setup.py install for manifestparser 16:06:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:06:41 INFO - Running setup.py install for mozcrash 16:06:41 INFO - Running setup.py install for mozdebug 16:06:41 INFO - Running setup.py install for mozdevice 16:06:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:06:41 INFO - Running setup.py install for mozfile 16:06:41 INFO - Running setup.py install for mozhttpd 16:06:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:06:41 INFO - Running setup.py install for mozinfo 16:06:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:06:41 INFO - Running setup.py install for mozInstall 16:06:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Running setup.py install for mozleak 16:06:42 INFO - Running setup.py install for mozlog 16:06:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Running setup.py install for moznetwork 16:06:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Running setup.py install for mozprocess 16:06:42 INFO - Running setup.py install for mozprofile 16:06:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Running setup.py install for mozrunner 16:06:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:06:42 INFO - Running setup.py install for mozscreenshot 16:06:42 INFO - Running setup.py install for moztest 16:06:42 INFO - Running setup.py install for mozversion 16:06:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:06:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:06:43 INFO - Cleaning up... 16:06:43 INFO - Return code: 0 16:06:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:43 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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:43 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 16:06:43 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 16:06:43 INFO - Using env: {'DISPLAY': ':0', 16:06:43 INFO - 'HOME': '/home/cltbld', 16:06:43 INFO - 'LANG': 'en_US.UTF-8', 16:06:43 INFO - 'LANGUAGE': 'en_US:en', 16:06:43 INFO - 'LOGNAME': 'cltbld', 16:06:43 INFO - 'MAIL': '/var/mail/cltbld', 16:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:43 INFO - 'MOZ_NO_REMOTE': '1', 16:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:43 INFO - 'NO_EM_RESTART': '1', 16:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:43 INFO - 'PWD': '/builds/slave/test', 16:06:43 INFO - 'SHELL': '/bin/bash', 16:06:43 INFO - 'SHLVL': '1', 16:06:43 INFO - 'TERM': 'linux', 16:06:43 INFO - 'TMOUT': '86400', 16:06:43 INFO - 'USER': 'cltbld', 16:06:43 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:43 INFO - '_': '/tools/buildbot/bin/python'} 16:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:06:43 INFO - Running setup.py (path:/tmp/pip-ZZzSty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:06:43 INFO - Running setup.py (path:/tmp/pip-uDNt4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:06:43 INFO - Running setup.py (path:/tmp/pip-Ca8p2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:06:43 INFO - Running setup.py (path:/tmp/pip-zV5ktH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:06:43 INFO - Running setup.py (path:/tmp/pip-obCCEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:06:43 INFO - Running setup.py (path:/tmp/pip-Dzccv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:06:43 INFO - Running setup.py (path:/tmp/pip-jEYFWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:06:43 INFO - Running setup.py (path:/tmp/pip-S49MGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:06:43 INFO - Running setup.py (path:/tmp/pip-A3HsGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:06:43 INFO - Running setup.py (path:/tmp/pip-pyuD_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:06:43 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)) 16:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:06:43 INFO - Running setup.py (path:/tmp/pip-b6DJC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:06:44 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)) 16:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:06:44 INFO - Running setup.py (path:/tmp/pip-RSN94f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:06:44 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)) 16:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:06:44 INFO - Running setup.py (path:/tmp/pip-4XiBxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:06:44 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)) 16:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:06:44 INFO - Running setup.py (path:/tmp/pip-R_n7RR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:06:44 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)) 16:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:44 INFO - Running setup.py (path:/tmp/pip-gh8Rod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:06:44 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)) 16:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:06:44 INFO - Running setup.py (path:/tmp/pip-t7hczn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:06:44 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)) 16:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:06:44 INFO - Running setup.py (path:/tmp/pip-xFB5W2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:06:44 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)) 16:06:44 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)) 16:06:44 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)) 16:06:44 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)) 16:06:44 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)) 16:06:44 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)) 16:06:44 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)) 16:06:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:45 INFO - Downloading blessings-1.6.tar.gz 16:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:06:45 INFO - Installing collected packages: blessings 16:06:45 INFO - Running setup.py install for blessings 16:06:45 INFO - Successfully installed blessings 16:06:45 INFO - Cleaning up... 16:06:45 INFO - Return code: 0 16:06:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:06:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:06:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:06:46 INFO - Reading from file tmpfile_stdout 16:06:46 INFO - Current package versions: 16:06:46 INFO - argparse == 1.2.1 16:06:46 INFO - blessings == 1.6 16:06:46 INFO - blobuploader == 1.2.4 16:06:46 INFO - docopt == 0.6.1 16:06:46 INFO - functools32 == 3.2.3-2 16:06:46 INFO - jsonschema == 2.5.1 16:06:46 INFO - manifestparser == 1.1 16:06:46 INFO - mozInstall == 1.13 16:06:46 INFO - mozcrash == 1.0 16:06:46 INFO - mozdebug == 0.1 16:06:46 INFO - mozdevice == 0.51 16:06:46 INFO - mozfile == 1.2 16:06:46 INFO - mozhttpd == 0.7 16:06:46 INFO - mozinfo == 0.10 16:06:46 INFO - mozleak == 0.1 16:06:46 INFO - mozlog == 3.5 16:06:46 INFO - moznetwork == 0.27 16:06:46 INFO - mozprocess == 0.25 16:06:46 INFO - mozprofile == 0.28 16:06:46 INFO - mozrunner == 6.13 16:06:46 INFO - mozscreenshot == 0.1 16:06:46 INFO - mozsystemmonitor == 0.3 16:06:46 INFO - moztest == 0.8 16:06:46 INFO - mozversion == 1.4 16:06:46 INFO - psutil == 3.1.1 16:06:46 INFO - requests == 1.2.3 16:06:46 INFO - wsgiref == 0.1.2 16:06:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:46 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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:46 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 16:06:46 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 16:06:46 INFO - Using env: {'DISPLAY': ':0', 16:06:46 INFO - 'HOME': '/home/cltbld', 16:06:46 INFO - 'LANG': 'en_US.UTF-8', 16:06:46 INFO - 'LANGUAGE': 'en_US:en', 16:06:46 INFO - 'LOGNAME': 'cltbld', 16:06:46 INFO - 'MAIL': '/var/mail/cltbld', 16:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:46 INFO - 'MOZ_NO_REMOTE': '1', 16:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:46 INFO - 'NO_EM_RESTART': '1', 16:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:46 INFO - 'PWD': '/builds/slave/test', 16:06:46 INFO - 'SHELL': '/bin/bash', 16:06:46 INFO - 'SHLVL': '1', 16:06:46 INFO - 'TERM': 'linux', 16:06:46 INFO - 'TMOUT': '86400', 16:06:46 INFO - 'USER': 'cltbld', 16:06:46 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:46 INFO - '_': '/tools/buildbot/bin/python'} 16:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:46 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)) 16:06:46 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)) 16:06:46 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)) 16:06:46 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)) 16:06:46 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)) 16:06:46 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)) 16:06:46 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)) 16:06:46 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)) 16:06:46 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)) 16:06:46 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:06:47 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)) 16:06:47 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)) 16:06:47 INFO - Installing collected packages: simplejson 16:06:47 INFO - Running setup.py install for simplejson 16:06:47 INFO - building 'simplejson._speedups' extension 16:06:47 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 16:06:48 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 16:06:48 INFO - Successfully installed simplejson 16:06:48 INFO - Cleaning up... 16:06:48 INFO - Return code: 0 16:06:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:06:48 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')]} 16:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:48 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')]} 16:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:48 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 0xe281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11959c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, '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': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', '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 16:06:48 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 16:06:48 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 16:06:48 INFO - Using env: {'DISPLAY': ':0', 16:06:48 INFO - 'HOME': '/home/cltbld', 16:06:48 INFO - 'LANG': 'en_US.UTF-8', 16:06:48 INFO - 'LANGUAGE': 'en_US:en', 16:06:48 INFO - 'LOGNAME': 'cltbld', 16:06:48 INFO - 'MAIL': '/var/mail/cltbld', 16:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:48 INFO - 'MOZ_NO_REMOTE': '1', 16:06:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:48 INFO - 'NO_EM_RESTART': '1', 16:06:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:48 INFO - 'PWD': '/builds/slave/test', 16:06:48 INFO - 'SHELL': '/bin/bash', 16:06:48 INFO - 'SHLVL': '1', 16:06:48 INFO - 'TERM': 'linux', 16:06:48 INFO - 'TMOUT': '86400', 16:06:48 INFO - 'USER': 'cltbld', 16:06:48 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:48 INFO - '_': '/tools/buildbot/bin/python'} 16:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:06:48 INFO - Cleaning up... 16:06:48 INFO - Return code: 0 16:06:48 INFO - Running post-action listener: _resource_record_post_action 16:06:48 INFO - Running post-action listener: _start_resource_monitoring 16:06:48 INFO - Starting resource monitoring. 16:06:48 INFO - [mozharness: 2017-10-03 23:06:48.755530Z] Finished create-virtualenv step (success) 16:06:48 INFO - [mozharness: 2017-10-03 23:06:48.756043Z] Running install step. 16:06:48 INFO - Running pre-action listener: _resource_record_pre_action 16:06:48 INFO - Running main action method: install 16:06:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:06:48 INFO - Reading from file tmpfile_stdout 16:06:48 INFO - Detecting whether we're running mozinstall >=1.0... 16:06:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:06:49 INFO - Reading from file tmpfile_stdout 16:06:49 INFO - Output received: 16:06:49 INFO - Usage: mozinstall [options] installer 16:06:49 INFO - Options: 16:06:49 INFO - -h, --help show this help message and exit 16:06:49 INFO - -d DEST, --destination=DEST 16:06:49 INFO - Directory to install application into. [default: 16:06:49 INFO - "/builds/slave/test"] 16:06:49 INFO - --app=APP Application being installed. [default: firefox] 16:06:49 INFO - mkdir: /builds/slave/test/build/application 16:06:49 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'] 16:06:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:07:06 INFO - Reading from file tmpfile_stdout 16:07:06 INFO - Output received: 16:07:06 INFO - /builds/slave/test/build/application/firefox/firefox 16:07:06 INFO - Running post-action listener: _resource_record_post_action 16:07:06 INFO - [mozharness: 2017-10-03 23:07:06.684744Z] Finished install step (success) 16:07:06 INFO - [mozharness: 2017-10-03 23:07:06.684890Z] Running setup-mitmproxy step. 16:07:06 INFO - Running pre-action listener: _resource_record_pre_action 16:07:06 INFO - Running main action method: setup_mitmproxy 16:07:06 INFO - Skipping: mitmproxy is not required 16:07:06 INFO - Running post-action listener: _resource_record_post_action 16:07:06 INFO - [mozharness: 2017-10-03 23:07:06.685767Z] Finished setup-mitmproxy step (success) 16:07:06 INFO - [mozharness: 2017-10-03 23:07:06.685988Z] Running run-tests step. 16:07:06 INFO - Running pre-action listener: _resource_record_pre_action 16:07:06 INFO - Running pre-action listener: _set_gcov_prefix 16:07:06 INFO - Running main action method: run_tests 16:07:06 WARNING - Try message not found. 16:07:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:07:06 INFO - Python 2.7.3 16:07:06 INFO - Return code: 0 16:07:06 INFO - grabbing minidump binary from tooltool 16:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:06 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 0x1192d60>, '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 0x1193a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1193ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:07:06 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 16:07:06 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 16:07:06 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 16:07:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:07:06 INFO - Return code: 0 16:07:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:07:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:07:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:07:06 INFO - ENV: RUST_BACKTRACE is now full 16:07:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:07:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:07:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 16:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 16:07:06 INFO - Using env: {'DISPLAY': ':0', 16:07:06 INFO - 'HOME': '/home/cltbld', 16:07:06 INFO - 'LANG': 'en_US.UTF-8', 16:07:06 INFO - 'LANGUAGE': 'en_US:en', 16:07:06 INFO - 'LOGNAME': 'cltbld', 16:07:06 INFO - 'MAIL': '/var/mail/cltbld', 16:07:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:07:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:06 INFO - 'MOZ_NO_REMOTE': '1', 16:07:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:07:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:06 INFO - 'NO_EM_RESTART': '1', 16:07:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:06 INFO - 'PWD': '/builds/slave/test', 16:07:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:07:06 INFO - 'RUST_BACKTRACE': 'full', 16:07:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:07:06 INFO - 'SHELL': '/bin/bash', 16:07:06 INFO - 'SHLVL': '1', 16:07:06 INFO - 'TERM': 'linux', 16:07:06 INFO - 'TMOUT': '86400', 16:07:06 INFO - 'USER': 'cltbld', 16:07:06 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:06 INFO - '_': '/tools/buildbot/bin/python'} 16:07:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 16:07:07 INFO - mozversion application_buildid: 20171003213749 16:07:07 INFO - mozversion application_changeset: c97190c389c4cfef20fe55b4bacade95a36ae6ef 16:07:07 INFO - mozversion application_display_name: Nightly 16:07:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:07:07 INFO - mozversion application_name: Firefox 16:07:07 INFO - mozversion application_remotingname: firefox 16:07:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:07:07 INFO - mozversion application_vendor: Mozilla 16:07:07 INFO - mozversion application_version: 58.0a1 16:07:07 INFO - mozversion platform_buildid: 20171003213749 16:07:07 INFO - mozversion platform_changeset: c97190c389c4cfef20fe55b4bacade95a36ae6ef 16:07:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:07:07 INFO - mozversion platform_version: 58.0a1 16:07:07 INFO - using testdate: 1507072027 16:07:07 INFO - actual date: 1507072027 16:07:07 INFO - starting webserver on 'localhost:50464' 16:07:07 INFO - SUITE-START | Running 2 tests 16:07:07 INFO - TEST-START | dromaeo_css 16:07:07 INFO - Initialising browser for dromaeo_css test... 16:07:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50464/getInfo.html -profile /tmp/tmp0PgUZ3/profile 16:07:07 INFO - TEST-INFO | started process 23131 (/builds/slave/test/build/application/firefox/firefox http://localhost:50464/getInfo.html) 16:07:12 INFO - TEST-INFO | 23131: exit 0 16:07:12 INFO - Browser initialized. 16:07:12 INFO - Running cycle 1/1 for dromaeo_css test... 16:07:12 INFO - Using env: {'DISPLAY': ':0', 16:07:12 INFO - 'HOME': '/home/cltbld', 16:07:12 INFO - 'JSGC_DISABLE_POISONING': '1', 16:07:12 INFO - 'LANG': 'en_US.UTF-8', 16:07:12 INFO - 'LANGUAGE': 'en_US:en', 16:07:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:07:12 INFO - 'LOGNAME': 'cltbld', 16:07:12 INFO - 'MAIL': '/var/mail/cltbld', 16:07:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:07:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:07:12 INFO - 'MOZ_CRASHREPORTER': '1', 16:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:07:12 INFO - 'MOZ_NO_REMOTE': '1', 16:07:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:07:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:12 INFO - 'NO_EM_RESTART': '1', 16:07:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:12 INFO - 'PWD': '/builds/slave/test', 16:07:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:07:12 INFO - 'RUST_BACKTRACE': 'full', 16:07:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:07:12 INFO - 'SHELL': '/bin/bash', 16:07:12 INFO - 'SHLVL': '1', 16:07:12 INFO - 'TERM': 'linux', 16:07:12 INFO - 'TMOUT': '86400', 16:07:12 INFO - 'USER': 'cltbld', 16:07:12 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:12 INFO - '_': '/tools/buildbot/bin/python'} 16:07:12 INFO - TEST-INFO | started process 23409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0PgUZ3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:13 INFO - PID 23409 | 16:07:13 INFO - PID 23409 | (/builds/slave/test/build/application/firefox/firefox:23458): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:07:13 INFO - PID 23409 | 16:07:23 INFO - PID 23409 | RSS: Main: 146403328 16:07:23 INFO - PID 23409 | 16:10:21 INFO - PID 23409 | Cycle 1(1): loaded http://localhost:50464/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50464/tests/dromaeo/cssquery-ext.html) 16:10:22 INFO - PID 23409 | RSS: Main: 143679488 16:10:22 INFO - PID 23409 | 16:13:24 INFO - PID 23409 | Cycle 1(1): loaded http://localhost:50464/tests/dromaeo/cssquery-ext.html (next: http://localhost:50464/tests/dromaeo/cssquery-jquery.html) 16:13:24 INFO - PID 23409 | RSS: Main: 142934016 16:13:24 INFO - PID 23409 | 16:16:26 INFO - PID 23409 | Cycle 1(1): loaded http://localhost:50464/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50464/tests/dromaeo/cssquery-mootools.html) 16:16:26 INFO - PID 23409 | RSS: Main: 145526784 16:16:26 INFO - PID 23409 | 16:19:28 INFO - PID 23409 | Cycle 1(1): loaded http://localhost:50464/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50464/tests/dromaeo/cssquery-prototype.html) 16:19:29 INFO - PID 23409 | RSS: Main: 145281024 16:19:29 INFO - PID 23409 | 16:22:32 INFO - PID 23409 | Cycle 1(1): loaded http://localhost:50464/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50464/tests/dromaeo/cssquery-yui.html) 16:22:32 INFO - PID 23409 | RSS: Main: 149372928 16:22:32 INFO - PID 23409 | 16:25:34 INFO - PID 23409 | Cycle 1(1): loaded http://localhost:50464/tests/dromaeo/cssquery-yui.html (next: http://localhost:50464/tests/dromaeo/cssquery-dojo.html) 16:25:34 INFO - PID 23409 | RSS: Main: 150069248 16:25:34 INFO - PID 23409 | 16:25:34 INFO - PID 23409 | __start_tp_report 16:25:34 INFO - PID 23409 | _x_x_mozilla_page_load 16:25:34 INFO - PID 23409 | _x_x_mozilla_page_load_details 16:25:34 INFO - PID 23409 | |i|pagename|runs| 16:25:34 INFO - PID 23409 | |0;dojo.html;3857.2285542891427;3882.9417558736614;3900.1029763154493;3910.0811309342316;3912.8826135215936;6315.115883776263;6324.2727086385075;6380.074889141072;6392.808215753524;6459.386358295966;6400.551961362707;6421.967890160543;6505.381988711077;6516.283208847023;6524.0540121682325;5678.318601767773;5711.428857114276;5797.217375654296;5810.680412577317;5838.854028649275;5804.796832110877;5828.2131912191735;5866.120081987693;5886.323072846634;5909.556783241253;5619.943800561983;5711.428857114276;5778.913316300238;5793.884122317572;5822.767089316443;5631.9718401408145;5657.066584013633;5661.433856614347;5662.433756624346;5677.914831277534;5625.353084395265;5649.12438572022;5656.519195868356;5660.179274005287;5678.290213723285;6312.305646378894;6342.016987366966;6350.87298254037;6360.491160707133;6382.489400847968;6843.8973415398805;6883.242843287235;6930.099087118692;6936.79189624307;6958.547694399847;6760.932390676079;6880.2431732509385;6890.1731792184755;6949.166100068024;7036.050133232051;6370.936290637081;6374.203224596926;6383.616982981036;6413.749318882819;6451.6774161292215;7030;7047.8238044048285;7125.465590080765;7135.750248741269;7156.427485801123;6604.1414616100565;6800.217974932847;6826.658667066627;6844.110265665531;6922.307769223037;6906.758263460855;6949.200841903245;6957.756478523227;6972.093627828452;6982.68577913985;6910.170779506491;6914.827129321807;6925.272846351161;6985.5808651481075;6995.510314277952;6853.897191542131;6913.308669133147;7003.299670032956;7014.19336776267;7025.051618031603;4992.051510213048;5027.195648696191;5031.949680503221;5032.018756342552;5078.4667609900425;4582.335561343653;4583.977080114578;4620.468646105741;4630.513796051433;4713.481517033192;6365.554411191172;6370.617762934146;6409.711562979677;6460.709268082947;6461.321561236096;6922.134733158356;6934.4452443804375;6940.271271516418;7031.8593628127155;7033.718651253995;6161.383861613893;6319.526035547352;6322.968385158137;6411.647359395185;6454.580452270681;5569.888602228014;5642.322921249476;5645.745941432645;5659.858503537363;5663;7030;7075.964620176866;7136.928630713626;7152.284771522806;7214.989901413806;6917.550359226634;6970.093887794555;6993.860122797617;6995.825104372329;7002.404795592228;7171.282871712787;7193.100862392201;7250.89123663156;7268.491205615767;7280.526765760209;6815.659217039029;6822;6912.066870972354;6925.411340035952;6926.792196234121;6897.172339319314;6951.478639101986;7003.19463261711;7011.228764835762;7016.7544135954995;6559.573627714183;6625.072489851328;6713.093732346266;6751.122354093936;6752.696128674123;6568.934310656832;6640.136782218281;6664.633445160369;6681.866362672622;6721.966390168018;6648.767293144717;6688.398044175853;6709.194896612437;6710.261871194067;6715.764948226789;6719.865602688016;6779.830504237433;6788.1175447191545;6889.965550172217;6895.34494223048;6481.027845823164;6529.902051469327;6530.281669016499;6590.439812615981;6633 16:25:34 INFO - PID 23409 | |1;ext.html;948.2793077261282;979.7984811634483;979.8481235408512;987.4124895687069;998.9850152247712;46179;46840.29739553909;46872;46962;46987.76506117464;43095.569044309545;43272.567274327244;43372.349414758726;43565.7821710891;43765;7854.489458185229;7864.292213700765;7934.365250779923;7952.880706789403;7974.521528708267;7846.529208247495;7901.525908445512;7926.048874135112;7930.6431210595665;7930.841383172333;8374.539400332978;8647.69733059343;8667.69663061793;8676.219140277373;8676.479411235347;8581.485110893334;8615.569221538897;8622.396432249745;8657.913420865774;8661.177188167145;8353.415260931737;8561.95719021407;8576.957115214447;8590.484570925764;8600.741977740678;41106;41716.37425438621;41761;41765.37351939723;41789;3690.8892733218045;3740.5511338639267;3753.174301653632;3756.849726010956;3758.5113935188524;33887.49168762471;34010.659893400996;34102.658973410194;34121.48817767711;34149;5417.295751552312;5434.239206511052;5448.4279150689;5465.207544905967;5506.366767821672;215537.92231038903;223465;223740;224618;225030;47974.28038579424;57445.71277143587;58692.413075868695;58699;58717;60682;60788.39211607916;60924;61163.38836611577;61406;16254.593635159215;16344.264508097163;16352.427665031904;16371.345146194257;16385.180740962904;13322.533711320057;13365.064445488722;13380.866191338157;13442.395092220873;13526.256055916821;16191.352345906032;16201.4329498467;16221.432249871197;16235.918820405823;16413;6140.263168419819;6186.350433204539;6189.319174890758;6285.371462853769;6296.087067375296;1090.820014697566;1094.5020015892749;1101.3557069114497;1101.8787933327328;1126.2791813239533;25887.482250355264;26044;26327.473450531266;26464.60303095455;26483;1195.3425615911387;1199.8380218670543;1219.091776626419;1231.1135982092878;1238.0590751029283;13351.399187036606;13837.792433113511;13848.86151138496;13849.515266965607;13937.372818223204;283936;285784;287317;287621;288885;60412.697936510915;61038;61321.38678613246;61325.853595123954;61331;1497.453429498247;1518.2105305241435;1519.270750039987;1544.8455154484461;1571.0259639023877;25877.611835822103;25908;25959.22122336333;26009.869950650125;26118;42511.57488425076;42638.36042459428;42672.57327426686;42674.35988460114;42749.35875961801;245216;245305;245784;246119;246241;35061.47407788834;35699.64300357067;38378;38994.61005390023;39335.213295734495;47459.05081898274;48572.514274858215;48817.511824881294;49096.509034910625;49162;35169.64830351664;35218.64781352256;35444.645553544135;36212.818935905154;36257.81871090628;35358.29283414266;35432.46851297284;35491;35501.289974199855;36008.63991360053;41693.58306417018;41761.79119104385;41804.790976044926;42097.78951105225;42319.57680423156;33534.32931341311;33669.66330336665;33778.98663040113;34140.31719365648;34653.653463465045;25518.489630207663;25666;25858.22425327243;26242;26452.8677356612 16:25:34 INFO - PID 23409 | |2;jquery.html;620.9968950155248;633.2053273142205;634.5336177909236;641.9582727122739;642.6272761798156;21351.57296854066;22159;23647.645285320712;24475.510489790217;24674.75325246747;26070.348241293977;26395.340116497096;26396.076137335196;26459.470810583778;26664;6716.496262780287;6828.69270882809;6909.654517274142;6913.688884000207;6922.861542769142;6332.588381755171;6582.144321238256;6593.637349945751;6609.4051535361805;6616.702248398834;6572.145621069254;6575.4082132608055;6575.704093315789;6595.868082638344;6599.835004124887;6485.77299794505;6566.1464009678675;6584.341565843401;6730.461563074942;6769.695363708645;6270.592411493239;6294.559380843343;6335.5248356372995;6343.1436756038265;6345.079963405326;30687.539686904714;31391.37217255665;31462.685373146203;31484;31487.212819679462;2164.3398763377163;2184.7050648162453;2187.1360812478983;2187.814035806958;2187.9562408751735;19619;20890.164393424246;20927.162913483444;21031.789682103135;21042.89478552613;2561.282840804559;2571.305747448197;2629.5924131759607;2630.1977896741487;2634.078072674568;133037;139203.30398347945;154978;159986;161597.99999999884;15943.20283985796;16140.75788863168;16373.508794736177;16545.50363489097;16927.238274277686;27120.864395677894;27150.864245678644;27155.321116971838;27213.183604491896;27296.317592060357;10075.798484030278;10183.847242291371;10198.592056317813;10226.846597301046;10300.330478518872;9010.684626038057;9011.909880901107;9147.954260228656;9191.356605037583;9199.034101419255;9679.983601721719;9886.555105020292;10038.899611003942;10068.087063457919;10093.444860532594;2760.158151763691;2804.5933339665635;2984.418038482475;3008.1422648484026;3012.126483319813;1014.7513859104419;1016.481594386868;1035.539185062655;1037.6938803053117;1053.0417320238546;20512.79487205139;21069.262575809775;21191.258305959218;21313.467163321042;21586;1087.4399684162663;1095.3537412926412;1104.4643347976225;1113.2930589075834;1116.6370929447962;11445.427728613702;11570.305781653127;11700.35648039349;11749.823752643717;11756;622326;633305;634405;636368;644741;129559;131115;134520;135010;135207;1121.0807138146643;1147.9942600286945;1218.530865616744;1232.8458942632171;1293.4050336845157;22476.550468990856;22752;22760.20339288183;23007.309780706604;23236.535269294858;65666.67166664136;66016.6699166501;67518.66240668765;68302;68642.3135768636;85988.57005715181;86920;86985.56507217676;88072.5596372014;88609;22180;22208.555828883007;22402;22471.887640562345;22478;24231.394215144763;24607.630885537088;24766.38084047913;24932.62601061022;24966.37584060413;19806;19968.600627987067;20075.69886451675;20126.899365503665;20139.093740781405;17862.642747144724;18115.275388984293;18349.54126146857;18476.076196190406;18499.167537460577;19855.702164467835;20541.89729051405;20563.69154462714;20792.168313267906;20951.790482094984;21923.01346439382;21964.670529942385;22275.888620556794;22397.888010559844;22566.774332256467;19129.23483060662;19215.90392048031;19296.61406771885;19366.12852421616;19403.223871045 16:25:34 INFO - PID 23409 | |3;mootools.html;1381.2127087560089;1466.494059549455;1481.2667729473912;1487.8140232470948;1494.0662086196123;16984.06587637681;17831.821681783178;17837.286508539677;17841.553961150978;17842.37551685691;17110.973341599514;17355.566110847234;17361.91319043403;17386.304547818072;17434.82565174348;2584.082650659017;2711.8372897626205;2711.8915243390243;2712.755851973322;2713.186044186736;2583.8708064596694;2690.744379283965;2700.351915540266;2703.0809524761576;2704.418550011747;2292.5529521743265;2362.5038741864228;2626;2715.117586784293;2749.3539018330675;2630.040035387089;2678.866056697167;2687.516247075526;2689.247010836959;2690.2870739254113;2634.4335967767024;2666.4400475900084;2681.8793154308105;2682.383051898055;2684.0203325785947;17057.744133838;17150.742738858928;17155.485335439957;17246.137693115295;17255.827441725673;8291.378146638966;8515.233628973423;8531.634938409825;8536.957315213383;8550.52972086535;5811.6803575803315;5901.822945311647;6052.727627256784;6093.908591371133;6128.3258841527395;2134.6658338538414;2207.602631526325;2235.2065016919087;2259.581977334182;2262.2195342606774;32064.51903221477;32114;32124.518132228037;32181.83909080463;32182.517262241086;7416.7774966751085;7627.313541781266;7634.198409167068;7764.844703105906;7841.7255396062;24359.14742983987;24360.26919192427;24364.756352436605;24372.146974855794;24376.14683486105;8845.380823342304;8983.146601072887;8995.730128096167;9008.59461324242;9019.098090190928;7941.642626081724;7998.920010799818;8091.716789912442;8139.593020348959;8143.95928020368;5869.589128761031;6066.059760737093;6107.4197951194565;6147.754089836446;6215.844603884939;1731.212298404223;1749.7025505664067;1903.5050886769263;1934.1683076277336;2001.6897380905982;896.5024411451707;899.3030401438937;928.5682157796657;929.4794914847707;937.5312343828086;8747.781305467413;8772.561371931379;8779.561021948877;8789.252913502423;8853.778655533662;1078.1805827571102;1079.83262594298;1089.4824958144818;1106.391484683421;1114.676743744305;5077.213031980049;5163.715995620202;5280.049591073567;5288.286081378965;5307.2569840222395;24643;25208.74791252101;25962.922966162663;25980.610290845652;26058.34854128662;25237.86429610672;25269.494610107697;25326.366840829127;25651.615225771628;25706.48587028249;12143.210691305037;12851.421686023965;13055.738885222243;13162.670933226746;13222.206667599974;9499.95250023773;9558.856617150888;9695.22438204956;9706.951465242628;9734.805303894023;21394.893025534773;21872.23447179341;22001.559968800855;22141.1143554256;22301.776982230618;22299;22505.212317568807;22661.2068577599;22700.205492807672;22706.772932270465;12295.631131066082;12543.12198146121;12545.121841471375;12545.87454125447;12559.058070644556;14611.92694036523;14744.55766327012;14747.2626368679;14942.402303907722;14953.925230373778;11772.82340764872;12068.115862673207;12334;12465.815747504335;12598.118131730691;12181.33002684861;12213.938930305292;12308.26150430977;12556.686082848002;12719.046071544486;11433.371164586028;11507.482163302839;11608.6517404478;11617.186796924472;11649.59226427071;8235.95882020586;8277.793055173668;8435.072142064491;8452.366072544706;8484.278836298983;8225.177482251966;8229.95885020571;8261.008678958564;8290.295324897452;8317.126701696478 16:25:34 INFO - PID 23409 | |4;prototype.html;536.5680627095187;552.9861753456162;561.955043596512;584.3718003146618;591.7632946821271;6066.272047354324;6165.996051283334;6225.470834979026;6280.748770049198;6390.584612000228;6315;6346.476845557057;6352.555321127523;6377.298497165308;6433.453156481704;5212.4266330703595;5223.8432847014465;5254.133068043769;5270.631055826094;5275.36695596528;4961.826336078238;5104.285400043997;5105.336306280197;5223.425423203445;5243.449437809032;5095.719735414569;5107;5108.157154069574;5111.258867464216;5124.205748109049;5061.063703214917;5093.617978651616;5096.108181068316;5096.210087436453;5110.412302585213;4581.473130589991;4627.120847039052;4635.374224479686;4646.4888862225125;4659.813607455698;6228.564000519966;6261.436470717657;6274.968625156891;6278.183836101323;6306.936930630681;5997.640141591475;6014.51883849291;6016.548758843104;6034.155218269446;6045.821064892348;6654.833629159261;6682.031105489726;6683.2648408675;6703.966480167616;6724.630145342005;5096.9490305097215;5120.92318615221;5154.582489815293;5174.249733788617;5188.273641690166;7082.893756593655;7124.073870396867;7148.821279468003;7172.533785303938;7190.96404517974;5269.78920843162;5342.9465705342445;5348.732563371894;5382.1119515280325;5392.272043274108;5437.32033495813;5442.700651464128;5445.156000819879;5448.83653490396;5458.1266997280245;6001.699915004319;6017.127516510169;6019.909701354483;6022.969885150546;6036.6679832609625;6710.932890671128;6744.258131605519;6763.492738044666;6856.588604683635;6878.793636190822;1754.8245175482605;1822.4077174918204;1888.310766570192;1956.2370675436591;1980.9801901981084;1522.6421790879135;1527.6791873706425;1531.3032570180542;1572.512521118457;1580.3046659469796;6035.245594300713;6095.603785753912;6149.477294430024;6244.1570387997945;6256.499480041586;6640.236372817091;6861.656917154122;6890.552114112566;6897.41371983387;6938.271481494471;6333.461655759312;6333.619982801047;6374.458171055512;6381.425671689571;6401.199850018747;3592.2995015972156;3620.87326943561;3631.2737452509605;3635.2547727715755;3640.9089772755365;7082.362587367162;7089.89365159523;7173.3185347392955;7204.423646108299;7206;7096.467764917652;7107.502474826816;7119.857602848017;7127.037849890302;7165.462590305748;7005.404540614105;7098.219195888551;7170.139583250043;7198.352148306572;7201.531900426456;6622.602643841385;6734.8653026940165;6782.525223234326;6831.180258369028;6885;6681.498887583353;6775.62734049632;6782.762603308861;6801.659917004031;6842.954885076093;6967.631005458168;7000.859982800417;7028.332308430691;7100.147982242164;7104.644767761694;5320.52115309624;5328.600354973315;5349.866253343697;5361.705106219196;5376.892462150813;6630.53586248958;6778.271950427344;6855.5201135922;6886.48351373639;6938.757143499954;6469.676516174079;6531.982830559267;6542.803715888531;6569.737210511526;6614.206295244601;6477.676116194078;6523.739050437929;6578.638174900427;6671.39957403836;6690.531662783753;6359.523035772243;6407.423331900151;6496.577722448026;6528.412442880163;6529.51028672861;6550.967245163743;6599;6641.468682505485;6679.599224046573;6713.932860671526;6482.773102941374;6544.8363790905605;6557.770478033246;6558.967205163944;6595.8021259362295 16:25:34 INFO - PID 23409 | |5;yui.html;947.1428357336613;971.4851128901682;985.3053597213969;986.3785814936589;996.8155891160134;21175.576488470222;21743.45641358967;21813.67279490807;21832.563348733016;21834.454138646543;19751;20204.98975051249;20321.187152513918;20513.692294615554;20525;1051.3901936876593;1070.855434516342;1071.6302875507938;1071.8552995345644;1074.7474343529263;1050.5430137890005;1077.9245452818307;1085.967420977368;1087.7770057138307;1095.4194277033184;1150.3098141115347;1159.7448561316496;1160.0603511155948;1161.790877642024;1163.9068874490063;1137.243732917609;1151.1193936638479;1151.41277948247;1151.5969410706227;1151.9827202591969;850.056437354536;850.285759961629;855.8117214212863;855.9272461840751;857.2199298638209;19997.400077997685;20526.07632656519;20669.069891854902;20789.792102078936;22744.54510909789;22709.318720438412;23304.65043024339;23604.409889752722;23631.291061268188;23777.167799126946;18513.907430462896;18796.342128025455;18900.149493272696;18939.147738351807;18949.43151705451;3877.0501227199516;3923.489946306962;3923.4899463069905;3924.843006279745;3927.136030073379;179136;181715;185959.07020464947;186195;187622;17691.203895824718;17962.461126166236;17981.730274045898;18065.819341806415;18361.908190458962;33534.66465335364;33727;33757;33780.83109584436;33946.98159055232;9282.443053416817;9340.859887101698;9418.293627977791;9422.246220302359;9457.621695132255;4630.374899388539;4631.3516107744945;4654.511276316006;4710.599599034121;4727.17274476972;6343.333949935282;6497;6545.8363540910905;6556.377144171296;6596.208454985433;1090.378484263962;1130.1467392118898;1136.4147464056018;1139.9088072954144;1144.2162118948547;685.2736099734298;708.5536112249353;710.928907109285;717.4968441115853;726.1649103530982;12937.223766574036;13160.089285268385;13557.661058473419;14013.369398377097;14256.35846386915;684.6850448793512;686.5102231406545;697.396751809681;698.5703792167882;700.7897630710767;6294.559380843297;6303.968480157661;6309.116723658692;6311.652859092794;6323.241211054703;149395.2530237342;150579.24710376377;156493;159042;162539;145376.27311863584;149209.25395372952;150529.24735376253;151075;152525;120391.79608203981;120741;122085.38957305334;122659;123597;11916.16586838913;12025.278483291031;12111.152219344562;12182;12318.507259709511;59204.40795592161;59603.701981489816;60280.39719602748;61033;61368.69315653393;72086;73508.63245683737;73882.26117738754;74153.6292318535;74789;15545.766813498032;15552.30014649321;15847.366105355657;15877.603059923595;15902.125383104041;15537.689246214786;15947.920260398623;15981.920090399473;16035.518934431986;16175.43385981485;15918.60203494922;15969.840301596836;16004.599885002968;16136.838631613533;16219.837801622305;13933.442662293395;14001.859981400463;14094.859051409354;14129.576112716635;14235.430582776988;17163.828361716223;17231.05229212405;17288.394906178222;17342.566435839206;17456.563585910455;11200.943995279971;11361.318320900773;11524.250923690268;11680;11717;12240.326782027074;12298.262104273772;12433.12968092225;12667.176633518791;12676.112672112864 16:25:34 INFO - PID 23409 | __end_tp_report 16:25:34 INFO - PID 23409 | __start_cc_report 16:25:34 INFO - PID 23409 | _x_x_mozilla_cycle_collect,270 16:25:34 INFO - PID 23409 | __end_cc_report 16:25:34 INFO - PID 23409 | __startTimestamp1507073134621__endTimestamp 16:25:34 INFO - PID 23409 | 16:25:34 INFO - PID 23409 | ------- Summary: start ------- 16:25:34 INFO - PID 23409 | Number of tests: 6 16:25:34 INFO - PID 23409 | 16:25:34 INFO - PID 23409 | [#0] dojo.html Cycles:165 Average:6374.15 Median:6625.07 stddev:764.05 (11.5%) stddev-sans-first:740.44 16:25:34 INFO - PID 23409 | Values: 3857.2 3882.9 3900.1 3910.1 3912.9 6315.1 6324.3 6380.1 6392.8 6459.4 6400.6 6422.0 6505.4 6516.3 6524.1 5678.3 5711.4 5797.2 5810.7 5838.9 5804.8 5828.2 5866.1 5886.3 5909.6 5619.9 5711.4 5778.9 5793.9 5822.8 5632.0 5657.1 5661.4 5662.4 5677.9 5625.4 5649.1 5656.5 5660.2 5678.3 6312.3 6342.0 6350.9 6360.5 6382.5 6843.9 6883.2 6930.1 6936.8 6958.5 6760.9 6880.2 6890.2 6949.2 7036.1 6370.9 6374.2 6383.6 6413.7 6451.7 7030.0 7047.8 7125.5 7135.8 7156.4 6604.1 6800.2 6826.7 6844.1 6922.3 6906.8 6949.2 6957.8 6972.1 6982.7 6910.2 6914.8 6925.3 6985.6 6995.5 6853.9 6913.3 7003.3 7014.2 7025.1 4992.1 5027.2 5031.9 5032.0 5078.5 4582.3 4584.0 4620.5 4630.5 4713.5 6365.6 6370.6 6409.7 6460.7 6461.3 6922.1 6934.4 6940.3 7031.9 7033.7 6161.4 6319.5 6323.0 6411.6 6454.6 5569.9 5642.3 5645.7 5659.9 5663.0 7030.0 7076.0 7136.9 7152.3 7215.0 6917.6 6970.1 6993.9 6995.8 7002.4 7171.3 7193.1 7250.9 7268.5 7280.5 6815.7 6822.0 6912.1 6925.4 6926.8 6897.2 6951.5 7003.2 7011.2 7016.8 6559.6 6625.1 6713.1 6751.1 6752.7 6568.9 6640.1 6664.6 6681.9 6722.0 6648.8 6688.4 6709.2 6710.3 6715.8 6719.9 6779.8 6788.1 6890.0 6895.3 6481.0 6529.9 6530.3 6590.4 6633.0 16:25:34 INFO - PID 23409 | 16:25:34 INFO - PID 23409 | [#1] ext.html Cycles:180 Average:43744.76 Median:3757.68 stddev:65847.72 (1752.4%) stddev-sans-first:65954.03 16:25:34 INFO - PID 23409 | Values: 948.3 979.8 979.8 987.4 999.0 46179.0 46840.3 46872.0 46962.0 46987.8 43095.6 43272.6 43372.3 43565.8 43765.0 7854.5 7864.3 7934.4 7952.9 7974.5 7846.5 7901.5 7926.0 7930.6 7930.8 8374.5 8647.7 8667.7 8676.2 8676.5 8581.5 8615.6 8622.4 8657.9 8661.2 8353.4 8562.0 8577.0 8590.5 8600.7 41106.0 41716.4 41761.0 41765.4 41789.0 3690.9 3740.6 3753.2 3756.8 3758.5 33887.5 34010.7 34102.7 34121.5 34149.0 5417.3 5434.2 5448.4 5465.2 5506.4 215537.9 223465.0 223740.0 224618.0 225030.0 47974.3 57445.7 58692.4 58699.0 58717.0 60682.0 60788.4 60924.0 61163.4 61406.0 16254.6 16344.3 16352.4 16371.3 16385.2 13322.5 13365.1 13380.9 13442.4 13526.3 16191.4 16201.4 16221.4 16235.9 16413.0 6140.3 6186.4 6189.3 6285.4 6296.1 1090.8 1094.5 1101.4 1101.9 1126.3 25887.5 26044.0 26327.5 26464.6 26483.0 1195.3 1199.8 1219.1 1231.1 1238.1 13351.4 13837.8 13848.9 13849.5 13937.4 283936.0 285784.0 287317.0 287621.0 288885.0 60412.7 61038.0 61321.4 61325.9 61331.0 1497.5 1518.2 1519.3 1544.8 1571.0 25877.6 25908.0 25959.2 26009.9 26118.0 42511.6 42638.4 42672.6 42674.4 42749.4 245216.0 245305.0 245784.0 246119.0 246241.0 35061.5 35699.6 38378.0 38994.6 39335.2 47459.1 48572.5 48817.5 49096.5 49162.0 35169.6 35218.6 35444.6 36212.8 36257.8 35358.3 35432.5 35491.0 35501.3 36008.6 41693.6 41761.8 41804.8 42097.8 42319.6 33534.3 33669.7 33779.0 34140.3 34653.7 25518.5 25666.0 25858.2 26242.0 26452.9 16:25:34 INFO - PID 23409 | 16:25:34 INFO - PID 23409 | [#2] jquery.html Cycles:180 Average:41492.52 Median:22659.39 stddev:105856.76 (467.2%) stddev-sans-first:106109.24 16:25:34 INFO - PID 23409 | Values: 621.0 633.2 634.5 642.0 642.6 21351.6 22159.0 23647.6 24475.5 24674.8 26070.3 26395.3 26396.1 26459.5 26664.0 6716.5 6828.7 6909.7 6913.7 6922.9 6332.6 6582.1 6593.6 6609.4 6616.7 6572.1 6575.4 6575.7 6595.9 6599.8 6485.8 6566.1 6584.3 6730.5 6769.7 6270.6 6294.6 6335.5 6343.1 6345.1 30687.5 31391.4 31462.7 31484.0 31487.2 2164.3 2184.7 2187.1 2187.8 2188.0 19619.0 20890.2 20927.2 21031.8 21042.9 2561.3 2571.3 2629.6 2630.2 2634.1 133037.0 139203.3 154978.0 159986.0 161598.0 15943.2 16140.8 16373.5 16545.5 16927.2 27120.9 27150.9 27155.3 27213.2 27296.3 10075.8 10183.8 10198.6 10226.8 10300.3 9010.7 9011.9 9148.0 9191.4 9199.0 9680.0 9886.6 10038.9 10068.1 10093.4 2760.2 2804.6 2984.4 3008.1 3012.1 1014.8 1016.5 1035.5 1037.7 1053.0 20512.8 21069.3 21191.3 21313.5 21586.0 1087.4 1095.4 1104.5 1113.3 1116.6 11445.4 11570.3 11700.4 11749.8 11756.0 622326.0 633305.0 634405.0 636368.0 644741.0 129559.0 131115.0 134520.0 135010.0 135207.0 1121.1 1148.0 1218.5 1232.8 1293.4 22476.6 22752.0 22760.2 23007.3 23236.5 65666.7 66016.7 67518.7 68302.0 68642.3 85988.6 86920.0 86985.6 88072.6 88609.0 22180.0 22208.6 22402.0 22471.9 22478.0 24231.4 24607.6 24766.4 24932.6 24966.4 19806.0 19968.6 20075.7 20126.9 20139.1 17862.6 18115.3 18349.5 18476.1 18499.2 19855.7 20541.9 20563.7 20792.2 20951.8 21923.0 21964.7 22275.9 22397.9 22566.8 19129.2 19215.9 19296.6 19366.1 19403.2 16:25:34 INFO - PID 23409 | 16:25:34 INFO - PID 23409 | [#3] mootools.html Cycles:180 Average:10748.64 Median:14273.50 stddev:8151.85 (57.1%) stddev-sans-first:8144.34 16:25:34 INFO - PID 23409 | Values: 1381.2 1466.5 1481.3 1487.8 1494.1 16984.1 17831.8 17837.3 17841.6 17842.4 17111.0 17355.6 17361.9 17386.3 17434.8 2584.1 2711.8 2711.9 2712.8 2713.2 2583.9 2690.7 2700.4 2703.1 2704.4 2292.6 2362.5 2626.0 2715.1 2749.4 2630.0 2678.9 2687.5 2689.2 2690.3 2634.4 2666.4 2681.9 2682.4 2684.0 17057.7 17150.7 17155.5 17246.1 17255.8 8291.4 8515.2 8531.6 8537.0 8550.5 5811.7 5901.8 6052.7 6093.9 6128.3 2134.7 2207.6 2235.2 2259.6 2262.2 32064.5 32114.0 32124.5 32181.8 32182.5 7416.8 7627.3 7634.2 7764.8 7841.7 24359.1 24360.3 24364.8 24372.1 24376.1 8845.4 8983.1 8995.7 9008.6 9019.1 7941.6 7998.9 8091.7 8139.6 8144.0 5869.6 6066.1 6107.4 6147.8 6215.8 1731.2 1749.7 1903.5 1934.2 2001.7 896.5 899.3 928.6 929.5 937.5 8747.8 8772.6 8779.6 8789.3 8853.8 1078.2 1079.8 1089.5 1106.4 1114.7 5077.2 5163.7 5280.0 5288.3 5307.3 24643.0 25208.7 25962.9 25980.6 26058.3 25237.9 25269.5 25326.4 25651.6 25706.5 12143.2 12851.4 13055.7 13162.7 13222.2 9500.0 9558.9 9695.2 9707.0 9734.8 21394.9 21872.2 22001.6 22141.1 22301.8 22299.0 22505.2 22661.2 22700.2 22706.8 12295.6 12543.1 12545.1 12545.9 12559.1 14611.9 14744.6 14747.3 14942.4 14953.9 11772.8 12068.1 12334.0 12465.8 12598.1 12181.3 12213.9 12308.3 12556.7 12719.0 11433.4 11507.5 11608.7 11617.2 11649.6 8236.0 8277.8 8435.1 8452.4 8484.3 8225.2 8230.0 8261.0 8290.3 8317.1 16:25:34 INFO - PID 23409 | 16:25:34 INFO - PID 23409 | [#4] prototype.html Cycles:180 Average:5738.98 Median:6333.54 stddev:1570.33 (24.8%) stddev-sans-first:1525.42 16:25:34 INFO - PID 23409 | Values: 536.6 553.0 562.0 584.4 591.8 6066.3 6166.0 6225.5 6280.7 6390.6 6315.0 6346.5 6352.6 6377.3 6433.5 5212.4 5223.8 5254.1 5270.6 5275.4 4961.8 5104.3 5105.3 5223.4 5243.4 5095.7 5107.0 5108.2 5111.3 5124.2 5061.1 5093.6 5096.1 5096.2 5110.4 4581.5 4627.1 4635.4 4646.5 4659.8 6228.6 6261.4 6275.0 6278.2 6306.9 5997.6 6014.5 6016.5 6034.2 6045.8 6654.8 6682.0 6683.3 6704.0 6724.6 5096.9 5120.9 5154.6 5174.2 5188.3 7082.9 7124.1 7148.8 7172.5 7191.0 5269.8 5342.9 5348.7 5382.1 5392.3 5437.3 5442.7 5445.2 5448.8 5458.1 6001.7 6017.1 6019.9 6023.0 6036.7 6710.9 6744.3 6763.5 6856.6 6878.8 1754.8 1822.4 1888.3 1956.2 1981.0 1522.6 1527.7 1531.3 1572.5 1580.3 6035.2 6095.6 6149.5 6244.2 6256.5 6640.2 6861.7 6890.6 6897.4 6938.3 6333.5 6333.6 6374.5 6381.4 6401.2 3592.3 3620.9 3631.3 3635.3 3640.9 7082.4 7089.9 7173.3 7204.4 7206.0 7096.5 7107.5 7119.9 7127.0 7165.5 7005.4 7098.2 7170.1 7198.4 7201.5 6622.6 6734.9 6782.5 6831.2 6885.0 6681.5 6775.6 6782.8 6801.7 6843.0 6967.6 7000.9 7028.3 7100.1 7104.6 5320.5 5328.6 5349.9 5361.7 5376.9 6630.5 6778.3 6855.5 6886.5 6938.8 6469.7 6532.0 6542.8 6569.7 6614.2 6477.7 6523.7 6578.6 6671.4 6690.5 6359.5 6407.4 6496.6 6528.4 6529.5 6551.0 6599.0 6641.5 6679.6 6713.9 6482.8 6544.8 6557.8 6559.0 6595.8 16:25:34 INFO - PID 23409 | 16:25:34 INFO - PID 23409 | [#5] yui.html Cycles:180 Average:29738.80 Median:100038.45 stddev:46810.84 (46.8%) stddev-sans-first:46892.24 16:25:34 INFO - PID 23409 | Values: 947.1 971.5 985.3 986.4 996.8 21175.6 21743.5 21813.7 21832.6 21834.5 19751.0 20205.0 20321.2 20513.7 20525.0 1051.4 1070.9 1071.6 1071.9 1074.7 1050.5 1077.9 1086.0 1087.8 1095.4 1150.3 1159.7 1160.1 1161.8 1163.9 1137.2 1151.1 1151.4 1151.6 1152.0 850.1 850.3 855.8 855.9 857.2 19997.4 20526.1 20669.1 20789.8 22744.5 22709.3 23304.7 23604.4 23631.3 23777.2 18513.9 18796.3 18900.1 18939.1 18949.4 3877.1 3923.5 3923.5 3924.8 3927.1 179136.0 181715.0 185959.1 186195.0 187622.0 17691.2 17962.5 17981.7 18065.8 18361.9 33534.7 33727.0 33757.0 33780.8 33947.0 9282.4 9340.9 9418.3 9422.2 9457.6 4630.4 4631.4 4654.5 4710.6 4727.2 6343.3 6497.0 6545.8 6556.4 6596.2 1090.4 1130.1 1136.4 1139.9 1144.2 685.3 708.6 710.9 717.5 726.2 12937.2 13160.1 13557.7 14013.4 14256.4 684.7 686.5 697.4 698.6 700.8 6294.6 6304.0 6309.1 6311.7 6323.2 149395.3 150579.2 156493.0 159042.0 162539.0 145376.3 149209.3 150529.2 151075.0 152525.0 120391.8 120741.0 122085.4 122659.0 123597.0 11916.2 12025.3 12111.2 12182.0 12318.5 59204.4 59603.7 60280.4 61033.0 61368.7 72086.0 73508.6 73882.3 74153.6 74789.0 15545.8 15552.3 15847.4 15877.6 15902.1 15537.7 15947.9 15981.9 16035.5 16175.4 15918.6 15969.8 16004.6 16136.8 16219.8 13933.4 14001.9 14094.9 14129.6 14235.4 17163.8 17231.1 17288.4 17342.6 17456.6 11200.9 11361.3 11524.3 11680.0 11717.0 12240.3 12298.3 12433.1 12667.2 12676.1 16:25:34 INFO - PID 23409 | -------- Summary: end -------- 16:25:34 INFO - PID 23409 | 16:25:35 INFO - TEST-INFO | 23409: exit 0 16:25:35 INFO - TEST-OK | dromaeo_css | took 1108056ms 16:25:35 INFO - TEST-START | kraken 16:25:35 INFO - Initialising browser for kraken test... 16:25:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50464/getInfo.html -profile /tmp/tmpRg2AAx/profile 16:25:35 INFO - TEST-INFO | started process 23819 (/builds/slave/test/build/application/firefox/firefox http://localhost:50464/getInfo.html) 16:25:39 INFO - TEST-INFO | 23819: exit 0 16:25:40 INFO - Browser initialized. 16:25:40 INFO - Running cycle 1/1 for kraken test... 16:25:40 INFO - Using env: {'DISPLAY': ':0', 16:25:40 INFO - 'HOME': '/home/cltbld', 16:25:40 INFO - 'JSGC_DISABLE_POISONING': '1', 16:25:40 INFO - 'LANG': 'en_US.UTF-8', 16:25:40 INFO - 'LANGUAGE': 'en_US:en', 16:25:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:25:40 INFO - 'LOGNAME': 'cltbld', 16:25:40 INFO - 'MAIL': '/var/mail/cltbld', 16:25:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:25:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:25:40 INFO - 'MOZ_CRASHREPORTER': '1', 16:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:25:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:25:40 INFO - 'MOZ_NO_REMOTE': '1', 16:25:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:25:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:25:40 INFO - 'NO_EM_RESTART': '1', 16:25:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:25:40 INFO - 'PWD': '/builds/slave/test', 16:25:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:25:40 INFO - 'RUST_BACKTRACE': 'full', 16:25:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:25:40 INFO - 'SHELL': '/bin/bash', 16:25:40 INFO - 'SHLVL': '1', 16:25:40 INFO - 'TERM': 'linux', 16:25:40 INFO - 'TMOUT': '86400', 16:25:40 INFO - 'USER': 'cltbld', 16:25:40 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406', 16:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:25:40 INFO - '_': '/tools/buildbot/bin/python'} 16:25:40 INFO - TEST-INFO | started process 24096 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRg2AAx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:25:40 INFO - PID 24096 | 16:25:40 INFO - PID 24096 | (/builds/slave/test/build/application/firefox/firefox:24145): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:25:40 INFO - PID 24096 | 16:25:51 INFO - PID 24096 | RSS: Main: 146485248 16:25:51 INFO - PID 24096 | 16:25:53 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50464/tests/kraken/driver.html?testName=audio-beat-detection) 16:25:54 INFO - PID 24096 | RSS: Main: 152080384 16:25:54 INFO - PID 24096 | 16:25:57 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50464/tests/kraken/driver.html?testName=audio-dft) 16:25:57 INFO - PID 24096 | RSS: Main: 152317952 16:25:57 INFO - PID 24096 | 16:26:00 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50464/tests/kraken/driver.html?testName=audio-fft) 16:26:01 INFO - PID 24096 | RSS: Main: 148983808 16:26:01 INFO - PID 24096 | 16:26:03 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50464/tests/kraken/driver.html?testName=audio-oscillator) 16:26:04 INFO - PID 24096 | RSS: Main: 149487616 16:26:04 INFO - PID 24096 | 16:26:06 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50464/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:26:06 INFO - PID 24096 | RSS: Main: 149803008 16:26:06 INFO - PID 24096 | 16:26:09 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50464/tests/kraken/driver.html?testName=imaging-darkroom) 16:26:10 INFO - PID 24096 | RSS: Main: 149716992 16:26:10 INFO - PID 24096 | 16:26:13 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50464/tests/kraken/driver.html?testName=imaging-desaturate) 16:26:14 INFO - PID 24096 | RSS: Main: 151490560 16:26:14 INFO - PID 24096 | 16:26:17 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50464/tests/kraken/driver.html?testName=json-parse-financial) 16:26:18 INFO - PID 24096 | RSS: Main: 152506368 16:26:18 INFO - PID 24096 | 16:26:19 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50464/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:26:20 INFO - PID 24096 | RSS: Main: 152317952 16:26:20 INFO - PID 24096 | 16:26:21 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50464/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:26:22 INFO - PID 24096 | RSS: Main: 152387584 16:26:22 INFO - PID 24096 | 16:26:24 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50464/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:26:25 INFO - PID 24096 | RSS: Main: 149233664 16:26:25 INFO - PID 24096 | 16:26:27 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50464/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:26:28 INFO - PID 24096 | RSS: Main: 149495808 16:26:28 INFO - PID 24096 | 16:26:30 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50464/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:26:31 INFO - PID 24096 | RSS: Main: 149921792 16:26:31 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | Cycle 1(1): loaded http://localhost:50464/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50464/tests/kraken/driver.html?testName=ai-astar) 16:26:33 INFO - PID 24096 | RSS: Main: 151486464 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | __start_tp_report 16:26:33 INFO - PID 24096 | _x_x_mozilla_page_load 16:26:33 INFO - PID 24096 | _x_x_mozilla_page_load_details 16:26:33 INFO - PID 24096 | |i|pagename|runs| 16:26:33 INFO - PID 24096 | |0;ai-astar;105;104;103;106;105;105;110;106;105;107 16:26:33 INFO - PID 24096 | |1;audio-beat-detection;113;113;117;114;143;112;111;113;140;121 16:26:33 INFO - PID 24096 | |2;audio-dft;160;163;163;161;164;163;163;162;168;165 16:26:33 INFO - PID 24096 | |3;audio-fft;73;73;73;73;73;75;74;82;79;77 16:26:33 INFO - PID 24096 | |4;audio-oscillator;82;78;77;78;78;77;76;77;77;86 16:26:33 INFO - PID 24096 | |5;imaging-gaussian-blur;114;114;109;113;114;114;109;114;113;111 16:26:33 INFO - PID 24096 | |6;imaging-darkroom;149;149;153;149;149;151;149;149;150;149 16:26:33 INFO - PID 24096 | |7;imaging-desaturate;94;94;94;93;94;95;95;95;94;94 16:26:33 INFO - PID 24096 | |8;json-parse-financial;46;47;44;46;42;45;44;52;50;44 16:26:33 INFO - PID 24096 | |9;json-stringify-tinderbox;40;40;40;40;40;40;40;39;40;40 16:26:33 INFO - PID 24096 | |10;stanford-crypto-aes;68;74;68;68;67;68;69;69;67;71 16:26:33 INFO - PID 24096 | |11;stanford-crypto-ccm;108;119;103;114;108;108;109;104;108;110 16:26:33 INFO - PID 24096 | |12;stanford-crypto-pbkdf2;150;146;149;154;147;168;146;145;151;144 16:26:33 INFO - PID 24096 | |13;stanford-crypto-sha256-iterative;60;58;58;60;59;66;57;62;60;60 16:26:33 INFO - PID 24096 | __end_tp_report 16:26:33 INFO - PID 24096 | __start_cc_report 16:26:33 INFO - PID 24096 | _x_x_mozilla_cycle_collect,714 16:26:33 INFO - PID 24096 | __end_cc_report 16:26:33 INFO - PID 24096 | __startTimestamp1507073193298__endTimestamp 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | ------- Summary: start ------- 16:26:33 INFO - PID 24096 | Number of tests: 14 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#0] ai-astar Cycles:10 Average:105.60 Median:105.50 stddev:1.90 (1.8%) stddev-sans-first:2.00 16:26:33 INFO - PID 24096 | Values: 105.0 104.0 103.0 106.0 105.0 105.0 110.0 106.0 105.0 107.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#1] audio-beat-detection Cycles:10 Average:119.70 Median:115.50 stddev:11.86 (10.3%) stddev-sans-first:12.33 16:26:33 INFO - PID 24096 | Values: 113.0 113.0 117.0 114.0 143.0 112.0 111.0 113.0 140.0 121.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:2.20 (1.4%) stddev-sans-first:2.01 16:26:33 INFO - PID 24096 | Values: 160.0 163.0 163.0 161.0 164.0 163.0 163.0 162.0 168.0 165.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#3] audio-fft Cycles:10 Average:75.20 Median:74.50 stddev:3.16 (4.2%) stddev-sans-first:3.24 16:26:33 INFO - PID 24096 | Values: 73.0 73.0 73.0 73.0 73.0 75.0 74.0 82.0 79.0 77.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#4] audio-oscillator Cycles:10 Average:78.60 Median:78.00 stddev:3.06 (3.9%) stddev-sans-first:2.99 16:26:33 INFO - PID 24096 | Values: 82.0 78.0 77.0 78.0 78.0 77.0 76.0 77.0 77.0 86.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#5] imaging-gaussian-blur Cycles:10 Average:112.50 Median:114.00 stddev:2.07 (1.8%) stddev-sans-first:2.12 16:26:33 INFO - PID 24096 | Values: 114.0 114.0 109.0 113.0 114.0 114.0 109.0 114.0 113.0 111.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:149.00 stddev:1.34 (0.9%) stddev-sans-first:1.39 16:26:33 INFO - PID 24096 | Values: 149.0 149.0 153.0 149.0 149.0 151.0 149.0 149.0 150.0 149.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 16:26:33 INFO - PID 24096 | Values: 94.0 94.0 94.0 93.0 94.0 95.0 95.0 95.0 94.0 94.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#8] json-parse-financial Cycles:10 Average:46.00 Median:46.00 stddev:3.02 (6.6%) stddev-sans-first:3.20 16:26:33 INFO - PID 24096 | Values: 46.0 47.0 44.0 46.0 42.0 45.0 44.0 52.0 50.0 44.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#9] json-stringify-tinderbox Cycles:10 Average:39.90 Median:40.00 stddev:0.32 (0.8%) stddev-sans-first:0.33 16:26:33 INFO - PID 24096 | Values: 40.0 40.0 40.0 40.0 40.0 40.0 40.0 39.0 40.0 40.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#10] stanford-crypto-aes Cycles:10 Average:68.90 Median:68.50 stddev:2.13 (3.1%) stddev-sans-first:2.24 16:26:33 INFO - PID 24096 | Values: 68.0 74.0 68.0 68.0 67.0 68.0 69.0 69.0 67.0 71.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#11] stanford-crypto-ccm Cycles:10 Average:109.10 Median:108.50 stddev:4.61 (4.2%) stddev-sans-first:4.87 16:26:33 INFO - PID 24096 | Values: 108.0 119.0 103.0 114.0 108.0 108.0 109.0 104.0 108.0 110.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.00 Median:149.50 stddev:7.02 (4.7%) stddev-sans-first:7.45 16:26:33 INFO - PID 24096 | Values: 150.0 146.0 149.0 154.0 147.0 168.0 146.0 145.0 151.0 144.0 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.00 Median:60.00 stddev:2.54 (4.2%) stddev-sans-first:2.69 16:26:33 INFO - PID 24096 | Values: 60.0 58.0 58.0 60.0 59.0 66.0 57.0 62.0 60.0 60.0 16:26:33 INFO - PID 24096 | -------- Summary: end -------- 16:26:33 INFO - PID 24096 | 16:26:33 INFO - PID 24096 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:26:33 INFO - TEST-INFO | 24096: exit 0 16:26:33 INFO - TEST-OK | kraken | took 58825ms 16:26:33 INFO - SUITE-END | took 1166s 16:26:33 INFO - Completed test suite (00:19:26) 16:26:33 INFO - javascript benchmark 16:26:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9231.604093327254, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3857.2285542891427, 3882.9417558736614, 3900.1029763154493, 3910.0811309342316, 3912.8826135215936, 6315.115883776263, 6324.2727086385075, 6380.074889141072, 6392.808215753524, 6459.386358295966, 6400.551961362707, 6421.967890160543, 6505.381988711077, 6516.283208847023, 6524.0540121682325, 5678.318601767773, 5711.428857114276, 5797.217375654296, 5810.680412577317, 5838.854028649275, 5804.796832110877, 5828.2131912191735, 5866.120081987693, 5886.323072846634, 5909.556783241253, 5619.943800561983, 5711.428857114276, 5778.913316300238, 5793.884122317572, 5822.767089316443, 5631.9718401408145, 5657.066584013633, 5661.433856614347, 5662.433756624346, 5677.914831277534, 5625.353084395265, 5649.12438572022, 5656.519195868356, 5660.179274005287, 5678.290213723285, 6312.305646378894, 6342.016987366966, 6350.87298254037, 6360.491160707133, 6382.489400847968, 6843.8973415398805, 6883.242843287235, 6930.099087118692, 6936.79189624307, 6958.547694399847, 6760.932390676079, 6880.2431732509385, 6890.1731792184755, 6949.166100068024, 7036.050133232051, 6370.936290637081, 6374.203224596926, 6383.616982981036, 6413.749318882819, 6451.6774161292215, 7030.0, 7047.8238044048285, 7125.465590080765, 7135.750248741269, 7156.427485801123, 6604.1414616100565, 6800.217974932847, 6826.658667066627, 6844.110265665531, 6922.307769223037, 6906.758263460855, 6949.200841903245, 6957.756478523227, 6972.093627828452, 6982.68577913985, 6910.170779506491, 6914.827129321807, 6925.272846351161, 6985.5808651481075, 6995.510314277952, 6853.897191542131, 6913.308669133147, 7003.299670032956, 7014.19336776267, 7025.051618031603, 4992.051510213048, 5027.195648696191, 5031.949680503221, 5032.018756342552, 5078.4667609900425, 4582.335561343653, 4583.977080114578, 4620.468646105741, 4630.513796051433, 4713.481517033192, 6365.554411191172, 6370.617762934146, 6409.711562979677, 6460.709268082947, 6461.321561236096, 6922.134733158356, 6934.4452443804375, 6940.271271516418, 7031.8593628127155, 7033.718651253995, 6161.383861613893, 6319.526035547352, 6322.968385158137, 6411.647359395185, 6454.580452270681, 5569.888602228014, 5642.322921249476, 5645.745941432645, 5659.858503537363, 5663.0, 7030.0, 7075.964620176866, 7136.928630713626, 7152.284771522806, 7214.989901413806, 6917.550359226634, 6970.093887794555, 6993.860122797617, 6995.825104372329, 7002.404795592228, 7171.282871712787, 7193.100862392201, 7250.89123663156, 7268.491205615767, 7280.526765760209, 6815.659217039029, 6822.0, 6912.066870972354, 6925.411340035952, 6926.792196234121, 6897.172339319314, 6951.478639101986, 7003.19463261711, 7011.228764835762, 7016.7544135954995, 6559.573627714183, 6625.072489851328, 6713.093732346266, 6751.122354093936, 6752.696128674123, 6568.934310656832, 6640.136782218281, 6664.633445160369, 6681.866362672622, 6721.966390168018, 6648.767293144717, 6688.398044175853, 6709.194896612437, 6710.261871194067, 6715.764948226789, 6719.865602688016, 6779.830504237433, 6788.1175447191545, 6889.965550172217, 6895.34494223048, 6481.027845823164, 6529.902051469327, 6530.281669016499, 6590.439812615981, 6633.0], "value": 6321.57418557492, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [948.2793077261282, 979.7984811634483, 979.8481235408512, 987.4124895687069, 998.9850152247712, 46179.0, 46840.29739553909, 46872.0, 46962.0, 46987.76506117464, 43095.569044309545, 43272.567274327244, 43372.349414758726, 43565.7821710891, 43765.0, 7854.489458185229, 7864.292213700765, 7934.365250779923, 7952.880706789403, 7974.521528708267, 7846.529208247495, 7901.525908445512, 7926.048874135112, 7930.6431210595665, 7930.841383172333, 8374.539400332978, 8647.69733059343, 8667.69663061793, 8676.219140277373, 8676.479411235347, 8581.485110893334, 8615.569221538897, 8622.396432249745, 8657.913420865774, 8661.177188167145, 8353.415260931737, 8561.95719021407, 8576.957115214447, 8590.484570925764, 8600.741977740678, 41106.0, 41716.37425438621, 41761.0, 41765.37351939723, 41789.0, 3690.8892733218045, 3740.5511338639267, 3753.174301653632, 3756.849726010956, 3758.5113935188524, 33887.49168762471, 34010.659893400996, 34102.658973410194, 34121.48817767711, 34149.0, 5417.295751552312, 5434.239206511052, 5448.4279150689, 5465.207544905967, 5506.366767821672, 215537.92231038903, 223465.0, 223740.0, 224618.0, 225030.0, 47974.28038579424, 57445.71277143587, 58692.413075868695, 58699.0, 58717.0, 60682.0, 60788.39211607916, 60924.0, 61163.38836611577, 61406.0, 16254.593635159215, 16344.264508097163, 16352.427665031904, 16371.345146194257, 16385.180740962904, 13322.533711320057, 13365.064445488722, 13380.866191338157, 13442.395092220873, 13526.256055916821, 16191.352345906032, 16201.4329498467, 16221.432249871197, 16235.918820405823, 16413.0, 6140.263168419819, 6186.350433204539, 6189.319174890758, 6285.371462853769, 6296.087067375296, 1090.820014697566, 1094.5020015892749, 1101.3557069114497, 1101.8787933327328, 1126.2791813239533, 25887.482250355264, 26044.0, 26327.473450531266, 26464.60303095455, 26483.0, 1195.3425615911387, 1199.8380218670543, 1219.091776626419, 1231.1135982092878, 1238.0590751029283, 13351.399187036606, 13837.792433113511, 13848.86151138496, 13849.515266965607, 13937.372818223204, 283936.0, 285784.0, 287317.0, 287621.0, 288885.0, 60412.697936510915, 61038.0, 61321.38678613246, 61325.853595123954, 61331.0, 1497.453429498247, 1518.2105305241435, 1519.270750039987, 1544.8455154484461, 1571.0259639023877, 25877.611835822103, 25908.0, 25959.22122336333, 26009.869950650125, 26118.0, 42511.57488425076, 42638.36042459428, 42672.57327426686, 42674.35988460114, 42749.35875961801, 245216.0, 245305.0, 245784.0, 246119.0, 246241.0, 35061.47407788834, 35699.64300357067, 38378.0, 38994.61005390023, 39335.213295734495, 47459.05081898274, 48572.514274858215, 48817.511824881294, 49096.509034910625, 49162.0, 35169.64830351664, 35218.64781352256, 35444.645553544135, 36212.818935905154, 36257.81871090628, 35358.29283414266, 35432.46851297284, 35491.0, 35501.289974199855, 36008.63991360053, 41693.58306417018, 41761.79119104385, 41804.790976044926, 42097.78951105225, 42319.57680423156, 33534.32931341311, 33669.66330336665, 33778.98663040113, 34140.31719365648, 34653.653463465045, 25518.489630207663, 25666.0, 25858.22425327243, 26242.0, 26452.8677356612], "value": 19040.105415851012, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.9968950155248, 633.2053273142205, 634.5336177909236, 641.9582727122739, 642.6272761798156, 21351.57296854066, 22159.0, 23647.645285320712, 24475.510489790217, 24674.75325246747, 26070.348241293977, 26395.340116497096, 26396.076137335196, 26459.470810583778, 26664.0, 6716.496262780287, 6828.69270882809, 6909.654517274142, 6913.688884000207, 6922.861542769142, 6332.588381755171, 6582.144321238256, 6593.637349945751, 6609.4051535361805, 6616.702248398834, 6572.145621069254, 6575.4082132608055, 6575.704093315789, 6595.868082638344, 6599.835004124887, 6485.77299794505, 6566.1464009678675, 6584.341565843401, 6730.461563074942, 6769.695363708645, 6270.592411493239, 6294.559380843343, 6335.5248356372995, 6343.1436756038265, 6345.079963405326, 30687.539686904714, 31391.37217255665, 31462.685373146203, 31484.0, 31487.212819679462, 2164.3398763377163, 2184.7050648162453, 2187.1360812478983, 2187.814035806958, 2187.9562408751735, 19619.0, 20890.164393424246, 20927.162913483444, 21031.789682103135, 21042.89478552613, 2561.282840804559, 2571.305747448197, 2629.5924131759607, 2630.1977896741487, 2634.078072674568, 133037.0, 139203.30398347945, 154978.0, 159986.0, 161597.99999999884, 15943.20283985796, 16140.75788863168, 16373.508794736177, 16545.50363489097, 16927.238274277686, 27120.864395677894, 27150.864245678644, 27155.321116971838, 27213.183604491896, 27296.317592060357, 10075.798484030278, 10183.847242291371, 10198.592056317813, 10226.846597301046, 10300.330478518872, 9010.684626038057, 9011.909880901107, 9147.954260228656, 9191.356605037583, 9199.034101419255, 9679.983601721719, 9886.555105020292, 10038.899611003942, 10068.087063457919, 10093.444860532594, 2760.158151763691, 2804.5933339665635, 2984.418038482475, 3008.1422648484026, 3012.126483319813, 1014.7513859104419, 1016.481594386868, 1035.539185062655, 1037.6938803053117, 1053.0417320238546, 20512.79487205139, 21069.262575809775, 21191.258305959218, 21313.467163321042, 21586.0, 1087.4399684162663, 1095.3537412926412, 1104.4643347976225, 1113.2930589075834, 1116.6370929447962, 11445.427728613702, 11570.305781653127, 11700.35648039349, 11749.823752643717, 11756.0, 622326.0, 633305.0, 634405.0, 636368.0, 644741.0, 129559.0, 131115.0, 134520.0, 135010.0, 135207.0, 1121.0807138146643, 1147.9942600286945, 1218.530865616744, 1232.8458942632171, 1293.4050336845157, 22476.550468990856, 22752.0, 22760.20339288183, 23007.309780706604, 23236.535269294858, 65666.67166664136, 66016.6699166501, 67518.66240668765, 68302.0, 68642.3135768636, 85988.57005715181, 86920.0, 86985.56507217676, 88072.5596372014, 88609.0, 22180.0, 22208.555828883007, 22402.0, 22471.887640562345, 22478.0, 24231.394215144763, 24607.630885537088, 24766.38084047913, 24932.62601061022, 24966.37584060413, 19806.0, 19968.600627987067, 20075.69886451675, 20126.899365503665, 20139.093740781405, 17862.642747144724, 18115.275388984293, 18349.54126146857, 18476.076196190406, 18499.167537460577, 19855.702164467835, 20541.89729051405, 20563.69154462714, 20792.168313267906, 20951.790482094984, 21923.01346439382, 21964.670529942385, 22275.888620556794, 22397.888010559844, 22566.774332256467, 19129.23483060662, 19215.90392048031, 19296.61406771885, 19366.12852421616, 19403.223871045], "value": 13197.34660204524, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.2127087560089, 1466.494059549455, 1481.2667729473912, 1487.8140232470948, 1494.0662086196123, 16984.06587637681, 17831.821681783178, 17837.286508539677, 17841.553961150978, 17842.37551685691, 17110.973341599514, 17355.566110847234, 17361.91319043403, 17386.304547818072, 17434.82565174348, 2584.082650659017, 2711.8372897626205, 2711.8915243390243, 2712.755851973322, 2713.186044186736, 2583.8708064596694, 2690.744379283965, 2700.351915540266, 2703.0809524761576, 2704.418550011747, 2292.5529521743265, 2362.5038741864228, 2626.0, 2715.117586784293, 2749.3539018330675, 2630.040035387089, 2678.866056697167, 2687.516247075526, 2689.247010836959, 2690.2870739254113, 2634.4335967767024, 2666.4400475900084, 2681.8793154308105, 2682.383051898055, 2684.0203325785947, 17057.744133838, 17150.742738858928, 17155.485335439957, 17246.137693115295, 17255.827441725673, 8291.378146638966, 8515.233628973423, 8531.634938409825, 8536.957315213383, 8550.52972086535, 5811.6803575803315, 5901.822945311647, 6052.727627256784, 6093.908591371133, 6128.3258841527395, 2134.6658338538414, 2207.602631526325, 2235.2065016919087, 2259.581977334182, 2262.2195342606774, 32064.51903221477, 32114.0, 32124.518132228037, 32181.83909080463, 32182.517262241086, 7416.7774966751085, 7627.313541781266, 7634.198409167068, 7764.844703105906, 7841.7255396062, 24359.14742983987, 24360.26919192427, 24364.756352436605, 24372.146974855794, 24376.14683486105, 8845.380823342304, 8983.146601072887, 8995.730128096167, 9008.59461324242, 9019.098090190928, 7941.642626081724, 7998.920010799818, 8091.716789912442, 8139.593020348959, 8143.95928020368, 5869.589128761031, 6066.059760737093, 6107.4197951194565, 6147.754089836446, 6215.844603884939, 1731.212298404223, 1749.7025505664067, 1903.5050886769263, 1934.1683076277336, 2001.6897380905982, 896.5024411451707, 899.3030401438937, 928.5682157796657, 929.4794914847707, 937.5312343828086, 8747.781305467413, 8772.561371931379, 8779.561021948877, 8789.252913502423, 8853.778655533662, 1078.1805827571102, 1079.83262594298, 1089.4824958144818, 1106.391484683421, 1114.676743744305, 5077.213031980049, 5163.715995620202, 5280.049591073567, 5288.286081378965, 5307.2569840222395, 24643.0, 25208.74791252101, 25962.922966162663, 25980.610290845652, 26058.34854128662, 25237.86429610672, 25269.494610107697, 25326.366840829127, 25651.615225771628, 25706.48587028249, 12143.210691305037, 12851.421686023965, 13055.738885222243, 13162.670933226746, 13222.206667599974, 9499.95250023773, 9558.856617150888, 9695.22438204956, 9706.951465242628, 9734.805303894023, 21394.893025534773, 21872.23447179341, 22001.559968800855, 22141.1143554256, 22301.776982230618, 22299.0, 22505.212317568807, 22661.2068577599, 22700.205492807672, 22706.772932270465, 12295.631131066082, 12543.12198146121, 12545.121841471375, 12545.87454125447, 12559.058070644556, 14611.92694036523, 14744.55766327012, 14747.2626368679, 14942.402303907722, 14953.925230373778, 11772.82340764872, 12068.115862673207, 12334.0, 12465.815747504335, 12598.118131730691, 12181.33002684861, 12213.938930305292, 12308.26150430977, 12556.686082848002, 12719.046071544486, 11433.371164586028, 11507.482163302839, 11608.6517404478, 11617.186796924472, 11649.59226427071, 8235.95882020586, 8277.793055173668, 8435.072142064491, 8452.366072544706, 8484.278836298983, 8225.177482251966, 8229.95885020571, 8261.008678958564, 8290.295324897452, 8317.126701696478], "value": 7435.239089964345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.5680627095187, 552.9861753456162, 561.955043596512, 584.3718003146618, 591.7632946821271, 6066.272047354324, 6165.996051283334, 6225.470834979026, 6280.748770049198, 6390.584612000228, 6315.0, 6346.476845557057, 6352.555321127523, 6377.298497165308, 6433.453156481704, 5212.4266330703595, 5223.8432847014465, 5254.133068043769, 5270.631055826094, 5275.36695596528, 4961.826336078238, 5104.285400043997, 5105.336306280197, 5223.425423203445, 5243.449437809032, 5095.719735414569, 5107.0, 5108.157154069574, 5111.258867464216, 5124.205748109049, 5061.063703214917, 5093.617978651616, 5096.108181068316, 5096.210087436453, 5110.412302585213, 4581.473130589991, 4627.120847039052, 4635.374224479686, 4646.4888862225125, 4659.813607455698, 6228.564000519966, 6261.436470717657, 6274.968625156891, 6278.183836101323, 6306.936930630681, 5997.640141591475, 6014.51883849291, 6016.548758843104, 6034.155218269446, 6045.821064892348, 6654.833629159261, 6682.031105489726, 6683.2648408675, 6703.966480167616, 6724.630145342005, 5096.9490305097215, 5120.92318615221, 5154.582489815293, 5174.249733788617, 5188.273641690166, 7082.893756593655, 7124.073870396867, 7148.821279468003, 7172.533785303938, 7190.96404517974, 5269.78920843162, 5342.9465705342445, 5348.732563371894, 5382.1119515280325, 5392.272043274108, 5437.32033495813, 5442.700651464128, 5445.156000819879, 5448.83653490396, 5458.1266997280245, 6001.699915004319, 6017.127516510169, 6019.909701354483, 6022.969885150546, 6036.6679832609625, 6710.932890671128, 6744.258131605519, 6763.492738044666, 6856.588604683635, 6878.793636190822, 1754.8245175482605, 1822.4077174918204, 1888.310766570192, 1956.2370675436591, 1980.9801901981084, 1522.6421790879135, 1527.6791873706425, 1531.3032570180542, 1572.512521118457, 1580.3046659469796, 6035.245594300713, 6095.603785753912, 6149.477294430024, 6244.1570387997945, 6256.499480041586, 6640.236372817091, 6861.656917154122, 6890.552114112566, 6897.41371983387, 6938.271481494471, 6333.461655759312, 6333.619982801047, 6374.458171055512, 6381.425671689571, 6401.199850018747, 3592.2995015972156, 3620.87326943561, 3631.2737452509605, 3635.2547727715755, 3640.9089772755365, 7082.362587367162, 7089.89365159523, 7173.3185347392955, 7204.423646108299, 7206.0, 7096.467764917652, 7107.502474826816, 7119.857602848017, 7127.037849890302, 7165.462590305748, 7005.404540614105, 7098.219195888551, 7170.139583250043, 7198.352148306572, 7201.531900426456, 6622.602643841385, 6734.8653026940165, 6782.525223234326, 6831.180258369028, 6885.0, 6681.498887583353, 6775.62734049632, 6782.762603308861, 6801.659917004031, 6842.954885076093, 6967.631005458168, 7000.859982800417, 7028.332308430691, 7100.147982242164, 7104.644767761694, 5320.52115309624, 5328.600354973315, 5349.866253343697, 5361.705106219196, 5376.892462150813, 6630.53586248958, 6778.271950427344, 6855.5201135922, 6886.48351373639, 6938.757143499954, 6469.676516174079, 6531.982830559267, 6542.803715888531, 6569.737210511526, 6614.206295244601, 6477.676116194078, 6523.739050437929, 6578.638174900427, 6671.39957403836, 6690.531662783753, 6359.523035772243, 6407.423331900151, 6496.577722448026, 6528.412442880163, 6529.51028672861, 6550.967245163743, 6599.0, 6641.468682505485, 6679.599224046573, 6713.932860671526, 6482.773102941374, 6544.8363790905605, 6557.770478033246, 6558.967205163944, 6595.8021259362295], "value": 5299.261295444751, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [947.1428357336613, 971.4851128901682, 985.3053597213969, 986.3785814936589, 996.8155891160134, 21175.576488470222, 21743.45641358967, 21813.67279490807, 21832.563348733016, 21834.454138646543, 19751.0, 20204.98975051249, 20321.187152513918, 20513.692294615554, 20525.0, 1051.3901936876593, 1070.855434516342, 1071.6302875507938, 1071.8552995345644, 1074.7474343529263, 1050.5430137890005, 1077.9245452818307, 1085.967420977368, 1087.7770057138307, 1095.4194277033184, 1150.3098141115347, 1159.7448561316496, 1160.0603511155948, 1161.790877642024, 1163.9068874490063, 1137.243732917609, 1151.1193936638479, 1151.41277948247, 1151.5969410706227, 1151.9827202591969, 850.056437354536, 850.285759961629, 855.8117214212863, 855.9272461840751, 857.2199298638209, 19997.400077997685, 20526.07632656519, 20669.069891854902, 20789.792102078936, 22744.54510909789, 22709.318720438412, 23304.65043024339, 23604.409889752722, 23631.291061268188, 23777.167799126946, 18513.907430462896, 18796.342128025455, 18900.149493272696, 18939.147738351807, 18949.43151705451, 3877.0501227199516, 3923.489946306962, 3923.4899463069905, 3924.843006279745, 3927.136030073379, 179136.0, 181715.0, 185959.07020464947, 186195.0, 187622.0, 17691.203895824718, 17962.461126166236, 17981.730274045898, 18065.819341806415, 18361.908190458962, 33534.66465335364, 33727.0, 33757.0, 33780.83109584436, 33946.98159055232, 9282.443053416817, 9340.859887101698, 9418.293627977791, 9422.246220302359, 9457.621695132255, 4630.374899388539, 4631.3516107744945, 4654.511276316006, 4710.599599034121, 4727.17274476972, 6343.333949935282, 6497.0, 6545.8363540910905, 6556.377144171296, 6596.208454985433, 1090.378484263962, 1130.1467392118898, 1136.4147464056018, 1139.9088072954144, 1144.2162118948547, 685.2736099734298, 708.5536112249353, 710.928907109285, 717.4968441115853, 726.1649103530982, 12937.223766574036, 13160.089285268385, 13557.661058473419, 14013.369398377097, 14256.35846386915, 684.6850448793512, 686.5102231406545, 697.396751809681, 698.5703792167882, 700.7897630710767, 6294.559380843297, 6303.968480157661, 6309.116723658692, 6311.652859092794, 6323.241211054703, 149395.2530237342, 150579.24710376377, 156493.0, 159042.0, 162539.0, 145376.27311863584, 149209.25395372952, 150529.24735376253, 151075.0, 152525.0, 120391.79608203981, 120741.0, 122085.38957305334, 122659.0, 123597.0, 11916.16586838913, 12025.278483291031, 12111.152219344562, 12182.0, 12318.507259709511, 59204.40795592161, 59603.701981489816, 60280.39719602748, 61033.0, 61368.69315653393, 72086.0, 73508.63245683737, 73882.26117738754, 74153.6292318535, 74789.0, 15545.766813498032, 15552.30014649321, 15847.366105355657, 15877.603059923595, 15902.125383104041, 15537.689246214786, 15947.920260398623, 15981.920090399473, 16035.518934431986, 16175.43385981485, 15918.60203494922, 15969.840301596836, 16004.599885002968, 16136.838631613533, 16219.837801622305, 13933.442662293395, 14001.859981400463, 14094.859051409354, 14129.576112716635, 14235.430582776988, 17163.828361716223, 17231.05229212405, 17288.394906178222, 17342.566435839206, 17456.563585910455, 11200.943995279971, 11361.318320900773, 11524.250923690268, 11680.0, 11717.0, 12240.326782027074, 12298.262104273772, 12433.12968092225, 12667.176633518791, 12676.112672112864], "value": 9888.834670731176, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1372.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 104.0, 103.0, 106.0, 105.0, 105.0, 110.0, 106.0, 105.0, 107.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 117.0, 114.0, 143.0, 112.0, 111.0, 113.0, 140.0, 121.0], "value": 119.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 163.0, 161.0, 164.0, 163.0, 163.0, 162.0, 168.0, 165.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 73.0, 75.0, 74.0, 82.0, 79.0, 77.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 77.0, 78.0, 78.0, 77.0, 76.0, 77.0, 77.0, 86.0], "value": 78.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 109.0, 113.0, 114.0, 114.0, 109.0, 114.0, 113.0, 111.0], "value": 112.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 153.0, 149.0, 149.0, 151.0, 149.0, 149.0, 150.0, 149.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 47.0, 44.0, 46.0, 42.0, 45.0, 44.0, 52.0, 50.0, 44.0], "value": 46.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 40.0, 40.0, 40.0, 40.0, 40.0, 40.0, 39.0, 40.0, 40.0], "value": 39.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 68.0, 68.0, 67.0, 68.0, 69.0, 69.0, 67.0, 71.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 103.0, 114.0, 108.0, 108.0, 109.0, 104.0, 108.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 146.0, 149.0, 154.0, 147.0, 168.0, 146.0, 145.0, 151.0, 144.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 58.0, 60.0, 59.0, 66.0, 57.0, 62.0, 60.0, 60.0], "value": 60.0, "unit": "score"}]}]} 16:26:33 INFO - Return code: 0 16:26:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:26:34 INFO - # TBPL SUCCESS # 16:26:34 INFO - Running post-action listener: _package_coverage_data 16:26:34 INFO - Running post-action listener: _resource_record_post_action 16:26:34 INFO - [mozharness: 2017-10-03 23:26:34.073974Z] Finished run-tests step (success) 16:26:34 INFO - Running post-run listener: _resource_record_post_run 16:26:34 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 12288; Write bytes: 321093632; Read time: 180; Write time: 220684 16:26:34 INFO - TinderboxPrint: CPU usage
13.1% 16:26:34 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 180 16:26:34 INFO - TinderboxPrint: I/O write bytes / time
321,093,632 / 220,684 16:26:34 INFO - TinderboxPrint: CPU idle
8,211.3 (86.9%) 16:26:34 INFO - TinderboxPrint: CPU user
1,188.7 (12.6%) 16:26:34 INFO - TinderboxPrint: Swap in / out
0 / 0 16:26:34 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12509184; Read time: 0; Write time: 2080 16:26:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:26:34 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 12288; Write bytes: 307589120; Read time: 180; Write time: 218580 16:26:34 INFO - Running post-run listener: _upload_blobber_files 16:26:34 INFO - Blob upload gear active. 16:26:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:26:34 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 16:26:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:26:35 INFO - (blobuploader) - INFO - Open directory for files ... 16:26:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:26:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:26:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:36 INFO - (blobuploader) - INFO - Done attempting. 16:26:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:26:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:26:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:43 INFO - (blobuploader) - INFO - Done attempting. 16:26:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:26:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:26:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:44 INFO - (blobuploader) - INFO - Done attempting. 16:26:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:26:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:26:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:45 INFO - (blobuploader) - INFO - Done attempting. 16:26:45 INFO - (blobuploader) - INFO - Iteration through files over. 16:26:45 INFO - Return code: 0 16:26:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:26:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:26:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc70b12df17a337a6804c48aee61425655af16b93527fd0f176b0c87a7f1c348552fc36a0163f894181c269748ff05dbb26a701ac1e8c036aad74b4dae971ded", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2861811278f6e806bd1c764f9a96ab85649edc3355cf775d02fdba57cbb1d6d76e0fb97395880fd04e4f6858f8721f9fc822dfebddaf80c3ae604c064994b18c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a4626a76ffcf1f9d10f137ae8668f80fc1fe96e59e28b5de9dfc2de7792470e432bc1c493ae6567b82ec603256f14951689a23aad13a377030073dcdd43063", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52e7488aa1a8d5416448499c8198d67bd2fa6a12f8022e26bc3a93541a2c4411a429269adc6d3b08b3b8893cb5a6096ade64799428c9cb0661596bea83df5a72"} 16:26:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:26:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:26:45 INFO - Contents: 16:26:45 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc70b12df17a337a6804c48aee61425655af16b93527fd0f176b0c87a7f1c348552fc36a0163f894181c269748ff05dbb26a701ac1e8c036aad74b4dae971ded", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2861811278f6e806bd1c764f9a96ab85649edc3355cf775d02fdba57cbb1d6d76e0fb97395880fd04e4f6858f8721f9fc822dfebddaf80c3ae604c064994b18c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a4626a76ffcf1f9d10f137ae8668f80fc1fe96e59e28b5de9dfc2de7792470e432bc1c493ae6567b82ec603256f14951689a23aad13a377030073dcdd43063", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52e7488aa1a8d5416448499c8198d67bd2fa6a12f8022e26bc3a93541a2c4411a429269adc6d3b08b3b8893cb5a6096ade64799428c9cb0661596bea83df5a72"} 16:26:45 INFO - Running post-run listener: copy_logs_to_upload_dir 16:26:45 INFO - Copying logs to upload dir... 16:26:45 INFO - mkdir: /builds/slave/test/build/upload/logs 16:26:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.548486 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-10-03 16:26:45.168091) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 16:26:45.171215) ========= 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=20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc70b12df17a337a6804c48aee61425655af16b93527fd0f176b0c87a7f1c348552fc36a0163f894181c269748ff05dbb26a701ac1e8c036aad74b4dae971ded", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2861811278f6e806bd1c764f9a96ab85649edc3355cf775d02fdba57cbb1d6d76e0fb97395880fd04e4f6858f8721f9fc822dfebddaf80c3ae604c064994b18c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a4626a76ffcf1f9d10f137ae8668f80fc1fe96e59e28b5de9dfc2de7792470e432bc1c493ae6567b82ec603256f14951689a23aad13a377030073dcdd43063", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52e7488aa1a8d5416448499c8198d67bd2fa6a12f8022e26bc3a93541a2c4411a429269adc6d3b08b3b8893cb5a6096ade64799428c9cb0661596bea83df5a72"} build_url:https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/Ffxf4E-mT86Y4EN0dsL5BA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc70b12df17a337a6804c48aee61425655af16b93527fd0f176b0c87a7f1c348552fc36a0163f894181c269748ff05dbb26a701ac1e8c036aad74b4dae971ded", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2861811278f6e806bd1c764f9a96ab85649edc3355cf775d02fdba57cbb1d6d76e0fb97395880fd04e4f6858f8721f9fc822dfebddaf80c3ae604c064994b18c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a4626a76ffcf1f9d10f137ae8668f80fc1fe96e59e28b5de9dfc2de7792470e432bc1c493ae6567b82ec603256f14951689a23aad13a377030073dcdd43063", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52e7488aa1a8d5416448499c8198d67bd2fa6a12f8022e26bc3a93541a2c4411a429269adc6d3b08b3b8893cb5a6096ade64799428c9cb0661596bea83df5a72"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 16:26:45.200545) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 16:26:45.200943) ========= 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=20e666a3d4cfbb71505a330e000001b4-1507070425.79569-1073941406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 16:26:45.282971) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 16:26:45.283327) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 16:26:45.283623) ========= ========= Total master_lag: 0.20 =========