builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-058 starttime: 1505674742.93 results: success (0) revision: 0408418cdef3a7ed4f72cdaaf5540b07b70021fd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:02.932165) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:02.932939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:02.933221) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:02.966912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:02.967180) ========= 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=0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-17 11:59:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2017-09-17 11:59:03 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:03.112361) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:03.112682) ========= 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=0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015265 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:03.162081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-17 11:59:03.162398) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0408418cdef3a7ed4f72cdaaf5540b07b70021fd --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 0408418cdef3a7ed4f72cdaaf5540b07b70021fd --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=0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-17 11:59:03,255 truncating revision to first 12 chars 2017-09-17 11:59:03,255 Setting DEBUG logging. 2017-09-17 11:59:03,255 attempt 1/10 2017-09-17 11:59:03,255 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0408418cdef3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-17 11:59:04,493 unpacking tar archive at: mozilla-central-0408418cdef3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.541272 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-17 11:59:04.719985) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:04.720294) ========= script_repo_revision: 0408418cdef3a7ed4f72cdaaf5540b07b70021fd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:04.720700) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:04.720968) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 11:59:04.734101) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-17 11:59:04.734387) ========= /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=0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:59:04 INFO - MultiFileLogger online at 20170917 11:59:04 in /builds/slave/test 11:59:04 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 11:59:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:59:04 INFO - {'append_to_log': False, 11:59:04 INFO - 'base_work_dir': '/builds/slave/test', 11:59:04 INFO - 'blob_upload_branch': 'Firefox', 11:59:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:59:04 INFO - 'branch': 'Firefox', 11:59:04 INFO - 'buildbot_json_path': 'buildprops.json', 11:59:04 INFO - 'code_coverage': False, 11:59:04 INFO - 'config_files': ('talos/linux_config.py',), 11:59:04 INFO - 'default_actions': ('clobber', 11:59:04 INFO - 'read-buildbot-config', 11:59:04 INFO - 'download-and-extract', 11:59:04 INFO - 'populate-webroot', 11:59:04 INFO - 'create-virtualenv', 11:59:04 INFO - 'install', 11:59:04 INFO - 'setup-mitmproxy', 11:59:04 INFO - 'run-tests'), 11:59:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:59:04 INFO - 'disable_ccov_upload': False, 11:59:04 INFO - 'disable_stylo': False, 11:59:04 INFO - 'download_minidump_stackwalk': True, 11:59:04 INFO - 'download_symbols': 'ondemand', 11:59:04 INFO - 'enable_stylo': False, 11:59:04 INFO - 'enable_webrender': False, 11:59:04 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:59:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:59:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:59:04 INFO - 'gecko_profile': False, 11:59:04 INFO - 'gecko_profile_interval': 0, 11:59:04 INFO - 'installer_path': 'installer.exe', 11:59:04 INFO - 'log_level': 'info', 11:59:04 INFO - 'log_name': 'talos', 11:59:04 INFO - 'log_to_console': True, 11:59:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:59:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:59:04 INFO - 'opt_config_files': (), 11:59:04 INFO - 'pip_index': False, 11:59:04 INFO - 'suite': 'dromaeojs-e10s', 11:59:04 INFO - 'system_bits': '32', 11:59:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:59:04 INFO - 'title': 'talos-linux64-ix-058', 11:59:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:59:04 INFO - 'use_talos_json': True, 11:59:04 INFO - 'verify': 'False', 11:59:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:59:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:59:04 INFO - 'work_dir': 'build'} 11:59:04 INFO - [mozharness: 2017-09-17 18:59:04.963696Z] Running clobber step. 11:59:04 INFO - Running pre-action listener: _resource_record_pre_action 11:59:04 INFO - Running main action method: clobber 11:59:04 INFO - rmtree: /builds/slave/test/build 11:59:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:59:06 INFO - Running post-action listener: _resource_record_post_action 11:59:06 INFO - [mozharness: 2017-09-17 18:59:06.426554Z] Finished clobber step (success) 11:59:06 INFO - [mozharness: 2017-09-17 18:59:06.426651Z] Running read-buildbot-config step. 11:59:06 INFO - Running pre-action listener: _resource_record_pre_action 11:59:06 INFO - Running main action method: read_buildbot_config 11:59:06 INFO - Using buildbot properties: 11:59:06 INFO - { 11:59:06 INFO - "project": "", 11:59:06 INFO - "product": "firefox", 11:59:06 INFO - "who": "ffxbld@noreply.mozilla.org", 11:59:06 INFO - "installer_path": "public/build/target.tar.bz2", 11:59:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:59:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:59:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 11:59:06 INFO - "stage_platform": "linux64-pgo", 11:59:06 INFO - "basedir": "/builds/slave/test", 11:59:06 INFO - "buildnumber": 192, 11:59:06 INFO - "platform": "ubuntu64_hw", 11:59:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:59:06 INFO - "slavebuilddir": "test", 11:59:06 INFO - "taskId": "aufyzwcZQZGpGhc787a0Dg", 11:59:06 INFO - "branch": "mozilla-central", 11:59:06 INFO - "script_repo_revision": "production", 11:59:06 INFO - "revision": "0408418cdef3a7ed4f72cdaaf5540b07b70021fd", 11:59:06 INFO - "slavename": "talos-linux64-ix-058", 11:59:06 INFO - "repo_path": "mozilla-central" 11:59:06 INFO - } 11:59:06 INFO - Finding installer, test and symbols from parent task. 11:59:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aufyzwcZQZGpGhc787a0Dg'}, attempt #1 11:59:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aufyzwcZQZGpGhc787a0Dg'}, attempt #1 11:59:07 INFO - Task dependencies: Xmaam2KfRC-lYYSkSPgMHw 11:59:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw'}, attempt #1 11:59:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.tar.bz2 11:59:07 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.test_packages.json 11:59:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.crashreporter-symbols.zip 11:59:07 INFO - Running post-action listener: _resource_record_post_action 11:59:07 INFO - [mozharness: 2017-09-17 18:59:07.769502Z] Finished read-buildbot-config step (success) 11:59:07 INFO - [mozharness: 2017-09-17 18:59:07.769718Z] Running download-and-extract step. 11:59:07 INFO - Running pre-action listener: _resource_record_pre_action 11:59:07 INFO - Running main action method: download_and_extract 11:59:07 INFO - mkdir: /builds/slave/test/build/tests 11:59:07 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')]} 11:59:07 INFO - trying https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.test_packages.json 11:59:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:59:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:59:09 INFO - Downloaded 989 bytes. 11:59:09 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:59:09 INFO - Using the following test package requirements: 11:59:09 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:59:09 INFO - u'common': [u'target.common.tests.zip'], 11:59:09 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:59:09 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:59:09 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:59:09 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:59:09 INFO - u'mozbase': [u'target.common.tests.zip'], 11:59:09 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:59:09 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:59:09 INFO - u'web-platform': [u'target.common.tests.zip', 11:59:09 INFO - u'target.web-platform.tests.tar.gz'], 11:59:09 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:59:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:59:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.common.tests.zip 11:59:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:59:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.common.tests.zip into memory 11:59:10 INFO - Content-Length response header: 55323490 11:59:10 INFO - Bytes received: 55323490 11:59:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.talos.tests.zip 11:59:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:59:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.talos.tests.zip into memory 11:59:21 INFO - Content-Length response header: 14508748 11:59:21 INFO - Bytes received: 14508748 11:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:22 INFO - trying https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.tar.bz2 11:59:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:59:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:59:25 INFO - Downloaded 62283561 bytes. 11:59:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.tar.bz2 11:59:25 INFO - mkdir: /builds/slave/test/properties 11:59:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:59:25 INFO - Writing to file /builds/slave/test/properties/build_url 11:59:25 INFO - Contents: 11:59:25 INFO - build_url:https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.tar.bz2 11:59:25 INFO - Running post-action listener: _resource_record_post_action 11:59:25 INFO - Running post-action listener: find_tests_for_verification 11:59:25 INFO - Running post-action listener: set_extra_try_arguments 11:59:25 INFO - [mozharness: 2017-09-17 18:59:25.631599Z] Finished download-and-extract step (success) 11:59:25 INFO - [mozharness: 2017-09-17 18:59:25.631697Z] Running populate-webroot step. 11:59:25 INFO - Running pre-action listener: _resource_record_pre_action 11:59:25 INFO - Running main action method: populate_webroot 11:59:25 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:59:25 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['tresize', 11:59:25 INFO - 'tcanvasmark']}, 11:59:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:59:25 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['dromaeo_css', 11:59:25 INFO - 'kraken']}, 11:59:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:59:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:59:25 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:59:25 INFO - 'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:59:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:59:25 INFO - 'tests': ['damp', 'tps']}, 11:59:25 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:59:25 INFO - 'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['damp', 'tps']}, 11:59:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:59:25 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['dromaeo_dom']}, 11:59:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:59:25 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['basic_compositor_video', 11:59:25 INFO - 'glvideo']}, 11:59:25 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:59:25 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:59:25 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:59:25 INFO - 'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['ts_paint_webext', 11:59:25 INFO - 'tp5o_webext']}, 11:59:25 INFO - 'other-e10s': {'tests': ['a11yr', 11:59:25 INFO - 'ts_paint', 11:59:25 INFO - 'tpaint', 11:59:25 INFO - 'sessionrestore', 11:59:25 INFO - 'sessionrestore_many_windows', 11:59:25 INFO - 'sessionrestore_no_auto_restore', 11:59:25 INFO - 'tabpaint']}, 11:59:25 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['a11yr', 11:59:25 INFO - 'ts_paint', 11:59:25 INFO - 'tpaint', 11:59:25 INFO - 'sessionrestore', 11:59:25 INFO - 'sessionrestore_many_windows', 11:59:25 INFO - 'sessionrestore_no_auto_restore', 11:59:25 INFO - 'tabpaint']}, 11:59:25 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:59:25 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:59:25 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['perf_reftest_singletons']}, 11:59:25 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['bloom_basic']}, 11:59:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:59:25 INFO - 'tsvgr_opacity', 11:59:25 INFO - 'tart', 11:59:25 INFO - 'tscrollx', 11:59:25 INFO - 'tsvg_static']}, 11:59:25 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['tsvgx', 11:59:25 INFO - 'tsvgr_opacity', 11:59:25 INFO - 'tart', 11:59:25 INFO - 'tscrollx', 11:59:25 INFO - 'tsvg_static']}, 11:59:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:59:25 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:59:25 INFO - 'talos_options': ['--disable-stylo'], 11:59:25 INFO - 'tests': ['tp5o']}, 11:59:25 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:59:25 INFO - 'talos_options': ['--mitmproxy', 11:59:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:59:25 INFO - '--firstNonBlankPaint'], 11:59:25 INFO - 'tests': ['tp6_google', 11:59:25 INFO - 'tp6_youtube', 11:59:25 INFO - 'tp6_amazon', 11:59:25 INFO - 'tp6_facebook']}, 11:59:25 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:59:25 INFO - 'talos_options': ['--disable-stylo', 11:59:25 INFO - '--mitmproxy', 11:59:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:59:25 INFO - '--firstNonBlankPaint'], 11:59:25 INFO - 'tests': ['tp6_google', 11:59:25 INFO - 'tp6_youtube', 11:59:25 INFO - 'tp6_amazon', 11:59:25 INFO - 'tp6_facebook']}, 11:59:25 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:59:25 INFO - 'talos_options': ['--stylo-threads=1', 11:59:25 INFO - '--mitmproxy', 11:59:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:59:25 INFO - '--firstNonBlankPaint'], 11:59:25 INFO - 'tests': ['tp6_google', 11:59:25 INFO - 'tp6_youtube', 11:59:25 INFO - 'tp6_amazon', 11:59:25 INFO - 'tp6_facebook']}, 11:59:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:59:25 INFO - 'talos_options': ['--xperf_path', 11:59:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:59:25 INFO - 'tests': ['tp5n']}, 11:59:25 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:59:25 INFO - 'talos_options': ['--disable-stylo', 11:59:25 INFO - '--xperf_path', 11:59:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:59:25 INFO - 'tests': ['tp5n']}}} 11:59:25 INFO - Running post-action listener: _resource_record_post_action 11:59:25 INFO - [mozharness: 2017-09-17 18:59:25.647377Z] Finished populate-webroot step (success) 11:59:25 INFO - [mozharness: 2017-09-17 18:59:25.647476Z] Running create-virtualenv step. 11:59:25 INFO - Running pre-action listener: _resource_record_pre_action 11:59:25 INFO - Running main action method: create_virtualenv 11:59:25 INFO - Creating virtualenv /builds/slave/test/build/venv 11:59:25 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:59:25 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:59:25 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:59:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:59:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:59:26 INFO - Installing distribute.............................................................................................................................................................................................done. 11:59:26 INFO - Installing pip...............done. 11:59:26 INFO - Return code: 0 11:59:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:59:26 INFO - Reading from file tmpfile_stdout 11:59:26 INFO - Output received: 11:59:26 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:59:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:59:26 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')]} 11:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:26 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')]} 11:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:26 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 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:26 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 11:59:26 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 11:59:26 INFO - Using env: {'DISPLAY': ':0', 11:59:26 INFO - 'HOME': '/home/cltbld', 11:59:26 INFO - 'LANG': 'en_US.UTF-8', 11:59:26 INFO - 'LANGUAGE': 'en_US:en', 11:59:26 INFO - 'LOGNAME': 'cltbld', 11:59:26 INFO - 'MAIL': '/var/mail/cltbld', 11:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:26 INFO - 'MOZ_NO_REMOTE': '1', 11:59:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:26 INFO - 'NO_EM_RESTART': '1', 11:59:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:26 INFO - 'PWD': '/builds/slave/test', 11:59:26 INFO - 'SHELL': '/bin/bash', 11:59:26 INFO - 'SHLVL': '1', 11:59:26 INFO - 'TERM': 'linux', 11:59:26 INFO - 'TMOUT': '86400', 11:59:26 INFO - 'USER': 'cltbld', 11:59:26 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:26 INFO - '_': '/tools/buildbot/bin/python'} 11:59:27 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:59:27 INFO - Downloading/unpacking pip>=1.5 11:59:27 INFO - Running setup.py egg_info for package pip 11:59:27 INFO - warning: no files found matching 'pip/cacert.pem' 11:59:27 INFO - warning: no files found matching '*.html' under directory 'docs' 11:59:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:59:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:59:27 INFO - Installing collected packages: pip 11:59:27 INFO - Found existing installation: pip 1.1 11:59:27 INFO - Uninstalling pip: 11:59:27 INFO - Successfully uninstalled pip 11:59:27 INFO - Running setup.py install for pip 11:59:27 INFO - warning: no files found matching 'pip/cacert.pem' 11:59:27 INFO - warning: no files found matching '*.html' under directory 'docs' 11:59:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:59:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:59:27 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:59:27 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:59:27 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:59:27 INFO - Successfully installed pip 11:59:27 INFO - Cleaning up... 11:59:27 INFO - Return code: 0 11:59:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:59: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')]} 11:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59: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')]} 11:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:27 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 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:27 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 11:59:27 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 11:59:27 INFO - Using env: {'DISPLAY': ':0', 11:59:27 INFO - 'HOME': '/home/cltbld', 11:59:27 INFO - 'LANG': 'en_US.UTF-8', 11:59:27 INFO - 'LANGUAGE': 'en_US:en', 11:59:27 INFO - 'LOGNAME': 'cltbld', 11:59:27 INFO - 'MAIL': '/var/mail/cltbld', 11:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:27 INFO - 'MOZ_NO_REMOTE': '1', 11:59:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:27 INFO - 'NO_EM_RESTART': '1', 11:59:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:27 INFO - 'PWD': '/builds/slave/test', 11:59:27 INFO - 'SHELL': '/bin/bash', 11:59:27 INFO - 'SHLVL': '1', 11:59:27 INFO - 'TERM': 'linux', 11:59:27 INFO - 'TMOUT': '86400', 11:59:27 INFO - 'USER': 'cltbld', 11:59:27 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:27 INFO - '_': '/tools/buildbot/bin/python'} 11:59:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:28 INFO - Downloading/unpacking psutil>=3.1.1 11:59:28 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 11:59:28 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 11:59:28 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 11:59:28 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 11:59:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:59:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:59:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:59:29 INFO - Installing collected packages: psutil 11:59:29 INFO - Running setup.py install for psutil 11:59:29 INFO - building 'psutil._psutil_linux' extension 11:59:29 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 11:59:30 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 11:59:30 INFO - building 'psutil._psutil_posix' extension 11:59:30 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 11:59:30 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 11:59:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:59:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:59:31 INFO - Successfully installed psutil 11:59:31 INFO - Cleaning up... 11:59:31 INFO - Return code: 0 11:59:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:59:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:59:31 INFO - Using env: {'DISPLAY': ':0', 11:59:31 INFO - 'HOME': '/home/cltbld', 11:59:31 INFO - 'LANG': 'en_US.UTF-8', 11:59:31 INFO - 'LANGUAGE': 'en_US:en', 11:59:31 INFO - 'LOGNAME': 'cltbld', 11:59:31 INFO - 'MAIL': '/var/mail/cltbld', 11:59:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:31 INFO - 'MOZ_NO_REMOTE': '1', 11:59:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:31 INFO - 'NO_EM_RESTART': '1', 11:59:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:31 INFO - 'PWD': '/builds/slave/test', 11:59:31 INFO - 'SHELL': '/bin/bash', 11:59:31 INFO - 'SHLVL': '1', 11:59:31 INFO - 'TERM': 'linux', 11:59:31 INFO - 'TMOUT': '86400', 11:59:31 INFO - 'USER': 'cltbld', 11:59:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:31 INFO - '_': '/tools/buildbot/bin/python'} 11:59:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:59:31 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 11:59:31 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 11:59:31 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 11:59:31 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 11:59:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:59:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:59:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:59:32 INFO - Installing collected packages: mozsystemmonitor 11:59:32 INFO - Running setup.py install for mozsystemmonitor 11:59:32 INFO - Successfully installed mozsystemmonitor 11:59:32 INFO - Cleaning up... 11:59:33 INFO - Return code: 0 11:59:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:59:33 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')]} 11:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:33 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')]} 11:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:33 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 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:33 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 11:59:33 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 11:59:33 INFO - Using env: {'DISPLAY': ':0', 11:59:33 INFO - 'HOME': '/home/cltbld', 11:59:33 INFO - 'LANG': 'en_US.UTF-8', 11:59:33 INFO - 'LANGUAGE': 'en_US:en', 11:59:33 INFO - 'LOGNAME': 'cltbld', 11:59:33 INFO - 'MAIL': '/var/mail/cltbld', 11:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:33 INFO - 'MOZ_NO_REMOTE': '1', 11:59:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:33 INFO - 'NO_EM_RESTART': '1', 11:59:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:33 INFO - 'PWD': '/builds/slave/test', 11:59:33 INFO - 'SHELL': '/bin/bash', 11:59:33 INFO - 'SHLVL': '1', 11:59:33 INFO - 'TERM': 'linux', 11:59:33 INFO - 'TMOUT': '86400', 11:59:33 INFO - 'USER': 'cltbld', 11:59:33 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:33 INFO - '_': '/tools/buildbot/bin/python'} 11:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:33 INFO - Downloading/unpacking jsonschema==2.5.1 11:59:33 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 11:59:33 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 11:59:33 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 11:59:33 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 11:59:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:59:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:59:34 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 11:59:34 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 11:59:34 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 11:59:34 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 11:59:34 INFO - Downloading functools32-3.2.3-2.tar.gz 11:59:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:59:34 INFO - warning: no files found matching '*.txt' 11:59:34 INFO - no previously-included directories found matching 'build' 11:59:34 INFO - no previously-included directories found matching 'dist' 11:59:34 INFO - no previously-included directories found matching '.git*' 11:59:34 INFO - Installing collected packages: jsonschema, functools32 11:59:34 INFO - Running setup.py install for functools32 11:59:34 INFO - warning: no files found matching '*.txt' 11:59:34 INFO - no previously-included directories found matching 'build' 11:59:34 INFO - no previously-included directories found matching 'dist' 11:59:34 INFO - no previously-included directories found matching '.git*' 11:59:34 INFO - Successfully installed jsonschema functools32 11:59:34 INFO - Cleaning up... 11:59:34 INFO - Return code: 0 11:59:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:59:34 INFO - Using env: {'DISPLAY': ':0', 11:59:34 INFO - 'HOME': '/home/cltbld', 11:59:34 INFO - 'LANG': 'en_US.UTF-8', 11:59:34 INFO - 'LANGUAGE': 'en_US:en', 11:59:34 INFO - 'LOGNAME': 'cltbld', 11:59:34 INFO - 'MAIL': '/var/mail/cltbld', 11:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:34 INFO - 'MOZ_NO_REMOTE': '1', 11:59:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:34 INFO - 'NO_EM_RESTART': '1', 11:59:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:34 INFO - 'PWD': '/builds/slave/test', 11:59:34 INFO - 'SHELL': '/bin/bash', 11:59:34 INFO - 'SHLVL': '1', 11:59:34 INFO - 'TERM': 'linux', 11:59:34 INFO - 'TMOUT': '86400', 11:59:34 INFO - 'USER': 'cltbld', 11:59:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:34 INFO - '_': '/tools/buildbot/bin/python'} 11:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:59:34 INFO - Cleaning up... 11:59:34 INFO - Return code: 0 11:59:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:59:34 INFO - Using env: {'DISPLAY': ':0', 11:59:34 INFO - 'HOME': '/home/cltbld', 11:59:34 INFO - 'LANG': 'en_US.UTF-8', 11:59:34 INFO - 'LANGUAGE': 'en_US:en', 11:59:34 INFO - 'LOGNAME': 'cltbld', 11:59:34 INFO - 'MAIL': '/var/mail/cltbld', 11:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:34 INFO - 'MOZ_NO_REMOTE': '1', 11:59:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:34 INFO - 'NO_EM_RESTART': '1', 11:59:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:34 INFO - 'PWD': '/builds/slave/test', 11:59:34 INFO - 'SHELL': '/bin/bash', 11:59:34 INFO - 'SHLVL': '1', 11:59:34 INFO - 'TERM': 'linux', 11:59:34 INFO - 'TMOUT': '86400', 11:59:34 INFO - 'USER': 'cltbld', 11:59:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:34 INFO - '_': '/tools/buildbot/bin/python'} 11:59:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:35 INFO - Downloading/unpacking blobuploader==1.2.4 11:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:36 INFO - Downloading blobuploader-1.2.4.tar.gz 11:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:59:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:59:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:36 INFO - Downloading docopt-0.6.1.tar.gz 11:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:59:36 INFO - Installing collected packages: blobuploader, requests, docopt 11:59:36 INFO - Running setup.py install for blobuploader 11:59:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:59:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:59:36 INFO - Running setup.py install for requests 11:59:37 INFO - Running setup.py install for docopt 11:59:37 INFO - Successfully installed blobuploader requests docopt 11:59:37 INFO - Cleaning up... 11:59:37 INFO - Return code: 0 11:59:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:37 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 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:37 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 11:59:37 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 11:59:37 INFO - Using env: {'DISPLAY': ':0', 11:59:37 INFO - 'HOME': '/home/cltbld', 11:59:37 INFO - 'LANG': 'en_US.UTF-8', 11:59:37 INFO - 'LANGUAGE': 'en_US:en', 11:59:37 INFO - 'LOGNAME': 'cltbld', 11:59:37 INFO - 'MAIL': '/var/mail/cltbld', 11:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:37 INFO - 'MOZ_NO_REMOTE': '1', 11:59:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:37 INFO - 'NO_EM_RESTART': '1', 11:59:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:37 INFO - 'PWD': '/builds/slave/test', 11:59:37 INFO - 'SHELL': '/bin/bash', 11:59:37 INFO - 'SHLVL': '1', 11:59:37 INFO - 'TERM': 'linux', 11:59:37 INFO - 'TMOUT': '86400', 11:59:37 INFO - 'USER': 'cltbld', 11:59:37 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:37 INFO - '_': '/tools/buildbot/bin/python'} 11:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:59:37 INFO - Running setup.py (path:/tmp/pip-iLDJ6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:59:37 INFO - Running setup.py (path:/tmp/pip-ZLgfSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:59:37 INFO - Running setup.py (path:/tmp/pip-mpC7ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:59:37 INFO - Running setup.py (path:/tmp/pip-mvDfy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:59:37 INFO - Running setup.py (path:/tmp/pip-1kSFge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:59:38 INFO - Running setup.py (path:/tmp/pip-BX_0C0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:59:38 INFO - Running setup.py (path:/tmp/pip-C9mFUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:59:38 INFO - Running setup.py (path:/tmp/pip-dUmgxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:59:38 INFO - Running setup.py (path:/tmp/pip-ZRP48A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:59:38 INFO - Running setup.py (path:/tmp/pip-nfwp4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:59:38 INFO - Running setup.py (path:/tmp/pip-DhgSci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:59:38 INFO - Running setup.py (path:/tmp/pip-PoQePu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:59:38 INFO - Running setup.py (path:/tmp/pip-GjdcmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:59:38 INFO - Running setup.py (path:/tmp/pip-oXQG23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:38 INFO - Running setup.py (path:/tmp/pip-S2FZe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:59:38 INFO - Running setup.py (path:/tmp/pip-L0dOrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:59:38 INFO - Running setup.py (path:/tmp/pip-wVdV1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:59:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:59:38 INFO - Running setup.py install for manifestparser 11:59:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:59:38 INFO - Running setup.py install for mozcrash 11:59:39 INFO - Running setup.py install for mozdebug 11:59:39 INFO - Running setup.py install for mozdevice 11:59:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:59:39 INFO - Running setup.py install for mozfile 11:59:39 INFO - Running setup.py install for mozhttpd 11:59:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:59:39 INFO - Running setup.py install for mozinfo 11:59:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:59:39 INFO - Running setup.py install for mozInstall 11:59:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:59:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:59:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:59:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:59:39 INFO - Running setup.py install for mozleak 11:59:39 INFO - Running setup.py install for mozlog 11:59:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:59:39 INFO - Running setup.py install for moznetwork 11:59:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:59:39 INFO - Running setup.py install for mozprocess 11:59:40 INFO - Running setup.py install for mozprofile 11:59:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:59:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:59:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:59:40 INFO - Running setup.py install for mozrunner 11:59:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:59:40 INFO - Running setup.py install for mozscreenshot 11:59:40 INFO - Running setup.py install for moztest 11:59:40 INFO - Running setup.py install for mozversion 11:59:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:59:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:59:40 INFO - Cleaning up... 11:59:40 INFO - Return code: 0 11:59:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:40 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 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:40 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 11:59:40 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 11:59:40 INFO - Using env: {'DISPLAY': ':0', 11:59:40 INFO - 'HOME': '/home/cltbld', 11:59:40 INFO - 'LANG': 'en_US.UTF-8', 11:59:40 INFO - 'LANGUAGE': 'en_US:en', 11:59:40 INFO - 'LOGNAME': 'cltbld', 11:59:40 INFO - 'MAIL': '/var/mail/cltbld', 11:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:40 INFO - 'MOZ_NO_REMOTE': '1', 11:59:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:40 INFO - 'NO_EM_RESTART': '1', 11:59:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:40 INFO - 'PWD': '/builds/slave/test', 11:59:40 INFO - 'SHELL': '/bin/bash', 11:59:40 INFO - 'SHLVL': '1', 11:59:40 INFO - 'TERM': 'linux', 11:59:40 INFO - 'TMOUT': '86400', 11:59:40 INFO - 'USER': 'cltbld', 11:59:40 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:40 INFO - '_': '/tools/buildbot/bin/python'} 11:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:59:40 INFO - Running setup.py (path:/tmp/pip-lg3AES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:59:40 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)) 11:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:59:40 INFO - Running setup.py (path:/tmp/pip-5RehtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:59:40 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)) 11:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:59:40 INFO - Running setup.py (path:/tmp/pip-tiOevB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:59:41 INFO - Running setup.py (path:/tmp/pip-gieAd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:59:41 INFO - Running setup.py (path:/tmp/pip-NrOTQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:59:41 INFO - Running setup.py (path:/tmp/pip-QhzgTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:59:41 INFO - Running setup.py (path:/tmp/pip-GPt2Pz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:59:41 INFO - Running setup.py (path:/tmp/pip-dNp75F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:59:41 INFO - Running setup.py (path:/tmp/pip-A1M9J4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:59:41 INFO - Running setup.py (path:/tmp/pip-_drer8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:59:41 INFO - Running setup.py (path:/tmp/pip-wx8k7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:59:41 INFO - Running setup.py (path:/tmp/pip-AO8HR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:59:41 INFO - Running setup.py (path:/tmp/pip-vtjd3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:59:41 INFO - Running setup.py (path:/tmp/pip-UtD_qY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:41 INFO - Running setup.py (path:/tmp/pip-vF0QFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:59:41 INFO - Running setup.py (path:/tmp/pip-cSzy6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:59:41 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)) 11:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:59:41 INFO - Running setup.py (path:/tmp/pip-9DTrLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:59:42 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)) 11:59:42 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)) 11:59:42 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)) 11:59:42 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)) 11:59:42 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)) 11:59:42 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)) 11:59:42 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)) 11:59:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:59:42 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 11:59:42 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 11:59:42 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 11:59:42 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 11:59:43 INFO - Downloading blessings-1.6.tar.gz 11:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:59:43 INFO - Installing collected packages: blessings 11:59:43 INFO - Running setup.py install for blessings 11:59:43 INFO - Successfully installed blessings 11:59:43 INFO - Cleaning up... 11:59:43 INFO - Return code: 0 11:59:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:59:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:59:43 INFO - Reading from file tmpfile_stdout 11:59:43 INFO - Current package versions: 11:59:43 INFO - argparse == 1.2.1 11:59:43 INFO - blessings == 1.6 11:59:43 INFO - blobuploader == 1.2.4 11:59:43 INFO - docopt == 0.6.1 11:59:43 INFO - functools32 == 3.2.3-2 11:59:43 INFO - jsonschema == 2.5.1 11:59:43 INFO - manifestparser == 1.1 11:59:43 INFO - mozInstall == 1.13 11:59:43 INFO - mozcrash == 1.0 11:59:43 INFO - mozdebug == 0.1 11:59:43 INFO - mozdevice == 0.51 11:59:43 INFO - mozfile == 1.2 11:59:43 INFO - mozhttpd == 0.7 11:59:43 INFO - mozinfo == 0.10 11:59:43 INFO - mozleak == 0.1 11:59:43 INFO - mozlog == 3.5 11:59:43 INFO - moznetwork == 0.27 11:59:43 INFO - mozprocess == 0.25 11:59:43 INFO - mozprofile == 0.28 11:59:43 INFO - mozrunner == 6.13 11:59:43 INFO - mozscreenshot == 0.1 11:59:43 INFO - mozsystemmonitor == 0.3 11:59:43 INFO - moztest == 0.8 11:59:43 INFO - mozversion == 1.4 11:59:43 INFO - psutil == 3.1.1 11:59:43 INFO - requests == 1.2.3 11:59:43 INFO - wsgiref == 0.1.2 11:59:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:43 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 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:43 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 11:59:43 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 11:59:43 INFO - Using env: {'DISPLAY': ':0', 11:59:43 INFO - 'HOME': '/home/cltbld', 11:59:43 INFO - 'LANG': 'en_US.UTF-8', 11:59:43 INFO - 'LANGUAGE': 'en_US:en', 11:59:43 INFO - 'LOGNAME': 'cltbld', 11:59:43 INFO - 'MAIL': '/var/mail/cltbld', 11:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:43 INFO - 'MOZ_NO_REMOTE': '1', 11:59:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:43 INFO - 'NO_EM_RESTART': '1', 11:59:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:43 INFO - 'PWD': '/builds/slave/test', 11:59:43 INFO - 'SHELL': '/bin/bash', 11:59:43 INFO - 'SHLVL': '1', 11:59:43 INFO - 'TERM': 'linux', 11:59:43 INFO - 'TMOUT': '86400', 11:59:43 INFO - 'USER': 'cltbld', 11:59:43 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:43 INFO - '_': '/tools/buildbot/bin/python'} 11:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:43 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)) 11:59:43 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)) 11:59:43 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)) 11:59:43 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)) 11:59:43 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)) 11:59:43 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)) 11:59:43 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)) 11:59:43 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)) 11:59:43 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)) 11:59:43 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:59:45 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)) 11:59:45 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)) 11:59:45 INFO - Installing collected packages: simplejson 11:59:45 INFO - Running setup.py install for simplejson 11:59:45 INFO - building 'simplejson._speedups' extension 11:59:45 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 11:59:45 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 11:59:46 INFO - Successfully installed simplejson 11:59:46 INFO - Cleaning up... 11:59:46 INFO - Return code: 0 11:59:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xda31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, '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': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', '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 11:59:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:59:46 INFO - Using env: {'DISPLAY': ':0', 11:59:46 INFO - 'HOME': '/home/cltbld', 11:59:46 INFO - 'LANG': 'en_US.UTF-8', 11:59:46 INFO - 'LANGUAGE': 'en_US:en', 11:59:46 INFO - 'LOGNAME': 'cltbld', 11:59:46 INFO - 'MAIL': '/var/mail/cltbld', 11:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:46 INFO - 'MOZ_NO_REMOTE': '1', 11:59:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:59:46 INFO - 'NO_EM_RESTART': '1', 11:59:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:46 INFO - 'PWD': '/builds/slave/test', 11:59:46 INFO - 'SHELL': '/bin/bash', 11:59:46 INFO - 'SHLVL': '1', 11:59:46 INFO - 'TERM': 'linux', 11:59:46 INFO - 'TMOUT': '86400', 11:59:46 INFO - 'USER': 'cltbld', 11:59:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 11:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:46 INFO - '_': '/tools/buildbot/bin/python'} 11:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:59:46 INFO - Cleaning up... 11:59:46 INFO - Return code: 0 11:59:46 INFO - Running post-action listener: _resource_record_post_action 11:59:46 INFO - Running post-action listener: _start_resource_monitoring 11:59:46 INFO - Starting resource monitoring. 11:59:46 INFO - [mozharness: 2017-09-17 18:59:46.374362Z] Finished create-virtualenv step (success) 11:59:46 INFO - [mozharness: 2017-09-17 18:59:46.374953Z] Running install step. 11:59:46 INFO - Running pre-action listener: _resource_record_pre_action 11:59:46 INFO - Running main action method: install 11:59:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:59:46 INFO - Reading from file tmpfile_stdout 11:59:46 INFO - Detecting whether we're running mozinstall >=1.0... 11:59:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:59:46 INFO - Reading from file tmpfile_stdout 11:59:46 INFO - Output received: 11:59:46 INFO - Usage: mozinstall [options] installer 11:59:46 INFO - Options: 11:59:46 INFO - -h, --help show this help message and exit 11:59:46 INFO - -d DEST, --destination=DEST 11:59:46 INFO - Directory to install application into. [default: 11:59:46 INFO - "/builds/slave/test"] 11:59:46 INFO - --app=APP Application being installed. [default: firefox] 11:59:46 INFO - mkdir: /builds/slave/test/build/application 11:59:46 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'] 11:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:00:04 INFO - Reading from file tmpfile_stdout 12:00:04 INFO - Output received: 12:00:04 INFO - /builds/slave/test/build/application/firefox/firefox 12:00:04 INFO - Running post-action listener: _resource_record_post_action 12:00:04 INFO - [mozharness: 2017-09-17 19:00:04.360670Z] Finished install step (success) 12:00:04 INFO - [mozharness: 2017-09-17 19:00:04.360924Z] Running setup-mitmproxy step. 12:00:04 INFO - Running pre-action listener: _resource_record_pre_action 12:00:04 INFO - Running main action method: setup_mitmproxy 12:00:04 INFO - Skipping: mitmproxy is not required 12:00:04 INFO - Running post-action listener: _resource_record_post_action 12:00:04 INFO - [mozharness: 2017-09-17 19:00:04.362161Z] Finished setup-mitmproxy step (success) 12:00:04 INFO - [mozharness: 2017-09-17 19:00:04.362383Z] Running run-tests step. 12:00:04 INFO - Running pre-action listener: _resource_record_pre_action 12:00:04 INFO - Running pre-action listener: _set_gcov_prefix 12:00:04 INFO - Running main action method: run_tests 12:00:04 WARNING - Try message not found. 12:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:00:04 INFO - Python 2.7.3 12:00:04 INFO - Return code: 0 12:00:04 INFO - grabbing minidump binary from tooltool 12:00:04 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')]} 12:00:04 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 0x1113090>, '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 0x1113d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:00:04 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 12:00:04 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 12:00:04 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 12:00:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:00:04 INFO - Return code: 0 12:00:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:00:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:00:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:00:04 INFO - ENV: RUST_BACKTRACE is now full 12:00:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:00:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:00:04 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/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 12:00:04 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/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 12:00:04 INFO - Using env: {'DISPLAY': ':0', 12:00:04 INFO - 'HOME': '/home/cltbld', 12:00:04 INFO - 'LANG': 'en_US.UTF-8', 12:00:04 INFO - 'LANGUAGE': 'en_US:en', 12:00:04 INFO - 'LOGNAME': 'cltbld', 12:00:04 INFO - 'MAIL': '/var/mail/cltbld', 12:00:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:00:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:04 INFO - 'MOZ_NO_REMOTE': '1', 12:00:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:04 INFO - 'NO_EM_RESTART': '1', 12:00:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:04 INFO - 'PWD': '/builds/slave/test', 12:00:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:00:04 INFO - 'RUST_BACKTRACE': 'full', 12:00:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:00:04 INFO - 'SHELL': '/bin/bash', 12:00:04 INFO - 'SHLVL': '1', 12:00:04 INFO - 'TERM': 'linux', 12:00:04 INFO - 'TMOUT': '86400', 12:00:04 INFO - 'USER': 'cltbld', 12:00:04 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 12:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:04 INFO - '_': '/tools/buildbot/bin/python'} 12:00:04 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/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 12:00:04 INFO - mozversion application_buildid: 20170917174109 12:00:04 INFO - mozversion application_changeset: 0408418cdef3a7ed4f72cdaaf5540b07b70021fd 12:00:04 INFO - mozversion application_display_name: Nightly 12:00:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:00:04 INFO - mozversion application_name: Firefox 12:00:04 INFO - mozversion application_remotingname: firefox 12:00:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:00:04 INFO - mozversion application_vendor: Mozilla 12:00:04 INFO - mozversion application_version: 57.0a1 12:00:04 INFO - mozversion platform_buildid: 20170917174109 12:00:04 INFO - mozversion platform_changeset: 0408418cdef3a7ed4f72cdaaf5540b07b70021fd 12:00:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:00:04 INFO - mozversion platform_version: 57.0a1 12:00:04 INFO - using testdate: 1505674804 12:00:04 INFO - actual date: 1505674804 12:00:04 INFO - starting webserver on 'localhost:32992' 12:00:04 INFO - SUITE-START | Running 2 tests 12:00:04 INFO - TEST-START | dromaeo_css 12:00:04 INFO - Initialising browser for dromaeo_css test... 12:00:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:32992/getInfo.html -profile /tmp/tmp7Zzvs1/profile 12:00:04 INFO - TEST-INFO | started process 6492 (/builds/slave/test/build/application/firefox/firefox http://localhost:32992/getInfo.html) 12:00:09 INFO - TEST-INFO | 6492: exit 0 12:00:10 INFO - Browser initialized. 12:00:10 INFO - Running cycle 1/1 for dromaeo_css test... 12:00:10 INFO - Using env: {'DISPLAY': ':0', 12:00:10 INFO - 'HOME': '/home/cltbld', 12:00:10 INFO - 'JSGC_DISABLE_POISONING': '1', 12:00:10 INFO - 'LANG': 'en_US.UTF-8', 12:00:10 INFO - 'LANGUAGE': 'en_US:en', 12:00:10 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:00:10 INFO - 'LOGNAME': 'cltbld', 12:00:10 INFO - 'MAIL': '/var/mail/cltbld', 12:00:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:00:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:00:10 INFO - 'MOZ_CRASHREPORTER': '1', 12:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:10 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:00:10 INFO - 'MOZ_NO_REMOTE': '1', 12:00:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:00:10 INFO - 'NO_EM_RESTART': '1', 12:00:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:10 INFO - 'PWD': '/builds/slave/test', 12:00:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:00:10 INFO - 'RUST_BACKTRACE': 'full', 12:00:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:00:10 INFO - 'SHELL': '/bin/bash', 12:00:10 INFO - 'SHLVL': '1', 12:00:10 INFO - 'TERM': 'linux', 12:00:10 INFO - 'TMOUT': '86400', 12:00:10 INFO - 'USER': 'cltbld', 12:00:10 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 12:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:10 INFO - '_': '/tools/buildbot/bin/python'} 12:00:10 INFO - TEST-INFO | started process 6726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7Zzvs1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:00:10 INFO - PID 6726 | 12:00:10 INFO - PID 6726 | (/builds/slave/test/build/application/firefox/firefox:6775): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:00:10 INFO - PID 6726 | 12:00:21 INFO - PID 6726 | RSS: Main: 149942272 12:00:21 INFO - PID 6726 | 12:03:17 INFO - PID 6726 | Cycle 1(1): loaded http://localhost:32992/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32992/tests/dromaeo/cssquery-ext.html) 12:03:19 INFO - PID 6726 | RSS: Main: 150089728 12:03:19 INFO - PID 6726 | 12:06:21 INFO - PID 6726 | Cycle 1(1): loaded http://localhost:32992/tests/dromaeo/cssquery-ext.html (next: http://localhost:32992/tests/dromaeo/cssquery-jquery.html) 12:06:21 INFO - PID 6726 | RSS: Main: 148869120 12:06:21 INFO - PID 6726 | 12:09:23 INFO - PID 6726 | Cycle 1(1): loaded http://localhost:32992/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32992/tests/dromaeo/cssquery-mootools.html) 12:09:23 INFO - PID 6726 | RSS: Main: 151273472 12:09:23 INFO - PID 6726 | 12:12:25 INFO - PID 6726 | Cycle 1(1): loaded http://localhost:32992/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32992/tests/dromaeo/cssquery-prototype.html) 12:12:25 INFO - PID 6726 | RSS: Main: 149909504 12:12:25 INFO - PID 6726 | 12:15:29 INFO - PID 6726 | Cycle 1(1): loaded http://localhost:32992/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32992/tests/dromaeo/cssquery-yui.html) 12:15:29 INFO - PID 6726 | RSS: Main: 151363584 12:15:29 INFO - PID 6726 | 12:18:31 INFO - PID 6726 | Cycle 1(1): loaded http://localhost:32992/tests/dromaeo/cssquery-yui.html (next: http://localhost:32992/tests/dromaeo/cssquery-dojo.html) 12:18:31 INFO - PID 6726 | RSS: Main: 154038272 12:18:31 INFO - PID 6726 | 12:18:31 INFO - PID 6726 | __start_tp_report 12:18:31 INFO - PID 6726 | _x_x_mozilla_page_load 12:18:31 INFO - PID 6726 | _x_x_mozilla_page_load_details 12:18:31 INFO - PID 6726 | |i|pagename|runs| 12:18:31 INFO - PID 6726 | |0;dojo.html;3860.8841734747957;3899.0642245860986;3911.647951684348;3920.823562939669;3924.195539914317;6145.4161854623735;6215.782447614334;6259.593126446778;6259.843503912399;6355.68221588921;6243;6323;6328.303886572473;6335.429811316981;6352.650604216767;5562.9443705563035;5649.604527683064;5662.66024038559;5680.261565996425;5703.258576385064;233.1859265155004;5644.604877658566;5685.459881311269;5723.828285151452;5733.48398644122;5589.7205139743055;5599.7480113394795;5605.663660180382;5663.575231857627;5685.289338832646;5581.162825576155;5596.972015139898;5671.6880571568545;5680.233168522279;5681.204631351614;5519.116941289414;5537.280153580009;5591.217229587861;5606.243157173811;5677.063284558043;6161.568690191689;6218.906716399258;6233.937660623426;6267.529935254828;6285.151504546873;6652.634105124216;6741.460683145337;6759.966200169017;6835.726570937157;6845.623490708009;6758.628275444848;6765.763198288037;6854.6229957352325;6854.862902741967;6869.690863911086;6324;6335.303116657164;6339.619622822645;6418.679066046679;6424.325445828167;7040;7061.364477196974;7088.503804733619;7153.535020223669;7164.534305270141;6648.634325112165;6695;6721.596704197764;6761.695723692445;6773.051772751819;6782.8304292392095;6821.113255276881;6835.008923706035;6849.520533562602;6862.245153033163;6594.967025164843;6672.932330827141;6717.6977036033495;6718.66406679664;6791.388775010273;6678.699458524378;6678.831204539186;6709.832254193684;6799.150106236721;6803.693833777393;4876.707397556158;4922.064807686528;4928.457869634337;4935.358403407534;4959.20652695567;4505.549445055533;4581.198290299184;4595.563421474954;4611.239145541015;4618.007128467396;6267.435930766162;6269.435750782452;6278.623282603058;6362.45919096882;6363.7772677956045;6638;6752.426043786333;6789.524733268624;6797.116374871235;6895.310468953065;6151.84620384494;6165.506759459233;6179.412955769195;6226.066090086432;6285.622862628257;5394.649347792381;5494.780208791603;5535.474129957647;5539.889202215933;5543.36251331094;6959;7028.226895041541;7051.612161331073;7139.678714457758;7185.6766445510075;6678.532502724763;6767.086443330186;6774.187097548326;6781.525293229427;6783.389494945479;6980.755673551402;6999.545029573265;7091;7105.573665580082;7196.532225405332;6591.077249185214;6675.399214070757;6681.198256209092;6699.5310328276555;6701.597904125768;6769.93230067693;6777.152855893013;6787.89818152738;6807.693653785493;6838.794836154923;6583;6632.369924857131;6637.1703537057865;6688.464922806261;6689;6556.213254409501;6559.8032059038305;6577.112089867806;6669.966650166718;6690.364415380531;6413.102165696713;6457.935420645734;6457.967710161607;6497.935020649733;6590.538662293593;6464.256610489666;6487.643179625166;6574.506911981716;6601.438877695449;6630.270670226368;6357.141785859035;6429.8714025720155;6444.903326450013;6448.935510644834;6468.611883287018 12:18:31 INFO - PID 6726 | |1;ext.html;899.3434792601397;929.567750995787;958.5878072428857;969.1181025267005;974.025974025974;44389.55610443898;45234.54765452344;45277.32084018742;45288.54711452885;45383.773081134634;42712.35931461031;43009.7849510752;43043.35434968478;43154;43188;8050.074241462248;8235.176482351777;8268.793280167984;8291.419600627958;8297.95851020747;7890.960545197295;7910.485818421813;7980.4812687175145;7988.960055199745;8001.599920004006;7987.321077708401;8014.7194848180325;8039.236272554098;8046.114927357986;8049.557274349909;7964.601769911481;7966.6813327467125;7975.122736498981;7983.5209887406945;7999.68001279951;7959.124496305401;8005.639746211435;8008.919910800934;8011.95994020032;8020.919790802076;39441.80279098615;39441.816745497395;40448.39327410091;40590.39114413286;40735.18529629391;3685.2445248724216;3699.4820725098507;3739.9626003739886;3750.549934007909;3760.2103558252797;33326;33367.3326533468;33505.497417538754;33710;33953.49069763956;5306.867328316785;5324.680519168824;5399.352077750695;5406.702631355274;5563.805266815682;220308;220539;220978;221237;222603;46875.5312446878;47902.760486197345;53260.73369633127;53391.19913201305;53923.73038134784;55516.444835551934;55593.44406555963;55653;55795.721021395446;56396;16606.916965415097;16618.086005269826;16796.41212557579;16796.49610511662;16799.748003779954;13564;13580.728385432236;13659.863401366058;13660.863391366158;13713.657158570915;15236.23818809055;15256.618584535476;15336.309866056054;15355.385784568492;15487.922560387351;5854.882902341929;5877.52979761618;5940.732667029994;6025.855087533355;6050.304215015241;1065.6589891234885;1078.2721662877527;1090.4275255490772;1092.093562343253;1092.1644941619668;25137.87431062833;25483.872580636977;25544;25677;25871;1164.039667274485;1185.5613423033533;1192.1773975956562;1197.7544603356291;1197.8323034775137;13273.734525309634;13325.267110309027;13396.397162127532;13441.529546465827;13506.392212350467;306557;309381;309701;310091;310596;59101.70449147727;59516.70241648851;59697.40302597005;59728.40271597228;59921.70039149776;1496.4463148634857;1508.3891024135023;1523.5353217268619;1532.494276888624;1541.7841502189704;24786.50426991414;24804.422447514004;24832.875835620707;24859.12993045235;25118;41715.9571010715;42072.579274206866;42187.578124219595;42202.1559568813;42264.36603451012;238686;239250;239927;240734;241358;34312.828435857664;34470.48294275538;36694.633053670186;36734.44898326581;37373;46439.071218576115;46532.30201546912;46709;46732.53267467282;46785.532144678116;34214.82892585521;34387;34404.655953440146;34607.13482162966;35385.115372115906;34074.65925340814;34649.48025779565;34679.479807802396;34718.82640586882;34731.652683473854;40624.390634141106;40713;40764.1847163061;40877.1824563513;41020;32722.50916236306;32784.34431311408;32993.67006330002;33424;33661.49507757337;25324.74675253224;25396.238112856645;25685.614715778906;25724.485510289316;25816.483670326863 12:18:31 INFO - PID 6726 | |2;jquery.html;594.9762009519619;595.6545203781807;596.7702434562693;605.4913872347228;613.0987448450777;23007.539849203025;23727.288181354565;23749.64375534364;23769.762302377014;23770.999999999978;22902.65646015307;22917.4270643234;24728.876355618242;24854.378640533996;24906.626400604004;6629.270780214172;6709.865802683943;6823.488238382129;6824.522283440161;6825.692843822015;6434.292227854932;6540;6664.366885145928;6682.4319932805765;6698.765543205989;6597.241317248506;6613.3386661333725;6637.966810165966;6646.435053020499;6647.966760166217;6388.648624325637;6586.308437614078;6606.768763093317;6619.867602647944;6638.269790323061;6102.572819902609;6124.693765311761;6142.938570614326;6144.87710245797;6174.289956654998;28489.287767805763;28958.710412895813;29000;29028.854855725585;29070;2234.944126396837;2272.125231785758;2272.1593010586025;2278.8176945844293;2279.2364557873134;19955.50111247216;20031.499212519804;20462;20589.279375221795;20677;2460.5078984203233;2491.277529516438;2547.4905018996274;2558.8720563971915;2571.768540831334;132556;138319;146420;150308.2484587581;151547.9999999989;15262.3895044197;16006.519804405887;16080.115593642227;16109.436169734003;16163.353465861233;24498.755012450005;24561.263162105166;24564.263072107864;24580;24586.385340366276;10473.738156546147;10524.947375263075;10546.789064218827;10560.630377936888;10733.248672593;9350;9444.291678124167;9444.433333999981;9519.904800952041;9676.70969870905;9174.724758257264;9300.72097837066;9307.906920930704;9529.285303602119;9540.475273859865;2717.6331195288776;2779.88880444784;2870.641169853768;2897.550879613621;2909.1272618214875;1034.3380236648552;1048.4024106259512;1054.6150655010865;1058.9622170273176;1062.129054175584;20465.74876763555;20559.954892245394;20692.068856901173;20837.583248334948;21081;1097.286763603664;1114.2701530497536;1121.243160866412;1121.8653761548503;1130.0507573638183;11164.385958772345;11536.480858361227;11567.363794991355;11602.941985290188;11736.002439792714;578582;591071;598625;619718;627793;125197.37401312935;125991.3700431492;126663;128313;129857;1085.3054045410943;1131.5360702112096;1178.452019810792;1191.7437750883428;1233.3524899427694;20864.68702969426;21377.35867923965;21398.144074236858;21497.462563436038;21550.8922455393;61697.38302616916;62230;62402.37597623966;62567.374326257974;63105.36894630995;78903.60548197222;79235.60382198052;81281.18718812735;81358;81427;14875.628109296922;14951.850481495481;15140.394384224508;15158.393664253306;15191.164485953379;23005.964731586784;23205.071797127926;23309.650355244346;23443.413914651588;23589.646155308026;18272.360467383576;18292.542686432946;18503.167357468676;19150.23399064022;19382.4185274442;16901.577460563585;17197.914010429868;17237.827621723623;17251.913740431217;17407.82592174062;19505;19758.80241197627;19774.80225197787;19884.50288742793;19963.301284454974;14797.556073317817;14866.033707808958;14930.701385972437;14966.32651530662;14995.475158369405;18734.156962936973;19131.904340478766;19156.904215478833;19212.71180932259;19217.231310747415 12:18:31 INFO - PID 6726 | |3;mootools.html;1441.0777102654304;1480.8519148085193;1486.7546854768964;1492.4850926430381;1507.781371701104;16451.670966580677;17130.14349282534;17165.914170429147;17171.914140429297;17179.74230386542;16748.58128546784;16769;16820.15899205041;16834.747478787798;16859.662806743858;2867.397846452237;2915.548090046041;2968.450836595226;2971.5542668599774;2975.8363290019042;2863.842488663123;2869.942601147976;2870.540713485843;2873.956890646642;2874.3245337345707;2704.7836173106202;2746.121241202816;2750.0787236275773;2750.2849259192653;2751.1058905855575;2622.056059818464;2689.690685571155;2705.661792275975;2705.91882243533;2706.3504758857734;2575.1244576843774;2598.558245098338;2607.3351295419548;2685.167598044593;2813.3951200491992;16289.185540722916;16371.42700005494;16452.259648315736;16505.917470412573;16587.585310367216;8398.286145677625;8667.566621668955;8712.433691810076;8713.56432178395;8717.520536370497;5778.248827652378;5963.135345374896;6081.483073938676;6123.632582045091;6150.600210986317;2095.6646936490088;2105;2114.8625339352893;2129.1057755742627;2172.350451241913;30039.399212015855;30087.247818804488;30155;30194.245143871358;30216.8489157555;7382.640898391326;7393.853952637242;7436.553806771611;7559.395248380117;7625.733099341496;19139.330123445892;19157.904210478857;19347.709784353247;19415.708764368545;19433.902830485757;8279.62741676626;8494.06565277819;8528.829423411496;8565.057843637194;8573.27127194183;7632.542047477169;7717.2668596483245;7981.1220765715725;7985.441019128722;7989.201079892081;5769.538436925036;5811.0411782056335;5857.7656893724625;5885.323187833368;6107.6640784757265;1684.2589260725488;1705.296860861693;1722.2336060453229;1748.3094177799953;1762.2246211666825;896.5965315608003;926.9165775080276;930.9487978161267;945.6926498887889;951.9762005949768;8019.35845132388;8160.714374996846;8179.509229446252;9106.95446522763;9184.311176661777;760.5740785160328;769.0924708843618;776.755322073545;778.980525486856;781.5232708048086;4646.09401166776;4806.591439727662;5395.892082158335;5849.64902105875;5850.736716847667;24354.391140221283;25270.494590108094;25471.36321591975;25528.468291902544;25602.23193304204;23981.760182398302;25289.620655690178;25298.241052768448;25380.238592842245;25538.361540961625;11420.486078126503;11766.058715302755;11793.941030294794;11848.348340841336;11922.642320730392;9122.90877091247;9188.632454701738;9284.860727088964;9296.535173241446;9302.581383837878;20215.264383438338;20853.582928341653;20950.47623809417;21137.365879023655;21336.573268534852;20951.58096838085;21663.89168054213;22097.11611535585;22144.778552214273;22420.215292464687;12130.93934530357;12151;12301.93849030785;12303.507859685871;12317.014638828692;15340.156291403633;15420.614484637974;15537.922310388376;15550.444955504812;15585.844141558438;12226.14416990802;12389.814152787896;12405.565805196775;12478.937605311916;12695.238285702519;11747.882521174679;11771;11776.234544754563;11804.114691398008;12035.217710848767;10871.402072886067;10879.23845330851;10976.286541375104;10980.341179529254;11039.39283339424;7963.601819909098;8050.677972881019;8093.635786389509;8123.512589244664;8139.877901831595;7714.228577142241;7824.334931530883;7911.802204944923;7916.6041697916025;8036.87944680842 12:18:31 INFO - PID 6726 | |4;prototype.html;532.6378062917217;560.3779804417094;568.9658620482769;569.8091139468278;575.6776205325017;6289.5282853785975;6302.432781049704;6350.714217860202;6355.237371515413;6362.618242905429;6238.344973777756;6240.4071613196675;6259.593126446778;6266.1540692006565;6303;5106.387233531981;5132.127538318495;5136.666116702402;5151.896962060757;5163.793448262065;4842.176829938901;5066.214736715797;5069.594432445414;5069.670471419365;5099.133147364938;4911.410630724318;4940.8517744467545;4982.900341993158;4984.725840078794;4999;4944.085344211332;4957.6033917286895;4978.701277923336;4990.850274491771;4995.350604421438;4507.414036175309;4522.9547704522865;4523.5024147343765;4524.072565124142;4528.93206601898;6127.44852963231;6158.3841615838055;6167.392075635758;6196.326991712258;6205.720742566596;5840.415958404168;5873.618214816023;5881.117832325142;5890.908727401754;5897.73460194288;6533.444657204143;6575.835604109888;6601.471882249409;6621.516931150819;6686.93313066868;5015.22264049073;5020.221865610836;5029.974850125763;5034.798608055673;5038.622103342264;6804.727810887559;6869.7595584154315;6900.2064762551945;6957.9215221640725;6982.1272340957385;5144.176931690911;5170.870728231825;5182.378114626269;5191.013707395583;5191.32512773337;5183.874611409304;5185.248139019821;5208.765605547759;5239.240310155083;5250.816221432232;5797.333306669702;5803.100519419497;5815.331236907725;5846.298444186725;5858.589898707135;6720.4287635550545;6721.630310332978;6730.427913627299;6732;6737.427318677869;1790.937317193918;1791.8297761712618;1794.9641007179782;1820.8179182081951;1824.8631352648604;1202.0977002915643;1264.7913094339515;1280.5390059578544;1298.4935875008755;1302.9934850325687;6182.536309776695;6188.164597779242;6202.782902598387;6250.749970001149;6280.842978925563;6598.37315455031;6710.429613482908;6751.864962700718;6765.661716914135;6804.693788779517;5988.191594134823;6078.513718902477;6137.5703700741415;6165.348899972965;6208.255009398901;3512.8594856205978;3520.7359448041498;3536.29274145172;3577.874774382884;3590.2281009583066;7056.54132481387;7084.264355233001;7100.147982242061;7109.82225444368;7125.679344429514;6956.686949087303;6956.791296261019;6959.965200173967;6986.056882320821;7000.159980802337;7144.8928266076055;7150.427965762727;7168.498205125591;7171.852503599399;7174.354308112295;6801.319868013159;6806;6807.89788153188;6823.38589526945;6861.348171923659;6710.020543939452;6750.898736519054;6778.32216778338;6802.183737951477;6822.931770682229;6980.546264492995;7061.364477197077;7071.080759501232;7136.571805691675;7147.142342918883;5068.417132029864;5111.872203194801;5140.254663073908;5149.48505149482;5152.367580855563;6421.197350331208;6685.197776266879;6688.632125232964;6704.363085506869;6804;6442.2591401989375;6606.702698378488;6617.569857959217;6619.768308109193;6630.602163870183;6557.540972131905;6582.473402127723;6583.703733331916;6589.901151482636;6626.138601981905;6548.672566371567;6550.639714815731;6553.640689351925;6573.638449885302;6614.702338394687;6579.572327798678;6681.398674119353;6684.498662600227;6706.966465167643;6716.764913228014;6329.2404911409085;6330.620162790247;6375.6174629522375;6424.453921416732;6432.549721519449 12:18:31 INFO - PID 6726 | |5;yui.html;954.3176628710471;957.8084383123372;984.9073907929102;997.8054279415508;1011.1102230037566;20090.397288081345;20685.586288274244;20733.585328293462;20751.792482075176;20757.688634670474;19008.5247868803;19038.61922761544;19040.428787136407;19060.523486912833;19072.809271907277;1041.2450973044538;1053.557505847542;1056.2553399853123;1057.5241141486324;1062.9893701063006;1058.2962330050507;1060.7984482948254;1062.9636104797837;1064.09551880901;1065.589747947042;1072.5977758340623;1101.4657890922936;1110.1673744691482;1111.2165923024288;1112.4826955465708;1097.6981330134197;1101.2071308657753;1101.5924108080021;1102.939338336391;1103.8068338040812;791.9604019799044;807.4105902691068;809.0332053196432;817.146082343952;819.8073452738602;21139.577208455743;21361.252356167613;21426.14295428181;21458.24896128628;21473.355799326044;21829.67255491169;21915.67126493104;22568.435789105402;22808.429789255402;22813.657795133087;18674.159662815207;19031.904840475847;19057.714134287995;19216.903915480474;19218.615627687508;3936.429217763437;3962.960370396288;3965.8810235692686;3969.702272329587;3971.6028397160344;141537;166075.99999999878;166397;168431.99999999878;168927;15415.61460963485;15570.844291557167;15725.921370393075;15902.363905443653;15964.281607327699;34401.65598343984;34427;34694;35349.64650353515;35724.642753572654;9284.582193801296;9317.347785655074;9333.719988400358;9337.3930694506;9362.297827662953;4407.162639098561;4447.066116115652;4532.750698711602;4553.954460455419;4573.108243892409;6003.969980150071;6089.969550152309;6093.390660933871;6094.725737341831;6124.265088189357;1208.2629595946466;1232.7904256276274;1242.1491278474275;1244.0420875925486;1248.2385744695728;728.9526180798231;732.5531425830239;738.0663460722191;742.8662840688728;745.3589912675095;12722.427490762753;13563.79390468772;13874.45541388552;14897.77653335201;14989.325480353411;703.5216053083952;706.586646811621;712.3197346534048;714.4677215474504;715.152544235083;5735;5816.127580862904;5918.23063001807;5921.818851471765;5941.55438342126;153109;154646;155017;157072;158275;134664.99999999805;134731;136133;136906.31546842202;140183.29908350392;114436;115968;116224;118968;119027.40486297687;10892.128629709763;10991.780164396507;11042.447877605928;11086.722831929266;11267.943660281646;53844.46155538395;53885;55571;56274.43725562692;56725.432745673665;70103.64948175226;70271.64864175646;70315.64842175756;72230;73440.63279683568;16667.583310417336;16866.831331687015;16927.15364231759;17059.91470042642;17095.145242737566;19151.425457236306;19395.2241910322;19604.215831366586;19655.70516442226;19984.800151998876;17128.57178570546;17149.399771007957;17200.741988870428;17241.310347585957;17357.65284694324;16960.06719630432;17045.829541704425;17196.054217017685;17256.568585785455;17468.47594572165;16472.0940348282;16857.91571042137;16957.49127526176;17029.74455383195;17079.31682732677;13443.798343025059;13745.656358591115;13810.861891380957;13825.861741382458;14105;13083.738325233633;13106;13133.277669728257;13189.406476708764;13391.129576577492 12:18:31 INFO - PID 6726 | __end_tp_report 12:18:31 INFO - PID 6726 | __start_cc_report 12:18:31 INFO - PID 6726 | _x_x_mozilla_cycle_collect,269 12:18:31 INFO - PID 6726 | __end_cc_report 12:18:31 INFO - PID 6726 | __startTimestamp1505675911492__endTimestamp 12:18:31 INFO - PID 6726 | 12:18:31 INFO - PID 6726 | ------- Summary: start ------- 12:18:31 INFO - PID 6726 | Number of tests: 6 12:18:31 INFO - PID 6726 | 12:18:31 INFO - PID 6726 | [#0] dojo.html Cycles:165 Average:6219.38 Median:6464.26 stddev:870.69 (13.5%) stddev-sans-first:853.48 12:18:31 INFO - PID 6726 | Values: 3860.9 3899.1 3911.6 3920.8 3924.2 6145.4 6215.8 6259.6 6259.8 6355.7 6243.0 6323.0 6328.3 6335.4 6352.7 5562.9 5649.6 5662.7 5680.3 5703.3 233.2 5644.6 5685.5 5723.8 5733.5 5589.7 5599.7 5605.7 5663.6 5685.3 5581.2 5597.0 5671.7 5680.2 5681.2 5519.1 5537.3 5591.2 5606.2 5677.1 6161.6 6218.9 6233.9 6267.5 6285.2 6652.6 6741.5 6760.0 6835.7 6845.6 6758.6 6765.8 6854.6 6854.9 6869.7 6324.0 6335.3 6339.6 6418.7 6424.3 7040.0 7061.4 7088.5 7153.5 7164.5 6648.6 6695.0 6721.6 6761.7 6773.1 6782.8 6821.1 6835.0 6849.5 6862.2 6595.0 6672.9 6717.7 6718.7 6791.4 6678.7 6678.8 6709.8 6799.2 6803.7 4876.7 4922.1 4928.5 4935.4 4959.2 4505.5 4581.2 4595.6 4611.2 4618.0 6267.4 6269.4 6278.6 6362.5 6363.8 6638.0 6752.4 6789.5 6797.1 6895.3 6151.8 6165.5 6179.4 6226.1 6285.6 5394.6 5494.8 5535.5 5539.9 5543.4 6959.0 7028.2 7051.6 7139.7 7185.7 6678.5 6767.1 6774.2 6781.5 6783.4 6980.8 6999.5 7091.0 7105.6 7196.5 6591.1 6675.4 6681.2 6699.5 6701.6 6769.9 6777.2 6787.9 6807.7 6838.8 6583.0 6632.4 6637.2 6688.5 6689.0 6556.2 6559.8 6577.1 6670.0 6690.4 6413.1 6457.9 6458.0 6497.9 6590.5 6464.3 6487.6 6574.5 6601.4 6630.3 6357.1 6429.9 6444.9 6448.9 6468.6 12:18:31 INFO - PID 6726 | 12:18:31 INFO - PID 6726 | [#1] ext.html Cycles:180 Average:43375.47 Median:20536.24 stddev:67667.58 (329.5%) stddev-sans-first:67782.25 12:18:31 INFO - PID 6726 | Values: 899.3 929.6 958.6 969.1 974.0 44389.6 45234.5 45277.3 45288.5 45383.8 42712.4 43009.8 43043.4 43154.0 43188.0 8050.1 8235.2 8268.8 8291.4 8298.0 7891.0 7910.5 7980.5 7989.0 8001.6 7987.3 8014.7 8039.2 8046.1 8049.6 7964.6 7966.7 7975.1 7983.5 7999.7 7959.1 8005.6 8008.9 8012.0 8020.9 39441.8 39441.8 40448.4 40590.4 40735.2 3685.2 3699.5 3740.0 3750.5 3760.2 33326.0 33367.3 33505.5 33710.0 33953.5 5306.9 5324.7 5399.4 5406.7 5563.8 220308.0 220539.0 220978.0 221237.0 222603.0 46875.5 47902.8 53260.7 53391.2 53923.7 55516.4 55593.4 55653.0 55795.7 56396.0 16606.9 16618.1 16796.4 16796.5 16799.7 13564.0 13580.7 13659.9 13660.9 13713.7 15236.2 15256.6 15336.3 15355.4 15487.9 5854.9 5877.5 5940.7 6025.9 6050.3 1065.7 1078.3 1090.4 1092.1 1092.2 25137.9 25483.9 25544.0 25677.0 25871.0 1164.0 1185.6 1192.2 1197.8 1197.8 13273.7 13325.3 13396.4 13441.5 13506.4 306557.0 309381.0 309701.0 310091.0 310596.0 59101.7 59516.7 59697.4 59728.4 59921.7 1496.4 1508.4 1523.5 1532.5 1541.8 24786.5 24804.4 24832.9 24859.1 25118.0 41716.0 42072.6 42187.6 42202.2 42264.4 238686.0 239250.0 239927.0 240734.0 241358.0 34312.8 34470.5 36694.6 36734.4 37373.0 46439.1 46532.3 46709.0 46732.5 46785.5 34214.8 34387.0 34404.7 34607.1 35385.1 34074.7 34649.5 34679.5 34718.8 34731.7 40624.4 40713.0 40764.2 40877.2 41020.0 32722.5 32784.3 32993.7 33424.0 33661.5 25324.7 25396.2 25685.6 25724.5 25816.5 12:18:31 INFO - PID 6726 | 12:18:31 INFO - PID 6726 | [#2] jquery.html Cycles:180 Average:39160.67 Median:22910.04 stddev:100750.72 (439.8%) stddev-sans-first:100991.74 12:18:31 INFO - PID 6726 | Values: 595.0 595.7 596.8 605.5 613.1 23007.5 23727.3 23749.6 23769.8 23771.0 22902.7 22917.4 24728.9 24854.4 24906.6 6629.3 6709.9 6823.5 6824.5 6825.7 6434.3 6540.0 6664.4 6682.4 6698.8 6597.2 6613.3 6638.0 6646.4 6648.0 6388.6 6586.3 6606.8 6619.9 6638.3 6102.6 6124.7 6142.9 6144.9 6174.3 28489.3 28958.7 29000.0 29028.9 29070.0 2234.9 2272.1 2272.2 2278.8 2279.2 19955.5 20031.5 20462.0 20589.3 20677.0 2460.5 2491.3 2547.5 2558.9 2571.8 132556.0 138319.0 146420.0 150308.2 151548.0 15262.4 16006.5 16080.1 16109.4 16163.4 24498.8 24561.3 24564.3 24580.0 24586.4 10473.7 10524.9 10546.8 10560.6 10733.2 9350.0 9444.3 9444.4 9519.9 9676.7 9174.7 9300.7 9307.9 9529.3 9540.5 2717.6 2779.9 2870.6 2897.6 2909.1 1034.3 1048.4 1054.6 1059.0 1062.1 20465.7 20560.0 20692.1 20837.6 21081.0 1097.3 1114.3 1121.2 1121.9 1130.1 11164.4 11536.5 11567.4 11602.9 11736.0 578582.0 591071.0 598625.0 619718.0 627793.0 125197.4 125991.4 126663.0 128313.0 129857.0 1085.3 1131.5 1178.5 1191.7 1233.4 20864.7 21377.4 21398.1 21497.5 21550.9 61697.4 62230.0 62402.4 62567.4 63105.4 78903.6 79235.6 81281.2 81358.0 81427.0 14875.6 14951.9 15140.4 15158.4 15191.2 23006.0 23205.1 23309.7 23443.4 23589.6 18272.4 18292.5 18503.2 19150.2 19382.4 16901.6 17197.9 17237.8 17251.9 17407.8 19505.0 19758.8 19774.8 19884.5 19963.3 14797.6 14866.0 14930.7 14966.3 14995.5 18734.2 19131.9 19156.9 19212.7 19217.2 12:18:31 INFO - PID 6726 | 12:18:31 INFO - PID 6726 | [#3] mootools.html Cycles:180 Average:10328.68 Median:2697.24 stddev:7671.92 (284.4%) stddev-sans-first:7664.39 12:18:31 INFO - PID 6726 | Values: 1441.1 1480.9 1486.8 1492.5 1507.8 16451.7 17130.1 17165.9 17171.9 17179.7 16748.6 16769.0 16820.2 16834.7 16859.7 2867.4 2915.5 2968.5 2971.6 2975.8 2863.8 2869.9 2870.5 2874.0 2874.3 2704.8 2746.1 2750.1 2750.3 2751.1 2622.1 2689.7 2705.7 2705.9 2706.4 2575.1 2598.6 2607.3 2685.2 2813.4 16289.2 16371.4 16452.3 16505.9 16587.6 8398.3 8667.6 8712.4 8713.6 8717.5 5778.2 5963.1 6081.5 6123.6 6150.6 2095.7 2105.0 2114.9 2129.1 2172.4 30039.4 30087.2 30155.0 30194.2 30216.8 7382.6 7393.9 7436.6 7559.4 7625.7 19139.3 19157.9 19347.7 19415.7 19433.9 8279.6 8494.1 8528.8 8565.1 8573.3 7632.5 7717.3 7981.1 7985.4 7989.2 5769.5 5811.0 5857.8 5885.3 6107.7 1684.3 1705.3 1722.2 1748.3 1762.2 896.6 926.9 930.9 945.7 952.0 8019.4 8160.7 8179.5 9107.0 9184.3 760.6 769.1 776.8 779.0 781.5 4646.1 4806.6 5395.9 5849.6 5850.7 24354.4 25270.5 25471.4 25528.5 25602.2 23981.8 25289.6 25298.2 25380.2 25538.4 11420.5 11766.1 11793.9 11848.3 11922.6 9122.9 9188.6 9284.9 9296.5 9302.6 20215.3 20853.6 20950.5 21137.4 21336.6 20951.6 21663.9 22097.1 22144.8 22420.2 12130.9 12151.0 12301.9 12303.5 12317.0 15340.2 15420.6 15537.9 15550.4 15585.8 12226.1 12389.8 12405.6 12478.9 12695.2 11747.9 11771.0 11776.2 11804.1 12035.2 10871.4 10879.2 10976.3 10980.3 11039.4 7963.6 8050.7 8093.6 8123.5 8139.9 7714.2 7824.3 7911.8 7916.6 8036.9 12:18:31 INFO - PID 6726 | 12:18:31 INFO - PID 6726 | [#4] prototype.html Cycles:180 Average:5651.91 Median:6255.17 stddev:1592.68 (25.5%) stddev-sans-first:1550.10 12:18:31 INFO - PID 6726 | Values: 532.6 560.4 569.0 569.8 575.7 6289.5 6302.4 6350.7 6355.2 6362.6 6238.3 6240.4 6259.6 6266.2 6303.0 5106.4 5132.1 5136.7 5151.9 5163.8 4842.2 5066.2 5069.6 5069.7 5099.1 4911.4 4940.9 4982.9 4984.7 4999.0 4944.1 4957.6 4978.7 4990.9 4995.4 4507.4 4523.0 4523.5 4524.1 4528.9 6127.4 6158.4 6167.4 6196.3 6205.7 5840.4 5873.6 5881.1 5890.9 5897.7 6533.4 6575.8 6601.5 6621.5 6686.9 5015.2 5020.2 5030.0 5034.8 5038.6 6804.7 6869.8 6900.2 6957.9 6982.1 5144.2 5170.9 5182.4 5191.0 5191.3 5183.9 5185.2 5208.8 5239.2 5250.8 5797.3 5803.1 5815.3 5846.3 5858.6 6720.4 6721.6 6730.4 6732.0 6737.4 1790.9 1791.8 1795.0 1820.8 1824.9 1202.1 1264.8 1280.5 1298.5 1303.0 6182.5 6188.2 6202.8 6250.7 6280.8 6598.4 6710.4 6751.9 6765.7 6804.7 5988.2 6078.5 6137.6 6165.3 6208.3 3512.9 3520.7 3536.3 3577.9 3590.2 7056.5 7084.3 7100.1 7109.8 7125.7 6956.7 6956.8 6960.0 6986.1 7000.2 7144.9 7150.4 7168.5 7171.9 7174.4 6801.3 6806.0 6807.9 6823.4 6861.3 6710.0 6750.9 6778.3 6802.2 6822.9 6980.5 7061.4 7071.1 7136.6 7147.1 5068.4 5111.9 5140.3 5149.5 5152.4 6421.2 6685.2 6688.6 6704.4 6804.0 6442.3 6606.7 6617.6 6619.8 6630.6 6557.5 6582.5 6583.7 6589.9 6626.1 6548.7 6550.6 6553.6 6573.6 6614.7 6579.6 6681.4 6684.5 6707.0 6716.8 6329.2 6330.6 6375.6 6424.5 6432.5 12:18:31 INFO - PID 6726 | 12:18:31 INFO - PID 6726 | [#5] yui.html Cycles:180 Average:28591.30 Median:18071.32 stddev:43484.96 (240.6%) stddev-sans-first:43557.44 12:18:31 INFO - PID 6726 | Values: 954.3 957.8 984.9 997.8 1011.1 20090.4 20685.6 20733.6 20751.8 20757.7 19008.5 19038.6 19040.4 19060.5 19072.8 1041.2 1053.6 1056.3 1057.5 1063.0 1058.3 1060.8 1063.0 1064.1 1065.6 1072.6 1101.5 1110.2 1111.2 1112.5 1097.7 1101.2 1101.6 1102.9 1103.8 792.0 807.4 809.0 817.1 819.8 21139.6 21361.3 21426.1 21458.2 21473.4 21829.7 21915.7 22568.4 22808.4 22813.7 18674.2 19031.9 19057.7 19216.9 19218.6 3936.4 3963.0 3965.9 3969.7 3971.6 141537.0 166076.0 166397.0 168432.0 168927.0 15415.6 15570.8 15725.9 15902.4 15964.3 34401.7 34427.0 34694.0 35349.6 35724.6 9284.6 9317.3 9333.7 9337.4 9362.3 4407.2 4447.1 4532.8 4554.0 4573.1 6004.0 6090.0 6093.4 6094.7 6124.3 1208.3 1232.8 1242.1 1244.0 1248.2 729.0 732.6 738.1 742.9 745.4 12722.4 13563.8 13874.5 14897.8 14989.3 703.5 706.6 712.3 714.5 715.2 5735.0 5816.1 5918.2 5921.8 5941.6 153109.0 154646.0 155017.0 157072.0 158275.0 134665.0 134731.0 136133.0 136906.3 140183.3 114436.0 115968.0 116224.0 118968.0 119027.4 10892.1 10991.8 11042.4 11086.7 11267.9 53844.5 53885.0 55571.0 56274.4 56725.4 70103.6 70271.6 70315.6 72230.0 73440.6 16667.6 16866.8 16927.2 17059.9 17095.1 19151.4 19395.2 19604.2 19655.7 19984.8 17128.6 17149.4 17200.7 17241.3 17357.7 16960.1 17045.8 17196.1 17256.6 17468.5 16472.1 16857.9 16957.5 17029.7 17079.3 13443.8 13745.7 13810.9 13825.9 14105.0 13083.7 13106.0 13133.3 13189.4 13391.1 12:18:31 INFO - PID 6726 | -------- Summary: end -------- 12:18:31 INFO - PID 6726 | 12:18:32 INFO - TEST-INFO | 6726: exit 0 12:18:32 INFO - TEST-OK | dromaeo_css | took 1107419ms 12:18:32 INFO - TEST-START | kraken 12:18:32 INFO - Initialising browser for kraken test... 12:18:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:32992/getInfo.html -profile /tmp/tmpYdDuQB/profile 12:18:32 INFO - TEST-INFO | started process 7106 (/builds/slave/test/build/application/firefox/firefox http://localhost:32992/getInfo.html) 12:18:37 INFO - TEST-INFO | 7106: exit 0 12:18:37 INFO - Browser initialized. 12:18:37 INFO - Running cycle 1/1 for kraken test... 12:18:37 INFO - Using env: {'DISPLAY': ':0', 12:18:37 INFO - 'HOME': '/home/cltbld', 12:18:37 INFO - 'JSGC_DISABLE_POISONING': '1', 12:18:37 INFO - 'LANG': 'en_US.UTF-8', 12:18:37 INFO - 'LANGUAGE': 'en_US:en', 12:18:37 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:18:37 INFO - 'LOGNAME': 'cltbld', 12:18:37 INFO - 'MAIL': '/var/mail/cltbld', 12:18:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:18:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:18:37 INFO - 'MOZ_CRASHREPORTER': '1', 12:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:37 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:18:37 INFO - 'MOZ_NO_REMOTE': '1', 12:18:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:18:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:37 INFO - 'NO_EM_RESTART': '1', 12:18:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:37 INFO - 'PWD': '/builds/slave/test', 12:18:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:18:37 INFO - 'RUST_BACKTRACE': 'full', 12:18:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:18:37 INFO - 'SHELL': '/bin/bash', 12:18:37 INFO - 'SHLVL': '1', 12:18:37 INFO - 'TERM': 'linux', 12:18:37 INFO - 'TMOUT': '86400', 12:18:37 INFO - 'USER': 'cltbld', 12:18:37 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998', 12:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:37 INFO - '_': '/tools/buildbot/bin/python'} 12:18:37 INFO - TEST-INFO | started process 7347 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYdDuQB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:18:37 INFO - PID 7347 | 12:18:37 INFO - PID 7347 | (/builds/slave/test/build/application/firefox/firefox:7398): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:18:37 INFO - PID 7347 | 12:18:48 INFO - PID 7347 | RSS: Main: 148566016 12:18:48 INFO - PID 7347 | 12:18:50 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32992/tests/kraken/driver.html?testName=audio-beat-detection) 12:18:51 INFO - PID 7347 | RSS: Main: 153731072 12:18:51 INFO - PID 7347 | 12:18:54 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32992/tests/kraken/driver.html?testName=audio-dft) 12:18:54 INFO - PID 7347 | RSS: Main: 153878528 12:18:54 INFO - PID 7347 | 12:18:57 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32992/tests/kraken/driver.html?testName=audio-fft) 12:18:58 INFO - PID 7347 | RSS: Main: 150433792 12:18:58 INFO - PID 7347 | 12:19:00 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32992/tests/kraken/driver.html?testName=audio-oscillator) 12:19:01 INFO - PID 7347 | RSS: Main: 151310336 12:19:01 INFO - PID 7347 | 12:19:03 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32992/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:19:03 INFO - PID 7347 | RSS: Main: 151330816 12:19:03 INFO - PID 7347 | 12:19:06 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32992/tests/kraken/driver.html?testName=imaging-darkroom) 12:19:07 INFO - PID 7347 | RSS: Main: 151470080 12:19:07 INFO - PID 7347 | 12:19:10 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32992/tests/kraken/driver.html?testName=imaging-desaturate) 12:19:11 INFO - PID 7347 | RSS: Main: 152276992 12:19:11 INFO - PID 7347 | 12:19:14 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32992/tests/kraken/driver.html?testName=json-parse-financial) 12:19:15 INFO - PID 7347 | RSS: Main: 154324992 12:19:15 INFO - PID 7347 | 12:19:16 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32992/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:19:17 INFO - PID 7347 | RSS: Main: 153931776 12:19:17 INFO - PID 7347 | 12:19:18 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32992/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:19:19 INFO - PID 7347 | RSS: Main: 154198016 12:19:19 INFO - PID 7347 | 12:19:21 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32992/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:19:22 INFO - PID 7347 | RSS: Main: 151244800 12:19:22 INFO - PID 7347 | 12:19:24 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:19:25 INFO - PID 7347 | RSS: Main: 151429120 12:19:25 INFO - PID 7347 | 12:19:27 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:19:28 INFO - PID 7347 | RSS: Main: 151674880 12:19:28 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | Cycle 1(1): loaded http://localhost:32992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32992/tests/kraken/driver.html?testName=ai-astar) 12:19:30 INFO - PID 7347 | RSS: Main: 153358336 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | __start_tp_report 12:19:30 INFO - PID 7347 | _x_x_mozilla_page_load 12:19:30 INFO - PID 7347 | _x_x_mozilla_page_load_details 12:19:30 INFO - PID 7347 | |i|pagename|runs| 12:19:30 INFO - PID 7347 | |0;ai-astar;107;107;105;105;104;113;108;104;105;108 12:19:30 INFO - PID 7347 | |1;audio-beat-detection;115;113;113;116;110;138;112;120;113;115 12:19:30 INFO - PID 7347 | |2;audio-dft;162;163;162;163;162;162;161;162;165;163 12:19:30 INFO - PID 7347 | |3;audio-fft;77;73;72;74;72;73;72;72;72;76 12:19:30 INFO - PID 7347 | |4;audio-oscillator;80;78;78;78;78;78;78;77;79;77 12:19:30 INFO - PID 7347 | |5;imaging-gaussian-blur;113;113;113;114;113;113;114;114;114;114 12:19:30 INFO - PID 7347 | |6;imaging-darkroom;149;150;148;150;149;152;149;150;150;149 12:19:30 INFO - PID 7347 | |7;imaging-desaturate;95;95;95;95;94;94;98;94;94;95 12:19:30 INFO - PID 7347 | |8;json-parse-financial;46;44;50;47;46;45;42;44;44;45 12:19:30 INFO - PID 7347 | |9;json-stringify-tinderbox;37;38;38;38;38;38;38;38;39;39 12:19:30 INFO - PID 7347 | |10;stanford-crypto-aes;76;72;73;75;73;74;73;73;73;71 12:19:30 INFO - PID 7347 | |11;stanford-crypto-ccm;108;110;111;108;114;106;117;106;103;108 12:19:30 INFO - PID 7347 | |12;stanford-crypto-pbkdf2;165;162;165;156;155;157;156;154;153;156 12:19:30 INFO - PID 7347 | |13;stanford-crypto-sha256-iterative;67;62;63;62;58;60;62;59;60;61 12:19:30 INFO - PID 7347 | __end_tp_report 12:19:30 INFO - PID 7347 | __start_cc_report 12:19:30 INFO - PID 7347 | _x_x_mozilla_cycle_collect,732 12:19:30 INFO - PID 7347 | __end_cc_report 12:19:30 INFO - PID 7347 | __startTimestamp1505675970372__endTimestamp 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | ------- Summary: start ------- 12:19:30 INFO - PID 7347 | Number of tests: 14 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#0] ai-astar Cycles:10 Average:106.60 Median:107.00 stddev:2.72 (2.5%) stddev-sans-first:2.88 12:19:30 INFO - PID 7347 | Values: 107.0 107.0 105.0 105.0 104.0 113.0 108.0 104.0 105.0 108.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#1] audio-beat-detection Cycles:10 Average:116.50 Median:115.00 stddev:8.02 (7.0%) stddev-sans-first:8.49 12:19:30 INFO - PID 7347 | Values: 115.0 113.0 113.0 116.0 110.0 138.0 112.0 120.0 113.0 115.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:1.08 (0.7%) stddev-sans-first:1.13 12:19:30 INFO - PID 7347 | Values: 162.0 163.0 162.0 163.0 162.0 162.0 161.0 162.0 165.0 163.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#3] audio-fft Cycles:10 Average:73.30 Median:73.00 stddev:1.83 (2.5%) stddev-sans-first:1.36 12:19:30 INFO - PID 7347 | Values: 77.0 73.0 72.0 74.0 72.0 73.0 72.0 72.0 72.0 76.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:78.00 stddev:0.88 (1.1%) stddev-sans-first:0.60 12:19:30 INFO - PID 7347 | Values: 80.0 78.0 78.0 78.0 78.0 78.0 78.0 77.0 79.0 77.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 12:19:30 INFO - PID 7347 | Values: 113.0 113.0 113.0 114.0 113.0 113.0 114.0 114.0 114.0 114.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:150.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 12:19:30 INFO - PID 7347 | Values: 149.0 150.0 148.0 150.0 149.0 152.0 149.0 150.0 150.0 149.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:1.20 (1.3%) stddev-sans-first:1.27 12:19:30 INFO - PID 7347 | Values: 95.0 95.0 95.0 95.0 94.0 94.0 98.0 94.0 94.0 95.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#8] json-parse-financial Cycles:10 Average:45.30 Median:45.50 stddev:2.16 (4.8%) stddev-sans-first:2.28 12:19:30 INFO - PID 7347 | Values: 46.0 44.0 50.0 47.0 46.0 45.0 42.0 44.0 44.0 45.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.57 (1.5%) stddev-sans-first:0.44 12:19:30 INFO - PID 7347 | Values: 37.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 39.0 39.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:1.42 (1.9%) stddev-sans-first:1.12 12:19:30 INFO - PID 7347 | Values: 76.0 72.0 73.0 75.0 73.0 74.0 73.0 73.0 73.0 71.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#11] stanford-crypto-ccm Cycles:10 Average:109.10 Median:109.00 stddev:4.09 (3.8%) stddev-sans-first:4.32 12:19:30 INFO - PID 7347 | Values: 108.0 110.0 111.0 108.0 114.0 106.0 117.0 106.0 103.0 108.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:156.50 stddev:4.43 (2.8%) stddev-sans-first:3.89 12:19:30 INFO - PID 7347 | Values: 165.0 162.0 165.0 156.0 155.0 157.0 156.0 154.0 153.0 156.0 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:62.00 stddev:2.50 (4.0%) stddev-sans-first:1.64 12:19:30 INFO - PID 7347 | Values: 67.0 62.0 63.0 62.0 58.0 60.0 62.0 59.0 60.0 61.0 12:19:30 INFO - PID 7347 | -------- Summary: end -------- 12:19:30 INFO - PID 7347 | 12:19:30 INFO - PID 7347 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:19:30 INFO - TEST-INFO | 7347: exit 0 12:19:31 INFO - TEST-OK | kraken | took 58892ms 12:19:31 INFO - SUITE-END | took 1166s 12:19:31 INFO - Completed test suite (00:19:26) 12:19:31 INFO - javascript benchmark 12:19:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8994.833653104599, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3860.8841734747957, 3899.0642245860986, 3911.647951684348, 3920.823562939669, 3924.195539914317, 6145.4161854623735, 6215.782447614334, 6259.593126446778, 6259.843503912399, 6355.68221588921, 6243.0, 6323.0, 6328.303886572473, 6335.429811316981, 6352.650604216767, 5562.9443705563035, 5649.604527683064, 5662.66024038559, 5680.261565996425, 5703.258576385064, 233.1859265155004, 5644.604877658566, 5685.459881311269, 5723.828285151452, 5733.48398644122, 5589.7205139743055, 5599.7480113394795, 5605.663660180382, 5663.575231857627, 5685.289338832646, 5581.162825576155, 5596.972015139898, 5671.6880571568545, 5680.233168522279, 5681.204631351614, 5519.116941289414, 5537.280153580009, 5591.217229587861, 5606.243157173811, 5677.063284558043, 6161.568690191689, 6218.906716399258, 6233.937660623426, 6267.529935254828, 6285.151504546873, 6652.634105124216, 6741.460683145337, 6759.966200169017, 6835.726570937157, 6845.623490708009, 6758.628275444848, 6765.763198288037, 6854.6229957352325, 6854.862902741967, 6869.690863911086, 6324.0, 6335.303116657164, 6339.619622822645, 6418.679066046679, 6424.325445828167, 7040.0, 7061.364477196974, 7088.503804733619, 7153.535020223669, 7164.534305270141, 6648.634325112165, 6695.0, 6721.596704197764, 6761.695723692445, 6773.051772751819, 6782.8304292392095, 6821.113255276881, 6835.008923706035, 6849.520533562602, 6862.245153033163, 6594.967025164843, 6672.932330827141, 6717.6977036033495, 6718.66406679664, 6791.388775010273, 6678.699458524378, 6678.831204539186, 6709.832254193684, 6799.150106236721, 6803.693833777393, 4876.707397556158, 4922.064807686528, 4928.457869634337, 4935.358403407534, 4959.20652695567, 4505.549445055533, 4581.198290299184, 4595.563421474954, 4611.239145541015, 4618.007128467396, 6267.435930766162, 6269.435750782452, 6278.623282603058, 6362.45919096882, 6363.7772677956045, 6638.0, 6752.426043786333, 6789.524733268624, 6797.116374871235, 6895.310468953065, 6151.84620384494, 6165.506759459233, 6179.412955769195, 6226.066090086432, 6285.622862628257, 5394.649347792381, 5494.780208791603, 5535.474129957647, 5539.889202215933, 5543.36251331094, 6959.0, 7028.226895041541, 7051.612161331073, 7139.678714457758, 7185.6766445510075, 6678.532502724763, 6767.086443330186, 6774.187097548326, 6781.525293229427, 6783.389494945479, 6980.755673551402, 6999.545029573265, 7091.0, 7105.573665580082, 7196.532225405332, 6591.077249185214, 6675.399214070757, 6681.198256209092, 6699.5310328276555, 6701.597904125768, 6769.93230067693, 6777.152855893013, 6787.89818152738, 6807.693653785493, 6838.794836154923, 6583.0, 6632.369924857131, 6637.1703537057865, 6688.464922806261, 6689.0, 6556.213254409501, 6559.8032059038305, 6577.112089867806, 6669.966650166718, 6690.364415380531, 6413.102165696713, 6457.935420645734, 6457.967710161607, 6497.935020649733, 6590.538662293593, 6464.256610489666, 6487.643179625166, 6574.506911981716, 6601.438877695449, 6630.270670226368, 6357.141785859035, 6429.8714025720155, 6444.903326450013, 6448.935510644834, 6468.611883287018], "value": 6163.455585671918, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.3434792601397, 929.567750995787, 958.5878072428857, 969.1181025267005, 974.025974025974, 44389.55610443898, 45234.54765452344, 45277.32084018742, 45288.54711452885, 45383.773081134634, 42712.35931461031, 43009.7849510752, 43043.35434968478, 43154.0, 43188.0, 8050.074241462248, 8235.176482351777, 8268.793280167984, 8291.419600627958, 8297.95851020747, 7890.960545197295, 7910.485818421813, 7980.4812687175145, 7988.960055199745, 8001.599920004006, 7987.321077708401, 8014.7194848180325, 8039.236272554098, 8046.114927357986, 8049.557274349909, 7964.601769911481, 7966.6813327467125, 7975.122736498981, 7983.5209887406945, 7999.68001279951, 7959.124496305401, 8005.639746211435, 8008.919910800934, 8011.95994020032, 8020.919790802076, 39441.80279098615, 39441.816745497395, 40448.39327410091, 40590.39114413286, 40735.18529629391, 3685.2445248724216, 3699.4820725098507, 3739.9626003739886, 3750.549934007909, 3760.2103558252797, 33326.0, 33367.3326533468, 33505.497417538754, 33710.0, 33953.49069763956, 5306.867328316785, 5324.680519168824, 5399.352077750695, 5406.702631355274, 5563.805266815682, 220308.0, 220539.0, 220978.0, 221237.0, 222603.0, 46875.5312446878, 47902.760486197345, 53260.73369633127, 53391.19913201305, 53923.73038134784, 55516.444835551934, 55593.44406555963, 55653.0, 55795.721021395446, 56396.0, 16606.916965415097, 16618.086005269826, 16796.41212557579, 16796.49610511662, 16799.748003779954, 13564.0, 13580.728385432236, 13659.863401366058, 13660.863391366158, 13713.657158570915, 15236.23818809055, 15256.618584535476, 15336.309866056054, 15355.385784568492, 15487.922560387351, 5854.882902341929, 5877.52979761618, 5940.732667029994, 6025.855087533355, 6050.304215015241, 1065.6589891234885, 1078.2721662877527, 1090.4275255490772, 1092.093562343253, 1092.1644941619668, 25137.87431062833, 25483.872580636977, 25544.0, 25677.0, 25871.0, 1164.039667274485, 1185.5613423033533, 1192.1773975956562, 1197.7544603356291, 1197.8323034775137, 13273.734525309634, 13325.267110309027, 13396.397162127532, 13441.529546465827, 13506.392212350467, 306557.0, 309381.0, 309701.0, 310091.0, 310596.0, 59101.70449147727, 59516.70241648851, 59697.40302597005, 59728.40271597228, 59921.70039149776, 1496.4463148634857, 1508.3891024135023, 1523.5353217268619, 1532.494276888624, 1541.7841502189704, 24786.50426991414, 24804.422447514004, 24832.875835620707, 24859.12993045235, 25118.0, 41715.9571010715, 42072.579274206866, 42187.578124219595, 42202.1559568813, 42264.36603451012, 238686.0, 239250.0, 239927.0, 240734.0, 241358.0, 34312.828435857664, 34470.48294275538, 36694.633053670186, 36734.44898326581, 37373.0, 46439.071218576115, 46532.30201546912, 46709.0, 46732.53267467282, 46785.532144678116, 34214.82892585521, 34387.0, 34404.655953440146, 34607.13482162966, 35385.115372115906, 34074.65925340814, 34649.48025779565, 34679.479807802396, 34718.82640586882, 34731.652683473854, 40624.390634141106, 40713.0, 40764.1847163061, 40877.1824563513, 41020.0, 32722.50916236306, 32784.34431311408, 32993.67006330002, 33424.0, 33661.49507757337, 25324.74675253224, 25396.238112856645, 25685.614715778906, 25724.485510289316, 25816.483670326863], "value": 18565.903093102475, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.9762009519619, 595.6545203781807, 596.7702434562693, 605.4913872347228, 613.0987448450777, 23007.539849203025, 23727.288181354565, 23749.64375534364, 23769.762302377014, 23770.999999999978, 22902.65646015307, 22917.4270643234, 24728.876355618242, 24854.378640533996, 24906.626400604004, 6629.270780214172, 6709.865802683943, 6823.488238382129, 6824.522283440161, 6825.692843822015, 6434.292227854932, 6540.0, 6664.366885145928, 6682.4319932805765, 6698.765543205989, 6597.241317248506, 6613.3386661333725, 6637.966810165966, 6646.435053020499, 6647.966760166217, 6388.648624325637, 6586.308437614078, 6606.768763093317, 6619.867602647944, 6638.269790323061, 6102.572819902609, 6124.693765311761, 6142.938570614326, 6144.87710245797, 6174.289956654998, 28489.287767805763, 28958.710412895813, 29000.0, 29028.854855725585, 29070.0, 2234.944126396837, 2272.125231785758, 2272.1593010586025, 2278.8176945844293, 2279.2364557873134, 19955.50111247216, 20031.499212519804, 20462.0, 20589.279375221795, 20677.0, 2460.5078984203233, 2491.277529516438, 2547.4905018996274, 2558.8720563971915, 2571.768540831334, 132556.0, 138319.0, 146420.0, 150308.2484587581, 151547.9999999989, 15262.3895044197, 16006.519804405887, 16080.115593642227, 16109.436169734003, 16163.353465861233, 24498.755012450005, 24561.263162105166, 24564.263072107864, 24580.0, 24586.385340366276, 10473.738156546147, 10524.947375263075, 10546.789064218827, 10560.630377936888, 10733.248672593, 9350.0, 9444.291678124167, 9444.433333999981, 9519.904800952041, 9676.70969870905, 9174.724758257264, 9300.72097837066, 9307.906920930704, 9529.285303602119, 9540.475273859865, 2717.6331195288776, 2779.88880444784, 2870.641169853768, 2897.550879613621, 2909.1272618214875, 1034.3380236648552, 1048.4024106259512, 1054.6150655010865, 1058.9622170273176, 1062.129054175584, 20465.74876763555, 20559.954892245394, 20692.068856901173, 20837.583248334948, 21081.0, 1097.286763603664, 1114.2701530497536, 1121.243160866412, 1121.8653761548503, 1130.0507573638183, 11164.385958772345, 11536.480858361227, 11567.363794991355, 11602.941985290188, 11736.002439792714, 578582.0, 591071.0, 598625.0, 619718.0, 627793.0, 125197.37401312935, 125991.3700431492, 126663.0, 128313.0, 129857.0, 1085.3054045410943, 1131.5360702112096, 1178.452019810792, 1191.7437750883428, 1233.3524899427694, 20864.68702969426, 21377.35867923965, 21398.144074236858, 21497.462563436038, 21550.8922455393, 61697.38302616916, 62230.0, 62402.37597623966, 62567.374326257974, 63105.36894630995, 78903.60548197222, 79235.60382198052, 81281.18718812735, 81358.0, 81427.0, 14875.628109296922, 14951.850481495481, 15140.394384224508, 15158.393664253306, 15191.164485953379, 23005.964731586784, 23205.071797127926, 23309.650355244346, 23443.413914651588, 23589.646155308026, 18272.360467383576, 18292.542686432946, 18503.167357468676, 19150.23399064022, 19382.4185274442, 16901.577460563585, 17197.914010429868, 17237.827621723623, 17251.913740431217, 17407.82592174062, 19505.0, 19758.80241197627, 19774.80225197787, 19884.50288742793, 19963.301284454974, 14797.556073317817, 14866.033707808958, 14930.701385972437, 14966.32651530662, 14995.475158369405, 18734.156962936973, 19131.904340478766, 19156.904215478833, 19212.71180932259, 19217.231310747415], "value": 12547.153170198275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.0777102654304, 1480.8519148085193, 1486.7546854768964, 1492.4850926430381, 1507.781371701104, 16451.670966580677, 17130.14349282534, 17165.914170429147, 17171.914140429297, 17179.74230386542, 16748.58128546784, 16769.0, 16820.15899205041, 16834.747478787798, 16859.662806743858, 2867.397846452237, 2915.548090046041, 2968.450836595226, 2971.5542668599774, 2975.8363290019042, 2863.842488663123, 2869.942601147976, 2870.540713485843, 2873.956890646642, 2874.3245337345707, 2704.7836173106202, 2746.121241202816, 2750.0787236275773, 2750.2849259192653, 2751.1058905855575, 2622.056059818464, 2689.690685571155, 2705.661792275975, 2705.91882243533, 2706.3504758857734, 2575.1244576843774, 2598.558245098338, 2607.3351295419548, 2685.167598044593, 2813.3951200491992, 16289.185540722916, 16371.42700005494, 16452.259648315736, 16505.917470412573, 16587.585310367216, 8398.286145677625, 8667.566621668955, 8712.433691810076, 8713.56432178395, 8717.520536370497, 5778.248827652378, 5963.135345374896, 6081.483073938676, 6123.632582045091, 6150.600210986317, 2095.6646936490088, 2105.0, 2114.8625339352893, 2129.1057755742627, 2172.350451241913, 30039.399212015855, 30087.247818804488, 30155.0, 30194.245143871358, 30216.8489157555, 7382.640898391326, 7393.853952637242, 7436.553806771611, 7559.395248380117, 7625.733099341496, 19139.330123445892, 19157.904210478857, 19347.709784353247, 19415.708764368545, 19433.902830485757, 8279.62741676626, 8494.06565277819, 8528.829423411496, 8565.057843637194, 8573.27127194183, 7632.542047477169, 7717.2668596483245, 7981.1220765715725, 7985.441019128722, 7989.201079892081, 5769.538436925036, 5811.0411782056335, 5857.7656893724625, 5885.323187833368, 6107.6640784757265, 1684.2589260725488, 1705.296860861693, 1722.2336060453229, 1748.3094177799953, 1762.2246211666825, 896.5965315608003, 926.9165775080276, 930.9487978161267, 945.6926498887889, 951.9762005949768, 8019.35845132388, 8160.714374996846, 8179.509229446252, 9106.95446522763, 9184.311176661777, 760.5740785160328, 769.0924708843618, 776.755322073545, 778.980525486856, 781.5232708048086, 4646.09401166776, 4806.591439727662, 5395.892082158335, 5849.64902105875, 5850.736716847667, 24354.391140221283, 25270.494590108094, 25471.36321591975, 25528.468291902544, 25602.23193304204, 23981.760182398302, 25289.620655690178, 25298.241052768448, 25380.238592842245, 25538.361540961625, 11420.486078126503, 11766.058715302755, 11793.941030294794, 11848.348340841336, 11922.642320730392, 9122.90877091247, 9188.632454701738, 9284.860727088964, 9296.535173241446, 9302.581383837878, 20215.264383438338, 20853.582928341653, 20950.47623809417, 21137.365879023655, 21336.573268534852, 20951.58096838085, 21663.89168054213, 22097.11611535585, 22144.778552214273, 22420.215292464687, 12130.93934530357, 12151.0, 12301.93849030785, 12303.507859685871, 12317.014638828692, 15340.156291403633, 15420.614484637974, 15537.922310388376, 15550.444955504812, 15585.844141558438, 12226.14416990802, 12389.814152787896, 12405.565805196775, 12478.937605311916, 12695.238285702519, 11747.882521174679, 11771.0, 11776.234544754563, 11804.114691398008, 12035.217710848767, 10871.402072886067, 10879.23845330851, 10976.286541375104, 10980.341179529254, 11039.39283339424, 7963.601819909098, 8050.677972881019, 8093.635786389509, 8123.512589244664, 8139.877901831595, 7714.228577142241, 7824.334931530883, 7911.802204944923, 7916.6041697916025, 8036.87944680842], "value": 7204.302580218076, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.6378062917217, 560.3779804417094, 568.9658620482769, 569.8091139468278, 575.6776205325017, 6289.5282853785975, 6302.432781049704, 6350.714217860202, 6355.237371515413, 6362.618242905429, 6238.344973777756, 6240.4071613196675, 6259.593126446778, 6266.1540692006565, 6303.0, 5106.387233531981, 5132.127538318495, 5136.666116702402, 5151.896962060757, 5163.793448262065, 4842.176829938901, 5066.214736715797, 5069.594432445414, 5069.670471419365, 5099.133147364938, 4911.410630724318, 4940.8517744467545, 4982.900341993158, 4984.725840078794, 4999.0, 4944.085344211332, 4957.6033917286895, 4978.701277923336, 4990.850274491771, 4995.350604421438, 4507.414036175309, 4522.9547704522865, 4523.5024147343765, 4524.072565124142, 4528.93206601898, 6127.44852963231, 6158.3841615838055, 6167.392075635758, 6196.326991712258, 6205.720742566596, 5840.415958404168, 5873.618214816023, 5881.117832325142, 5890.908727401754, 5897.73460194288, 6533.444657204143, 6575.835604109888, 6601.471882249409, 6621.516931150819, 6686.93313066868, 5015.22264049073, 5020.221865610836, 5029.974850125763, 5034.798608055673, 5038.622103342264, 6804.727810887559, 6869.7595584154315, 6900.2064762551945, 6957.9215221640725, 6982.1272340957385, 5144.176931690911, 5170.870728231825, 5182.378114626269, 5191.013707395583, 5191.32512773337, 5183.874611409304, 5185.248139019821, 5208.765605547759, 5239.240310155083, 5250.816221432232, 5797.333306669702, 5803.100519419497, 5815.331236907725, 5846.298444186725, 5858.589898707135, 6720.4287635550545, 6721.630310332978, 6730.427913627299, 6732.0, 6737.427318677869, 1790.937317193918, 1791.8297761712618, 1794.9641007179782, 1820.8179182081951, 1824.8631352648604, 1202.0977002915643, 1264.7913094339515, 1280.5390059578544, 1298.4935875008755, 1302.9934850325687, 6182.536309776695, 6188.164597779242, 6202.782902598387, 6250.749970001149, 6280.842978925563, 6598.37315455031, 6710.429613482908, 6751.864962700718, 6765.661716914135, 6804.693788779517, 5988.191594134823, 6078.513718902477, 6137.5703700741415, 6165.348899972965, 6208.255009398901, 3512.8594856205978, 3520.7359448041498, 3536.29274145172, 3577.874774382884, 3590.2281009583066, 7056.54132481387, 7084.264355233001, 7100.147982242061, 7109.82225444368, 7125.679344429514, 6956.686949087303, 6956.791296261019, 6959.965200173967, 6986.056882320821, 7000.159980802337, 7144.8928266076055, 7150.427965762727, 7168.498205125591, 7171.852503599399, 7174.354308112295, 6801.319868013159, 6806.0, 6807.89788153188, 6823.38589526945, 6861.348171923659, 6710.020543939452, 6750.898736519054, 6778.32216778338, 6802.183737951477, 6822.931770682229, 6980.546264492995, 7061.364477197077, 7071.080759501232, 7136.571805691675, 7147.142342918883, 5068.417132029864, 5111.872203194801, 5140.254663073908, 5149.48505149482, 5152.367580855563, 6421.197350331208, 6685.197776266879, 6688.632125232964, 6704.363085506869, 6804.0, 6442.2591401989375, 6606.702698378488, 6617.569857959217, 6619.768308109193, 6630.602163870183, 6557.540972131905, 6582.473402127723, 6583.703733331916, 6589.901151482636, 6626.138601981905, 6548.672566371567, 6550.639714815731, 6553.640689351925, 6573.638449885302, 6614.702338394687, 6579.572327798678, 6681.398674119353, 6684.498662600227, 6706.966465167643, 6716.764913228014, 6329.2404911409085, 6330.620162790247, 6375.6174629522375, 6424.453921416732, 6432.549721519449], "value": 5191.757640685775, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [954.3176628710471, 957.8084383123372, 984.9073907929102, 997.8054279415508, 1011.1102230037566, 20090.397288081345, 20685.586288274244, 20733.585328293462, 20751.792482075176, 20757.688634670474, 19008.5247868803, 19038.61922761544, 19040.428787136407, 19060.523486912833, 19072.809271907277, 1041.2450973044538, 1053.557505847542, 1056.2553399853123, 1057.5241141486324, 1062.9893701063006, 1058.2962330050507, 1060.7984482948254, 1062.9636104797837, 1064.09551880901, 1065.589747947042, 1072.5977758340623, 1101.4657890922936, 1110.1673744691482, 1111.2165923024288, 1112.4826955465708, 1097.6981330134197, 1101.2071308657753, 1101.5924108080021, 1102.939338336391, 1103.8068338040812, 791.9604019799044, 807.4105902691068, 809.0332053196432, 817.146082343952, 819.8073452738602, 21139.577208455743, 21361.252356167613, 21426.14295428181, 21458.24896128628, 21473.355799326044, 21829.67255491169, 21915.67126493104, 22568.435789105402, 22808.429789255402, 22813.657795133087, 18674.159662815207, 19031.904840475847, 19057.714134287995, 19216.903915480474, 19218.615627687508, 3936.429217763437, 3962.960370396288, 3965.8810235692686, 3969.702272329587, 3971.6028397160344, 141537.0, 166075.99999999878, 166397.0, 168431.99999999878, 168927.0, 15415.61460963485, 15570.844291557167, 15725.921370393075, 15902.363905443653, 15964.281607327699, 34401.65598343984, 34427.0, 34694.0, 35349.64650353515, 35724.642753572654, 9284.582193801296, 9317.347785655074, 9333.719988400358, 9337.3930694506, 9362.297827662953, 4407.162639098561, 4447.066116115652, 4532.750698711602, 4553.954460455419, 4573.108243892409, 6003.969980150071, 6089.969550152309, 6093.390660933871, 6094.725737341831, 6124.265088189357, 1208.2629595946466, 1232.7904256276274, 1242.1491278474275, 1244.0420875925486, 1248.2385744695728, 728.9526180798231, 732.5531425830239, 738.0663460722191, 742.8662840688728, 745.3589912675095, 12722.427490762753, 13563.79390468772, 13874.45541388552, 14897.77653335201, 14989.325480353411, 703.5216053083952, 706.586646811621, 712.3197346534048, 714.4677215474504, 715.152544235083, 5735.0, 5816.127580862904, 5918.23063001807, 5921.818851471765, 5941.55438342126, 153109.0, 154646.0, 155017.0, 157072.0, 158275.0, 134664.99999999805, 134731.0, 136133.0, 136906.31546842202, 140183.29908350392, 114436.0, 115968.0, 116224.0, 118968.0, 119027.40486297687, 10892.128629709763, 10991.780164396507, 11042.447877605928, 11086.722831929266, 11267.943660281646, 53844.46155538395, 53885.0, 55571.0, 56274.43725562692, 56725.432745673665, 70103.64948175226, 70271.64864175646, 70315.64842175756, 72230.0, 73440.63279683568, 16667.583310417336, 16866.831331687015, 16927.15364231759, 17059.91470042642, 17095.145242737566, 19151.425457236306, 19395.2241910322, 19604.215831366586, 19655.70516442226, 19984.800151998876, 17128.57178570546, 17149.399771007957, 17200.741988870428, 17241.310347585957, 17357.65284694324, 16960.06719630432, 17045.829541704425, 17196.054217017685, 17256.568585785455, 17468.47594572165, 16472.0940348282, 16857.91571042137, 16957.49127526176, 17029.74455383195, 17079.31682732677, 13443.798343025059, 13745.656358591115, 13810.861891380957, 13825.861741382458, 14105.0, 13083.738325233633, 13106.0, 13133.277669728257, 13189.406476708764, 13391.129576577492], "value": 9861.436266524255, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1380.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 105.0, 105.0, 104.0, 113.0, 108.0, 104.0, 105.0, 108.0], "value": 106.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 113.0, 116.0, 110.0, 138.0, 112.0, 120.0, 113.0, 115.0], "value": 116.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 163.0, 162.0, 162.0, 161.0, 162.0, 165.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 72.0, 74.0, 72.0, 73.0, 72.0, 72.0, 72.0, 76.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 78.0, 78.0, 78.0, 78.0, 78.0, 77.0, 79.0, 77.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 148.0, 150.0, 149.0, 152.0, 149.0, 150.0, 150.0, 149.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 98.0, 94.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 44.0, 50.0, 47.0, 46.0, 45.0, 42.0, 44.0, 44.0, 45.0], "value": 45.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 39.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 73.0, 75.0, 73.0, 74.0, 73.0, 73.0, 73.0, 71.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 111.0, 108.0, 114.0, 106.0, 117.0, 106.0, 103.0, 108.0], "value": 109.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 165.0, 156.0, 155.0, 157.0, 156.0, 154.0, 153.0, 156.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 62.0, 63.0, 62.0, 58.0, 60.0, 62.0, 59.0, 60.0, 61.0], "value": 61.4, "unit": "score"}]}]} 12:19:31 INFO - Return code: 0 12:19:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:19:31 INFO - # TBPL SUCCESS # 12:19:31 INFO - Running post-action listener: _package_coverage_data 12:19:31 INFO - Running post-action listener: _resource_record_post_action 12:19:31 INFO - [mozharness: 2017-09-17 19:19:31.654104Z] Finished run-tests step (success) 12:19:31 INFO - Running post-run listener: _resource_record_post_run 12:19:31 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 434176; Write bytes: 386592768; Read time: 220; Write time: 238028 12:19:31 INFO - TinderboxPrint: CPU usage
13.1% 12:19:31 INFO - TinderboxPrint: I/O read bytes / time
434,176 / 220 12:19:31 INFO - TinderboxPrint: I/O write bytes / time
386,592,768 / 238,028 12:19:31 INFO - TinderboxPrint: CPU idle
8,212.6 (86.9%) 12:19:31 INFO - TinderboxPrint: CPU user
1,185.7 (12.5%) 12:19:31 INFO - TinderboxPrint: Swap in / out
0 / 0 12:19:31 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 75300864; Read time: 0; Write time: 38260 12:19:31 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:19:31 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 434176; Write bytes: 311291904; Read time: 220; Write time: 199768 12:19:32 INFO - Running post-run listener: _upload_blobber_files 12:19:32 INFO - Blob upload gear active. 12:19:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:19:32 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 12:19:32 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'] 12:19:32 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 12:19:32 INFO - (blobuploader) - INFO - Open directory for files ... 12:19:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:19:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:19:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:19:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:33 INFO - (blobuploader) - INFO - Done attempting. 12:19:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:19:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:19:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:19:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:42 INFO - (blobuploader) - INFO - Done attempting. 12:19:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:19:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:19:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:19:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:43 INFO - (blobuploader) - INFO - Done attempting. 12:19:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:19:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:19:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:19:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:19:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:19:44 INFO - (blobuploader) - INFO - Done attempting. 12:19:44 INFO - (blobuploader) - INFO - Iteration through files over. 12:19:44 INFO - Return code: 0 12:19:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:19:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:19:44 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/091ad9b3c0a87e157fdc20270d13b9781d98afef64eaa591de0641480730510465d51adee6d621819d9de57ee742fccd2aea9e2d83111016c00884b5dc723fc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f34372157a22d9f3daebd80bdeedd5019ec766e2fb527a6950c22436bbb44531b3509630c03ef6847ab6588e1a7320d0bb5e399d27187c3770833bbc13e8d87", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb8b2577b12ae7bbd0fc55fc5b6b2852aa887f73af0a47147b3da818c7dfbcc9f505cd6205b164d93e18b9d7fd24f7b312feefcf0672f520ede0bb84a23a7404", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bf60a17851c33a75ea647782ee900f2ae5e1580bab157857d2cb576ae0b44083c9d6dba8445e85652d0aee28e25e3da61ba76473ca384bbb5fbda618593fd51"} 12:19:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:19:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:19:44 INFO - Contents: 12:19:44 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/091ad9b3c0a87e157fdc20270d13b9781d98afef64eaa591de0641480730510465d51adee6d621819d9de57ee742fccd2aea9e2d83111016c00884b5dc723fc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f34372157a22d9f3daebd80bdeedd5019ec766e2fb527a6950c22436bbb44531b3509630c03ef6847ab6588e1a7320d0bb5e399d27187c3770833bbc13e8d87", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb8b2577b12ae7bbd0fc55fc5b6b2852aa887f73af0a47147b3da818c7dfbcc9f505cd6205b164d93e18b9d7fd24f7b312feefcf0672f520ede0bb84a23a7404", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bf60a17851c33a75ea647782ee900f2ae5e1580bab157857d2cb576ae0b44083c9d6dba8445e85652d0aee28e25e3da61ba76473ca384bbb5fbda618593fd51"} 12:19:44 INFO - Running post-run listener: copy_logs_to_upload_dir 12:19:44 INFO - Copying logs to upload dir... 12:19:44 INFO - mkdir: /builds/slave/test/build/upload/logs 12:19:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.652783 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-17 12:19:44.462436) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 12:19:44.465788) ========= 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=0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998 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/091ad9b3c0a87e157fdc20270d13b9781d98afef64eaa591de0641480730510465d51adee6d621819d9de57ee742fccd2aea9e2d83111016c00884b5dc723fc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f34372157a22d9f3daebd80bdeedd5019ec766e2fb527a6950c22436bbb44531b3509630c03ef6847ab6588e1a7320d0bb5e399d27187c3770833bbc13e8d87", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb8b2577b12ae7bbd0fc55fc5b6b2852aa887f73af0a47147b3da818c7dfbcc9f505cd6205b164d93e18b9d7fd24f7b312feefcf0672f520ede0bb84a23a7404", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bf60a17851c33a75ea647782ee900f2ae5e1580bab157857d2cb576ae0b44083c9d6dba8445e85652d0aee28e25e3da61ba76473ca384bbb5fbda618593fd51"} build_url:https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/Xmaam2KfRC-lYYSkSPgMHw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/091ad9b3c0a87e157fdc20270d13b9781d98afef64eaa591de0641480730510465d51adee6d621819d9de57ee742fccd2aea9e2d83111016c00884b5dc723fc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f34372157a22d9f3daebd80bdeedd5019ec766e2fb527a6950c22436bbb44531b3509630c03ef6847ab6588e1a7320d0bb5e399d27187c3770833bbc13e8d87", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb8b2577b12ae7bbd0fc55fc5b6b2852aa887f73af0a47147b3da818c7dfbcc9f505cd6205b164d93e18b9d7fd24f7b312feefcf0672f520ede0bb84a23a7404", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bf60a17851c33a75ea647782ee900f2ae5e1580bab157857d2cb576ae0b44083c9d6dba8445e85652d0aee28e25e3da61ba76473ca384bbb5fbda618593fd51"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 12:19:44.494160) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 12:19:44.494468) ========= 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=0e2a256762fed810e2f0f810000001b3-1505662103.173913-1620638998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 12:19:44.578055) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 12:19:44.578388) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 12:19:44.578681) ========= ========= Total master_lag: 0.24 =========