builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-029 starttime: 1505471245.66 results: success (0) revision: 893fe1549e1e7342a66514b65960f08d40810a34 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:25.656838) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:25.657257) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:25.657537) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:25.843758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:25.844120) ========= 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=ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 03:27:25-- 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% 221M=0s 2017-09-15 03:27:26 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.356249 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:26.234498) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:26.234786) ========= 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=ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017726 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:26.288740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:26.292080) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 893fe1549e1e7342a66514b65960f08d40810a34 --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 893fe1549e1e7342a66514b65960f08d40810a34 --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=ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 03:27:26,166 truncating revision to first 12 chars 2017-09-15 03:27:26,166 Setting DEBUG logging. 2017-09-15 03:27:26,166 attempt 1/10 2017-09-15 03:27:26,166 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/893fe1549e1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 03:27:26,523 unpacking tar archive at: mozilla-central-893fe1549e1e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.671087 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:26.982704) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:26.983006) ========= script_repo_revision: 893fe1549e1e7342a66514b65960f08d40810a34 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:26.986446) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:26.986781) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 03:27:27.039568) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-15 03:27:27.039877) ========= /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=ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:27:27 INFO - MultiFileLogger online at 20170915 03:27:27 in /builds/slave/test 03:27:27 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 03:27:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:27:27 INFO - {'append_to_log': False, 03:27:27 INFO - 'base_work_dir': '/builds/slave/test', 03:27:27 INFO - 'blob_upload_branch': 'Firefox', 03:27:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:27:27 INFO - 'branch': 'Firefox', 03:27:27 INFO - 'buildbot_json_path': 'buildprops.json', 03:27:27 INFO - 'code_coverage': False, 03:27:27 INFO - 'config_files': ('talos/linux_config.py',), 03:27:27 INFO - 'default_actions': ('clobber', 03:27:27 INFO - 'read-buildbot-config', 03:27:27 INFO - 'download-and-extract', 03:27:27 INFO - 'populate-webroot', 03:27:27 INFO - 'create-virtualenv', 03:27:27 INFO - 'install', 03:27:27 INFO - 'setup-mitmproxy', 03:27:27 INFO - 'run-tests'), 03:27:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:27:27 INFO - 'disable_ccov_upload': False, 03:27:27 INFO - 'disable_stylo': False, 03:27:27 INFO - 'download_minidump_stackwalk': True, 03:27:27 INFO - 'download_symbols': 'ondemand', 03:27:27 INFO - 'enable_stylo': False, 03:27:27 INFO - 'enable_webrender': False, 03:27:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:27:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:27:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:27:27 INFO - 'gecko_profile': False, 03:27:27 INFO - 'gecko_profile_interval': 0, 03:27:27 INFO - 'installer_path': 'installer.exe', 03:27:27 INFO - 'log_level': 'info', 03:27:27 INFO - 'log_name': 'talos', 03:27:27 INFO - 'log_to_console': True, 03:27:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:27:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:27:27 INFO - 'opt_config_files': (), 03:27:27 INFO - 'pip_index': False, 03:27:27 INFO - 'suite': 'dromaeojs-e10s', 03:27:27 INFO - 'system_bits': '32', 03:27:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:27:27 INFO - 'title': 'talos-linux64-ix-029', 03:27:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:27:27 INFO - 'use_talos_json': True, 03:27:27 INFO - 'verify': 'False', 03:27:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:27:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:27:27 INFO - 'work_dir': 'build'} 03:27:27 INFO - [mozharness: 2017-09-15 10:27:27.080037Z] Running clobber step. 03:27:27 INFO - Running pre-action listener: _resource_record_pre_action 03:27:27 INFO - Running main action method: clobber 03:27:27 INFO - rmtree: /builds/slave/test/build 03:27:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:27:28 INFO - Running post-action listener: _resource_record_post_action 03:27:28 INFO - [mozharness: 2017-09-15 10:27:28.069166Z] Finished clobber step (success) 03:27:28 INFO - [mozharness: 2017-09-15 10:27:28.069262Z] Running read-buildbot-config step. 03:27:28 INFO - Running pre-action listener: _resource_record_pre_action 03:27:28 INFO - Running main action method: read_buildbot_config 03:27:28 INFO - Using buildbot properties: 03:27:28 INFO - { 03:27:28 INFO - "project": "", 03:27:28 INFO - "product": "firefox", 03:27:28 INFO - "who": "archaeopteryx@coole-files.de", 03:27:28 INFO - "installer_path": "public/build/target.tar.bz2", 03:27:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:27:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:27:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:27:28 INFO - "stage_platform": "linux64-pgo", 03:27:28 INFO - "basedir": "/builds/slave/test", 03:27:28 INFO - "buildnumber": 162, 03:27:28 INFO - "platform": "ubuntu64_hw", 03:27:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:27:28 INFO - "slavebuilddir": "test", 03:27:28 INFO - "taskId": "AopNMmwbT7qcGyqDr8btLQ", 03:27:28 INFO - "branch": "mozilla-central", 03:27:28 INFO - "script_repo_revision": "production", 03:27:28 INFO - "revision": "893fe1549e1e7342a66514b65960f08d40810a34", 03:27:28 INFO - "slavename": "talos-linux64-ix-029", 03:27:28 INFO - "repo_path": "mozilla-central" 03:27:28 INFO - } 03:27:28 INFO - Finding installer, test and symbols from parent task. 03:27:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AopNMmwbT7qcGyqDr8btLQ'}, attempt #1 03:27:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AopNMmwbT7qcGyqDr8btLQ'}, attempt #1 03:27:29 INFO - Task dependencies: UkW8pfz6SDugMp2heHwTHw 03:27:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw'}, attempt #1 03:27:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.tar.bz2 03:27:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.test_packages.json 03:27:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.crashreporter-symbols.zip 03:27:29 INFO - Running post-action listener: _resource_record_post_action 03:27:29 INFO - [mozharness: 2017-09-15 10:27:29.527059Z] Finished read-buildbot-config step (success) 03:27:29 INFO - [mozharness: 2017-09-15 10:27:29.527276Z] Running download-and-extract step. 03:27:29 INFO - Running pre-action listener: _resource_record_pre_action 03:27:29 INFO - Running main action method: download_and_extract 03:27:29 INFO - mkdir: /builds/slave/test/build/tests 03:27: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')]} 03:27:29 INFO - trying https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.test_packages.json 03:27:29 INFO - Downloading https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:27:30 INFO - Downloaded 989 bytes. 03:27:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:27:30 INFO - Using the following test package requirements: 03:27:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:27:30 INFO - u'common': [u'target.common.tests.zip'], 03:27:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:27:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:27:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:27:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:27:30 INFO - u'mozbase': [u'target.common.tests.zip'], 03:27:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:27:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:27:30 INFO - u'web-platform': [u'target.common.tests.zip', 03:27:30 INFO - u'target.web-platform.tests.tar.gz'], 03:27:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:27:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:27:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.common.tests.zip 03:27:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:27:30 INFO - Fetch https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.common.tests.zip into memory 03:27:32 INFO - Content-Length response header: 55289256 03:27:32 INFO - Bytes received: 55289256 03:27:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.talos.tests.zip 03:27:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:27:43 INFO - Fetch https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.talos.tests.zip into memory 03:27:44 INFO - Content-Length response header: 14502661 03:27:44 INFO - Bytes received: 14502661 03:27:44 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')]} 03:27:44 INFO - trying https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.tar.bz2 03:27:44 INFO - Downloading https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:27:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:27:47 INFO - Downloaded 62222432 bytes. 03:27:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.tar.bz2 03:27:48 INFO - mkdir: /builds/slave/test/properties 03:27:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:27:48 INFO - Writing to file /builds/slave/test/properties/build_url 03:27:48 INFO - Contents: 03:27:48 INFO - build_url:https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.tar.bz2 03:27:48 INFO - Running post-action listener: _resource_record_post_action 03:27:48 INFO - Running post-action listener: find_tests_for_verification 03:27:48 INFO - Running post-action listener: set_extra_try_arguments 03:27:48 INFO - [mozharness: 2017-09-15 10:27:48.445659Z] Finished download-and-extract step (success) 03:27:48 INFO - [mozharness: 2017-09-15 10:27:48.445894Z] Running populate-webroot step. 03:27:48 INFO - Running pre-action listener: _resource_record_pre_action 03:27:48 INFO - Running main action method: populate_webroot 03:27:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:27:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['tresize', 03:27:48 INFO - 'tcanvasmark']}, 03:27:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:27:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['dromaeo_css', 03:27:48 INFO - 'kraken']}, 03:27:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:27:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:27:48 INFO - 'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:27:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:27:48 INFO - 'tests': ['damp', 'tps']}, 03:27:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:27:48 INFO - 'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['damp', 'tps']}, 03:27:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:27:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['dromaeo_dom']}, 03:27:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:27:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['basic_compositor_video', 03:27:48 INFO - 'glvideo']}, 03:27:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:27:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:27:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:27:48 INFO - 'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['ts_paint_webext', 03:27:48 INFO - 'tp5o_webext']}, 03:27:48 INFO - 'other-e10s': {'tests': ['a11yr', 03:27:48 INFO - 'ts_paint', 03:27:48 INFO - 'tpaint', 03:27:48 INFO - 'sessionrestore', 03:27:48 INFO - 'sessionrestore_many_windows', 03:27:48 INFO - 'sessionrestore_no_auto_restore', 03:27:48 INFO - 'tabpaint']}, 03:27:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['a11yr', 03:27:48 INFO - 'ts_paint', 03:27:48 INFO - 'tpaint', 03:27:48 INFO - 'sessionrestore', 03:27:48 INFO - 'sessionrestore_many_windows', 03:27:48 INFO - 'sessionrestore_no_auto_restore', 03:27:48 INFO - 'tabpaint']}, 03:27:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:27:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:27:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['bloom_basic_singleton']}, 03:27:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['bloom_basic']}, 03:27:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:27:48 INFO - 'tsvgr_opacity', 03:27:48 INFO - 'tart', 03:27:48 INFO - 'tscrollx', 03:27:48 INFO - 'tsvg_static']}, 03:27:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['tsvgx', 03:27:48 INFO - 'tsvgr_opacity', 03:27:48 INFO - 'tart', 03:27:48 INFO - 'tscrollx', 03:27:48 INFO - 'tsvg_static']}, 03:27:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:27:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:27:48 INFO - 'talos_options': ['--disable-stylo'], 03:27:48 INFO - 'tests': ['tp5o']}, 03:27:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:27:48 INFO - 'talos_options': ['--mitmproxy', 03:27:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:27:48 INFO - '--firstNonBlankPaint'], 03:27:48 INFO - 'tests': ['tp6_google', 03:27:48 INFO - 'tp6_youtube', 03:27:48 INFO - 'tp6_amazon', 03:27:48 INFO - 'tp6_facebook']}, 03:27:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:27:48 INFO - 'talos_options': ['--disable-stylo', 03:27:48 INFO - '--mitmproxy', 03:27:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:27:48 INFO - '--firstNonBlankPaint'], 03:27:48 INFO - 'tests': ['tp6_google', 03:27:48 INFO - 'tp6_youtube', 03:27:48 INFO - 'tp6_amazon', 03:27:48 INFO - 'tp6_facebook']}, 03:27:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:27:48 INFO - 'talos_options': ['--stylo-threads=1', 03:27:48 INFO - '--mitmproxy', 03:27:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:27:48 INFO - '--firstNonBlankPaint'], 03:27:48 INFO - 'tests': ['tp6_google', 03:27:48 INFO - 'tp6_youtube', 03:27:48 INFO - 'tp6_amazon', 03:27:48 INFO - 'tp6_facebook']}, 03:27:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:27:48 INFO - 'talos_options': ['--xperf_path', 03:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:27:48 INFO - 'tests': ['tp5n']}, 03:27:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:27:48 INFO - 'talos_options': ['--disable-stylo', 03:27:48 INFO - '--xperf_path', 03:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:27:48 INFO - 'tests': ['tp5n']}}} 03:27:48 INFO - Running post-action listener: _resource_record_post_action 03:27:48 INFO - [mozharness: 2017-09-15 10:27:48.463185Z] Finished populate-webroot step (success) 03:27:48 INFO - [mozharness: 2017-09-15 10:27:48.463274Z] Running create-virtualenv step. 03:27:48 INFO - Running pre-action listener: _resource_record_pre_action 03:27:48 INFO - Running main action method: create_virtualenv 03:27:48 INFO - Creating virtualenv /builds/slave/test/build/venv 03:27:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:27:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:27:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:27:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:27:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:27:49 INFO - Installing distribute.............................................................................................................................................................................................done. 03:27:49 INFO - Installing pip...............done. 03:27:49 INFO - Return code: 0 03:27:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:27:49 INFO - Reading from file tmpfile_stdout 03:27:49 INFO - Output received: 03:27:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:27:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:49 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 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:27:49 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 03:27:49 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 03:27:49 INFO - Using env: {'DISPLAY': ':0', 03:27:49 INFO - 'HOME': '/home/cltbld', 03:27:49 INFO - 'LANG': 'en_US.UTF-8', 03:27:49 INFO - 'LANGUAGE': 'en_US:en', 03:27:49 INFO - 'LOGNAME': 'cltbld', 03:27:49 INFO - 'MAIL': '/var/mail/cltbld', 03:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:49 INFO - 'MOZ_NO_REMOTE': '1', 03:27:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:49 INFO - 'NO_EM_RESTART': '1', 03:27:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:49 INFO - 'PWD': '/builds/slave/test', 03:27:49 INFO - 'SHELL': '/bin/bash', 03:27:49 INFO - 'SHLVL': '1', 03:27:49 INFO - 'TERM': 'linux', 03:27:49 INFO - 'TMOUT': '86400', 03:27:49 INFO - 'USER': 'cltbld', 03:27:49 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:49 INFO - '_': '/tools/buildbot/bin/python'} 03:27:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:27:50 INFO - Downloading/unpacking pip>=1.5 03:27:50 INFO - Running setup.py egg_info for package pip 03:27:50 INFO - warning: no files found matching 'pip/cacert.pem' 03:27:50 INFO - warning: no files found matching '*.html' under directory 'docs' 03:27:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:27:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:27:50 INFO - Installing collected packages: pip 03:27:50 INFO - Found existing installation: pip 1.1 03:27:50 INFO - Uninstalling pip: 03:27:50 INFO - Successfully uninstalled pip 03:27:50 INFO - Running setup.py install for pip 03:27:50 INFO - warning: no files found matching 'pip/cacert.pem' 03:27:50 INFO - warning: no files found matching '*.html' under directory 'docs' 03:27:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:27:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:27:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:27:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:27:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:27:50 INFO - Successfully installed pip 03:27:50 INFO - Cleaning up... 03:27:50 INFO - Return code: 0 03:27:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:27:50 INFO - Using env: {'DISPLAY': ':0', 03:27:50 INFO - 'HOME': '/home/cltbld', 03:27:50 INFO - 'LANG': 'en_US.UTF-8', 03:27:50 INFO - 'LANGUAGE': 'en_US:en', 03:27:50 INFO - 'LOGNAME': 'cltbld', 03:27:50 INFO - 'MAIL': '/var/mail/cltbld', 03:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:50 INFO - 'MOZ_NO_REMOTE': '1', 03:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:50 INFO - 'NO_EM_RESTART': '1', 03:27:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:50 INFO - 'PWD': '/builds/slave/test', 03:27:50 INFO - 'SHELL': '/bin/bash', 03:27:50 INFO - 'SHLVL': '1', 03:27:50 INFO - 'TERM': 'linux', 03:27:50 INFO - 'TMOUT': '86400', 03:27:50 INFO - 'USER': 'cltbld', 03:27:50 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:50 INFO - '_': '/tools/buildbot/bin/python'} 03:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:51 INFO - Downloading/unpacking psutil>=3.1.1 03:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:27:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:27:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:27:52 INFO - Installing collected packages: psutil 03:27:52 INFO - Running setup.py install for psutil 03:27:52 INFO - building 'psutil._psutil_linux' extension 03:27:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:27:52 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 03:27:52 INFO - building 'psutil._psutil_posix' extension 03:27:52 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 03:27:52 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 03:27:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:27:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:27:53 INFO - Successfully installed psutil 03:27:53 INFO - Cleaning up... 03:27:54 INFO - Return code: 0 03:27:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:54 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 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:27:54 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 03:27:54 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 03:27:54 INFO - Using env: {'DISPLAY': ':0', 03:27:54 INFO - 'HOME': '/home/cltbld', 03:27:54 INFO - 'LANG': 'en_US.UTF-8', 03:27:54 INFO - 'LANGUAGE': 'en_US:en', 03:27:54 INFO - 'LOGNAME': 'cltbld', 03:27:54 INFO - 'MAIL': '/var/mail/cltbld', 03:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:54 INFO - 'MOZ_NO_REMOTE': '1', 03:27:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:54 INFO - 'NO_EM_RESTART': '1', 03:27:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:54 INFO - 'PWD': '/builds/slave/test', 03:27:54 INFO - 'SHELL': '/bin/bash', 03:27:54 INFO - 'SHLVL': '1', 03:27:54 INFO - 'TERM': 'linux', 03:27:54 INFO - 'TMOUT': '86400', 03:27:54 INFO - 'USER': 'cltbld', 03:27:54 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:54 INFO - '_': '/tools/buildbot/bin/python'} 03:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:27:56 INFO - Installing collected packages: mozsystemmonitor 03:27:56 INFO - Running setup.py install for mozsystemmonitor 03:27:56 INFO - Successfully installed mozsystemmonitor 03:27:56 INFO - Cleaning up... 03:27:56 INFO - Return code: 0 03:27:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:27:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:27:56 INFO - Using env: {'DISPLAY': ':0', 03:27:56 INFO - 'HOME': '/home/cltbld', 03:27:56 INFO - 'LANG': 'en_US.UTF-8', 03:27:56 INFO - 'LANGUAGE': 'en_US:en', 03:27:56 INFO - 'LOGNAME': 'cltbld', 03:27:56 INFO - 'MAIL': '/var/mail/cltbld', 03:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:56 INFO - 'MOZ_NO_REMOTE': '1', 03:27:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:56 INFO - 'NO_EM_RESTART': '1', 03:27:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:56 INFO - 'PWD': '/builds/slave/test', 03:27:56 INFO - 'SHELL': '/bin/bash', 03:27:56 INFO - 'SHLVL': '1', 03:27:56 INFO - 'TERM': 'linux', 03:27:56 INFO - 'TMOUT': '86400', 03:27:56 INFO - 'USER': 'cltbld', 03:27:56 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:56 INFO - '_': '/tools/buildbot/bin/python'} 03:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:56 INFO - Downloading/unpacking jsonschema==2.5.1 03:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:27:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:57 INFO - Downloading functools32-3.2.3-2.tar.gz 03:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:27:57 INFO - warning: no files found matching '*.txt' 03:27:57 INFO - no previously-included directories found matching 'build' 03:27:57 INFO - no previously-included directories found matching 'dist' 03:27:57 INFO - no previously-included directories found matching '.git*' 03:27:57 INFO - Installing collected packages: jsonschema, functools32 03:27:57 INFO - Running setup.py install for functools32 03:27:57 INFO - warning: no files found matching '*.txt' 03:27:57 INFO - no previously-included directories found matching 'build' 03:27:57 INFO - no previously-included directories found matching 'dist' 03:27:57 INFO - no previously-included directories found matching '.git*' 03:27:57 INFO - Successfully installed jsonschema functools32 03:27:57 INFO - Cleaning up... 03:27:57 INFO - Return code: 0 03:27:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:27:57 INFO - Using env: {'DISPLAY': ':0', 03:27:57 INFO - 'HOME': '/home/cltbld', 03:27:57 INFO - 'LANG': 'en_US.UTF-8', 03:27:57 INFO - 'LANGUAGE': 'en_US:en', 03:27:57 INFO - 'LOGNAME': 'cltbld', 03:27:57 INFO - 'MAIL': '/var/mail/cltbld', 03:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:57 INFO - 'MOZ_NO_REMOTE': '1', 03:27:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:57 INFO - 'NO_EM_RESTART': '1', 03:27:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:57 INFO - 'PWD': '/builds/slave/test', 03:27:57 INFO - 'SHELL': '/bin/bash', 03:27:57 INFO - 'SHLVL': '1', 03:27:57 INFO - 'TERM': 'linux', 03:27:57 INFO - 'TMOUT': '86400', 03:27:57 INFO - 'USER': 'cltbld', 03:27:57 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:57 INFO - '_': '/tools/buildbot/bin/python'} 03:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:27:58 INFO - Cleaning up... 03:27:58 INFO - Return code: 0 03:27:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:27:58 INFO - Using env: {'DISPLAY': ':0', 03:27:58 INFO - 'HOME': '/home/cltbld', 03:27:58 INFO - 'LANG': 'en_US.UTF-8', 03:27:58 INFO - 'LANGUAGE': 'en_US:en', 03:27:58 INFO - 'LOGNAME': 'cltbld', 03:27:58 INFO - 'MAIL': '/var/mail/cltbld', 03:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:58 INFO - 'MOZ_NO_REMOTE': '1', 03:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:58 INFO - 'NO_EM_RESTART': '1', 03:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:58 INFO - 'PWD': '/builds/slave/test', 03:27:58 INFO - 'SHELL': '/bin/bash', 03:27:58 INFO - 'SHLVL': '1', 03:27:58 INFO - 'TERM': 'linux', 03:27:58 INFO - 'TMOUT': '86400', 03:27:58 INFO - 'USER': 'cltbld', 03:27:58 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:58 INFO - '_': '/tools/buildbot/bin/python'} 03:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:58 INFO - Downloading/unpacking blobuploader==1.2.4 03:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:59 INFO - Downloading blobuploader-1.2.4.tar.gz 03:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:27:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:27:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:59 INFO - Downloading docopt-0.6.1.tar.gz 03:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:28:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:28:00 INFO - Running setup.py install for blobuploader 03:28:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:28:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:28:00 INFO - Running setup.py install for requests 03:28:00 INFO - Running setup.py install for docopt 03:28:00 INFO - Successfully installed blobuploader requests docopt 03:28:00 INFO - Cleaning up... 03:28:00 INFO - Return code: 0 03:28:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:28:00 INFO - Using env: {'DISPLAY': ':0', 03:28:00 INFO - 'HOME': '/home/cltbld', 03:28:00 INFO - 'LANG': 'en_US.UTF-8', 03:28:00 INFO - 'LANGUAGE': 'en_US:en', 03:28:00 INFO - 'LOGNAME': 'cltbld', 03:28:00 INFO - 'MAIL': '/var/mail/cltbld', 03:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:00 INFO - 'MOZ_NO_REMOTE': '1', 03:28:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:00 INFO - 'NO_EM_RESTART': '1', 03:28:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:00 INFO - 'PWD': '/builds/slave/test', 03:28:00 INFO - 'SHELL': '/bin/bash', 03:28:00 INFO - 'SHLVL': '1', 03:28:00 INFO - 'TERM': 'linux', 03:28:00 INFO - 'TMOUT': '86400', 03:28:00 INFO - 'USER': 'cltbld', 03:28:00 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:00 INFO - '_': '/tools/buildbot/bin/python'} 03:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:00 INFO - Running setup.py (path:/tmp/pip-cuJ9uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:00 INFO - Running setup.py (path:/tmp/pip-jGiqxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:00 INFO - Running setup.py (path:/tmp/pip-eAAJet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:01 INFO - Running setup.py (path:/tmp/pip-bhxoWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:01 INFO - Running setup.py (path:/tmp/pip-rYrvJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:01 INFO - Running setup.py (path:/tmp/pip-KmUIBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:01 INFO - Running setup.py (path:/tmp/pip-IHvFIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:01 INFO - Running setup.py (path:/tmp/pip-Zcjt4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:01 INFO - Running setup.py (path:/tmp/pip-2bgVUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:01 INFO - Running setup.py (path:/tmp/pip-KDalNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:01 INFO - Running setup.py (path:/tmp/pip-xH5k5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:01 INFO - Running setup.py (path:/tmp/pip-j6uYj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:01 INFO - Running setup.py (path:/tmp/pip-8YfuJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:01 INFO - Running setup.py (path:/tmp/pip-VbxQ1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:01 INFO - Running setup.py (path:/tmp/pip-VW5zUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:01 INFO - Running setup.py (path:/tmp/pip-C1CJwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:01 INFO - Running setup.py (path:/tmp/pip-Bo9NqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:28:02 INFO - Running setup.py install for manifestparser 03:28:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:28:02 INFO - Running setup.py install for mozcrash 03:28:02 INFO - Running setup.py install for mozdebug 03:28:02 INFO - Running setup.py install for mozdevice 03:28:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:28:02 INFO - Running setup.py install for mozfile 03:28:02 INFO - Running setup.py install for mozhttpd 03:28:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:28:02 INFO - Running setup.py install for mozinfo 03:28:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:28:02 INFO - Running setup.py install for mozInstall 03:28:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:28:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:28:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:28:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:28:02 INFO - Running setup.py install for mozleak 03:28:02 INFO - Running setup.py install for mozlog 03:28:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:28:03 INFO - Running setup.py install for moznetwork 03:28:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:28:03 INFO - Running setup.py install for mozprocess 03:28:03 INFO - Running setup.py install for mozprofile 03:28:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:28:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:28:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:28:03 INFO - Running setup.py install for mozrunner 03:28:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:28:03 INFO - Running setup.py install for mozscreenshot 03:28:03 INFO - Running setup.py install for moztest 03:28:03 INFO - Running setup.py install for mozversion 03:28:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:28:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:28:03 INFO - Cleaning up... 03:28:03 INFO - Return code: 0 03:28:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:28:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:28:03 INFO - Using env: {'DISPLAY': ':0', 03:28:03 INFO - 'HOME': '/home/cltbld', 03:28:03 INFO - 'LANG': 'en_US.UTF-8', 03:28:03 INFO - 'LANGUAGE': 'en_US:en', 03:28:03 INFO - 'LOGNAME': 'cltbld', 03:28:03 INFO - 'MAIL': '/var/mail/cltbld', 03:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:03 INFO - 'MOZ_NO_REMOTE': '1', 03:28:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:03 INFO - 'NO_EM_RESTART': '1', 03:28:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:03 INFO - 'PWD': '/builds/slave/test', 03:28:03 INFO - 'SHELL': '/bin/bash', 03:28:03 INFO - 'SHLVL': '1', 03:28:03 INFO - 'TERM': 'linux', 03:28:03 INFO - 'TMOUT': '86400', 03:28:03 INFO - 'USER': 'cltbld', 03:28:03 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:03 INFO - '_': '/tools/buildbot/bin/python'} 03:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:03 INFO - Running setup.py (path:/tmp/pip-z1XNp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:04 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)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:04 INFO - Running setup.py (path:/tmp/pip-qZfoRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:04 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)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:04 INFO - Running setup.py (path:/tmp/pip-JYndYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:04 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)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:04 INFO - Running setup.py (path:/tmp/pip-QHnhs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:04 INFO - Running setup.py (path:/tmp/pip-F_MqTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:04 INFO - Running setup.py (path:/tmp/pip-uJPHz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:04 INFO - Running setup.py (path:/tmp/pip-sEi62y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:04 INFO - Running setup.py (path:/tmp/pip-HtNGJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:04 INFO - Running setup.py (path:/tmp/pip-64gJnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:04 INFO - Running setup.py (path:/tmp/pip-EdajOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:04 INFO - Running setup.py (path:/tmp/pip-bPsOvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:04 INFO - Running setup.py (path:/tmp/pip-AETOO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:04 INFO - Running setup.py (path:/tmp/pip-1aTrXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:04 INFO - Running setup.py (path:/tmp/pip-dPnp7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:05 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)) 03:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:05 INFO - Running setup.py (path:/tmp/pip-CbSlJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:05 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)) 03:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:05 INFO - Running setup.py (path:/tmp/pip-_Trywa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:05 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)) 03:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:05 INFO - Running setup.py (path:/tmp/pip-PFuDLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:05 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)) 03:28:05 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)) 03:28:05 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)) 03:28:05 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)) 03:28:05 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)) 03:28:05 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)) 03:28:05 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)) 03:28:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:06 INFO - Downloading blessings-1.6.tar.gz 03:28:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:28:06 INFO - Installing collected packages: blessings 03:28:06 INFO - Running setup.py install for blessings 03:28:06 INFO - Successfully installed blessings 03:28:06 INFO - Cleaning up... 03:28:06 INFO - Return code: 0 03:28:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:06 INFO - Reading from file tmpfile_stdout 03:28:06 INFO - Current package versions: 03:28:06 INFO - argparse == 1.2.1 03:28:06 INFO - blessings == 1.6 03:28:06 INFO - blobuploader == 1.2.4 03:28:06 INFO - docopt == 0.6.1 03:28:06 INFO - functools32 == 3.2.3-2 03:28:06 INFO - jsonschema == 2.5.1 03:28:06 INFO - manifestparser == 1.1 03:28:06 INFO - mozInstall == 1.13 03:28:06 INFO - mozcrash == 1.0 03:28:06 INFO - mozdebug == 0.1 03:28:06 INFO - mozdevice == 0.51 03:28:06 INFO - mozfile == 1.2 03:28:06 INFO - mozhttpd == 0.7 03:28:06 INFO - mozinfo == 0.10 03:28:06 INFO - mozleak == 0.1 03:28:06 INFO - mozlog == 3.5 03:28:06 INFO - moznetwork == 0.27 03:28:06 INFO - mozprocess == 0.25 03:28:06 INFO - mozprofile == 0.28 03:28:06 INFO - mozrunner == 6.13 03:28:06 INFO - mozscreenshot == 0.1 03:28:06 INFO - mozsystemmonitor == 0.3 03:28:06 INFO - moztest == 0.8 03:28:06 INFO - mozversion == 1.4 03:28:06 INFO - psutil == 3.1.1 03:28:06 INFO - requests == 1.2.3 03:28:06 INFO - wsgiref == 0.1.2 03:28:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28: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')]} 03:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:28:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:28:06 INFO - Using env: {'DISPLAY': ':0', 03:28:06 INFO - 'HOME': '/home/cltbld', 03:28:06 INFO - 'LANG': 'en_US.UTF-8', 03:28:06 INFO - 'LANGUAGE': 'en_US:en', 03:28:06 INFO - 'LOGNAME': 'cltbld', 03:28:06 INFO - 'MAIL': '/var/mail/cltbld', 03:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:06 INFO - 'MOZ_NO_REMOTE': '1', 03:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:06 INFO - 'NO_EM_RESTART': '1', 03:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:06 INFO - 'PWD': '/builds/slave/test', 03:28:06 INFO - 'SHELL': '/bin/bash', 03:28:06 INFO - 'SHLVL': '1', 03:28:06 INFO - 'TERM': 'linux', 03:28:06 INFO - 'TMOUT': '86400', 03:28:06 INFO - 'USER': 'cltbld', 03:28:06 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:06 INFO - '_': '/tools/buildbot/bin/python'} 03:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:28:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:28:08 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)) 03:28:08 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)) 03:28:08 INFO - Installing collected packages: simplejson 03:28:08 INFO - Running setup.py install for simplejson 03:28:08 INFO - building 'simplejson._speedups' extension 03:28:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:28:09 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 03:28:09 INFO - Successfully installed simplejson 03:28:09 INFO - Cleaning up... 03:28:09 INFO - Return code: 0 03:28:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:09 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 0x1ba11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, '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': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', '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 03:28:09 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 03:28:09 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 03:28:09 INFO - Using env: {'DISPLAY': ':0', 03:28:09 INFO - 'HOME': '/home/cltbld', 03:28:09 INFO - 'LANG': 'en_US.UTF-8', 03:28:09 INFO - 'LANGUAGE': 'en_US:en', 03:28:09 INFO - 'LOGNAME': 'cltbld', 03:28:09 INFO - 'MAIL': '/var/mail/cltbld', 03:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:09 INFO - 'MOZ_NO_REMOTE': '1', 03:28:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:09 INFO - 'NO_EM_RESTART': '1', 03:28:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:09 INFO - 'PWD': '/builds/slave/test', 03:28:09 INFO - 'SHELL': '/bin/bash', 03:28:09 INFO - 'SHLVL': '1', 03:28:09 INFO - 'TERM': 'linux', 03:28:09 INFO - 'TMOUT': '86400', 03:28:09 INFO - 'USER': 'cltbld', 03:28:09 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:09 INFO - '_': '/tools/buildbot/bin/python'} 03:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:28:09 INFO - Cleaning up... 03:28:09 INFO - Return code: 0 03:28:09 INFO - Running post-action listener: _resource_record_post_action 03:28:09 INFO - Running post-action listener: _start_resource_monitoring 03:28:09 INFO - Starting resource monitoring. 03:28:09 INFO - [mozharness: 2017-09-15 10:28:09.452630Z] Finished create-virtualenv step (success) 03:28:09 INFO - [mozharness: 2017-09-15 10:28:09.453130Z] Running install step. 03:28:09 INFO - Running pre-action listener: _resource_record_pre_action 03:28:09 INFO - Running main action method: install 03:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:09 INFO - Reading from file tmpfile_stdout 03:28:09 INFO - Detecting whether we're running mozinstall >=1.0... 03:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:28:09 INFO - Reading from file tmpfile_stdout 03:28:09 INFO - Output received: 03:28:09 INFO - Usage: mozinstall [options] installer 03:28:09 INFO - Options: 03:28:09 INFO - -h, --help show this help message and exit 03:28:09 INFO - -d DEST, --destination=DEST 03:28:09 INFO - Directory to install application into. [default: 03:28:09 INFO - "/builds/slave/test"] 03:28:09 INFO - --app=APP Application being installed. [default: firefox] 03:28:09 INFO - mkdir: /builds/slave/test/build/application 03:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:28:27 INFO - Reading from file tmpfile_stdout 03:28:27 INFO - Output received: 03:28:27 INFO - /builds/slave/test/build/application/firefox/firefox 03:28:27 INFO - Running post-action listener: _resource_record_post_action 03:28:27 INFO - [mozharness: 2017-09-15 10:28:27.380748Z] Finished install step (success) 03:28:27 INFO - [mozharness: 2017-09-15 10:28:27.381000Z] Running setup-mitmproxy step. 03:28:27 INFO - Running pre-action listener: _resource_record_pre_action 03:28:27 INFO - Running main action method: setup_mitmproxy 03:28:27 INFO - Skipping: mitmproxy is not required 03:28:27 INFO - Running post-action listener: _resource_record_post_action 03:28:27 INFO - [mozharness: 2017-09-15 10:28:27.382182Z] Finished setup-mitmproxy step (success) 03:28:27 INFO - [mozharness: 2017-09-15 10:28:27.382401Z] Running run-tests step. 03:28:27 INFO - Running pre-action listener: _resource_record_pre_action 03:28:27 INFO - Running pre-action listener: _set_gcov_prefix 03:28:27 INFO - Running main action method: run_tests 03:28:27 WARNING - Try message not found. 03:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:28:27 INFO - Python 2.7.3 03:28:27 INFO - Return code: 0 03:28:27 INFO - grabbing minidump binary from tooltool 03:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:28:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f11090>, '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 0x1f11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:28:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:28:27 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:28:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:28:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:28:27 INFO - Return code: 0 03:28:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:28:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:28:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:28:27 INFO - ENV: RUST_BACKTRACE is now full 03:28:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:28:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:28:27 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/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:28:27 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/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 03:28:27 INFO - Using env: {'DISPLAY': ':0', 03:28:27 INFO - 'HOME': '/home/cltbld', 03:28:27 INFO - 'LANG': 'en_US.UTF-8', 03:28:27 INFO - 'LANGUAGE': 'en_US:en', 03:28:27 INFO - 'LOGNAME': 'cltbld', 03:28:27 INFO - 'MAIL': '/var/mail/cltbld', 03:28:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:28:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:27 INFO - 'MOZ_NO_REMOTE': '1', 03:28:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:28:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:27 INFO - 'NO_EM_RESTART': '1', 03:28:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:27 INFO - 'PWD': '/builds/slave/test', 03:28:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:28:27 INFO - 'RUST_BACKTRACE': 'full', 03:28:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:28:27 INFO - 'SHELL': '/bin/bash', 03:28:27 INFO - 'SHLVL': '1', 03:28:27 INFO - 'TERM': 'linux', 03:28:27 INFO - 'TMOUT': '86400', 03:28:27 INFO - 'USER': 'cltbld', 03:28:27 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:27 INFO - '_': '/tools/buildbot/bin/python'} 03:28:27 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/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:28:27 INFO - mozversion application_buildid: 20170915090610 03:28:27 INFO - mozversion application_changeset: 893fe1549e1e7342a66514b65960f08d40810a34 03:28:27 INFO - mozversion application_display_name: Nightly 03:28:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:28:27 INFO - mozversion application_name: Firefox 03:28:27 INFO - mozversion application_remotingname: firefox 03:28:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:28:27 INFO - mozversion application_vendor: Mozilla 03:28:27 INFO - mozversion application_version: 57.0a1 03:28:27 INFO - mozversion platform_buildid: 20170915090610 03:28:27 INFO - mozversion platform_changeset: 893fe1549e1e7342a66514b65960f08d40810a34 03:28:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:28:27 INFO - mozversion platform_version: 57.0a1 03:28:27 INFO - using testdate: 1505471307 03:28:27 INFO - actual date: 1505471307 03:28:27 INFO - starting webserver on 'localhost:43802' 03:28:27 INFO - SUITE-START | Running 2 tests 03:28:27 INFO - TEST-START | dromaeo_css 03:28:27 INFO - Initialising browser for dromaeo_css test... 03:28:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43802/getInfo.html -profile /tmp/tmpiVzurm/profile 03:28:27 INFO - TEST-INFO | started process 9088 (/builds/slave/test/build/application/firefox/firefox http://localhost:43802/getInfo.html) 03:28:34 INFO - TEST-INFO | 9088: exit 0 03:28:34 INFO - Browser initialized. 03:28:34 INFO - Running cycle 1/1 for dromaeo_css test... 03:28:34 INFO - Using env: {'DISPLAY': ':0', 03:28:34 INFO - 'HOME': '/home/cltbld', 03:28:34 INFO - 'JSGC_DISABLE_POISONING': '1', 03:28:34 INFO - 'LANG': 'en_US.UTF-8', 03:28:34 INFO - 'LANGUAGE': 'en_US:en', 03:28:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:28:34 INFO - 'LOGNAME': 'cltbld', 03:28:34 INFO - 'MAIL': '/var/mail/cltbld', 03:28:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:28:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:28:34 INFO - 'MOZ_CRASHREPORTER': '1', 03:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:28:34 INFO - 'MOZ_NO_REMOTE': '1', 03:28:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:28:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:34 INFO - 'NO_EM_RESTART': '1', 03:28:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:34 INFO - 'PWD': '/builds/slave/test', 03:28:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:28:34 INFO - 'RUST_BACKTRACE': 'full', 03:28:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:28:34 INFO - 'SHELL': '/bin/bash', 03:28:34 INFO - 'SHLVL': '1', 03:28:34 INFO - 'TERM': 'linux', 03:28:34 INFO - 'TMOUT': '86400', 03:28:34 INFO - 'USER': 'cltbld', 03:28:34 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:34 INFO - '_': '/tools/buildbot/bin/python'} 03:28:34 INFO - TEST-INFO | started process 9323 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiVzurm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:28:35 INFO - PID 9323 | 03:28:35 INFO - PID 9323 | (/builds/slave/test/build/application/firefox/firefox:9373): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:28:35 INFO - PID 9323 | 03:28:45 INFO - PID 9323 | RSS: Main: 165146624 03:28:45 INFO - PID 9323 | 03:31:41 INFO - PID 9323 | Cycle 1(1): loaded http://localhost:43802/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43802/tests/dromaeo/cssquery-ext.html) 03:31:43 INFO - PID 9323 | RSS: Main: 168861696 03:31:43 INFO - PID 9323 | 03:34:45 INFO - PID 9323 | Cycle 1(1): loaded http://localhost:43802/tests/dromaeo/cssquery-ext.html (next: http://localhost:43802/tests/dromaeo/cssquery-jquery.html) 03:34:45 INFO - PID 9323 | RSS: Main: 164638720 03:34:45 INFO - PID 9323 | 03:37:47 INFO - PID 9323 | Cycle 1(1): loaded http://localhost:43802/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43802/tests/dromaeo/cssquery-mootools.html) 03:37:47 INFO - PID 9323 | RSS: Main: 167333888 03:37:47 INFO - PID 9323 | 03:40:49 INFO - PID 9323 | Cycle 1(1): loaded http://localhost:43802/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43802/tests/dromaeo/cssquery-prototype.html) 03:40:50 INFO - PID 9323 | RSS: Main: 166023168 03:40:50 INFO - PID 9323 | 03:43:53 INFO - PID 9323 | Cycle 1(1): loaded http://localhost:43802/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43802/tests/dromaeo/cssquery-yui.html) 03:43:53 INFO - PID 9323 | RSS: Main: 167198720 03:43:53 INFO - PID 9323 | 03:46:55 INFO - PID 9323 | Cycle 1(1): loaded http://localhost:43802/tests/dromaeo/cssquery-yui.html (next: http://localhost:43802/tests/dromaeo/cssquery-dojo.html) 03:46:55 INFO - PID 9323 | RSS: Main: 170270720 03:46:55 INFO - PID 9323 | 03:46:55 INFO - PID 9323 | __start_tp_report 03:46:55 INFO - PID 9323 | _x_x_mozilla_page_load 03:46:55 INFO - PID 9323 | _x_x_mozilla_page_load_details 03:46:55 INFO - PID 9323 | |i|pagename|runs| 03:46:55 INFO - PID 9323 | |0;dojo.html;3834.8657796977113;3835.6164383561645;3839.8272077756496;3846.307664620367;3858.807059647017;6129.601575897564;6163.876722465548;6253.499720022399;6285.371462853718;6339.11252424661;6249.875002499947;6253.874922501559;6354.555181137311;6385.648789316586;6436.871262574746;5586.748596313156;5684.488396044355;5723.599348045638;5732.7133643317875;5734.111212762008;231.8011262722086;5419.3225846769155;5617.494425501704;5628.577856660746;5728.14077888318;5624.803131890405;5636.273044344794;5702.743376548045;5706.258186435757;5724.9427505724825;5648.717564121777;5713.457221563945;5727.341355744101;5734.541236701096;5735.655860648374;5641.887162256773;5650.943490565083;5732.22614946981;5733.741981610838;5741.799037033726;6221.937780622181;6286.937130628681;6289.8742025159245;6338.0492926061;6386;6680.665966701646;6745;6746.966265168692;6748.696308666122;6759;6881.449484041264;6885.345892140239;6892.689828957709;6913.619750913697;6916;6264.59280146789;6354.110424540522;6399.136116624242;6404;6407.487401007908;6979.057827193264;7089;7092.645367731593;7096.574205547684;7102.573845569283;6745.561538499982;6769.153855768029;6824.658767061627;6828.453723702092;6831.863362732717;6723.630200339028;6788.66056697173;6810.863782724317;6864.176298844171;6931.792046238621;6816.488763342769;6817.352351526597;6826.4538836892925;6845.760398386033;6847.486438517131;6784.151981002375;6806.353396427331;6899.310068993061;6912.343327383991;6914.1702995639835;4915.336429582032;4993.325901003391;5038.244263360525;5054.570361519312;5060.493950604984;4410.691251612357;4493.460784705856;4507.661925355612;4544.318352247189;4558.202314594932;6196.721147548371;6202.844928876814;6242.563020588606;6243.500519958483;6251.093591429218;6785.93214067853;6787.592744435349;6789.1174147360525;6815.93184068153;6863.10779598659;6068.514518838482;6127.9588746314175;6148.907766383508;6209.0996805462955;6225.750969961241;5472.945270547323;5482.506574408322;5482.5613950883835;5485.725713714378;5494.038543254915;6974.197967233732;7004.019437278785;7022.052022976833;7031.261717519689;7031.8242043948285;6733.898991515033;6801.761938332134;6802.795916122524;6924.584524928419;6927.965360173167;6921.515493915377;6937.9653101736185;6978.267281935324;7012.509124361246;7032.331928466788;6736.259011508828;6772.52592318533;6822.420094292041;6823.522353435212;6834.9316506836285;6700.731970721116;6825.726970921108;6845.794626161223;6855.862882742417;6932.48006399512;6537.901931470937;6559.770408035696;6573;6586.934130658632;6591.242007169237;6548.116004339544;6555.475561954937;6565.704543295467;6571.507136964651;6579.407853293226;6474.870502589828;6474.967625161844;6479.254885688206;6483.610983341015;6506.284308725942;6547.70535325902;6612.338766123349;6622.801315960529;6628.900566491603;6709.765158219634;6423.100765892872;6428.871422571616;6490.3185165558825;6521.67391630426;6525 03:46:55 INFO - PID 9323 | |1;ext.html;953.5232383808096;984.3896783993924;984.443789259069;986.16668914767;987.1757082835832;41401.17197656045;45532.77233613832;45594.316085258746;45686.314705279445;45939.310910336375;42378.15243695124;42549.7872510637;42561.93595160115;42635.573644263546;42798.358024629575;7597.936288919555;7692.961535192316;7716.652750626207;8172.101068882418;8317.459365141276;7555.848883022336;7622.847543049135;7651.579163146025;7670.463067585271;7673.731419400322;7605.695772169107;7627.809304767397;7635.465517413755;7644.273793989562;7667.8849817252785;7612.162662107136;7622.923770762277;7623.542587444744;7664.425168112386;7678.155402905676;8163.346932245406;8223.835523289501;8228.30059444948;8243;8250.216229458192;40203;40252.39621405681;40287;40458.19083618311;40518.59481405178;3759.906002349936;3777.1501412182315;3778.7165962552917;3802.353599887998;3803;33294.16764580905;33298.16754581131;33514.83242583772;33555.49666755;33591.32817343664;5380.569554435636;5389.164679474688;5456.83629491112;5482.80810171646;5512.145617429305;217462;220981;221011;221224;222062;46333;53040;53099;53229.46770532322;53236.201456978175;49562.50437495651;56198.43801562014;57242.42757572371;57460;57461.13808292879;17186;17280.395186168676;17342.219600118024;17350.219240134225;17361.392351267645;13960.371783269777;14031.719365612631;14102.647433814032;14104.153750774987;14116.153030818185;15182.46861359847;15203.695926081416;15213.326534081241;15341.309641066178;15377.154256516;6130.559318560135;6168.568200225941;6202.875942481125;6213.875722485525;6285;1104.618906477264;1108.7893300272922;1117.9161562882653;1118.6923596011;1123.9494222759995;26154.6076808848;26223.606645900327;26227.73772262253;26380;26568.468630627278;1208.1965492947093;1222.3643705273207;1227.3863068465766;1241.6709571963436;1250.4560516175493;13578.796318055238;13643.249621270726;13864;13930.233837139054;14020.298985050911;277500;278126;278301;279049;279281;59033;59186.40813591895;59563.80872382615;59641;59656.70171649114;1503.6317799465885;1525.6414742535496;1547.5434746749736;1556.820965588949;1561.7657351397381;24867.502649947262;25188.681123922266;25251.242462726146;25317.367065823502;25433.87283063647;42117.78941105275;42219.57780422157;42304.78847605742;42369.36445953375;42456.1508769829;235848;237127;237174;237388;237628;32591.18522036873;33155.33689326248;33183.502247465825;33207.50188747219;36526.63473365339;40533.59466405416;41361.58638413698;41709.374359384026;41791.58208417877;41798.79100604599;30734.538981915735;30831.383372332875;30878.22804429907;31220.843895780377;31348.37303253968;31163.532547012266;31202;31546.211344715648;31667.36665266728;31713.524297136024;35602.287954241285;36228.63771362358;36311.27377452489;36542.63457365392;36615.45076823903;25696.871515642302;26186.476270474104;26255.4748905017;26586.468270634865;26911.327216819736;23439.414014649086;23490;23767.762322377246;24023.75976240215;24104.035838566262 03:46:55 INFO - PID 9323 | |2;jquery.html;564.4412032088234;567.5714835299349;587.8559752860547;606.7694276175052;612.4029071655136;23357.299281021555;24019.75980240197;24116.397090072736;24158.637620435664;24166.154184603543;25213.74786252137;26182.345441363974;26335.473290534177;26406.207813765617;26421.207363779118;6641.634710090955;6782.287859774727;6799.388055075042;6815.284395138513;6816.079829223066;6507.3492650735025;6577.24361698406;6674.532782705212;6677;6677.966610166943;6698.229703584101;6702.731890724365;6704.161979752531;6705.932940670604;6711.765088221913;6695.3639404257;6698.598084114944;6699.296573859748;6704.329567043281;6705.832354191136;5757.395473475267;5918.556108291852;5954.07711804671;6251.4061164189325;6262.185915830913;29117.41765164706;29582.408351832844;29623.703762962527;29648.8517557413;29663.851680741674;2190.113004233272;2213.380253529014;2215.966760498594;2222.2111150541486;2224.588451136545;19270.518237044045;19697;19899.403017909342;19992.20031198765;20187.39437816868;2414.770596793302;2418.6372044193336;2472.3819045238693;2474.5545801755675;2480.305514455937;139183;144601.99999999895;152914;156121;162073.9999999988;15422.460213892684;15431.459898903484;15736.291866866019;15988.520344389686;16228.675426491403;25345.61981570278;25386.365340866625;25427.74572254254;25445.745542544708;25476.108336208144;10470.654678308569;10769.515371808287;10769.892301076889;10938.507767150497;10977.377465436386;10000.149987251165;10009.549570269355;10105.898941010642;10156.339837910513;10197.388156710622;9394.718158455258;9503.57233924475;9516.19112375442;9636.855447168298;9682.370645907993;2751.5322395192866;2810.747032767061;2827.7596404305864;2850.7861910356805;2898.231968528342;1024.2420608749471;1034.4982683398546;1041.7864337810731;1047.711879233217;1053.2469284461495;21038.368848934555;21062.157513699585;21107.472313191985;21429.78570214309;21653.78346216549;1085.10478854945;1086.6522712732008;1107.7175320293275;1110.2616759854768;1113.2318700096907;11515.424228788526;11588.826167607493;11604.361760103107;11728.12039097054;11729.530818767324;635173;644388;645477;653480;669347;127691;129900.3504982488;130817;132019;132621;1080.2654195147218;1135.0295497349648;1205.6503613951857;1206.4269472000906;1247.4448870252832;21855.672164917858;22113.00491477855;22304.55020423667;22333.330000100024;22441.32676019722;62954.68522657357;63045.36954630395;63638.36361636325;64526.677366612865;65729.67135164485;74513.62743186466;77120.22879771354;78051.60974195093;78308;79129;15195.468158614396;15387.307571159101;15453.5363939082;15491.690166196839;15528;24006.75993240045;24209.273721788373;24230.273091807274;24342.75657243405;24547.877260613583;19594.706079408534;19661.50846228856;19812.70280945758;19939;20095.397138085882;17245.741313880553;17422.912885435493;17523.649527009133;17542.298308067533;17791.822081779534;19990.600187995868;20162.89918550398;20166.29417970364;20169.596608067463;20319.695204571646;14970.401183952956;15046.54860354191;15070.773938390714;15091.54725358241;15111.924440377728;18801.623967520845;19636.116374762885;19890.602187955872;20068.19727210895;20092.292155166975 03:46:55 INFO - PID 9323 | |3;mootools.html;1425.4013314407948;1473.3664524254573;1477.1580199286411;1480.8445113263103;1487.107735358784;16546.83453165468;17172.141392930334;17180.39868604599;17182.57043573911;17197.7420338695;16707;17056.914715426406;17922.37271695491;18002.909985450053;18047.548811279692;2826.3075546491036;2899.4201159768027;2902.1873875314946;2903.3322335862763;2916.023132250688;2843.9857800710965;2853.671827739816;2869.3830826372327;2926.6926972667884;2927.92680182995;2843.147055883237;2867.197184788263;2885.4229154169143;2886.278430392402;2888.812227205225;2694.4476382341572;2727.2091093582835;2734.125079974409;2737.835729856205;2749.7250274972544;2701.095133130384;2709.6883858356346;2720;2732.5901114832736;2742.616033755256;16283.755743663854;16551.337946482126;16575.668486630322;16643.667126657398;16669.49991500245;8527.786805329853;8704.825903481895;8759.16787905148;8855.512946787923;8872.822543549093;5667.4615911488345;5817.854553636151;5859.8535036624;5862.384449632812;6047.0627052806885;2030.5634288627869;2060.701198326234;2076.8650037747498;2106.968395474069;2116.7459904811367;30638.38723225523;30926.690733092608;30933.917312894166;30976.845115774504;30982.225444363845;7287.817804554928;7311.415086793044;7419.517731347445;7527.059117610299;7542.396608271325;19843.206271749255;19878.204871805254;19890.801091988895;19940.20239190445;19945;8522.48865068098;8647.481151130953;8672.089430609694;8676.175763302475;8677.435966662148;7765.262300081483;7803.687852485837;7902.881456778153;7966.6415011324625;7981.241782030698;5717.62835415705;5832.679202643811;5852.561057920673;5889.204957330791;5926.222133359975;1565.1782814022501;1590.363854458203;1621.408186012097;1696.8218337074677;1701.4045084220425;962.0812124421161;965.9178969787506;975.4781192062354;992.8758905136858;999.8750156230471;9612.327137100336;9862.063104005108;10027.197824174049;10032.448215348226;10060.798784024279;1052.4948024947914;1098.8296813993843;1102.2614848051664;1111.4887151910211;1113.9721506962392;5617.8033768817895;5663.631863928831;6147.66187859663;6204.937950620526;6269;24042.51914961691;24762.2571322857;24945.501089978097;25112.623310650353;25207.36981575475;24023.75976240215;24822.875885620455;24887.62668559973;24911.501769964863;25142.24573262805;11986.400679966142;12397.690057748629;12429.875701243052;12515.812262816065;12527.87472125267;9603.231741460806;9639.855402169112;9665;9738.318317717692;9822.803543929223;20496.487587810425;20604;21507.784922151204;21608.891955540123;21710.674339884597;21325.6801147986;21583;21638.459038524164;21806.56386872222;21865.67201491947;12155.392230388623;12445.62663120108;12462.688432789253;12483.438245279165;12485.250884946932;15568.299426525607;15615.9849609775;15680.05919644825;15751.842481575037;15754.133522656364;12408.379581021094;12564.371781411075;12586.937065314614;12670.239785612892;12905.225686458842;12070.155089143676;12098.637040888787;12321.691957701129;12637.873621264038;12750.808737868754;11128.109751219721;11238.438078095898;11373.14701397415;11416.429178541206;11430.714232144262;8470.534120623424;8519.574021299035;8543.829123417621;8558.27254683334;8589.184027517376;7519.924800751923;7576.356009739014;7584.924150758642;7688.769336919901;7730.6907723692675 03:46:55 INFO - PID 9323 | |4;prototype.html;533.9893202135955;563.915412688097;567.2682239910515;568.5508448325821;571.2316933724143;6364.363563643633;6414.967925160374;6426.325235850239;6443.261613814122;6510.283868774431;6226;6290.685465726719;6340.144080549125;6357.872842543146;6437.553729582752;5126.179811230204;5184.662996905208;5228.712420816853;5240.580753539708;5247.527722504974;4855.805767769285;5106.64253502255;5198.68807871527;5235.424103348629;5240.716024573966;4962.106820772259;4985.376827896513;4987.600991920638;5003.924941125868;5005.17414626588;4945.752712364385;4991.550760431579;5015.247712843066;5041.571466425358;5048.419431765357;4486.980858613637;4525.751083690396;4537.659675524316;4549.818007279705;4552.408186935677;6139.201903752528;6211.130441738161;6273.184486016834;6290.62256264621;6333.145025421555;5898.174255604176;5919.644821310735;5922.911156332659;5928.614640048426;5949.2266005419015;6557.409833114994;6558.21301443825;6579.440747536465;6636.402723754885;6658.833529161761;5020.924686129711;5041.344625198701;5052.191649336089;5062.139436295838;5063.923791245608;6767.39093481589;6899.862002759916;6930.480213983971;6932.688029038705;6984.161900571913;5141.048905952336;5146.871328216825;5157.865269640673;5198.584113270929;5233.267342572043;5217.660852044605;5235.3717553893775;5272.9736351318;5274.3143391359645;5296.973515132399;5837.737301821428;5892.440218179266;5918.704064796743;5937.732802023919;5942.722314702273;6802.489813264025;6815.556988795713;6846.349596788297;6848.315168483112;6884.173899132136;1834.5780470491861;1843.5391152211946;1846.9261229550668;1848.7226915962713;1851.092964447411;1239.2007155384906;1288.6005338345144;1292.1213574769058;1292.9030322725832;1293.411497768513;6115.755369785248;6154.907676384768;6156.322804491502;6181.567290289637;6187.81436556895;6126.448619624255;6754.425873800684;6770.796876093626;6811.557248778912;6882.174139103239;6108.786192483241;6135.355787642323;6137.171481849893;6153;6222.315545290015;3552.8223588820974;3586.3544561979093;3591.94612080819;3598.208394153282;3604.116991337139;6864.176298844171;6927.549709268881;6946.687399067054;6961.164660240703;7007.474439417064;6828.385445309946;6831.555948863407;6842.623655698885;6895.172579290417;6928.44572434204;6793.694283757242;6900.861982760317;6929.604493798002;6973.616451095137;6998.860022799414;6754.5609535380045;6773.661316934232;6788.793642066249;6825.931740682529;6852.6231057292325;6768.357006084414;6771;6772.530130114101;6780.69486873082;6826.146731658543;6793.388595026471;6859.451243900576;6894.276101009322;6932.7226910923;6993.125859267591;5165.509276618715;5168.508991645787;5185.910958698715;5191.61062920275;5226.503482169188;6640.435562977201;6741.52809303344;6781.525293229624;6825.839607266677;6904.344087311697;6550.868982620225;6558.901616475852;6564.737410503526;6611.140551728245;6637.469002479694;6515.934840651533;6543.803685889431;6588.5058620604605;6594.439472644879;6638.137042184485;6498.415142637185;6521.706523206372;6526.748271846205;6567.770128045496;6585;6623.271440141484;6649.800505984828;6684.331566843277;6688.699008544724;6717.395434410926;6460;6486.3513648636645;6497.4477169441125;6518.185226846645;6522.434615692295 03:46:55 INFO - PID 9323 | |5;yui.html;930.4510338900047;971.6210677835643;982.6069572171128;982.764136607214;983.2723784399547;17554.648907021852;17920.64158716828;17949.910250448764;17993.640127197446;18001.27994880205;16659.66680666386;16680.83319166808;16751.66496670066;16891.915540422313;16911.323547058102;1067.909227715643;1094.3871431998066;1094.781043791241;1094.956201751929;1095.3263742798163;1060.4591658254308;1079.5196137718717;1083.0793825248554;1087.8477013218155;1089.3409487260212;1160.2168536238005;1172.5134069361204;1173.7124404520862;1173.7945859474557;1174.131142954212;1164.470166074434;1166.1720178673152;1169.082270417718;1170.373849990251;1171.4786919820683;803.3091541274499;819.2749416766145;830.2735106781566;831.5052543736467;831.9542425166613;18143.364982225692;18161;18224.817751822444;18295.817041829676;18382.264709411742;20081.598368032704;20443.89778051115;20586.176552937864;20900.999999999847;20953.89523052375;17034.233459494353;17075.487735367835;17135.22891469887;17279.740803887827;17507.649847003115;3822.675072618803;3835.61643835617;3844.3656796628525;3845.8461661533784;3850.133719912997;147419;150883;151494;154170;157608.21195893947;17098.743518847226;17342.04618745956;17401.129943502776;17499.650007000044;17508.47474575765;34107.4883876742;34221.65778342234;34369.14077148091;34381.65618343835;34396.65603343934;7517.624118794039;7723.845523089507;7797.181295963842;7866.370690344758;7879.6848126075465;4355.760433176142;4482.013956929471;4490.191765482179;4498.482674492409;4502.572255635775;5993.0710739835395;6065.272167339947;6077.483413909778;6093;6099.085137229451;1024.666983230453;1086.7120213143523;1090.2150451674781;1090.9290896091888;1097.6597254851022;709.1100668660798;733.4828945593343;738.2322384719938;743.5501521579366;757.0499023725299;11931.1051671125;12088.09339299556;12253.877461225451;12495.437705303102;12659.113862029753;686.7287421468592;700.1702981966387;700.6881937537747;708.0370695853635;713.3579778199662;5438.510534051954;5497.917531237034;5521.171824226398;5541.445855414507;5550.666959982414;145391.27304363623;146371;147383.2630836839;151935;154590;101411.49294253481;103027.4848625752;119976.00059998395;127192.36403818107;137801;108824;112414;113428.99999999836;113961;117920;12464.5014199431;12761.680957976125;12931.612051638465;13009.609711708665;13424.261665608095;52880.942381152934;53416.198757019454;53417;54275;54444.727776360865;66458.66770666116;66939;66980.33019669741;67122.32877671356;70750.29249707643;19299.131539081056;19726;19776.901115494908;19937.501562461053;20017.59964800725;21173.682394763782;21254.36236912895;21258.681119782905;21263.149474020865;21394.893025535395;18826.6234675303;18841.15214815309;18879.528011799266;18901.716474252622;19099.90450047741;15575.76636350433;15692.450764223197;15693.686126277182;15821.208939553206;15917.204139792733;17788.733169002735;17952.91023544874;18032.909835451264;18069.367572134914;18099.819001809814;13510.189388636712;13630.659233519242;13674.93162534181;13931.721365572834;14008.019438639605;12950.870491294967;13097;13133;13265.801012985006;13345 03:46:55 INFO - PID 9323 | __end_tp_report 03:46:55 INFO - PID 9323 | __start_cc_report 03:46:55 INFO - PID 9323 | _x_x_mozilla_cycle_collect,358 03:46:55 INFO - PID 9323 | __end_cc_report 03:46:55 INFO - PID 9323 | __startTimestamp1505472415571__endTimestamp 03:46:55 INFO - PID 9323 | 03:46:55 INFO - PID 9323 | ------- Summary: start ------- 03:46:55 INFO - PID 9323 | Number of tests: 6 03:46:55 INFO - PID 9323 | 03:46:55 INFO - PID 9323 | [#0] dojo.html Cycles:165 Average:6235.87 Median:6506.28 stddev:880.22 (13.5%) stddev-sans-first:862.53 03:46:55 INFO - PID 9323 | Values: 3834.9 3835.6 3839.8 3846.3 3858.8 6129.6 6163.9 6253.5 6285.4 6339.1 6249.9 6253.9 6354.6 6385.6 6436.9 5586.7 5684.5 5723.6 5732.7 5734.1 231.8 5419.3 5617.5 5628.6 5728.1 5624.8 5636.3 5702.7 5706.3 5724.9 5648.7 5713.5 5727.3 5734.5 5735.7 5641.9 5650.9 5732.2 5733.7 5741.8 6221.9 6286.9 6289.9 6338.0 6386.0 6680.7 6745.0 6747.0 6748.7 6759.0 6881.4 6885.3 6892.7 6913.6 6916.0 6264.6 6354.1 6399.1 6404.0 6407.5 6979.1 7089.0 7092.6 7096.6 7102.6 6745.6 6769.2 6824.7 6828.5 6831.9 6723.6 6788.7 6810.9 6864.2 6931.8 6816.5 6817.4 6826.5 6845.8 6847.5 6784.2 6806.4 6899.3 6912.3 6914.2 4915.3 4993.3 5038.2 5054.6 5060.5 4410.7 4493.5 4507.7 4544.3 4558.2 6196.7 6202.8 6242.6 6243.5 6251.1 6785.9 6787.6 6789.1 6815.9 6863.1 6068.5 6128.0 6148.9 6209.1 6225.8 5472.9 5482.5 5482.6 5485.7 5494.0 6974.2 7004.0 7022.1 7031.3 7031.8 6733.9 6801.8 6802.8 6924.6 6928.0 6921.5 6938.0 6978.3 7012.5 7032.3 6736.3 6772.5 6822.4 6823.5 6834.9 6700.7 6825.7 6845.8 6855.9 6932.5 6537.9 6559.8 6573.0 6586.9 6591.2 6548.1 6555.5 6565.7 6571.5 6579.4 6474.9 6475.0 6479.3 6483.6 6506.3 6547.7 6612.3 6622.8 6628.9 6709.8 6423.1 6428.9 6490.3 6521.7 6525.0 03:46:55 INFO - PID 9323 | 03:46:55 INFO - PID 9323 | [#1] ext.html Cycles:180 Average:41710.97 Median:36269.96 stddev:64174.08 (176.9%) stddev-sans-first:64281.14 03:46:55 INFO - PID 9323 | Values: 953.5 984.4 984.4 986.2 987.2 41401.2 45532.8 45594.3 45686.3 45939.3 42378.2 42549.8 42561.9 42635.6 42798.4 7597.9 7693.0 7716.7 8172.1 8317.5 7555.8 7622.8 7651.6 7670.5 7673.7 7605.7 7627.8 7635.5 7644.3 7667.9 7612.2 7622.9 7623.5 7664.4 7678.2 8163.3 8223.8 8228.3 8243.0 8250.2 40203.0 40252.4 40287.0 40458.2 40518.6 3759.9 3777.2 3778.7 3802.4 3803.0 33294.2 33298.2 33514.8 33555.5 33591.3 5380.6 5389.2 5456.8 5482.8 5512.1 217462.0 220981.0 221011.0 221224.0 222062.0 46333.0 53040.0 53099.0 53229.5 53236.2 49562.5 56198.4 57242.4 57460.0 57461.1 17186.0 17280.4 17342.2 17350.2 17361.4 13960.4 14031.7 14102.6 14104.2 14116.2 15182.5 15203.7 15213.3 15341.3 15377.2 6130.6 6168.6 6202.9 6213.9 6285.0 1104.6 1108.8 1117.9 1118.7 1123.9 26154.6 26223.6 26227.7 26380.0 26568.5 1208.2 1222.4 1227.4 1241.7 1250.5 13578.8 13643.2 13864.0 13930.2 14020.3 277500.0 278126.0 278301.0 279049.0 279281.0 59033.0 59186.4 59563.8 59641.0 59656.7 1503.6 1525.6 1547.5 1556.8 1561.8 24867.5 25188.7 25251.2 25317.4 25433.9 42117.8 42219.6 42304.8 42369.4 42456.2 235848.0 237127.0 237174.0 237388.0 237628.0 32591.2 33155.3 33183.5 33207.5 36526.6 40533.6 41361.6 41709.4 41791.6 41798.8 30734.5 30831.4 30878.2 31220.8 31348.4 31163.5 31202.0 31546.2 31667.4 31713.5 35602.3 36228.6 36311.3 36542.6 36615.5 25696.9 26186.5 26255.5 26586.5 26911.3 23439.4 23490.0 23767.8 24023.8 24104.0 03:46:55 INFO - PID 9323 | 03:46:55 INFO - PID 9323 | [#2] jquery.html Cycles:180 Average:40967.46 Median:2223.40 stddev:108218.52 (4867.3%) stddev-sans-first:108479.58 03:46:55 INFO - PID 9323 | Values: 564.4 567.6 587.9 606.8 612.4 23357.3 24019.8 24116.4 24158.6 24166.2 25213.7 26182.3 26335.5 26406.2 26421.2 6641.6 6782.3 6799.4 6815.3 6816.1 6507.3 6577.2 6674.5 6677.0 6678.0 6698.2 6702.7 6704.2 6705.9 6711.8 6695.4 6698.6 6699.3 6704.3 6705.8 5757.4 5918.6 5954.1 6251.4 6262.2 29117.4 29582.4 29623.7 29648.9 29663.9 2190.1 2213.4 2216.0 2222.2 2224.6 19270.5 19697.0 19899.4 19992.2 20187.4 2414.8 2418.6 2472.4 2474.6 2480.3 139183.0 144602.0 152914.0 156121.0 162074.0 15422.5 15431.5 15736.3 15988.5 16228.7 25345.6 25386.4 25427.7 25445.7 25476.1 10470.7 10769.5 10769.9 10938.5 10977.4 10000.1 10009.5 10105.9 10156.3 10197.4 9394.7 9503.6 9516.2 9636.9 9682.4 2751.5 2810.7 2827.8 2850.8 2898.2 1024.2 1034.5 1041.8 1047.7 1053.2 21038.4 21062.2 21107.5 21429.8 21653.8 1085.1 1086.7 1107.7 1110.3 1113.2 11515.4 11588.8 11604.4 11728.1 11729.5 635173.0 644388.0 645477.0 653480.0 669347.0 127691.0 129900.4 130817.0 132019.0 132621.0 1080.3 1135.0 1205.7 1206.4 1247.4 21855.7 22113.0 22304.6 22333.3 22441.3 62954.7 63045.4 63638.4 64526.7 65729.7 74513.6 77120.2 78051.6 78308.0 79129.0 15195.5 15387.3 15453.5 15491.7 15528.0 24006.8 24209.3 24230.3 24342.8 24547.9 19594.7 19661.5 19812.7 19939.0 20095.4 17245.7 17422.9 17523.6 17542.3 17791.8 19990.6 20162.9 20166.3 20169.6 20319.7 14970.4 15046.5 15070.8 15091.5 15111.9 18801.6 19636.1 19890.6 20068.2 20092.3 03:46:55 INFO - PID 9323 | 03:46:55 INFO - PID 9323 | [#3] mootools.html Cycles:180 Average:10516.58 Median:25127.43 stddev:7711.25 (30.7%) stddev-sans-first:7702.63 03:46:55 INFO - PID 9323 | Values: 1425.4 1473.4 1477.2 1480.8 1487.1 16546.8 17172.1 17180.4 17182.6 17197.7 16707.0 17056.9 17922.4 18002.9 18047.5 2826.3 2899.4 2902.2 2903.3 2916.0 2844.0 2853.7 2869.4 2926.7 2927.9 2843.1 2867.2 2885.4 2886.3 2888.8 2694.4 2727.2 2734.1 2737.8 2749.7 2701.1 2709.7 2720.0 2732.6 2742.6 16283.8 16551.3 16575.7 16643.7 16669.5 8527.8 8704.8 8759.2 8855.5 8872.8 5667.5 5817.9 5859.9 5862.4 6047.1 2030.6 2060.7 2076.9 2107.0 2116.7 30638.4 30926.7 30933.9 30976.8 30982.2 7287.8 7311.4 7419.5 7527.1 7542.4 19843.2 19878.2 19890.8 19940.2 19945.0 8522.5 8647.5 8672.1 8676.2 8677.4 7765.3 7803.7 7902.9 7966.6 7981.2 5717.6 5832.7 5852.6 5889.2 5926.2 1565.2 1590.4 1621.4 1696.8 1701.4 962.1 965.9 975.5 992.9 999.9 9612.3 9862.1 10027.2 10032.4 10060.8 1052.5 1098.8 1102.3 1111.5 1114.0 5617.8 5663.6 6147.7 6204.9 6269.0 24042.5 24762.3 24945.5 25112.6 25207.4 24023.8 24822.9 24887.6 24911.5 25142.2 11986.4 12397.7 12429.9 12515.8 12527.9 9603.2 9639.9 9665.0 9738.3 9822.8 20496.5 20604.0 21507.8 21608.9 21710.7 21325.7 21583.0 21638.5 21806.6 21865.7 12155.4 12445.6 12462.7 12483.4 12485.3 15568.3 15616.0 15680.1 15751.8 15754.1 12408.4 12564.4 12586.9 12670.2 12905.2 12070.2 12098.6 12321.7 12637.9 12750.8 11128.1 11238.4 11373.1 11416.4 11430.7 8470.5 8519.6 8543.8 8558.3 8589.2 7519.9 7576.4 7584.9 7688.8 7730.7 03:46:55 INFO - PID 9323 | 03:46:55 INFO - PID 9323 | [#4] prototype.html Cycles:180 Average:5661.65 Median:6290.65 stddev:1575.60 (25.0%) stddev-sans-first:1532.30 03:46:55 INFO - PID 9323 | Values: 534.0 563.9 567.3 568.6 571.2 6364.4 6415.0 6426.3 6443.3 6510.3 6226.0 6290.7 6340.1 6357.9 6437.6 5126.2 5184.7 5228.7 5240.6 5247.5 4855.8 5106.6 5198.7 5235.4 5240.7 4962.1 4985.4 4987.6 5003.9 5005.2 4945.8 4991.6 5015.2 5041.6 5048.4 4487.0 4525.8 4537.7 4549.8 4552.4 6139.2 6211.1 6273.2 6290.6 6333.1 5898.2 5919.6 5922.9 5928.6 5949.2 6557.4 6558.2 6579.4 6636.4 6658.8 5020.9 5041.3 5052.2 5062.1 5063.9 6767.4 6899.9 6930.5 6932.7 6984.2 5141.0 5146.9 5157.9 5198.6 5233.3 5217.7 5235.4 5273.0 5274.3 5297.0 5837.7 5892.4 5918.7 5937.7 5942.7 6802.5 6815.6 6846.3 6848.3 6884.2 1834.6 1843.5 1846.9 1848.7 1851.1 1239.2 1288.6 1292.1 1292.9 1293.4 6115.8 6154.9 6156.3 6181.6 6187.8 6126.4 6754.4 6770.8 6811.6 6882.2 6108.8 6135.4 6137.2 6153.0 6222.3 3552.8 3586.4 3591.9 3598.2 3604.1 6864.2 6927.5 6946.7 6961.2 7007.5 6828.4 6831.6 6842.6 6895.2 6928.4 6793.7 6900.9 6929.6 6973.6 6998.9 6754.6 6773.7 6788.8 6825.9 6852.6 6768.4 6771.0 6772.5 6780.7 6826.1 6793.4 6859.5 6894.3 6932.7 6993.1 5165.5 5168.5 5185.9 5191.6 5226.5 6640.4 6741.5 6781.5 6825.8 6904.3 6550.9 6558.9 6564.7 6611.1 6637.5 6515.9 6543.8 6588.5 6594.4 6638.1 6498.4 6521.7 6526.7 6567.8 6585.0 6623.3 6649.8 6684.3 6688.7 6717.4 6460.0 6486.4 6497.4 6518.2 6522.4 03:46:55 INFO - PID 9323 | 03:46:55 INFO - PID 9323 | [#5] yui.html Cycles:180 Average:27182.65 Median:17997.46 stddev:40603.33 (225.6%) stddev-sans-first:40669.38 03:46:55 INFO - PID 9323 | Values: 930.5 971.6 982.6 982.8 983.3 17554.6 17920.6 17949.9 17993.6 18001.3 16659.7 16680.8 16751.7 16891.9 16911.3 1067.9 1094.4 1094.8 1095.0 1095.3 1060.5 1079.5 1083.1 1087.8 1089.3 1160.2 1172.5 1173.7 1173.8 1174.1 1164.5 1166.2 1169.1 1170.4 1171.5 803.3 819.3 830.3 831.5 832.0 18143.4 18161.0 18224.8 18295.8 18382.3 20081.6 20443.9 20586.2 20901.0 20953.9 17034.2 17075.5 17135.2 17279.7 17507.6 3822.7 3835.6 3844.4 3845.8 3850.1 147419.0 150883.0 151494.0 154170.0 157608.2 17098.7 17342.0 17401.1 17499.7 17508.5 34107.5 34221.7 34369.1 34381.7 34396.7 7517.6 7723.8 7797.2 7866.4 7879.7 4355.8 4482.0 4490.2 4498.5 4502.6 5993.1 6065.3 6077.5 6093.0 6099.1 1024.7 1086.7 1090.2 1090.9 1097.7 709.1 733.5 738.2 743.6 757.0 11931.1 12088.1 12253.9 12495.4 12659.1 686.7 700.2 700.7 708.0 713.4 5438.5 5497.9 5521.2 5541.4 5550.7 145391.3 146371.0 147383.3 151935.0 154590.0 101411.5 103027.5 119976.0 127192.4 137801.0 108824.0 112414.0 113429.0 113961.0 117920.0 12464.5 12761.7 12931.6 13009.6 13424.3 52880.9 53416.2 53417.0 54275.0 54444.7 66458.7 66939.0 66980.3 67122.3 70750.3 19299.1 19726.0 19776.9 19937.5 20017.6 21173.7 21254.4 21258.7 21263.1 21394.9 18826.6 18841.2 18879.5 18901.7 19099.9 15575.8 15692.5 15693.7 15821.2 15917.2 17788.7 17952.9 18032.9 18069.4 18099.8 13510.2 13630.7 13674.9 13931.7 14008.0 12950.9 13097.0 13133.0 13265.8 13345.0 03:46:55 INFO - PID 9323 | -------- Summary: end -------- 03:46:55 INFO - PID 9323 | 03:46:56 INFO - TEST-INFO | 9323: exit 0 03:46:56 INFO - TEST-OK | dromaeo_css | took 1108631ms 03:46:56 INFO - TEST-START | kraken 03:46:56 INFO - Initialising browser for kraken test... 03:46:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43802/getInfo.html -profile /tmp/tmp9exwpo/profile 03:46:56 INFO - TEST-INFO | started process 9696 (/builds/slave/test/build/application/firefox/firefox http://localhost:43802/getInfo.html) 03:47:02 INFO - TEST-INFO | 9696: exit 0 03:47:02 INFO - Browser initialized. 03:47:02 INFO - Running cycle 1/1 for kraken test... 03:47:02 INFO - Using env: {'DISPLAY': ':0', 03:47:02 INFO - 'HOME': '/home/cltbld', 03:47:02 INFO - 'JSGC_DISABLE_POISONING': '1', 03:47:02 INFO - 'LANG': 'en_US.UTF-8', 03:47:02 INFO - 'LANGUAGE': 'en_US:en', 03:47:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:47:02 INFO - 'LOGNAME': 'cltbld', 03:47:02 INFO - 'MAIL': '/var/mail/cltbld', 03:47:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:02 INFO - 'MOZ_CRASHREPORTER': '1', 03:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:47:02 INFO - 'MOZ_NO_REMOTE': '1', 03:47:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:02 INFO - 'NO_EM_RESTART': '1', 03:47:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:02 INFO - 'PWD': '/builds/slave/test', 03:47:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:02 INFO - 'RUST_BACKTRACE': 'full', 03:47:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:02 INFO - 'SHELL': '/bin/bash', 03:47:02 INFO - 'SHLVL': '1', 03:47:02 INFO - 'TERM': 'linux', 03:47:02 INFO - 'TMOUT': '86400', 03:47:02 INFO - 'USER': 'cltbld', 03:47:02 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144', 03:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:02 INFO - '_': '/tools/buildbot/bin/python'} 03:47:02 INFO - TEST-INFO | started process 9932 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9exwpo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:03 INFO - PID 9932 | 03:47:03 INFO - PID 9932 | (/builds/slave/test/build/application/firefox/firefox:9981): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:47:03 INFO - PID 9932 | 03:47:13 INFO - PID 9932 | RSS: Main: 164974592 03:47:13 INFO - PID 9932 | 03:47:16 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43802/tests/kraken/driver.html?testName=audio-beat-detection) 03:47:17 INFO - PID 9932 | RSS: Main: 170553344 03:47:17 INFO - PID 9932 | 03:47:19 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43802/tests/kraken/driver.html?testName=audio-dft) 03:47:20 INFO - PID 9932 | RSS: Main: 170504192 03:47:20 INFO - PID 9932 | 03:47:23 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43802/tests/kraken/driver.html?testName=audio-fft) 03:47:24 INFO - PID 9932 | RSS: Main: 165601280 03:47:24 INFO - PID 9932 | 03:47:26 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43802/tests/kraken/driver.html?testName=audio-oscillator) 03:47:26 INFO - PID 9932 | RSS: Main: 167038976 03:47:26 INFO - PID 9932 | 03:47:28 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43802/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:47:29 INFO - PID 9932 | RSS: Main: 167305216 03:47:29 INFO - PID 9932 | 03:47:32 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43802/tests/kraken/driver.html?testName=imaging-darkroom) 03:47:33 INFO - PID 9932 | RSS: Main: 170577920 03:47:33 INFO - PID 9932 | 03:47:36 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43802/tests/kraken/driver.html?testName=imaging-desaturate) 03:47:37 INFO - PID 9932 | RSS: Main: 170012672 03:47:37 INFO - PID 9932 | 03:47:40 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43802/tests/kraken/driver.html?testName=json-parse-financial) 03:47:40 INFO - PID 9932 | RSS: Main: 170233856 03:47:40 INFO - PID 9932 | 03:47:42 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43802/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:47:43 INFO - PID 9932 | RSS: Main: 170250240 03:47:43 INFO - PID 9932 | 03:47:44 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43802/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:47:45 INFO - PID 9932 | RSS: Main: 170102784 03:47:45 INFO - PID 9932 | 03:47:47 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43802/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:47:48 INFO - PID 9932 | RSS: Main: 167542784 03:47:48 INFO - PID 9932 | 03:47:50 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43802/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:47:51 INFO - PID 9932 | RSS: Main: 167612416 03:47:51 INFO - PID 9932 | 03:47:54 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43802/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:47:54 INFO - PID 9932 | RSS: Main: 167960576 03:47:54 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | Cycle 1(1): loaded http://localhost:43802/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43802/tests/kraken/driver.html?testName=ai-astar) 03:47:56 INFO - PID 9932 | RSS: Main: 169697280 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | __start_tp_report 03:47:56 INFO - PID 9932 | _x_x_mozilla_page_load 03:47:56 INFO - PID 9932 | _x_x_mozilla_page_load_details 03:47:56 INFO - PID 9932 | |i|pagename|runs| 03:47:56 INFO - PID 9932 | |0;ai-astar;105;105;104;106;106;92;112;106;104;107 03:47:56 INFO - PID 9932 | |1;audio-beat-detection;111;111;112;115;111;116;111;115;110;137 03:47:56 INFO - PID 9932 | |2;audio-dft;164;160;162;161;161;161;162;163;163;166 03:47:56 INFO - PID 9932 | |3;audio-fft;73;73;74;71;72;74;72;73;74;73 03:47:56 INFO - PID 9932 | |4;audio-oscillator;78;78;77;78;78;77;78;77;77;77 03:47:56 INFO - PID 9932 | |5;imaging-gaussian-blur;109;113;113;113;110;113;113;114;114;114 03:47:56 INFO - PID 9932 | |6;imaging-darkroom;150;157;152;150;150;150;148;150;150;151 03:47:56 INFO - PID 9932 | |7;imaging-desaturate;94;93;94;94;94;94;94;94;93;94 03:47:56 INFO - PID 9932 | |8;json-parse-financial;48;48;43;44;47;46;45;46;44;43 03:47:56 INFO - PID 9932 | |9;json-stringify-tinderbox;38;38;38;38;39;38;38;39;39;39 03:47:56 INFO - PID 9932 | |10;stanford-crypto-aes;72;71;71;72;71;72;71;72;72;70 03:47:56 INFO - PID 9932 | |11;stanford-crypto-ccm;103;113;106;105;108;116;107;104;118;109 03:47:56 INFO - PID 9932 | |12;stanford-crypto-pbkdf2;183;153;155;155;158;153;148;155;150;152 03:47:56 INFO - PID 9932 | |13;stanford-crypto-sha256-iterative;62;60;62;58;61;60;59;58;58;60 03:47:56 INFO - PID 9932 | __end_tp_report 03:47:56 INFO - PID 9932 | __start_cc_report 03:47:56 INFO - PID 9932 | _x_x_mozilla_cycle_collect,916 03:47:56 INFO - PID 9932 | __end_cc_report 03:47:56 INFO - PID 9932 | __startTimestamp1505472476579__endTimestamp 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | ------- Summary: start ------- 03:47:56 INFO - PID 9932 | Number of tests: 14 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#0] ai-astar Cycles:10 Average:104.70 Median:106.00 stddev:5.01 (4.7%) stddev-sans-first:5.32 03:47:56 INFO - PID 9932 | Values: 105.0 105.0 104.0 106.0 106.0 92.0 112.0 106.0 104.0 107.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#1] audio-beat-detection Cycles:10 Average:114.90 Median:113.50 stddev:8.05 (7.1%) stddev-sans-first:8.41 03:47:56 INFO - PID 9932 | Values: 111.0 111.0 112.0 115.0 111.0 116.0 111.0 115.0 110.0 137.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:1.77 (1.1%) stddev-sans-first:1.76 03:47:56 INFO - PID 9932 | Values: 164.0 160.0 162.0 161.0 161.0 161.0 162.0 163.0 163.0 166.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#3] audio-fft Cycles:10 Average:72.90 Median:73.00 stddev:0.99 (1.4%) stddev-sans-first:1.05 03:47:56 INFO - PID 9932 | Values: 73.0 73.0 74.0 71.0 72.0 74.0 72.0 73.0 74.0 73.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.00 stddev:0.53 (0.7%) stddev-sans-first:0.53 03:47:56 INFO - PID 9932 | Values: 78.0 78.0 77.0 78.0 78.0 77.0 78.0 77.0 77.0 77.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#5] imaging-gaussian-blur Cycles:10 Average:112.60 Median:113.00 stddev:1.71 (1.5%) stddev-sans-first:1.22 03:47:56 INFO - PID 9932 | Values: 109.0 113.0 113.0 113.0 110.0 113.0 113.0 114.0 114.0 114.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:150.00 stddev:2.39 (1.6%) stddev-sans-first:2.52 03:47:56 INFO - PID 9932 | Values: 150.0 157.0 152.0 150.0 150.0 150.0 148.0 150.0 150.0 151.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:47:56 INFO - PID 9932 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#8] json-parse-financial Cycles:10 Average:45.40 Median:46.00 stddev:1.90 (4.1%) stddev-sans-first:1.76 03:47:56 INFO - PID 9932 | Values: 48.0 48.0 43.0 44.0 47.0 46.0 45.0 46.0 44.0 43.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.53 03:47:56 INFO - PID 9932 | Values: 38.0 38.0 38.0 38.0 39.0 38.0 38.0 39.0 39.0 39.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#10] stanford-crypto-aes Cycles:10 Average:71.40 Median:72.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 03:47:56 INFO - PID 9932 | Values: 72.0 71.0 71.0 72.0 71.0 72.0 71.0 72.0 72.0 70.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#11] stanford-crypto-ccm Cycles:10 Average:108.90 Median:108.50 stddev:5.13 (4.7%) stddev-sans-first:4.98 03:47:56 INFO - PID 9932 | Values: 103.0 113.0 106.0 105.0 108.0 116.0 107.0 104.0 118.0 109.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.20 Median:155.00 stddev:9.83 (6.3%) stddev-sans-first:2.99 03:47:56 INFO - PID 9932 | Values: 183.0 153.0 155.0 155.0 158.0 153.0 148.0 155.0 150.0 152.0 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:60.00 stddev:1.55 (2.6%) stddev-sans-first:1.42 03:47:56 INFO - PID 9932 | Values: 62.0 60.0 62.0 58.0 61.0 60.0 59.0 58.0 58.0 60.0 03:47:56 INFO - PID 9932 | -------- Summary: end -------- 03:47:56 INFO - PID 9932 | 03:47:56 INFO - PID 9932 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:47:57 INFO - TEST-INFO | 9932: exit 0 03:47:57 INFO - TEST-OK | kraken | took 60913ms 03:47:57 INFO - SUITE-END | took 1169s 03:47:57 INFO - Completed test suite (00:19:29) 03:47:57 INFO - javascript benchmark 03:47:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8999.264379802376, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3834.8657796977113, 3835.6164383561645, 3839.8272077756496, 3846.307664620367, 3858.807059647017, 6129.601575897564, 6163.876722465548, 6253.499720022399, 6285.371462853718, 6339.11252424661, 6249.875002499947, 6253.874922501559, 6354.555181137311, 6385.648789316586, 6436.871262574746, 5586.748596313156, 5684.488396044355, 5723.599348045638, 5732.7133643317875, 5734.111212762008, 231.8011262722086, 5419.3225846769155, 5617.494425501704, 5628.577856660746, 5728.14077888318, 5624.803131890405, 5636.273044344794, 5702.743376548045, 5706.258186435757, 5724.9427505724825, 5648.717564121777, 5713.457221563945, 5727.341355744101, 5734.541236701096, 5735.655860648374, 5641.887162256773, 5650.943490565083, 5732.22614946981, 5733.741981610838, 5741.799037033726, 6221.937780622181, 6286.937130628681, 6289.8742025159245, 6338.0492926061, 6386.0, 6680.665966701646, 6745.0, 6746.966265168692, 6748.696308666122, 6759.0, 6881.449484041264, 6885.345892140239, 6892.689828957709, 6913.619750913697, 6916.0, 6264.59280146789, 6354.110424540522, 6399.136116624242, 6404.0, 6407.487401007908, 6979.057827193264, 7089.0, 7092.645367731593, 7096.574205547684, 7102.573845569283, 6745.561538499982, 6769.153855768029, 6824.658767061627, 6828.453723702092, 6831.863362732717, 6723.630200339028, 6788.66056697173, 6810.863782724317, 6864.176298844171, 6931.792046238621, 6816.488763342769, 6817.352351526597, 6826.4538836892925, 6845.760398386033, 6847.486438517131, 6784.151981002375, 6806.353396427331, 6899.310068993061, 6912.343327383991, 6914.1702995639835, 4915.336429582032, 4993.325901003391, 5038.244263360525, 5054.570361519312, 5060.493950604984, 4410.691251612357, 4493.460784705856, 4507.661925355612, 4544.318352247189, 4558.202314594932, 6196.721147548371, 6202.844928876814, 6242.563020588606, 6243.500519958483, 6251.093591429218, 6785.93214067853, 6787.592744435349, 6789.1174147360525, 6815.93184068153, 6863.10779598659, 6068.514518838482, 6127.9588746314175, 6148.907766383508, 6209.0996805462955, 6225.750969961241, 5472.945270547323, 5482.506574408322, 5482.5613950883835, 5485.725713714378, 5494.038543254915, 6974.197967233732, 7004.019437278785, 7022.052022976833, 7031.261717519689, 7031.8242043948285, 6733.898991515033, 6801.761938332134, 6802.795916122524, 6924.584524928419, 6927.965360173167, 6921.515493915377, 6937.9653101736185, 6978.267281935324, 7012.509124361246, 7032.331928466788, 6736.259011508828, 6772.52592318533, 6822.420094292041, 6823.522353435212, 6834.9316506836285, 6700.731970721116, 6825.726970921108, 6845.794626161223, 6855.862882742417, 6932.48006399512, 6537.901931470937, 6559.770408035696, 6573.0, 6586.934130658632, 6591.242007169237, 6548.116004339544, 6555.475561954937, 6565.704543295467, 6571.507136964651, 6579.407853293226, 6474.870502589828, 6474.967625161844, 6479.254885688206, 6483.610983341015, 6506.284308725942, 6547.70535325902, 6612.338766123349, 6622.801315960529, 6628.900566491603, 6709.765158219634, 6423.100765892872, 6428.871422571616, 6490.3185165558825, 6521.67391630426, 6525.0], "value": 6177.418504014903, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [953.5232383808096, 984.3896783993924, 984.443789259069, 986.16668914767, 987.1757082835832, 41401.17197656045, 45532.77233613832, 45594.316085258746, 45686.314705279445, 45939.310910336375, 42378.15243695124, 42549.7872510637, 42561.93595160115, 42635.573644263546, 42798.358024629575, 7597.936288919555, 7692.961535192316, 7716.652750626207, 8172.101068882418, 8317.459365141276, 7555.848883022336, 7622.847543049135, 7651.579163146025, 7670.463067585271, 7673.731419400322, 7605.695772169107, 7627.809304767397, 7635.465517413755, 7644.273793989562, 7667.8849817252785, 7612.162662107136, 7622.923770762277, 7623.542587444744, 7664.425168112386, 7678.155402905676, 8163.346932245406, 8223.835523289501, 8228.30059444948, 8243.0, 8250.216229458192, 40203.0, 40252.39621405681, 40287.0, 40458.19083618311, 40518.59481405178, 3759.906002349936, 3777.1501412182315, 3778.7165962552917, 3802.353599887998, 3803.0, 33294.16764580905, 33298.16754581131, 33514.83242583772, 33555.49666755, 33591.32817343664, 5380.569554435636, 5389.164679474688, 5456.83629491112, 5482.80810171646, 5512.145617429305, 217462.0, 220981.0, 221011.0, 221224.0, 222062.0, 46333.0, 53040.0, 53099.0, 53229.46770532322, 53236.201456978175, 49562.50437495651, 56198.43801562014, 57242.42757572371, 57460.0, 57461.13808292879, 17186.0, 17280.395186168676, 17342.219600118024, 17350.219240134225, 17361.392351267645, 13960.371783269777, 14031.719365612631, 14102.647433814032, 14104.153750774987, 14116.153030818185, 15182.46861359847, 15203.695926081416, 15213.326534081241, 15341.309641066178, 15377.154256516, 6130.559318560135, 6168.568200225941, 6202.875942481125, 6213.875722485525, 6285.0, 1104.618906477264, 1108.7893300272922, 1117.9161562882653, 1118.6923596011, 1123.9494222759995, 26154.6076808848, 26223.606645900327, 26227.73772262253, 26380.0, 26568.468630627278, 1208.1965492947093, 1222.3643705273207, 1227.3863068465766, 1241.6709571963436, 1250.4560516175493, 13578.796318055238, 13643.249621270726, 13864.0, 13930.233837139054, 14020.298985050911, 277500.0, 278126.0, 278301.0, 279049.0, 279281.0, 59033.0, 59186.40813591895, 59563.80872382615, 59641.0, 59656.70171649114, 1503.6317799465885, 1525.6414742535496, 1547.5434746749736, 1556.820965588949, 1561.7657351397381, 24867.502649947262, 25188.681123922266, 25251.242462726146, 25317.367065823502, 25433.87283063647, 42117.78941105275, 42219.57780422157, 42304.78847605742, 42369.36445953375, 42456.1508769829, 235848.0, 237127.0, 237174.0, 237388.0, 237628.0, 32591.18522036873, 33155.33689326248, 33183.502247465825, 33207.50188747219, 36526.63473365339, 40533.59466405416, 41361.58638413698, 41709.374359384026, 41791.58208417877, 41798.79100604599, 30734.538981915735, 30831.383372332875, 30878.22804429907, 31220.843895780377, 31348.37303253968, 31163.532547012266, 31202.0, 31546.211344715648, 31667.36665266728, 31713.524297136024, 35602.287954241285, 36228.63771362358, 36311.27377452489, 36542.63457365392, 36615.45076823903, 25696.871515642302, 26186.476270474104, 26255.4748905017, 26586.468270634865, 26911.327216819736, 23439.414014649086, 23490.0, 23767.762322377246, 24023.75976240215, 24104.035838566262], "value": 18173.63117259988, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.4412032088234, 567.5714835299349, 587.8559752860547, 606.7694276175052, 612.4029071655136, 23357.299281021555, 24019.75980240197, 24116.397090072736, 24158.637620435664, 24166.154184603543, 25213.74786252137, 26182.345441363974, 26335.473290534177, 26406.207813765617, 26421.207363779118, 6641.634710090955, 6782.287859774727, 6799.388055075042, 6815.284395138513, 6816.079829223066, 6507.3492650735025, 6577.24361698406, 6674.532782705212, 6677.0, 6677.966610166943, 6698.229703584101, 6702.731890724365, 6704.161979752531, 6705.932940670604, 6711.765088221913, 6695.3639404257, 6698.598084114944, 6699.296573859748, 6704.329567043281, 6705.832354191136, 5757.395473475267, 5918.556108291852, 5954.07711804671, 6251.4061164189325, 6262.185915830913, 29117.41765164706, 29582.408351832844, 29623.703762962527, 29648.8517557413, 29663.851680741674, 2190.113004233272, 2213.380253529014, 2215.966760498594, 2222.2111150541486, 2224.588451136545, 19270.518237044045, 19697.0, 19899.403017909342, 19992.20031198765, 20187.39437816868, 2414.770596793302, 2418.6372044193336, 2472.3819045238693, 2474.5545801755675, 2480.305514455937, 139183.0, 144601.99999999895, 152914.0, 156121.0, 162073.9999999988, 15422.460213892684, 15431.459898903484, 15736.291866866019, 15988.520344389686, 16228.675426491403, 25345.61981570278, 25386.365340866625, 25427.74572254254, 25445.745542544708, 25476.108336208144, 10470.654678308569, 10769.515371808287, 10769.892301076889, 10938.507767150497, 10977.377465436386, 10000.149987251165, 10009.549570269355, 10105.898941010642, 10156.339837910513, 10197.388156710622, 9394.718158455258, 9503.57233924475, 9516.19112375442, 9636.855447168298, 9682.370645907993, 2751.5322395192866, 2810.747032767061, 2827.7596404305864, 2850.7861910356805, 2898.231968528342, 1024.2420608749471, 1034.4982683398546, 1041.7864337810731, 1047.711879233217, 1053.2469284461495, 21038.368848934555, 21062.157513699585, 21107.472313191985, 21429.78570214309, 21653.78346216549, 1085.10478854945, 1086.6522712732008, 1107.7175320293275, 1110.2616759854768, 1113.2318700096907, 11515.424228788526, 11588.826167607493, 11604.361760103107, 11728.12039097054, 11729.530818767324, 635173.0, 644388.0, 645477.0, 653480.0, 669347.0, 127691.0, 129900.3504982488, 130817.0, 132019.0, 132621.0, 1080.2654195147218, 1135.0295497349648, 1205.6503613951857, 1206.4269472000906, 1247.4448870252832, 21855.672164917858, 22113.00491477855, 22304.55020423667, 22333.330000100024, 22441.32676019722, 62954.68522657357, 63045.36954630395, 63638.36361636325, 64526.677366612865, 65729.67135164485, 74513.62743186466, 77120.22879771354, 78051.60974195093, 78308.0, 79129.0, 15195.468158614396, 15387.307571159101, 15453.5363939082, 15491.690166196839, 15528.0, 24006.75993240045, 24209.273721788373, 24230.273091807274, 24342.75657243405, 24547.877260613583, 19594.706079408534, 19661.50846228856, 19812.70280945758, 19939.0, 20095.397138085882, 17245.741313880553, 17422.912885435493, 17523.649527009133, 17542.298308067533, 17791.822081779534, 19990.600187995868, 20162.89918550398, 20166.29417970364, 20169.596608067463, 20319.695204571646, 14970.401183952956, 15046.54860354191, 15070.773938390714, 15091.54725358241, 15111.924440377728, 18801.623967520845, 19636.116374762885, 19890.602187955872, 20068.19727210895, 20092.292155166975], "value": 12739.542014917024, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.4013314407948, 1473.3664524254573, 1477.1580199286411, 1480.8445113263103, 1487.107735358784, 16546.83453165468, 17172.141392930334, 17180.39868604599, 17182.57043573911, 17197.7420338695, 16707.0, 17056.914715426406, 17922.37271695491, 18002.909985450053, 18047.548811279692, 2826.3075546491036, 2899.4201159768027, 2902.1873875314946, 2903.3322335862763, 2916.023132250688, 2843.9857800710965, 2853.671827739816, 2869.3830826372327, 2926.6926972667884, 2927.92680182995, 2843.147055883237, 2867.197184788263, 2885.4229154169143, 2886.278430392402, 2888.812227205225, 2694.4476382341572, 2727.2091093582835, 2734.125079974409, 2737.835729856205, 2749.7250274972544, 2701.095133130384, 2709.6883858356346, 2720.0, 2732.5901114832736, 2742.616033755256, 16283.755743663854, 16551.337946482126, 16575.668486630322, 16643.667126657398, 16669.49991500245, 8527.786805329853, 8704.825903481895, 8759.16787905148, 8855.512946787923, 8872.822543549093, 5667.4615911488345, 5817.854553636151, 5859.8535036624, 5862.384449632812, 6047.0627052806885, 2030.5634288627869, 2060.701198326234, 2076.8650037747498, 2106.968395474069, 2116.7459904811367, 30638.38723225523, 30926.690733092608, 30933.917312894166, 30976.845115774504, 30982.225444363845, 7287.817804554928, 7311.415086793044, 7419.517731347445, 7527.059117610299, 7542.396608271325, 19843.206271749255, 19878.204871805254, 19890.801091988895, 19940.20239190445, 19945.0, 8522.48865068098, 8647.481151130953, 8672.089430609694, 8676.175763302475, 8677.435966662148, 7765.262300081483, 7803.687852485837, 7902.881456778153, 7966.6415011324625, 7981.241782030698, 5717.62835415705, 5832.679202643811, 5852.561057920673, 5889.204957330791, 5926.222133359975, 1565.1782814022501, 1590.363854458203, 1621.408186012097, 1696.8218337074677, 1701.4045084220425, 962.0812124421161, 965.9178969787506, 975.4781192062354, 992.8758905136858, 999.8750156230471, 9612.327137100336, 9862.063104005108, 10027.197824174049, 10032.448215348226, 10060.798784024279, 1052.4948024947914, 1098.8296813993843, 1102.2614848051664, 1111.4887151910211, 1113.9721506962392, 5617.8033768817895, 5663.631863928831, 6147.66187859663, 6204.937950620526, 6269.0, 24042.51914961691, 24762.2571322857, 24945.501089978097, 25112.623310650353, 25207.36981575475, 24023.75976240215, 24822.875885620455, 24887.62668559973, 24911.501769964863, 25142.24573262805, 11986.400679966142, 12397.690057748629, 12429.875701243052, 12515.812262816065, 12527.87472125267, 9603.231741460806, 9639.855402169112, 9665.0, 9738.318317717692, 9822.803543929223, 20496.487587810425, 20604.0, 21507.784922151204, 21608.891955540123, 21710.674339884597, 21325.6801147986, 21583.0, 21638.459038524164, 21806.56386872222, 21865.67201491947, 12155.392230388623, 12445.62663120108, 12462.688432789253, 12483.438245279165, 12485.250884946932, 15568.299426525607, 15615.9849609775, 15680.05919644825, 15751.842481575037, 15754.133522656364, 12408.379581021094, 12564.371781411075, 12586.937065314614, 12670.239785612892, 12905.225686458842, 12070.155089143676, 12098.637040888787, 12321.691957701129, 12637.873621264038, 12750.808737868754, 11128.109751219721, 11238.438078095898, 11373.14701397415, 11416.429178541206, 11430.714232144262, 8470.534120623424, 8519.574021299035, 8543.829123417621, 8558.27254683334, 8589.184027517376, 7519.924800751923, 7576.356009739014, 7584.924150758642, 7688.769336919901, 7730.6907723692675], "value": 7400.943229333135, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.9893202135955, 563.915412688097, 567.2682239910515, 568.5508448325821, 571.2316933724143, 6364.363563643633, 6414.967925160374, 6426.325235850239, 6443.261613814122, 6510.283868774431, 6226.0, 6290.685465726719, 6340.144080549125, 6357.872842543146, 6437.553729582752, 5126.179811230204, 5184.662996905208, 5228.712420816853, 5240.580753539708, 5247.527722504974, 4855.805767769285, 5106.64253502255, 5198.68807871527, 5235.424103348629, 5240.716024573966, 4962.106820772259, 4985.376827896513, 4987.600991920638, 5003.924941125868, 5005.17414626588, 4945.752712364385, 4991.550760431579, 5015.247712843066, 5041.571466425358, 5048.419431765357, 4486.980858613637, 4525.751083690396, 4537.659675524316, 4549.818007279705, 4552.408186935677, 6139.201903752528, 6211.130441738161, 6273.184486016834, 6290.62256264621, 6333.145025421555, 5898.174255604176, 5919.644821310735, 5922.911156332659, 5928.614640048426, 5949.2266005419015, 6557.409833114994, 6558.21301443825, 6579.440747536465, 6636.402723754885, 6658.833529161761, 5020.924686129711, 5041.344625198701, 5052.191649336089, 5062.139436295838, 5063.923791245608, 6767.39093481589, 6899.862002759916, 6930.480213983971, 6932.688029038705, 6984.161900571913, 5141.048905952336, 5146.871328216825, 5157.865269640673, 5198.584113270929, 5233.267342572043, 5217.660852044605, 5235.3717553893775, 5272.9736351318, 5274.3143391359645, 5296.973515132399, 5837.737301821428, 5892.440218179266, 5918.704064796743, 5937.732802023919, 5942.722314702273, 6802.489813264025, 6815.556988795713, 6846.349596788297, 6848.315168483112, 6884.173899132136, 1834.5780470491861, 1843.5391152211946, 1846.9261229550668, 1848.7226915962713, 1851.092964447411, 1239.2007155384906, 1288.6005338345144, 1292.1213574769058, 1292.9030322725832, 1293.411497768513, 6115.755369785248, 6154.907676384768, 6156.322804491502, 6181.567290289637, 6187.81436556895, 6126.448619624255, 6754.425873800684, 6770.796876093626, 6811.557248778912, 6882.174139103239, 6108.786192483241, 6135.355787642323, 6137.171481849893, 6153.0, 6222.315545290015, 3552.8223588820974, 3586.3544561979093, 3591.94612080819, 3598.208394153282, 3604.116991337139, 6864.176298844171, 6927.549709268881, 6946.687399067054, 6961.164660240703, 7007.474439417064, 6828.385445309946, 6831.555948863407, 6842.623655698885, 6895.172579290417, 6928.44572434204, 6793.694283757242, 6900.861982760317, 6929.604493798002, 6973.616451095137, 6998.860022799414, 6754.5609535380045, 6773.661316934232, 6788.793642066249, 6825.931740682529, 6852.6231057292325, 6768.357006084414, 6771.0, 6772.530130114101, 6780.69486873082, 6826.146731658543, 6793.388595026471, 6859.451243900576, 6894.276101009322, 6932.7226910923, 6993.125859267591, 5165.509276618715, 5168.508991645787, 5185.910958698715, 5191.61062920275, 5226.503482169188, 6640.435562977201, 6741.52809303344, 6781.525293229624, 6825.839607266677, 6904.344087311697, 6550.868982620225, 6558.901616475852, 6564.737410503526, 6611.140551728245, 6637.469002479694, 6515.934840651533, 6543.803685889431, 6588.5058620604605, 6594.439472644879, 6638.137042184485, 6498.415142637185, 6521.706523206372, 6526.748271846205, 6567.770128045496, 6585.0, 6623.271440141484, 6649.800505984828, 6684.331566843277, 6688.699008544724, 6717.395434410926, 6460.0, 6486.3513648636645, 6497.4477169441125, 6518.185226846645, 6522.434615692295], "value": 5207.1493081880335, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [930.4510338900047, 971.6210677835643, 982.6069572171128, 982.764136607214, 983.2723784399547, 17554.648907021852, 17920.64158716828, 17949.910250448764, 17993.640127197446, 18001.27994880205, 16659.66680666386, 16680.83319166808, 16751.66496670066, 16891.915540422313, 16911.323547058102, 1067.909227715643, 1094.3871431998066, 1094.781043791241, 1094.956201751929, 1095.3263742798163, 1060.4591658254308, 1079.5196137718717, 1083.0793825248554, 1087.8477013218155, 1089.3409487260212, 1160.2168536238005, 1172.5134069361204, 1173.7124404520862, 1173.7945859474557, 1174.131142954212, 1164.470166074434, 1166.1720178673152, 1169.082270417718, 1170.373849990251, 1171.4786919820683, 803.3091541274499, 819.2749416766145, 830.2735106781566, 831.5052543736467, 831.9542425166613, 18143.364982225692, 18161.0, 18224.817751822444, 18295.817041829676, 18382.264709411742, 20081.598368032704, 20443.89778051115, 20586.176552937864, 20900.999999999847, 20953.89523052375, 17034.233459494353, 17075.487735367835, 17135.22891469887, 17279.740803887827, 17507.649847003115, 3822.675072618803, 3835.61643835617, 3844.3656796628525, 3845.8461661533784, 3850.133719912997, 147419.0, 150883.0, 151494.0, 154170.0, 157608.21195893947, 17098.743518847226, 17342.04618745956, 17401.129943502776, 17499.650007000044, 17508.47474575765, 34107.4883876742, 34221.65778342234, 34369.14077148091, 34381.65618343835, 34396.65603343934, 7517.624118794039, 7723.845523089507, 7797.181295963842, 7866.370690344758, 7879.6848126075465, 4355.760433176142, 4482.013956929471, 4490.191765482179, 4498.482674492409, 4502.572255635775, 5993.0710739835395, 6065.272167339947, 6077.483413909778, 6093.0, 6099.085137229451, 1024.666983230453, 1086.7120213143523, 1090.2150451674781, 1090.9290896091888, 1097.6597254851022, 709.1100668660798, 733.4828945593343, 738.2322384719938, 743.5501521579366, 757.0499023725299, 11931.1051671125, 12088.09339299556, 12253.877461225451, 12495.437705303102, 12659.113862029753, 686.7287421468592, 700.1702981966387, 700.6881937537747, 708.0370695853635, 713.3579778199662, 5438.510534051954, 5497.917531237034, 5521.171824226398, 5541.445855414507, 5550.666959982414, 145391.27304363623, 146371.0, 147383.2630836839, 151935.0, 154590.0, 101411.49294253481, 103027.4848625752, 119976.00059998395, 127192.36403818107, 137801.0, 108824.0, 112414.0, 113428.99999999836, 113961.0, 117920.0, 12464.5014199431, 12761.680957976125, 12931.612051638465, 13009.609711708665, 13424.261665608095, 52880.942381152934, 53416.198757019454, 53417.0, 54275.0, 54444.727776360865, 66458.66770666116, 66939.0, 66980.33019669741, 67122.32877671356, 70750.29249707643, 19299.131539081056, 19726.0, 19776.901115494908, 19937.501562461053, 20017.59964800725, 21173.682394763782, 21254.36236912895, 21258.681119782905, 21263.149474020865, 21394.893025535395, 18826.6234675303, 18841.15214815309, 18879.528011799266, 18901.716474252622, 19099.90450047741, 15575.76636350433, 15692.450764223197, 15693.686126277182, 15821.208939553206, 15917.204139792733, 17788.733169002735, 17952.91023544874, 18032.909835451264, 18069.367572134914, 18099.819001809814, 13510.189388636712, 13630.659233519242, 13674.93162534181, 13931.721365572834, 14008.019438639605, 12950.870491294967, 13097.0, 13133.0, 13265.801012985006, 13345.0], "value": 9636.659801136826, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1369.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 104.0, 106.0, 106.0, 92.0, 112.0, 106.0, 104.0, 107.0], "value": 104.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 112.0, 115.0, 111.0, 116.0, 111.0, 115.0, 110.0, 137.0], "value": 114.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 162.0, 161.0, 161.0, 161.0, 162.0, 163.0, 163.0, 166.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 71.0, 72.0, 74.0, 72.0, 73.0, 74.0, 73.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 77.0, 78.0, 78.0, 77.0, 78.0, 77.0, 77.0, 77.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 113.0, 113.0, 110.0, 113.0, 113.0, 114.0, 114.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 157.0, 152.0, 150.0, 150.0, 150.0, 148.0, 150.0, 150.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 43.0, 44.0, 47.0, 46.0, 45.0, 46.0, 44.0, 43.0], "value": 45.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 39.0, 39.0, 39.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 71.0, 72.0, 71.0, 72.0, 71.0, 72.0, 72.0, 70.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 106.0, 105.0, 108.0, 116.0, 107.0, 104.0, 118.0, 109.0], "value": 108.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 153.0, 155.0, 155.0, 158.0, 153.0, 148.0, 155.0, 150.0, 152.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 62.0, 58.0, 61.0, 60.0, 59.0, 58.0, 58.0, 60.0], "value": 59.8, "unit": "score"}]}]} 03:47:57 INFO - Return code: 0 03:47:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:47:57 INFO - # TBPL SUCCESS # 03:47:57 INFO - Running post-action listener: _package_coverage_data 03:47:57 INFO - Running post-action listener: _resource_record_post_action 03:47:57 INFO - [mozharness: 2017-09-15 10:47:57.861824Z] Finished run-tests step (success) 03:47:57 INFO - Running post-run listener: _resource_record_post_run 03:47:57 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 12288; Write bytes: 392806400; Read time: 496; Write time: 513936 03:47:57 INFO - TinderboxPrint: CPU usage
13.1% 03:47:57 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 496 03:47:57 INFO - TinderboxPrint: I/O write bytes / time
392,806,400 / 513,936 03:47:57 INFO - TinderboxPrint: CPU idle
8,229.5 (86.9%) 03:47:57 INFO - TinderboxPrint: CPU user
1,188.5 (12.5%) 03:47:57 INFO - TinderboxPrint: Swap in / out
0 / 0 03:47:57 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 75231232; Read time: 472; Write time: 93720 03:47:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:47:57 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 8192; Write bytes: 317575168; Read time: 24; Write time: 420216 03:47:58 INFO - Running post-run listener: _upload_blobber_files 03:47:58 INFO - Blob upload gear active. 03:47:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:47:58 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 03:47:58 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'] 03:47:58 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 03:47:58 INFO - (blobuploader) - INFO - Open directory for files ... 03:47:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:47:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:48:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:48:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:48:00 INFO - (blobuploader) - INFO - Done attempting. 03:48:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:48:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:48:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:48:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:48:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:48:01 INFO - (blobuploader) - INFO - Done attempting. 03:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:48:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:48:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:48:02 INFO - (blobuploader) - INFO - Done attempting. 03:48:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:48:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:48:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:48:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:48:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:48:07 INFO - (blobuploader) - INFO - Done attempting. 03:48:07 INFO - (blobuploader) - INFO - Iteration through files over. 03:48:07 INFO - Return code: 0 03:48:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:48:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:48:07 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdb47b99676eecb9ffa3f5528f389d3e6c39c237091dc1c75e7acf72a2401f6dd70d18e6c49fa162a5d25604cce39a2314a344c7af0697426e01823a4a10479d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226d3e5be380eb3370d73529fff3b59b3fa204050a59394ed45ccf75595ce7a420b3aa47236a2c8e6bf4f45fb57ab744a23368bb93a32299b1037fc83ac494c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/978c81d532598175d14eabf3133ad2ae3c3adab7641ec33e61aedab64ac4cb0680defc772c08cd6add674cb4096835e257c47ddf9d041e10af8af8f6e6d44694", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/affb4d5fe5ab901d4a431f62f4e6e0ea8b5dfd4ee7437ef420be86fe044e949296a0722385688a1c7abffa4fd87183d4c0095f45bfe999a79b7ffae71e15844a"} 03:48:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:48:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:48:07 INFO - Contents: 03:48:07 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdb47b99676eecb9ffa3f5528f389d3e6c39c237091dc1c75e7acf72a2401f6dd70d18e6c49fa162a5d25604cce39a2314a344c7af0697426e01823a4a10479d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226d3e5be380eb3370d73529fff3b59b3fa204050a59394ed45ccf75595ce7a420b3aa47236a2c8e6bf4f45fb57ab744a23368bb93a32299b1037fc83ac494c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/978c81d532598175d14eabf3133ad2ae3c3adab7641ec33e61aedab64ac4cb0680defc772c08cd6add674cb4096835e257c47ddf9d041e10af8af8f6e6d44694", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/affb4d5fe5ab901d4a431f62f4e6e0ea8b5dfd4ee7437ef420be86fe044e949296a0722385688a1c7abffa4fd87183d4c0095f45bfe999a79b7ffae71e15844a"} 03:48:07 INFO - Running post-run listener: copy_logs_to_upload_dir 03:48:07 INFO - Copying logs to upload dir... 03:48:07 INFO - mkdir: /builds/slave/test/build/upload/logs 03:48:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.011266 ========= master_lag: 0.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-15 03:48:08.460089) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 03:48:08.464508) ========= 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=ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144 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/cdb47b99676eecb9ffa3f5528f389d3e6c39c237091dc1c75e7acf72a2401f6dd70d18e6c49fa162a5d25604cce39a2314a344c7af0697426e01823a4a10479d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226d3e5be380eb3370d73529fff3b59b3fa204050a59394ed45ccf75595ce7a420b3aa47236a2c8e6bf4f45fb57ab744a23368bb93a32299b1037fc83ac494c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/978c81d532598175d14eabf3133ad2ae3c3adab7641ec33e61aedab64ac4cb0680defc772c08cd6add674cb4096835e257c47ddf9d041e10af8af8f6e6d44694", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/affb4d5fe5ab901d4a431f62f4e6e0ea8b5dfd4ee7437ef420be86fe044e949296a0722385688a1c7abffa4fd87183d4c0095f45bfe999a79b7ffae71e15844a"} build_url:https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011642 build_url: 'https://queue.taskcluster.net/v1/task/UkW8pfz6SDugMp2heHwTHw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdb47b99676eecb9ffa3f5528f389d3e6c39c237091dc1c75e7acf72a2401f6dd70d18e6c49fa162a5d25604cce39a2314a344c7af0697426e01823a4a10479d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226d3e5be380eb3370d73529fff3b59b3fa204050a59394ed45ccf75595ce7a420b3aa47236a2c8e6bf4f45fb57ab744a23368bb93a32299b1037fc83ac494c1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/978c81d532598175d14eabf3133ad2ae3c3adab7641ec33e61aedab64ac4cb0680defc772c08cd6add674cb4096835e257c47ddf9d041e10af8af8f6e6d44694", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/affb4d5fe5ab901d4a431f62f4e6e0ea8b5dfd4ee7437ef420be86fe044e949296a0722385688a1c7abffa4fd87183d4c0095f45bfe999a79b7ffae71e15844a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 03:48:08.492948) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:48:08.500367) ========= 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=ba164b0a7b0ec7655860e956000001c1-1505470477.840195-938033144 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:48:08.549923) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 03:48:08.550236) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 03:48:08.550534) ========= ========= Total master_lag: 0.56 =========