builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-080 starttime: 1505297785.77 results: success (0) revision: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:25.772141) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:25.772507) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:25.772765) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:26.445218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:26.449520) ========= 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=781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 03:16:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 112M=0s 2017-09-13 03:16:26 (112 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.353466 ========= master_lag: 0.48 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:27.282662) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:27.283003) ========= 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=781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014836 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:27.348912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:27.349352) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 124f2094635bbf1901c1d14fc2c0a80bf7f847bb --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 124f2094635bbf1901c1d14fc2c0a80bf7f847bb --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=781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 03:16:27,318 truncating revision to first 12 chars 2017-09-13 03:16:27,318 Setting DEBUG logging. 2017-09-13 03:16:27,318 attempt 1/10 2017-09-13 03:16:27,318 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/124f2094635b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 03:16:27,674 unpacking tar archive at: mozilla-central-124f2094635b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.630512 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:28.023891) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:28.024275) ========= script_repo_revision: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:28.028888) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:28.029182) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 03:16:28.051471) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-13 03:16:28.051745) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:16:28 INFO - MultiFileLogger online at 20170913 03:16:28 in /builds/slave/test 03:16:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:16:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:16:28 INFO - {'append_to_log': False, 03:16:28 INFO - 'base_work_dir': '/builds/slave/test', 03:16:28 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:16:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:16:28 INFO - 'branch': 'Firefox-Non-PGO', 03:16:28 INFO - 'buildbot_json_path': 'buildprops.json', 03:16:28 INFO - 'code_coverage': False, 03:16:28 INFO - 'config_files': ('talos/linux_config.py',), 03:16:28 INFO - 'default_actions': ('clobber', 03:16:28 INFO - 'read-buildbot-config', 03:16:28 INFO - 'download-and-extract', 03:16:28 INFO - 'populate-webroot', 03:16:28 INFO - 'create-virtualenv', 03:16:28 INFO - 'install', 03:16:28 INFO - 'setup-mitmproxy', 03:16:28 INFO - 'run-tests'), 03:16:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:16:28 INFO - 'disable_ccov_upload': False, 03:16:28 INFO - 'disable_stylo': False, 03:16:28 INFO - 'download_minidump_stackwalk': True, 03:16:28 INFO - 'download_symbols': 'ondemand', 03:16:28 INFO - 'enable_stylo': False, 03:16:28 INFO - 'enable_webrender': False, 03:16:28 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:16:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:16:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:16:28 INFO - 'gecko_profile': False, 03:16:28 INFO - 'gecko_profile_interval': 0, 03:16:28 INFO - 'installer_path': 'installer.exe', 03:16:28 INFO - 'log_level': 'info', 03:16:28 INFO - 'log_name': 'talos', 03:16:28 INFO - 'log_to_console': True, 03:16:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:16:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:16:28 INFO - 'opt_config_files': (), 03:16:28 INFO - 'pip_index': False, 03:16:28 INFO - 'suite': 'dromaeojs-e10s', 03:16:28 INFO - 'system_bits': '32', 03:16:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:16:28 INFO - 'title': 'talos-linux64-ix-080', 03:16:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:16:28 INFO - 'use_talos_json': True, 03:16:28 INFO - 'verify': 'False', 03:16:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:16:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:16:28 INFO - 'work_dir': 'build'} 03:16:28 INFO - [mozharness: 2017-09-13 10:16:28.154208Z] Running clobber step. 03:16:28 INFO - Running pre-action listener: _resource_record_pre_action 03:16:28 INFO - Running main action method: clobber 03:16:28 INFO - rmtree: /builds/slave/test/build 03:16:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:16:29 INFO - Running post-action listener: _resource_record_post_action 03:16:29 INFO - [mozharness: 2017-09-13 10:16:29.169819Z] Finished clobber step (success) 03:16:29 INFO - [mozharness: 2017-09-13 10:16:29.169917Z] Running read-buildbot-config step. 03:16:29 INFO - Running pre-action listener: _resource_record_pre_action 03:16:29 INFO - Running main action method: read_buildbot_config 03:16:29 INFO - Using buildbot properties: 03:16:29 INFO - { 03:16:29 INFO - "project": "", 03:16:29 INFO - "product": "firefox", 03:16:29 INFO - "who": "archaeopteryx@coole-files.de", 03:16:29 INFO - "installer_path": "public/build/target.tar.bz2", 03:16:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:16:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:16:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:16:29 INFO - "stage_platform": "linux64", 03:16:29 INFO - "basedir": "/builds/slave/test", 03:16:29 INFO - "buildnumber": 815, 03:16:29 INFO - "platform": "ubuntu64_hw", 03:16:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:16:29 INFO - "slavebuilddir": "test", 03:16:29 INFO - "taskId": "O_FBvG4wTK2kYhc1SI42UA", 03:16:29 INFO - "branch": "mozilla-central", 03:16:29 INFO - "script_repo_revision": "production", 03:16:29 INFO - "revision": "124f2094635bbf1901c1d14fc2c0a80bf7f847bb", 03:16:29 INFO - "slavename": "talos-linux64-ix-080", 03:16:29 INFO - "repo_path": "mozilla-central" 03:16:29 INFO - } 03:16:29 INFO - Finding installer, test and symbols from parent task. 03:16:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O_FBvG4wTK2kYhc1SI42UA'}, attempt #1 03:16:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O_FBvG4wTK2kYhc1SI42UA'}, attempt #1 03:16:30 INFO - Task dependencies: PtRvbvlCQYK7a280i3EPGQ 03:16:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ'}, attempt #1 03:16:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.tar.bz2 03:16:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.test_packages.json 03:16:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.crashreporter-symbols.zip 03:16:30 INFO - Running post-action listener: _resource_record_post_action 03:16:30 INFO - [mozharness: 2017-09-13 10:16:30.540436Z] Finished read-buildbot-config step (success) 03:16:30 INFO - [mozharness: 2017-09-13 10:16:30.540652Z] Running download-and-extract step. 03:16:30 INFO - Running pre-action listener: _resource_record_pre_action 03:16:30 INFO - Running main action method: download_and_extract 03:16:30 INFO - mkdir: /builds/slave/test/build/tests 03:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:30 INFO - trying https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.test_packages.json 03:16:30 INFO - Downloading https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:16:31 INFO - Downloaded 989 bytes. 03:16:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:16:31 INFO - Using the following test package requirements: 03:16:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:16:31 INFO - u'common': [u'target.common.tests.zip'], 03:16:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:16:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:16:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:16:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:16:31 INFO - u'mozbase': [u'target.common.tests.zip'], 03:16:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:16:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:16:31 INFO - u'web-platform': [u'target.common.tests.zip', 03:16:31 INFO - u'target.web-platform.tests.tar.gz'], 03:16:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:16:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:16:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.common.tests.zip 03:16:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:16:31 INFO - Fetch https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.common.tests.zip into memory 03:16:33 INFO - Content-Length response header: 55043887 03:16:33 INFO - Bytes received: 55043887 03:16:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.talos.tests.zip 03:16:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:16:43 INFO - Fetch https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.talos.tests.zip into memory 03:16:45 INFO - Content-Length response header: 14499231 03:16:45 INFO - Bytes received: 14499231 03:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:45 INFO - trying https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.tar.bz2 03:16:45 INFO - Downloading https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:16:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:16:48 INFO - Downloaded 62966811 bytes. 03:16:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.tar.bz2 03:16:48 INFO - mkdir: /builds/slave/test/properties 03:16:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:16:48 INFO - Writing to file /builds/slave/test/properties/build_url 03:16:48 INFO - Contents: 03:16:48 INFO - build_url:https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.tar.bz2 03:16:48 INFO - Running post-action listener: _resource_record_post_action 03:16:48 INFO - Running post-action listener: find_tests_for_verification 03:16:48 INFO - Running post-action listener: set_extra_try_arguments 03:16:48 INFO - [mozharness: 2017-09-13 10:16:48.622722Z] Finished download-and-extract step (success) 03:16:48 INFO - [mozharness: 2017-09-13 10:16:48.622821Z] Running populate-webroot step. 03:16:48 INFO - Running pre-action listener: _resource_record_pre_action 03:16:48 INFO - Running main action method: populate_webroot 03:16:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:16:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['tresize', 03:16:48 INFO - 'tcanvasmark']}, 03:16:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:16:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['dromaeo_css', 03:16:48 INFO - 'kraken']}, 03:16:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:16:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:16:48 INFO - 'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:16:48 INFO - 'tests': ['damp', 'tps']}, 03:16:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:16:48 INFO - 'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['damp', 'tps']}, 03:16:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:16:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['dromaeo_dom']}, 03:16:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:16:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['basic_compositor_video', 03:16:48 INFO - 'glvideo']}, 03:16:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:16:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:16:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:16:48 INFO - 'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['ts_paint_webext', 03:16:48 INFO - 'tp5o_webext']}, 03:16:48 INFO - 'other-e10s': {'tests': ['a11yr', 03:16:48 INFO - 'ts_paint', 03:16:48 INFO - 'tpaint', 03:16:48 INFO - 'sessionrestore', 03:16:48 INFO - 'sessionrestore_many_windows', 03:16:48 INFO - 'sessionrestore_no_auto_restore', 03:16:48 INFO - 'tabpaint']}, 03:16:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['a11yr', 03:16:48 INFO - 'ts_paint', 03:16:48 INFO - 'tpaint', 03:16:48 INFO - 'sessionrestore', 03:16:48 INFO - 'sessionrestore_many_windows', 03:16:48 INFO - 'sessionrestore_no_auto_restore', 03:16:48 INFO - 'tabpaint']}, 03:16:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:16:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:16:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['bloom_basic_singleton']}, 03:16:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['bloom_basic']}, 03:16:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:16:48 INFO - 'tsvgr_opacity', 03:16:48 INFO - 'tart', 03:16:48 INFO - 'tscrollx', 03:16:48 INFO - 'tsvg_static']}, 03:16:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['tsvgx', 03:16:48 INFO - 'tsvgr_opacity', 03:16:48 INFO - 'tart', 03:16:48 INFO - 'tscrollx', 03:16:48 INFO - 'tsvg_static']}, 03:16:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:16:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:16:48 INFO - 'talos_options': ['--disable-stylo'], 03:16:48 INFO - 'tests': ['tp5o']}, 03:16:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:16:48 INFO - 'talos_options': ['--mitmproxy', 03:16:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:16:48 INFO - '--firstNonBlankPaint'], 03:16:48 INFO - 'tests': ['tp6_google', 03:16:48 INFO - 'tp6_youtube', 03:16:48 INFO - 'tp6_amazon', 03:16:48 INFO - 'tp6_facebook']}, 03:16:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:16:48 INFO - 'talos_options': ['--disable-stylo', 03:16:48 INFO - '--mitmproxy', 03:16:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:16:48 INFO - '--firstNonBlankPaint'], 03:16:48 INFO - 'tests': ['tp6_google', 03:16:48 INFO - 'tp6_youtube', 03:16:48 INFO - 'tp6_amazon', 03:16:48 INFO - 'tp6_facebook']}, 03:16:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:16:48 INFO - 'talos_options': ['--stylo-threads=1', 03:16:48 INFO - '--mitmproxy', 03:16:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:16:48 INFO - '--firstNonBlankPaint'], 03:16:48 INFO - 'tests': ['tp6_google', 03:16:48 INFO - 'tp6_youtube', 03:16:48 INFO - 'tp6_amazon', 03:16:48 INFO - 'tp6_facebook']}, 03:16:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:16:48 INFO - 'talos_options': ['--xperf_path', 03:16:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:48 INFO - 'tests': ['tp5n']}, 03:16:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:16:48 INFO - 'talos_options': ['--disable-stylo', 03:16:48 INFO - '--xperf_path', 03:16:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:48 INFO - 'tests': ['tp5n']}}} 03:16:48 INFO - Running post-action listener: _resource_record_post_action 03:16:48 INFO - [mozharness: 2017-09-13 10:16:48.640857Z] Finished populate-webroot step (success) 03:16:48 INFO - [mozharness: 2017-09-13 10:16:48.640947Z] Running create-virtualenv step. 03:16:48 INFO - Running pre-action listener: _resource_record_pre_action 03:16:48 INFO - Running main action method: create_virtualenv 03:16:48 INFO - Creating virtualenv /builds/slave/test/build/venv 03:16:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:16:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:16:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:16:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:16:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:16:49 INFO - Installing distribute.............................................................................................................................................................................................done. 03:16:49 INFO - Installing pip...............done. 03:16:49 INFO - Return code: 0 03:16:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:16:49 INFO - Reading from file tmpfile_stdout 03:16:49 INFO - Output received: 03:16:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:16:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:16:49 INFO - Using env: {'DISPLAY': ':0', 03:16:49 INFO - 'HOME': '/home/cltbld', 03:16:49 INFO - 'LANG': 'en_US.UTF-8', 03:16:49 INFO - 'LANGUAGE': 'en_US:en', 03:16:49 INFO - 'LOGNAME': 'cltbld', 03:16:49 INFO - 'MAIL': '/var/mail/cltbld', 03:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:49 INFO - 'MOZ_NO_REMOTE': '1', 03:16:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:49 INFO - 'NO_EM_RESTART': '1', 03:16:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:49 INFO - 'PWD': '/builds/slave/test', 03:16:49 INFO - 'SHELL': '/bin/bash', 03:16:49 INFO - 'SHLVL': '1', 03:16:49 INFO - 'TERM': 'linux', 03:16:49 INFO - 'TMOUT': '86400', 03:16:49 INFO - 'USER': 'cltbld', 03:16:49 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:49 INFO - '_': '/tools/buildbot/bin/python'} 03:16:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:16:50 INFO - Downloading/unpacking pip>=1.5 03:16:50 INFO - Running setup.py egg_info for package pip 03:16:50 INFO - warning: no files found matching 'pip/cacert.pem' 03:16:50 INFO - warning: no files found matching '*.html' under directory 'docs' 03:16:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:16:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:16:50 INFO - Installing collected packages: pip 03:16:50 INFO - Found existing installation: pip 1.1 03:16:50 INFO - Uninstalling pip: 03:16:50 INFO - Successfully uninstalled pip 03:16:50 INFO - Running setup.py install for pip 03:16:50 INFO - warning: no files found matching 'pip/cacert.pem' 03:16:50 INFO - warning: no files found matching '*.html' under directory 'docs' 03:16:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:16:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:16:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Successfully installed pip 03:16:50 INFO - Cleaning up... 03:16:50 INFO - Return code: 0 03:16:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:16:50 INFO - Using env: {'DISPLAY': ':0', 03:16:50 INFO - 'HOME': '/home/cltbld', 03:16:50 INFO - 'LANG': 'en_US.UTF-8', 03:16:50 INFO - 'LANGUAGE': 'en_US:en', 03:16:50 INFO - 'LOGNAME': 'cltbld', 03:16:50 INFO - 'MAIL': '/var/mail/cltbld', 03:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:50 INFO - 'MOZ_NO_REMOTE': '1', 03:16:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:50 INFO - 'NO_EM_RESTART': '1', 03:16:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:50 INFO - 'PWD': '/builds/slave/test', 03:16:50 INFO - 'SHELL': '/bin/bash', 03:16:50 INFO - 'SHLVL': '1', 03:16:50 INFO - 'TERM': 'linux', 03:16:50 INFO - 'TMOUT': '86400', 03:16:50 INFO - 'USER': 'cltbld', 03:16:50 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:50 INFO - '_': '/tools/buildbot/bin/python'} 03:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:51 INFO - Downloading/unpacking psutil>=3.1.1 03:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:16:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:52 INFO - Installing collected packages: psutil 03:16:52 INFO - Running setup.py install for psutil 03:16:52 INFO - building 'psutil._psutil_linux' extension 03:16:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:16:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:16:52 INFO - building 'psutil._psutil_posix' extension 03:16:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:16:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:16:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:52 INFO - Successfully installed psutil 03:16:52 INFO - Cleaning up... 03:16:52 INFO - Return code: 0 03:16:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:52 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 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:16:52 INFO - Using env: {'DISPLAY': ':0', 03:16:52 INFO - 'HOME': '/home/cltbld', 03:16:52 INFO - 'LANG': 'en_US.UTF-8', 03:16:52 INFO - 'LANGUAGE': 'en_US:en', 03:16:52 INFO - 'LOGNAME': 'cltbld', 03:16:52 INFO - 'MAIL': '/var/mail/cltbld', 03:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:52 INFO - 'MOZ_NO_REMOTE': '1', 03:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:52 INFO - 'NO_EM_RESTART': '1', 03:16:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:52 INFO - 'PWD': '/builds/slave/test', 03:16:52 INFO - 'SHELL': '/bin/bash', 03:16:52 INFO - 'SHLVL': '1', 03:16:52 INFO - 'TERM': 'linux', 03:16:52 INFO - 'TMOUT': '86400', 03:16:52 INFO - 'USER': 'cltbld', 03:16:52 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:52 INFO - '_': '/tools/buildbot/bin/python'} 03:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:16:54 INFO - Installing collected packages: mozsystemmonitor 03:16:54 INFO - Running setup.py install for mozsystemmonitor 03:16:54 INFO - Successfully installed mozsystemmonitor 03:16:54 INFO - Cleaning up... 03:16:54 INFO - Return code: 0 03:16:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:16:54 INFO - Using env: {'DISPLAY': ':0', 03:16:54 INFO - 'HOME': '/home/cltbld', 03:16:54 INFO - 'LANG': 'en_US.UTF-8', 03:16:54 INFO - 'LANGUAGE': 'en_US:en', 03:16:54 INFO - 'LOGNAME': 'cltbld', 03:16:54 INFO - 'MAIL': '/var/mail/cltbld', 03:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:54 INFO - 'MOZ_NO_REMOTE': '1', 03:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:54 INFO - 'NO_EM_RESTART': '1', 03:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:54 INFO - 'PWD': '/builds/slave/test', 03:16:54 INFO - 'SHELL': '/bin/bash', 03:16:54 INFO - 'SHLVL': '1', 03:16:54 INFO - 'TERM': 'linux', 03:16:54 INFO - 'TMOUT': '86400', 03:16:54 INFO - 'USER': 'cltbld', 03:16:54 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:54 INFO - '_': '/tools/buildbot/bin/python'} 03:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:55 INFO - Downloading/unpacking jsonschema==2.5.1 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:16:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:56 INFO - Downloading functools32-3.2.3-2.tar.gz 03:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:16:56 INFO - warning: no files found matching '*.txt' 03:16:56 INFO - no previously-included directories found matching 'build' 03:16:56 INFO - no previously-included directories found matching 'dist' 03:16:56 INFO - no previously-included directories found matching '.git*' 03:16:56 INFO - Installing collected packages: jsonschema, functools32 03:16:56 INFO - Running setup.py install for functools32 03:16:57 INFO - warning: no files found matching '*.txt' 03:16:57 INFO - no previously-included directories found matching 'build' 03:16:57 INFO - no previously-included directories found matching 'dist' 03:16:57 INFO - no previously-included directories found matching '.git*' 03:16:57 INFO - Successfully installed jsonschema functools32 03:16:57 INFO - Cleaning up... 03:16:57 INFO - Return code: 0 03:16:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:16:57 INFO - Using env: {'DISPLAY': ':0', 03:16:57 INFO - 'HOME': '/home/cltbld', 03:16:57 INFO - 'LANG': 'en_US.UTF-8', 03:16:57 INFO - 'LANGUAGE': 'en_US:en', 03:16:57 INFO - 'LOGNAME': 'cltbld', 03:16:57 INFO - 'MAIL': '/var/mail/cltbld', 03:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:57 INFO - 'MOZ_NO_REMOTE': '1', 03:16:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:57 INFO - 'NO_EM_RESTART': '1', 03:16:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:57 INFO - 'PWD': '/builds/slave/test', 03:16:57 INFO - 'SHELL': '/bin/bash', 03:16:57 INFO - 'SHLVL': '1', 03:16:57 INFO - 'TERM': 'linux', 03:16:57 INFO - 'TMOUT': '86400', 03:16:57 INFO - 'USER': 'cltbld', 03:16:57 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:57 INFO - '_': '/tools/buildbot/bin/python'} 03:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:16:58 INFO - Cleaning up... 03:16:58 INFO - Return code: 0 03:16:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:16:58 INFO - Using env: {'DISPLAY': ':0', 03:16:58 INFO - 'HOME': '/home/cltbld', 03:16:58 INFO - 'LANG': 'en_US.UTF-8', 03:16:58 INFO - 'LANGUAGE': 'en_US:en', 03:16:58 INFO - 'LOGNAME': 'cltbld', 03:16:58 INFO - 'MAIL': '/var/mail/cltbld', 03:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:58 INFO - 'MOZ_NO_REMOTE': '1', 03:16:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:58 INFO - 'NO_EM_RESTART': '1', 03:16:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:58 INFO - 'PWD': '/builds/slave/test', 03:16:58 INFO - 'SHELL': '/bin/bash', 03:16:58 INFO - 'SHLVL': '1', 03:16:58 INFO - 'TERM': 'linux', 03:16:58 INFO - 'TMOUT': '86400', 03:16:58 INFO - 'USER': 'cltbld', 03:16:58 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:58 INFO - '_': '/tools/buildbot/bin/python'} 03:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:58 INFO - Downloading/unpacking blobuploader==1.2.4 03:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:59 INFO - Downloading blobuploader-1.2.4.tar.gz 03:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:16:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:16:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:59 INFO - Downloading docopt-0.6.1.tar.gz 03:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:17:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:17:00 INFO - Running setup.py install for blobuploader 03:17:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:17:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:17:00 INFO - Running setup.py install for requests 03:17:00 INFO - Running setup.py install for docopt 03:17:00 INFO - Successfully installed blobuploader requests docopt 03:17:00 INFO - Cleaning up... 03:17:00 INFO - Return code: 0 03:17:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:17:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:17:00 INFO - Using env: {'DISPLAY': ':0', 03:17:00 INFO - 'HOME': '/home/cltbld', 03:17:00 INFO - 'LANG': 'en_US.UTF-8', 03:17:00 INFO - 'LANGUAGE': 'en_US:en', 03:17:00 INFO - 'LOGNAME': 'cltbld', 03:17:00 INFO - 'MAIL': '/var/mail/cltbld', 03:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:00 INFO - 'MOZ_NO_REMOTE': '1', 03:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:00 INFO - 'NO_EM_RESTART': '1', 03:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:00 INFO - 'PWD': '/builds/slave/test', 03:17:00 INFO - 'SHELL': '/bin/bash', 03:17:00 INFO - 'SHLVL': '1', 03:17:00 INFO - 'TERM': 'linux', 03:17:00 INFO - 'TMOUT': '86400', 03:17:00 INFO - 'USER': 'cltbld', 03:17:00 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:00 INFO - '_': '/tools/buildbot/bin/python'} 03:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:00 INFO - Running setup.py (path:/tmp/pip-hLPpsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:00 INFO - Running setup.py (path:/tmp/pip-ZBzfJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:00 INFO - Running setup.py (path:/tmp/pip-kLRmz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:01 INFO - Running setup.py (path:/tmp/pip-63bNua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:01 INFO - Running setup.py (path:/tmp/pip-WsLVSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:01 INFO - Running setup.py (path:/tmp/pip-fnsR1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:01 INFO - Running setup.py (path:/tmp/pip-tPL5gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:01 INFO - Running setup.py (path:/tmp/pip-iNqdTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:01 INFO - Running setup.py (path:/tmp/pip-BWpMv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:01 INFO - Running setup.py (path:/tmp/pip-TETcBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:01 INFO - Running setup.py (path:/tmp/pip-r5FZiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:01 INFO - Running setup.py (path:/tmp/pip-RwSGDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:01 INFO - Running setup.py (path:/tmp/pip-JWS9O_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:01 INFO - Running setup.py (path:/tmp/pip-TeUJT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:01 INFO - Running setup.py (path:/tmp/pip-oPRddg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:01 INFO - Running setup.py (path:/tmp/pip-FQ7Ynv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:01 INFO - Running setup.py (path:/tmp/pip-iavoKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:17:02 INFO - Running setup.py install for manifestparser 03:17:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:17:02 INFO - Running setup.py install for mozcrash 03:17:02 INFO - Running setup.py install for mozdebug 03:17:02 INFO - Running setup.py install for mozdevice 03:17:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:17:02 INFO - Running setup.py install for mozfile 03:17:02 INFO - Running setup.py install for mozhttpd 03:17:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:17:02 INFO - Running setup.py install for mozinfo 03:17:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:17:02 INFO - Running setup.py install for mozInstall 03:17:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:17:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:17:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:17:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:17:02 INFO - Running setup.py install for mozleak 03:17:02 INFO - Running setup.py install for mozlog 03:17:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:17:03 INFO - Running setup.py install for moznetwork 03:17:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:17:03 INFO - Running setup.py install for mozprocess 03:17:03 INFO - Running setup.py install for mozprofile 03:17:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:17:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:17:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:17:03 INFO - Running setup.py install for mozrunner 03:17:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:17:03 INFO - Running setup.py install for mozscreenshot 03:17:03 INFO - Running setup.py install for moztest 03:17:03 INFO - Running setup.py install for mozversion 03:17:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:17:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:17:03 INFO - Cleaning up... 03:17:03 INFO - Return code: 0 03:17:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:17:03 INFO - Using env: {'DISPLAY': ':0', 03:17:03 INFO - 'HOME': '/home/cltbld', 03:17:03 INFO - 'LANG': 'en_US.UTF-8', 03:17:03 INFO - 'LANGUAGE': 'en_US:en', 03:17:03 INFO - 'LOGNAME': 'cltbld', 03:17:03 INFO - 'MAIL': '/var/mail/cltbld', 03:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:03 INFO - 'MOZ_NO_REMOTE': '1', 03:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:03 INFO - 'NO_EM_RESTART': '1', 03:17:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:03 INFO - 'PWD': '/builds/slave/test', 03:17:03 INFO - 'SHELL': '/bin/bash', 03:17:03 INFO - 'SHLVL': '1', 03:17:03 INFO - 'TERM': 'linux', 03:17:03 INFO - 'TMOUT': '86400', 03:17:03 INFO - 'USER': 'cltbld', 03:17:03 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:03 INFO - '_': '/tools/buildbot/bin/python'} 03:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:04 INFO - Running setup.py (path:/tmp/pip-uf4y9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:04 INFO - Running setup.py (path:/tmp/pip-qul1Qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:04 INFO - Running setup.py (path:/tmp/pip-uSNEXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:04 INFO - Running setup.py (path:/tmp/pip-8i920N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:04 INFO - Running setup.py (path:/tmp/pip-eUHeiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:04 INFO - Running setup.py (path:/tmp/pip-1Vp1Jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:04 INFO - Running setup.py (path:/tmp/pip-VrgnMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:04 INFO - Running setup.py (path:/tmp/pip-HY4k2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:04 INFO - Running setup.py (path:/tmp/pip-UGQ53t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:04 INFO - Running setup.py (path:/tmp/pip-4xfhMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:04 INFO - Running setup.py (path:/tmp/pip-TuBeuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:04 INFO - Running setup.py (path:/tmp/pip-hIpZYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:04 INFO - Running setup.py (path:/tmp/pip-li2n4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:04 INFO - Running setup.py (path:/tmp/pip-detT_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:05 INFO - Running setup.py (path:/tmp/pip-g7cipA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:05 INFO - Running setup.py (path:/tmp/pip-As2RkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:05 INFO - Running setup.py (path:/tmp/pip-yAj0cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:17:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:06 INFO - Downloading blessings-1.6.tar.gz 03:17:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:17:06 INFO - Installing collected packages: blessings 03:17:06 INFO - Running setup.py install for blessings 03:17:06 INFO - Successfully installed blessings 03:17:06 INFO - Cleaning up... 03:17:06 INFO - Return code: 0 03:17:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:17:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:06 INFO - Reading from file tmpfile_stdout 03:17:06 INFO - Current package versions: 03:17:06 INFO - argparse == 1.2.1 03:17:06 INFO - blessings == 1.6 03:17:06 INFO - blobuploader == 1.2.4 03:17:06 INFO - docopt == 0.6.1 03:17:06 INFO - functools32 == 3.2.3-2 03:17:06 INFO - jsonschema == 2.5.1 03:17:06 INFO - manifestparser == 1.1 03:17:06 INFO - mozInstall == 1.13 03:17:06 INFO - mozcrash == 1.0 03:17:06 INFO - mozdebug == 0.1 03:17:06 INFO - mozdevice == 0.51 03:17:06 INFO - mozfile == 1.2 03:17:06 INFO - mozhttpd == 0.7 03:17:06 INFO - mozinfo == 0.10 03:17:06 INFO - mozleak == 0.1 03:17:06 INFO - mozlog == 3.5 03:17:06 INFO - moznetwork == 0.27 03:17:06 INFO - mozprocess == 0.25 03:17:06 INFO - mozprofile == 0.28 03:17:06 INFO - mozrunner == 6.13 03:17:06 INFO - mozscreenshot == 0.1 03:17:06 INFO - mozsystemmonitor == 0.3 03:17:06 INFO - moztest == 0.8 03:17:06 INFO - mozversion == 1.4 03:17:06 INFO - psutil == 3.1.1 03:17:06 INFO - requests == 1.2.3 03:17:06 INFO - wsgiref == 0.1.2 03:17:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:17:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:17:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:17:06 INFO - Using env: {'DISPLAY': ':0', 03:17:06 INFO - 'HOME': '/home/cltbld', 03:17:06 INFO - 'LANG': 'en_US.UTF-8', 03:17:06 INFO - 'LANGUAGE': 'en_US:en', 03:17:06 INFO - 'LOGNAME': 'cltbld', 03:17:06 INFO - 'MAIL': '/var/mail/cltbld', 03:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:06 INFO - 'MOZ_NO_REMOTE': '1', 03:17:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:06 INFO - 'NO_EM_RESTART': '1', 03:17:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:06 INFO - 'PWD': '/builds/slave/test', 03:17:06 INFO - 'SHELL': '/bin/bash', 03:17:06 INFO - 'SHLVL': '1', 03:17:06 INFO - 'TERM': 'linux', 03:17:06 INFO - 'TMOUT': '86400', 03:17:06 INFO - 'USER': 'cltbld', 03:17:06 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:06 INFO - '_': '/tools/buildbot/bin/python'} 03:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:17:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:17:08 INFO - Installing collected packages: simplejson 03:17:08 INFO - Running setup.py install for simplejson 03:17:08 INFO - building 'simplejson._speedups' extension 03:17:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:17:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:17:09 INFO - Successfully installed simplejson 03:17:09 INFO - Cleaning up... 03:17:09 INFO - Return code: 0 03:17:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, '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': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:17:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:17:09 INFO - Using env: {'DISPLAY': ':0', 03:17:09 INFO - 'HOME': '/home/cltbld', 03:17:09 INFO - 'LANG': 'en_US.UTF-8', 03:17:09 INFO - 'LANGUAGE': 'en_US:en', 03:17:09 INFO - 'LOGNAME': 'cltbld', 03:17:09 INFO - 'MAIL': '/var/mail/cltbld', 03:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:09 INFO - 'MOZ_NO_REMOTE': '1', 03:17:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:09 INFO - 'NO_EM_RESTART': '1', 03:17:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:09 INFO - 'PWD': '/builds/slave/test', 03:17:09 INFO - 'SHELL': '/bin/bash', 03:17:09 INFO - 'SHLVL': '1', 03:17:09 INFO - 'TERM': 'linux', 03:17:09 INFO - 'TMOUT': '86400', 03:17:09 INFO - 'USER': 'cltbld', 03:17:09 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:09 INFO - '_': '/tools/buildbot/bin/python'} 03:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:17:09 INFO - Cleaning up... 03:17:09 INFO - Return code: 0 03:17:09 INFO - Running post-action listener: _resource_record_post_action 03:17:09 INFO - Running post-action listener: _start_resource_monitoring 03:17:09 INFO - Starting resource monitoring. 03:17:09 INFO - [mozharness: 2017-09-13 10:17:09.491256Z] Finished create-virtualenv step (success) 03:17:09 INFO - [mozharness: 2017-09-13 10:17:09.491763Z] Running install step. 03:17:09 INFO - Running pre-action listener: _resource_record_pre_action 03:17:09 INFO - Running main action method: install 03:17:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:09 INFO - Reading from file tmpfile_stdout 03:17:09 INFO - Detecting whether we're running mozinstall >=1.0... 03:17:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:17:09 INFO - Reading from file tmpfile_stdout 03:17:09 INFO - Output received: 03:17:09 INFO - Usage: mozinstall [options] installer 03:17:09 INFO - Options: 03:17:09 INFO - -h, --help show this help message and exit 03:17:09 INFO - -d DEST, --destination=DEST 03:17:09 INFO - Directory to install application into. [default: 03:17:09 INFO - "/builds/slave/test"] 03:17:09 INFO - --app=APP Application being installed. [default: firefox] 03:17:09 INFO - mkdir: /builds/slave/test/build/application 03:17:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:17:26 INFO - Reading from file tmpfile_stdout 03:17:26 INFO - Output received: 03:17:26 INFO - /builds/slave/test/build/application/firefox/firefox 03:17:26 INFO - Running post-action listener: _resource_record_post_action 03:17:26 INFO - [mozharness: 2017-09-13 10:17:26.392075Z] Finished install step (success) 03:17:26 INFO - [mozharness: 2017-09-13 10:17:26.392326Z] Running setup-mitmproxy step. 03:17:26 INFO - Running pre-action listener: _resource_record_pre_action 03:17:26 INFO - Running main action method: setup_mitmproxy 03:17:26 INFO - Skipping: mitmproxy is not required 03:17:26 INFO - Running post-action listener: _resource_record_post_action 03:17:26 INFO - [mozharness: 2017-09-13 10:17:26.393529Z] Finished setup-mitmproxy step (success) 03:17:26 INFO - [mozharness: 2017-09-13 10:17:26.393750Z] Running run-tests step. 03:17:26 INFO - Running pre-action listener: _resource_record_pre_action 03:17:26 INFO - Running pre-action listener: _set_gcov_prefix 03:17:26 INFO - Running main action method: run_tests 03:17:26 WARNING - Try message not found. 03:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:17:26 INFO - Python 2.7.3 03:17:26 INFO - Return code: 0 03:17:26 INFO - grabbing minidump binary from tooltool 03:17: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')]} 03:17:26 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 0x1df4090>, '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 0x1df4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1deef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:17:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:17:26 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:17:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:17:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:17:26 INFO - Return code: 0 03:17:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:17:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:17:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:17:26 INFO - ENV: RUST_BACKTRACE is now full 03:17:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:17:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-080 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:17:26 INFO - Using env: {'DISPLAY': ':0', 03:17:26 INFO - 'HOME': '/home/cltbld', 03:17:26 INFO - 'LANG': 'en_US.UTF-8', 03:17:26 INFO - 'LANGUAGE': 'en_US:en', 03:17:26 INFO - 'LOGNAME': 'cltbld', 03:17:26 INFO - 'MAIL': '/var/mail/cltbld', 03:17:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:26 INFO - 'MOZ_NO_REMOTE': '1', 03:17:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:26 INFO - 'NO_EM_RESTART': '1', 03:17:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:26 INFO - 'PWD': '/builds/slave/test', 03:17:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:26 INFO - 'RUST_BACKTRACE': 'full', 03:17:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:17:26 INFO - 'SHELL': '/bin/bash', 03:17:26 INFO - 'SHLVL': '1', 03:17:26 INFO - 'TERM': 'linux', 03:17:26 INFO - 'TMOUT': '86400', 03:17:26 INFO - 'USER': 'cltbld', 03:17:26 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:26 INFO - '_': '/tools/buildbot/bin/python'} 03:17:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:17:26 INFO - mozversion application_buildid: 20170913092615 03:17:26 INFO - mozversion application_changeset: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb 03:17:26 INFO - mozversion application_display_name: Nightly 03:17:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:17:26 INFO - mozversion application_name: Firefox 03:17:26 INFO - mozversion application_remotingname: firefox 03:17:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:17:26 INFO - mozversion application_vendor: Mozilla 03:17:26 INFO - mozversion application_version: 57.0a1 03:17:26 INFO - mozversion platform_buildid: 20170913092615 03:17:26 INFO - mozversion platform_changeset: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb 03:17:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:17:26 INFO - mozversion platform_version: 57.0a1 03:17:26 INFO - using testdate: 1505297846 03:17:26 INFO - actual date: 1505297846 03:17:26 INFO - starting webserver on 'localhost:57595' 03:17:26 INFO - SUITE-START | Running 2 tests 03:17:26 INFO - TEST-START | dromaeo_css 03:17:26 INFO - Initialising browser for dromaeo_css test... 03:17:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57595/getInfo.html -profile /tmp/tmpIYftnv/profile 03:17:26 INFO - TEST-INFO | started process 5231 (/builds/slave/test/build/application/firefox/firefox http://localhost:57595/getInfo.html) 03:17:32 INFO - TEST-INFO | 5231: exit 0 03:17:32 INFO - Browser initialized. 03:17:32 INFO - Running cycle 1/1 for dromaeo_css test... 03:17:32 INFO - Using env: {'DISPLAY': ':0', 03:17:32 INFO - 'HOME': '/home/cltbld', 03:17:32 INFO - 'JSGC_DISABLE_POISONING': '1', 03:17:32 INFO - 'LANG': 'en_US.UTF-8', 03:17:32 INFO - 'LANGUAGE': 'en_US:en', 03:17:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:17:32 INFO - 'LOGNAME': 'cltbld', 03:17:32 INFO - 'MAIL': '/var/mail/cltbld', 03:17:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:32 INFO - 'MOZ_CRASHREPORTER': '1', 03:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:17:32 INFO - 'MOZ_NO_REMOTE': '1', 03:17:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:32 INFO - 'NO_EM_RESTART': '1', 03:17:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:32 INFO - 'PWD': '/builds/slave/test', 03:17:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:32 INFO - 'RUST_BACKTRACE': 'full', 03:17:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:17:32 INFO - 'SHELL': '/bin/bash', 03:17:32 INFO - 'SHLVL': '1', 03:17:32 INFO - 'TERM': 'linux', 03:17:32 INFO - 'TMOUT': '86400', 03:17:32 INFO - 'USER': 'cltbld', 03:17:32 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:32 INFO - '_': '/tools/buildbot/bin/python'} 03:17:32 INFO - TEST-INFO | started process 5427 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIYftnv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:33 INFO - PID 5427 | 03:17:33 INFO - PID 5427 | (/builds/slave/test/build/application/firefox/firefox:5478): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:17:33 INFO - PID 5427 | 03:17:44 INFO - PID 5427 | RSS: Main: 173293568 03:17:44 INFO - PID 5427 | 03:20:40 INFO - PID 5427 | Cycle 1(1): loaded http://localhost:57595/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57595/tests/dromaeo/cssquery-ext.html) 03:20:42 INFO - PID 5427 | RSS: Main: 175919104 03:20:42 INFO - PID 5427 | 03:23:44 INFO - PID 5427 | Cycle 1(1): loaded http://localhost:57595/tests/dromaeo/cssquery-ext.html (next: http://localhost:57595/tests/dromaeo/cssquery-jquery.html) 03:23:44 INFO - PID 5427 | RSS: Main: 171577344 03:23:44 INFO - PID 5427 | 03:26:46 INFO - PID 5427 | Cycle 1(1): loaded http://localhost:57595/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57595/tests/dromaeo/cssquery-mootools.html) 03:26:47 INFO - PID 5427 | RSS: Main: 174129152 03:26:47 INFO - PID 5427 | 03:29:48 INFO - PID 5427 | Cycle 1(1): loaded http://localhost:57595/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57595/tests/dromaeo/cssquery-prototype.html) 03:29:49 INFO - PID 5427 | RSS: Main: 172359680 03:29:49 INFO - PID 5427 | 03:32:52 INFO - PID 5427 | Cycle 1(1): loaded http://localhost:57595/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57595/tests/dromaeo/cssquery-yui.html) 03:32:53 INFO - PID 5427 | RSS: Main: 174039040 03:32:53 INFO - PID 5427 | 03:35:54 INFO - PID 5427 | Cycle 1(1): loaded http://localhost:57595/tests/dromaeo/cssquery-yui.html (next: http://localhost:57595/tests/dromaeo/cssquery-dojo.html) 03:35:55 INFO - PID 5427 | RSS: Main: 176730112 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | __start_tp_report 03:35:55 INFO - PID 5427 | _x_x_mozilla_page_load 03:35:55 INFO - PID 5427 | _x_x_mozilla_page_load_details 03:35:55 INFO - PID 5427 | |i|pagename|runs| 03:35:55 INFO - PID 5427 | |0;dojo.html;3166.3983843069814;3184.3153721949775;3187.442197615418;3189.330240649464;3192.6807319268078;6158.137860699506;6334.081558174062;6343.873122537552;6372.904406433901;6466.482681385483;6349.047642853574;6380.202474690664;6406.551541392105;6428.710708018139;6450.258220304667;5839.708014599285;5840.328362238333;5843.357230704619;5905.114232865061;5950.880982380349;5869.148973398855;5914.053751399777;5921.319048309435;5925.674087925162;5926.496247818919;1325.6235967151606;5750.597458177929;5907.350191478934;5967.16459695643;5983.700814959235;5440.809571664972;5782.074868021096;5812.505936995361;5865.58940874139;5902.468777809975;5793.217915581385;5811.6222445541325;5845.269341332333;5868.207791948118;5869;6339.714712837934;6346.619202847798;6365.331640177761;6413.7434502619835;6459.450946669492;6802.251752307242;6815.250322464525;6870.793876183722;6888.862222755517;6912.170539535237;6667.299933506961;6759.155105611799;6862.245153033163;6869.931300687029;6963.40811031063;6127.080937859312;6233.532485063683;6240.438360547527;6257.092721555395;6269.561130720851;6945.374916257561;7007.824804379931;7081.150261968493;7086.716531338792;7097.538659987084;6680.031395447633;6687.765928192587;6758.324167583202;6769.458443324522;6787.04981302618;6655.234648015444;6772.424343930723;6784.830379240559;6812.046313516112;6864.38220560142;6834.419074378733;6841.589504629738;6842.178938527309;6849.623270720158;6883.449324054063;6795.3204679531655;6811.897821532681;6893.20728116263;6903.033575299462;6907.378335949788;5248.8425347239645;5342.652727572696;5348.946510534923;5423.701696406657;5434.347878254634;4579.244424669892;4595.035042641083;4617.238155704338;4617.676762626651;4620.953790462119;5760.510356619734;5768.134779783066;5829.970850145722;5901.734421951022;5909.3204281507315;6779.559328643622;6800.251972283045;6828.487863410264;6863.210730765926;6884.65576721162;5848.64908105515;5850.47345738877;5905.881882362329;5908.645481271052;5917.230760001173;5690.743916523684;5762.1068734346245;5853.502452291603;5905.320888097838;5906;6529.15121034272;6547.836304092436;6556.3443655634055;6580.078788969548;6587.374199451045;6717.731290748316;6723.798286051524;6792.117024786746;6798.490113241625;6822.931770682229;6448.32292609288;6476.190476190476;6490;6569.441597464078;6576.079348891255;6364.459020983269;6519.08732777421;6529.5102867284195;6533.314002029719;6534.673266336759;6773.796786096425;6794.252632210551;6864.5881247125335;6878.174619045746;6887.690053947485;6518.73925042993;6584.539082264196;6648.966755166193;6650.434713049445;6651.900221496585;6613.702383392662;6614.173228346457;6658.167729033871;6679.998000299994;6689.966550167218;6354.968225158845;6492.74029038852;6509.316521765336;6524.347565243438;6625.801225963229;6622.933770662232;6658.1344425224415;6719.630420326929;6723.361280678328;6740.764073257413;6397.648129352744;6463.806085817433;6471.449926756279;6473.320301368289;6473.385028422293 03:35:55 INFO - PID 5427 | |1;ext.html;858.7638399440153;880.0891077734547;883.2404132446095;886.2732559301368;890.8930928288602;38800.41799373008;42247;42362.57637423625;42386.57613423865;42416.36375454371;38642.227155456945;38766.8061659692;38836.41745373815;38858.611413885854;38903;7002.264762199972;7121.038659780941;7743.690252389898;7938.880916786253;7951.244631760002;7782.571958542278;7885.566293853835;7889.250521200477;7891.250331218525;7893.763187104368;7841.215878412142;7861.292483676468;7879.724209652663;7908.604569771517;7915.762527124167;7842.72550460734;7875.4487185897015;7875.763727088197;7882.921170788276;7886.2113788621255;7726.647836628567;7798.844023119505;7805.4536182467255;7807.8438431231625;7809.101953275333;38204;38286.277117529775;38394.61605383938;38475.61524384748;38477.230455391014;3349.062262566485;3369.124027752802;3377.8311084445927;3378.763486555942;3383.5939687237687;30482.54276185859;30547.23631909198;30644.08067757971;30682;30817.38365232705;4808.422989241313;4900.975495122501;4905.656604037718;4951.455339912607;4957.677750946213;189016;190544;191049;191222;191233;41965.1606967865;42857;49259.26111108336;49326;49660;52373.47626523686;53640.195397069074;53705.73147134239;53733.73133134309;53771;12956.481740730265;13013.479460821462;13094.14888032275;13098.2141071536;13114.082014259102;10456.68629941103;10615.946920265504;10616.734581635366;10620.628278010387;10641.467926603638;14560.49038283655;14569.41722331095;14617.269136543131;14648.1927231496;14729.55811325662;5748.6838223898085;5847.766089356463;5848.795292164754;5888.4700376966275;6012.518998480111;974.4153507895348;975.5317447625038;980.548947484151;991.5686676295834;997.2371136080903;20533.178672853217;20593.79406205949;21055.473613159487;21070.78929210719;21273.04271307795;1058.878229003659;1072.2065671403107;1081.9134469242442;1082.1775450657558;1102.311055590256;10753.946230268955;10902.836457453144;10943.343399395902;10967.341959482457;11024.173187011007;215659;216712;217496;217756;217830;52635;52671.47328526742;52745.7362713184;53108.20337694938;53147.73426132845;1438.3455527734645;1447.167878469884;1454.70178613384;1459.496473716566;1461.919594422317;21801.890990544947;22347.10545639832;22493.32520024402;22540.32379028632;22697;37621.435678464295;37651.81174094112;37682;37687.24625507529;37712.245755084194;194125;194801;195053;195082;196073;34451.13872153216;35431.645683543866;36016.459753104245;36100.458493122096;36156.63843361533;44240.77879610581;45343.54656453525;45400.77299613481;45538.77230613826;45740.54259457363;32691.836540817145;32788.344233115684;32810.50784238286;32822.67177328196;32979.83510082434;32465.675343246265;32762.1809454756;32967.50548741819;33217.169570760925;33786.49320260247;36482;36678;36952.630473695994;37029.44455833219;37157.25685486329;32031;32229.677703222667;32239.838800805846;32412;32896.342073158885;23754.881225594454;24044.87977560171;24081;24169.879150604134;24657.50684986326 03:35:55 INFO - PID 5427 | |2;jquery.html;495.41540981641657;556.2268446858869;564.2099816407865;564.4017341617885;570.5093619487243;21757.12971481141;22465.887670561664;22490.100395984184;22552.88723556382;22557.210497632586;23733;24228.394290142754;24564.26307210782;24621.384465388375;24640.630390544113;6393.648349340784;6402;6403.871922561546;6408.263049749292;6436.517261205405;6354.7775827846035;6472.676366181696;6476.773312934049;6477.773277935274;6496.772612958547;6260.373962603749;6442.871142577145;6490.578112422701;6496.155499785022;6496.740130394802;6472.579282346656;6509.674516274191;6513.120728701612;6515.218173819125;6536.705848236817;5717.456841600041;5904;5908.793192238251;6037.124616930564;6049.758009679607;29074.127776166748;29558.70441295581;29601.259968500744;29609.70390296091;29645.110646680634;1970.6748386516208;1988.1053525913396;1988.8508361872773;1989.333573252962;1993.2425678242175;18711.906440467847;18945.431637050773;19292;19302.13140408671;19689.310874119335;2368.46709490365;2411.4333131714034;2416.4804567018177;2456.1403508771964;2497.188413765515;119828;125814.37092814477;125933;134118.3294083533;140697;14444.638884027983;14572.63568410777;14677.926610367093;15007.623394353628;15714.449994250146;22518.887405563197;22602.321930342117;22640.320790376314;22684.206052788075;22688.432789180402;9304.53477326131;9304.53477326131;9358.4384936904;9417.670381536613;9427.48148851806;8304.958475207586;8489.533075680896;8563.571821408905;8630.223279904838;8712.128787121237;8742.683188954932;8892.955535222283;8955.41789783662;9171.495567743705;9257.907420925705;2665.333666583354;2690.340866487723;2749.463854548344;2776.9305767355977;2790.9302267443477;949.6771097826772;956.1776871890169;957.5308099031424;963.0483611282342;967.6371360739722;18796.53008674767;18933;19379.51551211203;19455.708164377545;19516.12177452018;982.3172894838161;987.5407935309971;1002.7192386131894;1009.1371877045168;1011.898810118997;10730.73173170677;10864.58760361148;10877;10913.618023369145;11180.776384472427;441905;444183;447179;453741;459799;103013;103541;105762;106258.4687076575;109219.45390273156;1027.429776474049;1068.5939343049592;1121.9775604487866;1127.4982632728356;1145.3643228008534;20972.580548388643;21283.255086071913;21306.254281100086;21417.143314267254;21478.677819832403;54228.72885635705;55211;56149.31552053445;56520.7173964144;59116.1132583003;68691.31308687049;68735.31264687289;68755.65622171857;68788;69170.65414672895;14202.28988550589;14543.709125817635;14575.85424145745;14576.34406451691;14587.635309117357;22663.320100397013;23118.306450806504;23282;23369.065237390314;23408.18071367494;17584.12079396051;17745.822541774418;17861.374851880122;17870.821291787437;18463.615228858158;16529.586760331087;16903.746443803106;16923.830761692225;16964.236609352363;16969.660606788042;18271.94547298865;18419.53951151232;18474.16866241049;18723.625527489647;18772.530686732942;14282;14310.49913253031;14392.136471811305;14401.135931844123;14406.42374305058;18715.812841871953;18838.811611884255;19020.42938711841;19035.333763318216;19061 03:35:55 INFO - PID 5427 | |3;mootools.html;1324.807902854086;1380.096037095702;1398.216998480851;1413.8162038934936;1414.0949792133042;14311.928440357797;14897.925510372434;14905.180215088165;14922.179280139588;14932.925335373322;14571.417143314255;14643.926780366084;14667.92666036671;14676.55970320892;14699.118052916832;2706.336947447878;2723.986380068097;2749.8762555684943;2759.530879750447;2769.529180039388;2685.382362056718;2711.4848178845955;2734.4804487147476;2738.6850512191154;2741.2735625059377;2677.839329640228;2822.1251412062325;2831.1365033664724;2832.7592154666877;2834.4897918374686;2784.0673374419594;2785.373291009527;2798.4543014112255;2817.197098826835;2818.943621127576;2582.160797740723;2708.106324912774;2740.4245108527234;2755.435135797157;2768.9030883918967;14782.26088695561;15057.472988445352;15059;15089.094654320776;15096.471623493126;7997.080335761404;8226.588670566447;8238.423310368276;8242.670293188265;8267.958660206661;5180.974095129538;5248.291480650101;5281.788728450857;5352.759125839347;5395.7032363220005;1869.7662792150982;1897.5635603811063;1902.4482899958996;1922.6827573450503;1934.264979307868;26283;26295.21114366572;26566.46867062648;26578.33554161142;26617.334566635796;6452.35476452351;6584.176977877765;6605.801825945229;6752.932470675329;6755.7973260802255;16937.491875243762;16944.23750931211;16987.320507179575;17008.829911700974;17381.826181738274;7549.433792465587;7764.689412423553;7769.417293702995;7770.53376797394;7772.417068719868;7100.644967751592;7197.0284011658805;7502.399808015346;7524.623768811538;7543.585102819397;5099.69401835891;5228.2941802856885;5231.921521177185;5253.605979551549;5383.138697808332;1521.414255511636;1526.6030831983542;1535.6468012356997;1549.0782984124428;1570.280542805626;854.5812551849673;880.555319563629;888.138505649519;894.3337213775778;894.7941973346167;7222.422206223489;7298.598577078205;7305.196428392873;7336.413086953031;7453.068366454193;707.8478127202676;709.0746575718737;712.8716830970371;715.4955756191872;716.1477841368754;4238.1099969006855;4467.5755803198645;4839.2983017462275;4894.608431325485;5002.674826136289;20668.793312066988;21144.894275528524;21481.46296342604;21518.354449366543;21534.138634454448;21044.89477552633;21110.577788444145;21872.562548748934;21897.233596824033;21928.89035554844;11243.606473773541;11269.774604508028;11284.605038823602;11315.60395386174;11317.603883864025;7564.50830696003;7611.657475413509;7954.761357159063;7999.84000320002;8012.879806802786;18081;18250.269989200813;18376.792860871403;18705.906470467562;18832.811671883104;18761.249550017845;18784.342548010754;18824.905875470537;19023.71464428007;19332.516687082934;10774.138068954622;10945.45272736376;10947.890521094687;10951.288166269167;10951.671449856518;13132.934335328262;13391.397387117799;13461.057725959481;13480;13490.32548372597;10801.891981080404;11032.834507482234;11048.723781905517;11049.723756906142;11281.887181128084;10393.272470926962;10591.258611897094;10606.469676516297;10704.625338113128;10802.729931751455;9717;9838.458884761121;9850.211983041196;9896.059874311928;9939.30424870281;6688.963210702349;6807;6897.862042759017;6910.2398736137975;6964.930350696428;6454.632824910294;6469.967650161719;6476.6114033158165;6481.675916204076;6530.67346632676 03:35:55 INFO - PID 5427 | |4;prototype.html;478.8659175430881;495.6158976792985;501.95231453011974;510.4793111026755;516.8449465160452;6238.656873871941;6254.655993920333;6258.280297765753;6288.528360372968;6352.841178970528;6120.234970628671;6135.601185922912;6159.907601385971;6198.1632479615355;6208.9068663969965;5091.854332775133;5158.716270605115;5166.974165129188;5180.663256888309;5245.160774276116;4869.415670119591;4993.176125939216;5140.434552199255;5159.458256883029;5174.818881339153;4959.900801983958;4985.252212168186;4999.500049995007;5000.474855169172;5030.82392116276;4950.019992003209;4951.257311403282;4953.678010929278;4956.826511072095;4983.975080124613;4387.122575484916;4445.866624001285;4447.3773671685985;4458.554144585515;4470.541202338701;6018.127371531147;6037.969810150965;6080;6121.265448146251;6166.938330616681;5644.943550564483;5665.943340566583;5675.858103547403;5702.378989341798;5729.656220626775;6579.57861911808;6587.93412065878;6599.49219296669;6608.1409416776;6620.23867255267;4645.558671926162;4860.4896485869185;4889.437714662824;4891.144049791273;4923.852284431473;6635.999021032809;6640.900386494206;6725.016120244589;6750.8186067438;6773.322667733188;4959.231319145538;4971.676841005323;4981.9252711209365;4993.500649934977;5018.272350509155;5021;5037.19404895215;5060.139776237975;5064.18972964324;5068.670536415121;5635.5773317001385;5663.178839068394;5674.744636491368;5704.286964129484;5732.140178973104;6621.5364924454825;6643.933560664332;6655.801955648029;6694.665266736643;6719.932800672029;1710.5894585299438;1729.6454226883711;1738.2004278031993;1738.765266689006;1747.53690272078;1160.5937921727327;1189.9821502677466;1194.66794524105;1210.1045226532303;1218.5917717564537;5684.573656975744;5696.572757043155;5738.483536481654;5740.282464691913;5742.799002034909;6658.6337751424135;6669.965660473602;6730.663466826639;6765.188177418643;6765.424938880152;5711.485966263056;5716.171155182476;5730.942690573124;5754.510866576305;5762.971185144047;3458.8789392371145;3500.842462089212;3513.894583162509;3534.2931413716847;3552.5204097446513;6412.487001039905;6428.874946884276;6471.741130354733;6473.41739243461;6511.609303441714;6812.9901679004815;6863.2546981207915;6872.931270687229;6882.69027893736;6909.896351554731;6020.939790602038;6530.183727034121;6562.901556476657;6582.868342633121;6585.967070164618;6602.60384376939;6673.532852700265;6682.164729408824;6745.3591908768585;6763.966180169067;6560.9343906560325;6632.568883022588;6648.5346025777735;6657.567258128206;6668.83327916806;6324.525660575568;6336.968315158395;6383.36166383358;6478.449331806849;6530.869382612416;4893.853184404474;4910.238912968352;4965.726885021359;4966.776495057803;5001;6428.292887782433;6472.352764723679;6594.373534514213;6632.867342653217;6657;6348.460380867678;6402.871942561216;6541.70562324706;6556.262590413639;6561.311062338576;6460.806175814733;6464.838379040561;6532.183477065367;6558.344165583403;6605.702743376463;6295.5907865988565;6377.17096777416;6429.839254018687;6485.967570162119;6488.11278980849;6457.741690332334;6461.773837915651;6559.639219842764;6564.310747371457;6609.173853268341;6304.842378940563;6397.200349956255;6402.391772781578;6417.08022849593;6420.293767685457 03:35:55 INFO - PID 5427 | |5;yui.html;886.0740526150172;897.2956229359953;898.6410671986037;908.3641450984309;915.0346384564282;15654.530364089067;16266.43067492638;16281.104539250351;16337.101459419742;16363.59091022722;15909.761353579706;15996.600084997881;16089.034657920532;16099.436519721812;16142;934.598122807191;954.6754103604773;959.1128206409079;960.0735290444719;960.3421656165514;971.4414211828192;977.6578197630844;979.1383582447436;979.8628192053118;981.4062492192228;929.5352323838081;1002.1481740520572;1015.0509273828957;1018.2770233134484;1028.269928350868;1002.0780881588955;1006.7181189266944;1008.8083264179783;1009.1523120578712;1011.8988101189859;742.7771668499482;743.2938708227206;745.1579714922158;745.1616930952679;746.1158527145296;16037.438689645807;16076.919615401965;16082.276297566637;16095.436659716854;16116.59708507285;21141.471463213387;21864;21886.781132188633;21955.560888782296;21963.890180549155;16314;16693.499195024167;16710.66578668432;16789;16814.15929203535;3658.158623516606;3677.191017976041;3678.834452449646;3680.7423480356388;3682.723795715305;115912.42043789811;144061.27969360087;145208;145340;146442;17510.21204045821;17535.824641753676;17780.822191777916;17868.46394608164;18033;31547.36905261882;31769.20576985594;31856;31877.521837172462;31888.840555797073;8358;8502.65989360431;8544.914550854537;8623.094575069654;8627.094155113748;4049.028233224005;4112.835486580563;4113.794310284474;4115.9176816463505;4116.526599441043;5395.568354531628;5423.159410291411;5502.394736578973;5521.613487055867;5522.475364840414;1086.8532748078908;1095.912327013837;1098.5770478365728;1106.4246591772232;1108.6729414822646;556.7689408895344;617.2531237202945;617.3641149615903;635.8887194740902;644.3588629313863;10702.143828493701;10994.505247263893;11048.779024419628;11058.170637202242;11523.583915559673;607.4623957797293;615.2094558492316;624.5940138909655;626.7493002798917;626.7806267806232;4850.369451971221;4894.730789806595;4922.778474968635;5084.44071152173;5105.744712764347;117536;119782;120386;120597.77907773186;121074;106128;107915;109639.45180274207;111695.4415227935;112472.4376378113;90417.54791226002;92919.53540232255;93034.53482732543;93467;95590;9099.545022748704;9383;9439.480828554495;9464.95267523658;9544.522773861418;43606;43720.562794371646;43979.34030989602;45993.31010034785;46051.697415129776;54843;55670.72164639151;55812;56887.431125689865;57174.42825571857;14599.197044162673;14784.63038424005;15054;15171.468998584996;15243.237838108273;15917.68164636724;15939.043657380595;16065.518034458984;16095;16122.596935076717;14793.556193314218;14980.1011939284;15122.54632361031;15175.16536590498;15217.695646086795;15741.291641875914;15803.762943555626;15873.841261587237;16034.67930641404;16072.758908616614;15189.696206076038;15303.61740956485;15367.769483457982;15368.154751488775;15373.077615343114;11112.722181945193;11225.494852731483;11332.490037948477;11417.885821142014;11537.07703383744;10836.241463097507;10960.616378426717;11051.557937682725;11053.502592383202;11248.437578120898 03:35:55 INFO - PID 5427 | __end_tp_report 03:35:55 INFO - PID 5427 | __start_cc_report 03:35:55 INFO - PID 5427 | _x_x_mozilla_cycle_collect,371 03:35:55 INFO - PID 5427 | __end_cc_report 03:35:55 INFO - PID 5427 | __startTimestamp1505298955217__endTimestamp 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | ------- Summary: start ------- 03:35:55 INFO - PID 5427 | Number of tests: 6 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | [#0] dojo.html Cycles:165 Average:6220.90 Median:6473.39 stddev:846.31 (13.1%) stddev-sans-first:814.28 03:35:55 INFO - PID 5427 | Values: 3166.4 3184.3 3187.4 3189.3 3192.7 6158.1 6334.1 6343.9 6372.9 6466.5 6349.0 6380.2 6406.6 6428.7 6450.3 5839.7 5840.3 5843.4 5905.1 5950.9 5869.1 5914.1 5921.3 5925.7 5926.5 1325.6 5750.6 5907.4 5967.2 5983.7 5440.8 5782.1 5812.5 5865.6 5902.5 5793.2 5811.6 5845.3 5868.2 5869.0 6339.7 6346.6 6365.3 6413.7 6459.5 6802.3 6815.3 6870.8 6888.9 6912.2 6667.3 6759.2 6862.2 6869.9 6963.4 6127.1 6233.5 6240.4 6257.1 6269.6 6945.4 7007.8 7081.2 7086.7 7097.5 6680.0 6687.8 6758.3 6769.5 6787.0 6655.2 6772.4 6784.8 6812.0 6864.4 6834.4 6841.6 6842.2 6849.6 6883.4 6795.3 6811.9 6893.2 6903.0 6907.4 5248.8 5342.7 5348.9 5423.7 5434.3 4579.2 4595.0 4617.2 4617.7 4621.0 5760.5 5768.1 5830.0 5901.7 5909.3 6779.6 6800.3 6828.5 6863.2 6884.7 5848.6 5850.5 5905.9 5908.6 5917.2 5690.7 5762.1 5853.5 5905.3 5906.0 6529.2 6547.8 6556.3 6580.1 6587.4 6717.7 6723.8 6792.1 6798.5 6822.9 6448.3 6476.2 6490.0 6569.4 6576.1 6364.5 6519.1 6529.5 6533.3 6534.7 6773.8 6794.3 6864.6 6878.2 6887.7 6518.7 6584.5 6649.0 6650.4 6651.9 6613.7 6614.2 6658.2 6680.0 6690.0 6355.0 6492.7 6509.3 6524.3 6625.8 6622.9 6658.1 6719.6 6723.4 6740.8 6397.6 6463.8 6471.4 6473.3 6473.4 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | [#1] ext.html Cycles:180 Average:37023.55 Median:36580.00 stddev:52178.11 (142.6%) stddev-sans-first:52253.82 03:35:55 INFO - PID 5427 | Values: 858.8 880.1 883.2 886.3 890.9 38800.4 42247.0 42362.6 42386.6 42416.4 38642.2 38766.8 38836.4 38858.6 38903.0 7002.3 7121.0 7743.7 7938.9 7951.2 7782.6 7885.6 7889.3 7891.3 7893.8 7841.2 7861.3 7879.7 7908.6 7915.8 7842.7 7875.4 7875.8 7882.9 7886.2 7726.6 7798.8 7805.5 7807.8 7809.1 38204.0 38286.3 38394.6 38475.6 38477.2 3349.1 3369.1 3377.8 3378.8 3383.6 30482.5 30547.2 30644.1 30682.0 30817.4 4808.4 4901.0 4905.7 4951.5 4957.7 189016.0 190544.0 191049.0 191222.0 191233.0 41965.2 42857.0 49259.3 49326.0 49660.0 52373.5 53640.2 53705.7 53733.7 53771.0 12956.5 13013.5 13094.1 13098.2 13114.1 10456.7 10615.9 10616.7 10620.6 10641.5 14560.5 14569.4 14617.3 14648.2 14729.6 5748.7 5847.8 5848.8 5888.5 6012.5 974.4 975.5 980.5 991.6 997.2 20533.2 20593.8 21055.5 21070.8 21273.0 1058.9 1072.2 1081.9 1082.2 1102.3 10753.9 10902.8 10943.3 10967.3 11024.2 215659.0 216712.0 217496.0 217756.0 217830.0 52635.0 52671.5 52745.7 53108.2 53147.7 1438.3 1447.2 1454.7 1459.5 1461.9 21801.9 22347.1 22493.3 22540.3 22697.0 37621.4 37651.8 37682.0 37687.2 37712.2 194125.0 194801.0 195053.0 195082.0 196073.0 34451.1 35431.6 36016.5 36100.5 36156.6 44240.8 45343.5 45400.8 45538.8 45740.5 32691.8 32788.3 32810.5 32822.7 32979.8 32465.7 32762.2 32967.5 33217.2 33786.5 36482.0 36678.0 36952.6 37029.4 37157.3 32031.0 32229.7 32239.8 32412.0 32896.3 23754.9 24044.9 24081.0 24169.9 24657.5 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | [#2] jquery.html Cycles:180 Average:32979.53 Median:23325.53 stddev:75738.91 (324.7%) stddev-sans-first:75912.11 03:35:55 INFO - PID 5427 | Values: 495.4 556.2 564.2 564.4 570.5 21757.1 22465.9 22490.1 22552.9 22557.2 23733.0 24228.4 24564.3 24621.4 24640.6 6393.6 6402.0 6403.9 6408.3 6436.5 6354.8 6472.7 6476.8 6477.8 6496.8 6260.4 6442.9 6490.6 6496.2 6496.7 6472.6 6509.7 6513.1 6515.2 6536.7 5717.5 5904.0 5908.8 6037.1 6049.8 29074.1 29558.7 29601.3 29609.7 29645.1 1970.7 1988.1 1988.9 1989.3 1993.2 18711.9 18945.4 19292.0 19302.1 19689.3 2368.5 2411.4 2416.5 2456.1 2497.2 119828.0 125814.4 125933.0 134118.3 140697.0 14444.6 14572.6 14677.9 15007.6 15714.4 22518.9 22602.3 22640.3 22684.2 22688.4 9304.5 9304.5 9358.4 9417.7 9427.5 8305.0 8489.5 8563.6 8630.2 8712.1 8742.7 8893.0 8955.4 9171.5 9257.9 2665.3 2690.3 2749.5 2776.9 2790.9 949.7 956.2 957.5 963.0 967.6 18796.5 18933.0 19379.5 19455.7 19516.1 982.3 987.5 1002.7 1009.1 1011.9 10730.7 10864.6 10877.0 10913.6 11180.8 441905.0 444183.0 447179.0 453741.0 459799.0 103013.0 103541.0 105762.0 106258.5 109219.5 1027.4 1068.6 1122.0 1127.5 1145.4 20972.6 21283.3 21306.3 21417.1 21478.7 54228.7 55211.0 56149.3 56520.7 59116.1 68691.3 68735.3 68755.7 68788.0 69170.7 14202.3 14543.7 14575.9 14576.3 14587.6 22663.3 23118.3 23282.0 23369.1 23408.2 17584.1 17745.8 17861.4 17870.8 18463.6 16529.6 16903.7 16923.8 16964.2 16969.7 18271.9 18419.5 18474.2 18723.6 18772.5 14282.0 14310.5 14392.1 14401.1 14406.4 18715.8 18838.8 19020.4 19035.3 19061.0 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | [#3] mootools.html Cycles:180 Average:9143.18 Median:2717.74 stddev:6622.07 (243.7%) stddev-sans-first:6614.59 03:35:55 INFO - PID 5427 | Values: 1324.8 1380.1 1398.2 1413.8 1414.1 14311.9 14897.9 14905.2 14922.2 14932.9 14571.4 14643.9 14667.9 14676.6 14699.1 2706.3 2724.0 2749.9 2759.5 2769.5 2685.4 2711.5 2734.5 2738.7 2741.3 2677.8 2822.1 2831.1 2832.8 2834.5 2784.1 2785.4 2798.5 2817.2 2818.9 2582.2 2708.1 2740.4 2755.4 2768.9 14782.3 15057.5 15059.0 15089.1 15096.5 7997.1 8226.6 8238.4 8242.7 8268.0 5181.0 5248.3 5281.8 5352.8 5395.7 1869.8 1897.6 1902.4 1922.7 1934.3 26283.0 26295.2 26566.5 26578.3 26617.3 6452.4 6584.2 6605.8 6752.9 6755.8 16937.5 16944.2 16987.3 17008.8 17381.8 7549.4 7764.7 7769.4 7770.5 7772.4 7100.6 7197.0 7502.4 7524.6 7543.6 5099.7 5228.3 5231.9 5253.6 5383.1 1521.4 1526.6 1535.6 1549.1 1570.3 854.6 880.6 888.1 894.3 894.8 7222.4 7298.6 7305.2 7336.4 7453.1 707.8 709.1 712.9 715.5 716.1 4238.1 4467.6 4839.3 4894.6 5002.7 20668.8 21144.9 21481.5 21518.4 21534.1 21044.9 21110.6 21872.6 21897.2 21928.9 11243.6 11269.8 11284.6 11315.6 11317.6 7564.5 7611.7 7954.8 7999.8 8012.9 18081.0 18250.3 18376.8 18705.9 18832.8 18761.2 18784.3 18824.9 19023.7 19332.5 10774.1 10945.5 10947.9 10951.3 10951.7 13132.9 13391.4 13461.1 13480.0 13490.3 10801.9 11032.8 11048.7 11049.7 11281.9 10393.3 10591.3 10606.5 10704.6 10802.7 9717.0 9838.5 9850.2 9896.1 9939.3 6689.0 6807.0 6897.9 6910.2 6964.9 6454.6 6470.0 6476.6 6481.7 6530.7 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | [#4] prototype.html Cycles:180 Average:5496.03 Median:6128.43 stddev:1541.90 (25.2%) stddev-sans-first:1499.53 03:35:55 INFO - PID 5427 | Values: 478.9 495.6 502.0 510.5 516.8 6238.7 6254.7 6258.3 6288.5 6352.8 6120.2 6135.6 6159.9 6198.2 6208.9 5091.9 5158.7 5167.0 5180.7 5245.2 4869.4 4993.2 5140.4 5159.5 5174.8 4959.9 4985.3 4999.5 5000.5 5030.8 4950.0 4951.3 4953.7 4956.8 4984.0 4387.1 4445.9 4447.4 4458.6 4470.5 6018.1 6038.0 6080.0 6121.3 6166.9 5644.9 5665.9 5675.9 5702.4 5729.7 6579.6 6587.9 6599.5 6608.1 6620.2 4645.6 4860.5 4889.4 4891.1 4923.9 6636.0 6640.9 6725.0 6750.8 6773.3 4959.2 4971.7 4981.9 4993.5 5018.3 5021.0 5037.2 5060.1 5064.2 5068.7 5635.6 5663.2 5674.7 5704.3 5732.1 6621.5 6643.9 6655.8 6694.7 6719.9 1710.6 1729.6 1738.2 1738.8 1747.5 1160.6 1190.0 1194.7 1210.1 1218.6 5684.6 5696.6 5738.5 5740.3 5742.8 6658.6 6670.0 6730.7 6765.2 6765.4 5711.5 5716.2 5730.9 5754.5 5763.0 3458.9 3500.8 3513.9 3534.3 3552.5 6412.5 6428.9 6471.7 6473.4 6511.6 6813.0 6863.3 6872.9 6882.7 6909.9 6020.9 6530.2 6562.9 6582.9 6586.0 6602.6 6673.5 6682.2 6745.4 6764.0 6560.9 6632.6 6648.5 6657.6 6668.8 6324.5 6337.0 6383.4 6478.4 6530.9 4893.9 4910.2 4965.7 4966.8 5001.0 6428.3 6472.4 6594.4 6632.9 6657.0 6348.5 6402.9 6541.7 6556.3 6561.3 6460.8 6464.8 6532.2 6558.3 6605.7 6295.6 6377.2 6429.8 6486.0 6488.1 6457.7 6461.8 6559.6 6564.3 6609.2 6304.8 6397.2 6402.4 6417.1 6420.3 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | [#5] yui.html Cycles:180 Average:23713.90 Median:17658.32 stddev:35134.49 (199.0%) stddev-sans-first:35191.24 03:35:55 INFO - PID 5427 | Values: 886.1 897.3 898.6 908.4 915.0 15654.5 16266.4 16281.1 16337.1 16363.6 15909.8 15996.6 16089.0 16099.4 16142.0 934.6 954.7 959.1 960.1 960.3 971.4 977.7 979.1 979.9 981.4 929.5 1002.1 1015.1 1018.3 1028.3 1002.1 1006.7 1008.8 1009.2 1011.9 742.8 743.3 745.2 745.2 746.1 16037.4 16076.9 16082.3 16095.4 16116.6 21141.5 21864.0 21886.8 21955.6 21963.9 16314.0 16693.5 16710.7 16789.0 16814.2 3658.2 3677.2 3678.8 3680.7 3682.7 115912.4 144061.3 145208.0 145340.0 146442.0 17510.2 17535.8 17780.8 17868.5 18033.0 31547.4 31769.2 31856.0 31877.5 31888.8 8358.0 8502.7 8544.9 8623.1 8627.1 4049.0 4112.8 4113.8 4115.9 4116.5 5395.6 5423.2 5502.4 5521.6 5522.5 1086.9 1095.9 1098.6 1106.4 1108.7 556.8 617.3 617.4 635.9 644.4 10702.1 10994.5 11048.8 11058.2 11523.6 607.5 615.2 624.6 626.7 626.8 4850.4 4894.7 4922.8 5084.4 5105.7 117536.0 119782.0 120386.0 120597.8 121074.0 106128.0 107915.0 109639.5 111695.4 112472.4 90417.5 92919.5 93034.5 93467.0 95590.0 9099.5 9383.0 9439.5 9465.0 9544.5 43606.0 43720.6 43979.3 45993.3 46051.7 54843.0 55670.7 55812.0 56887.4 57174.4 14599.2 14784.6 15054.0 15171.5 15243.2 15917.7 15939.0 16065.5 16095.0 16122.6 14793.6 14980.1 15122.5 15175.2 15217.7 15741.3 15803.8 15873.8 16034.7 16072.8 15189.7 15303.6 15367.8 15368.2 15373.1 11112.7 11225.5 11332.5 11417.9 11537.1 10836.2 10960.6 11051.6 11053.5 11248.4 03:35:55 INFO - PID 5427 | -------- Summary: end -------- 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | 03:35:55 INFO - PID 5427 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 03:35:55 INFO - PID 5427 | 03:35:55 INFO - TEST-INFO | 5427: exit 0 03:35:55 INFO - TEST-OK | dromaeo_css | took 1109230ms 03:35:55 INFO - TEST-START | kraken 03:35:55 INFO - Initialising browser for kraken test... 03:35:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57595/getInfo.html -profile /tmp/tmpSdtwh9/profile 03:35:55 INFO - TEST-INFO | started process 5804 (/builds/slave/test/build/application/firefox/firefox http://localhost:57595/getInfo.html) 03:36:01 INFO - TEST-INFO | 5804: exit 0 03:36:01 INFO - Browser initialized. 03:36:01 INFO - Running cycle 1/1 for kraken test... 03:36:01 INFO - Using env: {'DISPLAY': ':0', 03:36:01 INFO - 'HOME': '/home/cltbld', 03:36:01 INFO - 'JSGC_DISABLE_POISONING': '1', 03:36:01 INFO - 'LANG': 'en_US.UTF-8', 03:36:01 INFO - 'LANGUAGE': 'en_US:en', 03:36:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:36:01 INFO - 'LOGNAME': 'cltbld', 03:36:01 INFO - 'MAIL': '/var/mail/cltbld', 03:36:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:36:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:36:01 INFO - 'MOZ_CRASHREPORTER': '1', 03:36:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:36:01 INFO - 'MOZ_NO_REMOTE': '1', 03:36:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:36:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:36:01 INFO - 'NO_EM_RESTART': '1', 03:36:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:36:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:01 INFO - 'PWD': '/builds/slave/test', 03:36:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:36:01 INFO - 'RUST_BACKTRACE': 'full', 03:36:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:36:01 INFO - 'SHELL': '/bin/bash', 03:36:01 INFO - 'SHLVL': '1', 03:36:01 INFO - 'TERM': 'linux', 03:36:01 INFO - 'TMOUT': '86400', 03:36:01 INFO - 'USER': 'cltbld', 03:36:01 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451', 03:36:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:01 INFO - '_': '/tools/buildbot/bin/python'} 03:36:01 INFO - TEST-INFO | started process 6003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSdtwh9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:36:02 INFO - PID 6003 | 03:36:02 INFO - PID 6003 | (/builds/slave/test/build/application/firefox/firefox:6054): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:36:02 INFO - PID 6003 | 03:36:13 INFO - PID 6003 | RSS: Main: 173215744 03:36:13 INFO - PID 6003 | 03:36:15 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57595/tests/kraken/driver.html?testName=audio-beat-detection) 03:36:16 INFO - PID 6003 | RSS: Main: 177500160 03:36:16 INFO - PID 6003 | 03:36:19 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57595/tests/kraken/driver.html?testName=audio-dft) 03:36:19 INFO - PID 6003 | RSS: Main: 177868800 03:36:19 INFO - PID 6003 | 03:36:22 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57595/tests/kraken/driver.html?testName=audio-fft) 03:36:23 INFO - PID 6003 | RSS: Main: 173420544 03:36:23 INFO - PID 6003 | 03:36:25 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57595/tests/kraken/driver.html?testName=audio-oscillator) 03:36:26 INFO - PID 6003 | RSS: Main: 174510080 03:36:26 INFO - PID 6003 | 03:36:28 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57595/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:36:28 INFO - PID 6003 | RSS: Main: 174632960 03:36:28 INFO - PID 6003 | 03:36:32 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57595/tests/kraken/driver.html?testName=imaging-darkroom) 03:36:32 INFO - PID 6003 | RSS: Main: 177082368 03:36:32 INFO - PID 6003 | 03:36:36 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57595/tests/kraken/driver.html?testName=imaging-desaturate) 03:36:37 INFO - PID 6003 | RSS: Main: 176934912 03:36:37 INFO - PID 6003 | 03:36:40 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57595/tests/kraken/driver.html?testName=json-parse-financial) 03:36:40 INFO - PID 6003 | RSS: Main: 177049600 03:36:40 INFO - PID 6003 | 03:36:42 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57595/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:36:43 INFO - PID 6003 | RSS: Main: 177209344 03:36:43 INFO - PID 6003 | 03:36:44 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57595/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:36:45 INFO - PID 6003 | RSS: Main: 177201152 03:36:45 INFO - PID 6003 | 03:36:47 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57595/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:36:48 INFO - PID 6003 | RSS: Main: 174280704 03:36:48 INFO - PID 6003 | 03:36:50 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57595/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:36:51 INFO - PID 6003 | RSS: Main: 174714880 03:36:51 INFO - PID 6003 | 03:36:54 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57595/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:36:55 INFO - PID 6003 | RSS: Main: 174637056 03:36:55 INFO - PID 6003 | 03:36:56 INFO - PID 6003 | Cycle 1(1): loaded http://localhost:57595/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57595/tests/kraken/driver.html?testName=ai-astar) 03:36:57 INFO - PID 6003 | RSS: Main: 176488448 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | __start_tp_report 03:36:57 INFO - PID 6003 | _x_x_mozilla_page_load 03:36:57 INFO - PID 6003 | _x_x_mozilla_page_load_details 03:36:57 INFO - PID 6003 | |i|pagename|runs| 03:36:57 INFO - PID 6003 | |0;ai-astar;107;107;108;108;111;95;109;107;107;107 03:36:57 INFO - PID 6003 | |1;audio-beat-detection;116;116;129;116;115;139;117;119;115;120 03:36:57 INFO - PID 6003 | |2;audio-dft;163;163;164;162;162;165;168;163;169;165 03:36:57 INFO - PID 6003 | |3;audio-fft;74;74;75;75;74;73;74;73;75;73 03:36:57 INFO - PID 6003 | |4;audio-oscillator;80;80;79;80;80;78;79;78;80;79 03:36:57 INFO - PID 6003 | |5;imaging-gaussian-blur;115;113;113;113;113;115;113;114;114;114 03:36:57 INFO - PID 6003 | |6;imaging-darkroom;148;149;150;149;148;148;149;151;148;147 03:36:57 INFO - PID 6003 | |7;imaging-desaturate;93;94;94;93;93;94;94;94;95;94 03:36:57 INFO - PID 6003 | |8;json-parse-financial;52;51;52;52;53;52;52;51;54;56 03:36:57 INFO - PID 6003 | |9;json-stringify-tinderbox;38;37;38;38;37;37;37;39;38;38 03:36:57 INFO - PID 6003 | |10;stanford-crypto-aes;80;77;74;78;79;75;75;75;76;73 03:36:57 INFO - PID 6003 | |11;stanford-crypto-ccm;112;111;125;113;117;122;119;116;117;119 03:36:57 INFO - PID 6003 | |12;stanford-crypto-pbkdf2;171;168;169;174;168;183;168;170;173;175 03:36:57 INFO - PID 6003 | |13;stanford-crypto-sha256-iterative;69;67;71;66;65;67;65;64;69;67 03:36:57 INFO - PID 6003 | __end_tp_report 03:36:57 INFO - PID 6003 | __start_cc_report 03:36:57 INFO - PID 6003 | _x_x_mozilla_cycle_collect,938 03:36:57 INFO - PID 6003 | __end_cc_report 03:36:57 INFO - PID 6003 | __startTimestamp1505299017055__endTimestamp 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | ------- Summary: start ------- 03:36:57 INFO - PID 6003 | Number of tests: 14 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#0] ai-astar Cycles:10 Average:106.60 Median:108.00 stddev:4.27 (4.0%) stddev-sans-first:4.53 03:36:57 INFO - PID 6003 | Values: 107.0 107.0 108.0 108.0 111.0 95.0 109.0 107.0 107.0 107.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#1] audio-beat-detection Cycles:10 Average:120.20 Median:118.00 stddev:7.81 (6.6%) stddev-sans-first:8.14 03:36:57 INFO - PID 6003 | Values: 116.0 116.0 129.0 116.0 115.0 139.0 117.0 119.0 115.0 120.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.50 stddev:2.41 (1.5%) stddev-sans-first:2.51 03:36:57 INFO - PID 6003 | Values: 163.0 163.0 164.0 162.0 162.0 165.0 168.0 163.0 169.0 165.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#3] audio-fft Cycles:10 Average:74.00 Median:74.00 stddev:0.82 (1.1%) stddev-sans-first:0.87 03:36:57 INFO - PID 6003 | Values: 74.0 74.0 75.0 75.0 74.0 73.0 74.0 73.0 75.0 73.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:80.00 stddev:0.82 (1.0%) stddev-sans-first:0.83 03:36:57 INFO - PID 6003 | Values: 80.0 80.0 79.0 80.0 80.0 78.0 79.0 78.0 80.0 79.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.73 03:36:57 INFO - PID 6003 | Values: 115.0 113.0 113.0 113.0 113.0 115.0 113.0 114.0 114.0 114.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:1.16 (0.8%) stddev-sans-first:1.20 03:36:57 INFO - PID 6003 | Values: 148.0 149.0 150.0 149.0 148.0 148.0 149.0 151.0 148.0 147.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 03:36:57 INFO - PID 6003 | Values: 93.0 94.0 94.0 93.0 93.0 94.0 94.0 94.0 95.0 94.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.00 stddev:1.51 (2.9%) stddev-sans-first:1.59 03:36:57 INFO - PID 6003 | Values: 52.0 51.0 52.0 52.0 53.0 52.0 52.0 51.0 54.0 56.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#9] json-stringify-tinderbox Cycles:10 Average:37.70 Median:38.00 stddev:0.67 (1.8%) stddev-sans-first:0.71 03:36:57 INFO - PID 6003 | Values: 38.0 37.0 38.0 38.0 37.0 37.0 37.0 39.0 38.0 38.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:76.50 stddev:2.25 (2.9%) stddev-sans-first:1.92 03:36:57 INFO - PID 6003 | Values: 80.0 77.0 74.0 78.0 79.0 75.0 75.0 75.0 76.0 73.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#11] stanford-crypto-ccm Cycles:10 Average:117.10 Median:118.00 stddev:4.41 (3.7%) stddev-sans-first:4.27 03:36:57 INFO - PID 6003 | Values: 112.0 111.0 125.0 113.0 117.0 122.0 119.0 116.0 117.0 119.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.00 stddev:4.68 (2.7%) stddev-sans-first:4.95 03:36:57 INFO - PID 6003 | Values: 171.0 168.0 169.0 174.0 168.0 183.0 168.0 170.0 173.0 175.0 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:2.16 (3.2%) stddev-sans-first:2.17 03:36:57 INFO - PID 6003 | Values: 69.0 67.0 71.0 66.0 65.0 67.0 65.0 64.0 69.0 67.0 03:36:57 INFO - PID 6003 | -------- Summary: end -------- 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 03:36:57 INFO - PID 6003 | 03:36:57 INFO - PID 6003 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:36:57 INFO - TEST-INFO | 6003: exit 0 03:36:57 INFO - TEST-OK | kraken | took 61786ms 03:36:57 INFO - SUITE-END | took 1171s 03:36:58 INFO - Completed test suite (00:19:31) 03:36:58 INFO - javascript benchmark 03:36:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8364.172194145309, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3166.3983843069814, 3184.3153721949775, 3187.442197615418, 3189.330240649464, 3192.6807319268078, 6158.137860699506, 6334.081558174062, 6343.873122537552, 6372.904406433901, 6466.482681385483, 6349.047642853574, 6380.202474690664, 6406.551541392105, 6428.710708018139, 6450.258220304667, 5839.708014599285, 5840.328362238333, 5843.357230704619, 5905.114232865061, 5950.880982380349, 5869.148973398855, 5914.053751399777, 5921.319048309435, 5925.674087925162, 5926.496247818919, 1325.6235967151606, 5750.597458177929, 5907.350191478934, 5967.16459695643, 5983.700814959235, 5440.809571664972, 5782.074868021096, 5812.505936995361, 5865.58940874139, 5902.468777809975, 5793.217915581385, 5811.6222445541325, 5845.269341332333, 5868.207791948118, 5869.0, 6339.714712837934, 6346.619202847798, 6365.331640177761, 6413.7434502619835, 6459.450946669492, 6802.251752307242, 6815.250322464525, 6870.793876183722, 6888.862222755517, 6912.170539535237, 6667.299933506961, 6759.155105611799, 6862.245153033163, 6869.931300687029, 6963.40811031063, 6127.080937859312, 6233.532485063683, 6240.438360547527, 6257.092721555395, 6269.561130720851, 6945.374916257561, 7007.824804379931, 7081.150261968493, 7086.716531338792, 7097.538659987084, 6680.031395447633, 6687.765928192587, 6758.324167583202, 6769.458443324522, 6787.04981302618, 6655.234648015444, 6772.424343930723, 6784.830379240559, 6812.046313516112, 6864.38220560142, 6834.419074378733, 6841.589504629738, 6842.178938527309, 6849.623270720158, 6883.449324054063, 6795.3204679531655, 6811.897821532681, 6893.20728116263, 6903.033575299462, 6907.378335949788, 5248.8425347239645, 5342.652727572696, 5348.946510534923, 5423.701696406657, 5434.347878254634, 4579.244424669892, 4595.035042641083, 4617.238155704338, 4617.676762626651, 4620.953790462119, 5760.510356619734, 5768.134779783066, 5829.970850145722, 5901.734421951022, 5909.3204281507315, 6779.559328643622, 6800.251972283045, 6828.487863410264, 6863.210730765926, 6884.65576721162, 5848.64908105515, 5850.47345738877, 5905.881882362329, 5908.645481271052, 5917.230760001173, 5690.743916523684, 5762.1068734346245, 5853.502452291603, 5905.320888097838, 5906.0, 6529.15121034272, 6547.836304092436, 6556.3443655634055, 6580.078788969548, 6587.374199451045, 6717.731290748316, 6723.798286051524, 6792.117024786746, 6798.490113241625, 6822.931770682229, 6448.32292609288, 6476.190476190476, 6490.0, 6569.441597464078, 6576.079348891255, 6364.459020983269, 6519.08732777421, 6529.5102867284195, 6533.314002029719, 6534.673266336759, 6773.796786096425, 6794.252632210551, 6864.5881247125335, 6878.174619045746, 6887.690053947485, 6518.73925042993, 6584.539082264196, 6648.966755166193, 6650.434713049445, 6651.900221496585, 6613.702383392662, 6614.173228346457, 6658.167729033871, 6679.998000299994, 6689.966550167218, 6354.968225158845, 6492.74029038852, 6509.316521765336, 6524.347565243438, 6625.801225963229, 6622.933770662232, 6658.1344425224415, 6719.630420326929, 6723.361280678328, 6740.764073257413, 6397.648129352744, 6463.806085817433, 6471.449926756279, 6473.320301368289, 6473.385028422293], "value": 6159.150849502445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [858.7638399440153, 880.0891077734547, 883.2404132446095, 886.2732559301368, 890.8930928288602, 38800.41799373008, 42247.0, 42362.57637423625, 42386.57613423865, 42416.36375454371, 38642.227155456945, 38766.8061659692, 38836.41745373815, 38858.611413885854, 38903.0, 7002.264762199972, 7121.038659780941, 7743.690252389898, 7938.880916786253, 7951.244631760002, 7782.571958542278, 7885.566293853835, 7889.250521200477, 7891.250331218525, 7893.763187104368, 7841.215878412142, 7861.292483676468, 7879.724209652663, 7908.604569771517, 7915.762527124167, 7842.72550460734, 7875.4487185897015, 7875.763727088197, 7882.921170788276, 7886.2113788621255, 7726.647836628567, 7798.844023119505, 7805.4536182467255, 7807.8438431231625, 7809.101953275333, 38204.0, 38286.277117529775, 38394.61605383938, 38475.61524384748, 38477.230455391014, 3349.062262566485, 3369.124027752802, 3377.8311084445927, 3378.763486555942, 3383.5939687237687, 30482.54276185859, 30547.23631909198, 30644.08067757971, 30682.0, 30817.38365232705, 4808.422989241313, 4900.975495122501, 4905.656604037718, 4951.455339912607, 4957.677750946213, 189016.0, 190544.0, 191049.0, 191222.0, 191233.0, 41965.1606967865, 42857.0, 49259.26111108336, 49326.0, 49660.0, 52373.47626523686, 53640.195397069074, 53705.73147134239, 53733.73133134309, 53771.0, 12956.481740730265, 13013.479460821462, 13094.14888032275, 13098.2141071536, 13114.082014259102, 10456.68629941103, 10615.946920265504, 10616.734581635366, 10620.628278010387, 10641.467926603638, 14560.49038283655, 14569.41722331095, 14617.269136543131, 14648.1927231496, 14729.55811325662, 5748.6838223898085, 5847.766089356463, 5848.795292164754, 5888.4700376966275, 6012.518998480111, 974.4153507895348, 975.5317447625038, 980.548947484151, 991.5686676295834, 997.2371136080903, 20533.178672853217, 20593.79406205949, 21055.473613159487, 21070.78929210719, 21273.04271307795, 1058.878229003659, 1072.2065671403107, 1081.9134469242442, 1082.1775450657558, 1102.311055590256, 10753.946230268955, 10902.836457453144, 10943.343399395902, 10967.341959482457, 11024.173187011007, 215659.0, 216712.0, 217496.0, 217756.0, 217830.0, 52635.0, 52671.47328526742, 52745.7362713184, 53108.20337694938, 53147.73426132845, 1438.3455527734645, 1447.167878469884, 1454.70178613384, 1459.496473716566, 1461.919594422317, 21801.890990544947, 22347.10545639832, 22493.32520024402, 22540.32379028632, 22697.0, 37621.435678464295, 37651.81174094112, 37682.0, 37687.24625507529, 37712.245755084194, 194125.0, 194801.0, 195053.0, 195082.0, 196073.0, 34451.13872153216, 35431.645683543866, 36016.459753104245, 36100.458493122096, 36156.63843361533, 44240.77879610581, 45343.54656453525, 45400.77299613481, 45538.77230613826, 45740.54259457363, 32691.836540817145, 32788.344233115684, 32810.50784238286, 32822.67177328196, 32979.83510082434, 32465.675343246265, 32762.1809454756, 32967.50548741819, 33217.169570760925, 33786.49320260247, 36482.0, 36678.0, 36952.630473695994, 37029.44455833219, 37157.25685486329, 32031.0, 32229.677703222667, 32239.838800805846, 32412.0, 32896.342073158885, 23754.881225594454, 24044.87977560171, 24081.0, 24169.879150604134, 24657.50684986326], "value": 16870.344814380085, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.41540981641657, 556.2268446858869, 564.2099816407865, 564.4017341617885, 570.5093619487243, 21757.12971481141, 22465.887670561664, 22490.100395984184, 22552.88723556382, 22557.210497632586, 23733.0, 24228.394290142754, 24564.26307210782, 24621.384465388375, 24640.630390544113, 6393.648349340784, 6402.0, 6403.871922561546, 6408.263049749292, 6436.517261205405, 6354.7775827846035, 6472.676366181696, 6476.773312934049, 6477.773277935274, 6496.772612958547, 6260.373962603749, 6442.871142577145, 6490.578112422701, 6496.155499785022, 6496.740130394802, 6472.579282346656, 6509.674516274191, 6513.120728701612, 6515.218173819125, 6536.705848236817, 5717.456841600041, 5904.0, 5908.793192238251, 6037.124616930564, 6049.758009679607, 29074.127776166748, 29558.70441295581, 29601.259968500744, 29609.70390296091, 29645.110646680634, 1970.6748386516208, 1988.1053525913396, 1988.8508361872773, 1989.333573252962, 1993.2425678242175, 18711.906440467847, 18945.431637050773, 19292.0, 19302.13140408671, 19689.310874119335, 2368.46709490365, 2411.4333131714034, 2416.4804567018177, 2456.1403508771964, 2497.188413765515, 119828.0, 125814.37092814477, 125933.0, 134118.3294083533, 140697.0, 14444.638884027983, 14572.63568410777, 14677.926610367093, 15007.623394353628, 15714.449994250146, 22518.887405563197, 22602.321930342117, 22640.320790376314, 22684.206052788075, 22688.432789180402, 9304.53477326131, 9304.53477326131, 9358.4384936904, 9417.670381536613, 9427.48148851806, 8304.958475207586, 8489.533075680896, 8563.571821408905, 8630.223279904838, 8712.128787121237, 8742.683188954932, 8892.955535222283, 8955.41789783662, 9171.495567743705, 9257.907420925705, 2665.333666583354, 2690.340866487723, 2749.463854548344, 2776.9305767355977, 2790.9302267443477, 949.6771097826772, 956.1776871890169, 957.5308099031424, 963.0483611282342, 967.6371360739722, 18796.53008674767, 18933.0, 19379.51551211203, 19455.708164377545, 19516.12177452018, 982.3172894838161, 987.5407935309971, 1002.7192386131894, 1009.1371877045168, 1011.898810118997, 10730.73173170677, 10864.58760361148, 10877.0, 10913.618023369145, 11180.776384472427, 441905.0, 444183.0, 447179.0, 453741.0, 459799.0, 103013.0, 103541.0, 105762.0, 106258.4687076575, 109219.45390273156, 1027.429776474049, 1068.5939343049592, 1121.9775604487866, 1127.4982632728356, 1145.3643228008534, 20972.580548388643, 21283.255086071913, 21306.254281100086, 21417.143314267254, 21478.677819832403, 54228.72885635705, 55211.0, 56149.31552053445, 56520.7173964144, 59116.1132583003, 68691.31308687049, 68735.31264687289, 68755.65622171857, 68788.0, 69170.65414672895, 14202.28988550589, 14543.709125817635, 14575.85424145745, 14576.34406451691, 14587.635309117357, 22663.320100397013, 23118.306450806504, 23282.0, 23369.065237390314, 23408.18071367494, 17584.12079396051, 17745.822541774418, 17861.374851880122, 17870.821291787437, 18463.615228858158, 16529.586760331087, 16903.746443803106, 16923.830761692225, 16964.236609352363, 16969.660606788042, 18271.94547298865, 18419.53951151232, 18474.16866241049, 18723.625527489647, 18772.530686732942, 14282.0, 14310.49913253031, 14392.136471811305, 14401.135931844123, 14406.42374305058, 18715.812841871953, 18838.811611884255, 19020.42938711841, 19035.333763318216, 19061.0], "value": 11736.764532024097, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.807902854086, 1380.096037095702, 1398.216998480851, 1413.8162038934936, 1414.0949792133042, 14311.928440357797, 14897.925510372434, 14905.180215088165, 14922.179280139588, 14932.925335373322, 14571.417143314255, 14643.926780366084, 14667.92666036671, 14676.55970320892, 14699.118052916832, 2706.336947447878, 2723.986380068097, 2749.8762555684943, 2759.530879750447, 2769.529180039388, 2685.382362056718, 2711.4848178845955, 2734.4804487147476, 2738.6850512191154, 2741.2735625059377, 2677.839329640228, 2822.1251412062325, 2831.1365033664724, 2832.7592154666877, 2834.4897918374686, 2784.0673374419594, 2785.373291009527, 2798.4543014112255, 2817.197098826835, 2818.943621127576, 2582.160797740723, 2708.106324912774, 2740.4245108527234, 2755.435135797157, 2768.9030883918967, 14782.26088695561, 15057.472988445352, 15059.0, 15089.094654320776, 15096.471623493126, 7997.080335761404, 8226.588670566447, 8238.423310368276, 8242.670293188265, 8267.958660206661, 5180.974095129538, 5248.291480650101, 5281.788728450857, 5352.759125839347, 5395.7032363220005, 1869.7662792150982, 1897.5635603811063, 1902.4482899958996, 1922.6827573450503, 1934.264979307868, 26283.0, 26295.21114366572, 26566.46867062648, 26578.33554161142, 26617.334566635796, 6452.35476452351, 6584.176977877765, 6605.801825945229, 6752.932470675329, 6755.7973260802255, 16937.491875243762, 16944.23750931211, 16987.320507179575, 17008.829911700974, 17381.826181738274, 7549.433792465587, 7764.689412423553, 7769.417293702995, 7770.53376797394, 7772.417068719868, 7100.644967751592, 7197.0284011658805, 7502.399808015346, 7524.623768811538, 7543.585102819397, 5099.69401835891, 5228.2941802856885, 5231.921521177185, 5253.605979551549, 5383.138697808332, 1521.414255511636, 1526.6030831983542, 1535.6468012356997, 1549.0782984124428, 1570.280542805626, 854.5812551849673, 880.555319563629, 888.138505649519, 894.3337213775778, 894.7941973346167, 7222.422206223489, 7298.598577078205, 7305.196428392873, 7336.413086953031, 7453.068366454193, 707.8478127202676, 709.0746575718737, 712.8716830970371, 715.4955756191872, 716.1477841368754, 4238.1099969006855, 4467.5755803198645, 4839.2983017462275, 4894.608431325485, 5002.674826136289, 20668.793312066988, 21144.894275528524, 21481.46296342604, 21518.354449366543, 21534.138634454448, 21044.89477552633, 21110.577788444145, 21872.562548748934, 21897.233596824033, 21928.89035554844, 11243.606473773541, 11269.774604508028, 11284.605038823602, 11315.60395386174, 11317.603883864025, 7564.50830696003, 7611.657475413509, 7954.761357159063, 7999.84000320002, 8012.879806802786, 18081.0, 18250.269989200813, 18376.792860871403, 18705.906470467562, 18832.811671883104, 18761.249550017845, 18784.342548010754, 18824.905875470537, 19023.71464428007, 19332.516687082934, 10774.138068954622, 10945.45272736376, 10947.890521094687, 10951.288166269167, 10951.671449856518, 13132.934335328262, 13391.397387117799, 13461.057725959481, 13480.0, 13490.32548372597, 10801.891981080404, 11032.834507482234, 11048.723781905517, 11049.723756906142, 11281.887181128084, 10393.272470926962, 10591.258611897094, 10606.469676516297, 10704.625338113128, 10802.729931751455, 9717.0, 9838.458884761121, 9850.211983041196, 9896.059874311928, 9939.30424870281, 6688.963210702349, 6807.0, 6897.862042759017, 6910.2398736137975, 6964.930350696428, 6454.632824910294, 6469.967650161719, 6476.6114033158165, 6481.675916204076, 6530.67346632676], "value": 6503.193080620842, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.8659175430881, 495.6158976792985, 501.95231453011974, 510.4793111026755, 516.8449465160452, 6238.656873871941, 6254.655993920333, 6258.280297765753, 6288.528360372968, 6352.841178970528, 6120.234970628671, 6135.601185922912, 6159.907601385971, 6198.1632479615355, 6208.9068663969965, 5091.854332775133, 5158.716270605115, 5166.974165129188, 5180.663256888309, 5245.160774276116, 4869.415670119591, 4993.176125939216, 5140.434552199255, 5159.458256883029, 5174.818881339153, 4959.900801983958, 4985.252212168186, 4999.500049995007, 5000.474855169172, 5030.82392116276, 4950.019992003209, 4951.257311403282, 4953.678010929278, 4956.826511072095, 4983.975080124613, 4387.122575484916, 4445.866624001285, 4447.3773671685985, 4458.554144585515, 4470.541202338701, 6018.127371531147, 6037.969810150965, 6080.0, 6121.265448146251, 6166.938330616681, 5644.943550564483, 5665.943340566583, 5675.858103547403, 5702.378989341798, 5729.656220626775, 6579.57861911808, 6587.93412065878, 6599.49219296669, 6608.1409416776, 6620.23867255267, 4645.558671926162, 4860.4896485869185, 4889.437714662824, 4891.144049791273, 4923.852284431473, 6635.999021032809, 6640.900386494206, 6725.016120244589, 6750.8186067438, 6773.322667733188, 4959.231319145538, 4971.676841005323, 4981.9252711209365, 4993.500649934977, 5018.272350509155, 5021.0, 5037.19404895215, 5060.139776237975, 5064.18972964324, 5068.670536415121, 5635.5773317001385, 5663.178839068394, 5674.744636491368, 5704.286964129484, 5732.140178973104, 6621.5364924454825, 6643.933560664332, 6655.801955648029, 6694.665266736643, 6719.932800672029, 1710.5894585299438, 1729.6454226883711, 1738.2004278031993, 1738.765266689006, 1747.53690272078, 1160.5937921727327, 1189.9821502677466, 1194.66794524105, 1210.1045226532303, 1218.5917717564537, 5684.573656975744, 5696.572757043155, 5738.483536481654, 5740.282464691913, 5742.799002034909, 6658.6337751424135, 6669.965660473602, 6730.663466826639, 6765.188177418643, 6765.424938880152, 5711.485966263056, 5716.171155182476, 5730.942690573124, 5754.510866576305, 5762.971185144047, 3458.8789392371145, 3500.842462089212, 3513.894583162509, 3534.2931413716847, 3552.5204097446513, 6412.487001039905, 6428.874946884276, 6471.741130354733, 6473.41739243461, 6511.609303441714, 6812.9901679004815, 6863.2546981207915, 6872.931270687229, 6882.69027893736, 6909.896351554731, 6020.939790602038, 6530.183727034121, 6562.901556476657, 6582.868342633121, 6585.967070164618, 6602.60384376939, 6673.532852700265, 6682.164729408824, 6745.3591908768585, 6763.966180169067, 6560.9343906560325, 6632.568883022588, 6648.5346025777735, 6657.567258128206, 6668.83327916806, 6324.525660575568, 6336.968315158395, 6383.36166383358, 6478.449331806849, 6530.869382612416, 4893.853184404474, 4910.238912968352, 4965.726885021359, 4966.776495057803, 5001.0, 6428.292887782433, 6472.352764723679, 6594.373534514213, 6632.867342653217, 6657.0, 6348.460380867678, 6402.871942561216, 6541.70562324706, 6556.262590413639, 6561.311062338576, 6460.806175814733, 6464.838379040561, 6532.183477065367, 6558.344165583403, 6605.702743376463, 6295.5907865988565, 6377.17096777416, 6429.839254018687, 6485.967570162119, 6488.11278980849, 6457.741690332334, 6461.773837915651, 6559.639219842764, 6564.310747371457, 6609.173853268341, 6304.842378940563, 6397.200349956255, 6402.391772781578, 6417.08022849593, 6420.293767685457], "value": 5037.046761382156, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.0740526150172, 897.2956229359953, 898.6410671986037, 908.3641450984309, 915.0346384564282, 15654.530364089067, 16266.43067492638, 16281.104539250351, 16337.101459419742, 16363.59091022722, 15909.761353579706, 15996.600084997881, 16089.034657920532, 16099.436519721812, 16142.0, 934.598122807191, 954.6754103604773, 959.1128206409079, 960.0735290444719, 960.3421656165514, 971.4414211828192, 977.6578197630844, 979.1383582447436, 979.8628192053118, 981.4062492192228, 929.5352323838081, 1002.1481740520572, 1015.0509273828957, 1018.2770233134484, 1028.269928350868, 1002.0780881588955, 1006.7181189266944, 1008.8083264179783, 1009.1523120578712, 1011.8988101189859, 742.7771668499482, 743.2938708227206, 745.1579714922158, 745.1616930952679, 746.1158527145296, 16037.438689645807, 16076.919615401965, 16082.276297566637, 16095.436659716854, 16116.59708507285, 21141.471463213387, 21864.0, 21886.781132188633, 21955.560888782296, 21963.890180549155, 16314.0, 16693.499195024167, 16710.66578668432, 16789.0, 16814.15929203535, 3658.158623516606, 3677.191017976041, 3678.834452449646, 3680.7423480356388, 3682.723795715305, 115912.42043789811, 144061.27969360087, 145208.0, 145340.0, 146442.0, 17510.21204045821, 17535.824641753676, 17780.822191777916, 17868.46394608164, 18033.0, 31547.36905261882, 31769.20576985594, 31856.0, 31877.521837172462, 31888.840555797073, 8358.0, 8502.65989360431, 8544.914550854537, 8623.094575069654, 8627.094155113748, 4049.028233224005, 4112.835486580563, 4113.794310284474, 4115.9176816463505, 4116.526599441043, 5395.568354531628, 5423.159410291411, 5502.394736578973, 5521.613487055867, 5522.475364840414, 1086.8532748078908, 1095.912327013837, 1098.5770478365728, 1106.4246591772232, 1108.6729414822646, 556.7689408895344, 617.2531237202945, 617.3641149615903, 635.8887194740902, 644.3588629313863, 10702.143828493701, 10994.505247263893, 11048.779024419628, 11058.170637202242, 11523.583915559673, 607.4623957797293, 615.2094558492316, 624.5940138909655, 626.7493002798917, 626.7806267806232, 4850.369451971221, 4894.730789806595, 4922.778474968635, 5084.44071152173, 5105.744712764347, 117536.0, 119782.0, 120386.0, 120597.77907773186, 121074.0, 106128.0, 107915.0, 109639.45180274207, 111695.4415227935, 112472.4376378113, 90417.54791226002, 92919.53540232255, 93034.53482732543, 93467.0, 95590.0, 9099.545022748704, 9383.0, 9439.480828554495, 9464.95267523658, 9544.522773861418, 43606.0, 43720.562794371646, 43979.34030989602, 45993.31010034785, 46051.697415129776, 54843.0, 55670.72164639151, 55812.0, 56887.431125689865, 57174.42825571857, 14599.197044162673, 14784.63038424005, 15054.0, 15171.468998584996, 15243.237838108273, 15917.68164636724, 15939.043657380595, 16065.518034458984, 16095.0, 16122.596935076717, 14793.556193314218, 14980.1011939284, 15122.54632361031, 15175.16536590498, 15217.695646086795, 15741.291641875914, 15803.762943555626, 15873.841261587237, 16034.67930641404, 16072.758908616614, 15189.696206076038, 15303.61740956485, 15367.769483457982, 15368.154751488775, 15373.077615343114, 11112.722181945193, 11225.494852731483, 11332.490037948477, 11417.885821142014, 11537.07703383744, 10836.241463097507, 10960.616378426717, 11051.557937682725, 11053.502592383202, 11248.437578120898], "value": 8570.705670001204, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1423.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 108.0, 108.0, 111.0, 95.0, 109.0, 107.0, 107.0, 107.0], "value": 106.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 129.0, 116.0, 115.0, 139.0, 117.0, 119.0, 115.0, 120.0], "value": 120.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 162.0, 162.0, 165.0, 168.0, 163.0, 169.0, 165.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 75.0, 74.0, 73.0, 74.0, 73.0, 75.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 80.0, 80.0, 78.0, 79.0, 78.0, 80.0, 79.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 113.0, 113.0, 113.0, 115.0, 113.0, 114.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 150.0, 149.0, 148.0, 148.0, 149.0, 151.0, 148.0, 147.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 52.0, 53.0, 52.0, 52.0, 51.0, 54.0, 56.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 38.0, 38.0, 37.0, 37.0, 37.0, 39.0, 38.0, 38.0], "value": 37.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 74.0, 78.0, 79.0, 75.0, 75.0, 75.0, 76.0, 73.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 125.0, 113.0, 117.0, 122.0, 119.0, 116.0, 117.0, 119.0], "value": 117.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 169.0, 174.0, 168.0, 183.0, 168.0, 170.0, 173.0, 175.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 71.0, 66.0, 65.0, 67.0, 65.0, 64.0, 69.0, 67.0], "value": 67.0, "unit": "score"}]}]} 03:36:58 INFO - Return code: 0 03:36:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:36:58 INFO - # TBPL SUCCESS # 03:36:58 INFO - Running post-action listener: _package_coverage_data 03:36:58 INFO - Running post-action listener: _resource_record_post_action 03:36:58 INFO - [mozharness: 2017-09-13 10:36:58.324923Z] Finished run-tests step (success) 03:36:58 INFO - Running post-run listener: _resource_record_post_run 03:36:58 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 305483776; Read time: 4; Write time: 230104 03:36:58 INFO - TinderboxPrint: CPU usage
13.1% 03:36:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 03:36:58 INFO - TinderboxPrint: I/O write bytes / time
305,483,776 / 230,104 03:36:58 INFO - TinderboxPrint: CPU idle
8,230.0 (86.9%) 03:36:58 INFO - TinderboxPrint: CPU user
1,190.7 (12.6%) 03:36:58 INFO - TinderboxPrint: Swap in / out
0 / 0 03:36:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 12599296; Read time: 0; Write time: 3644 03:36:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:36:58 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292884480; Read time: 4; Write time: 226460 03:36:58 INFO - Running post-run listener: _upload_blobber_files 03:36:58 INFO - Blob upload gear active. 03:36:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:36:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:36:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:36:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:36:59 INFO - (blobuploader) - INFO - Open directory for files ... 03:36:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:36:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:37:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:00 INFO - (blobuploader) - INFO - Done attempting. 03:37:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:37:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:37:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:01 INFO - (blobuploader) - INFO - Done attempting. 03:37:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:37:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:37:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:02 INFO - (blobuploader) - INFO - Done attempting. 03:37:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:37:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:37:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:11 INFO - (blobuploader) - INFO - Done attempting. 03:37:11 INFO - (blobuploader) - INFO - Iteration through files over. 03:37:11 INFO - Return code: 0 03:37:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:37:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:37:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c591f1cb6a66cc71601b89f79ba0f012e456b62cf124543f0b0a0b92ca2efa7b57cfec8f1adb8a42887138dceeab05f8fc5f83c7f562f3a79753ca76b833f76", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b1a354a1c559abf21ca8490408a017a49cf635628a5779a54c0979568df45c447174c78abc99a35e3eaa377fa8880ed068591d5088ded2f49e6bdd5295aca75", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ff67fb11eb93db379bf3b7f213f1418f038e3dd00381ee5f79480bd0a9b6ae818a7d2ab1fa068631c760d702cabe3f1d06e7a7ecad695d8a84d81e078a39a36", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0493dce7baa7a948fc6f088f96a4025c059fe061525dcdbae18ffe44b04124066692159161e54681a163a6b5d5c6292f9f71cf7e9a5eb949878091f065f77188"} 03:37:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:37:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:37:11 INFO - Contents: 03:37:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c591f1cb6a66cc71601b89f79ba0f012e456b62cf124543f0b0a0b92ca2efa7b57cfec8f1adb8a42887138dceeab05f8fc5f83c7f562f3a79753ca76b833f76", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b1a354a1c559abf21ca8490408a017a49cf635628a5779a54c0979568df45c447174c78abc99a35e3eaa377fa8880ed068591d5088ded2f49e6bdd5295aca75", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ff67fb11eb93db379bf3b7f213f1418f038e3dd00381ee5f79480bd0a9b6ae818a7d2ab1fa068631c760d702cabe3f1d06e7a7ecad695d8a84d81e078a39a36", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0493dce7baa7a948fc6f088f96a4025c059fe061525dcdbae18ffe44b04124066692159161e54681a163a6b5d5c6292f9f71cf7e9a5eb949878091f065f77188"} 03:37:11 INFO - Running post-run listener: copy_logs_to_upload_dir 03:37:11 INFO - Copying logs to upload dir... 03:37:11 INFO - mkdir: /builds/slave/test/build/upload/logs 03:37:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.007049 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-13 03:37:12.129382) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 03:37:12.136880) ========= 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=781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c591f1cb6a66cc71601b89f79ba0f012e456b62cf124543f0b0a0b92ca2efa7b57cfec8f1adb8a42887138dceeab05f8fc5f83c7f562f3a79753ca76b833f76", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b1a354a1c559abf21ca8490408a017a49cf635628a5779a54c0979568df45c447174c78abc99a35e3eaa377fa8880ed068591d5088ded2f49e6bdd5295aca75", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ff67fb11eb93db379bf3b7f213f1418f038e3dd00381ee5f79480bd0a9b6ae818a7d2ab1fa068631c760d702cabe3f1d06e7a7ecad695d8a84d81e078a39a36", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0493dce7baa7a948fc6f088f96a4025c059fe061525dcdbae18ffe44b04124066692159161e54681a163a6b5d5c6292f9f71cf7e9a5eb949878091f065f77188"} build_url:https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011842 build_url: 'https://queue.taskcluster.net/v1/task/PtRvbvlCQYK7a280i3EPGQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c591f1cb6a66cc71601b89f79ba0f012e456b62cf124543f0b0a0b92ca2efa7b57cfec8f1adb8a42887138dceeab05f8fc5f83c7f562f3a79753ca76b833f76", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b1a354a1c559abf21ca8490408a017a49cf635628a5779a54c0979568df45c447174c78abc99a35e3eaa377fa8880ed068591d5088ded2f49e6bdd5295aca75", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ff67fb11eb93db379bf3b7f213f1418f038e3dd00381ee5f79480bd0a9b6ae818a7d2ab1fa068631c760d702cabe3f1d06e7a7ecad695d8a84d81e078a39a36", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0493dce7baa7a948fc6f088f96a4025c059fe061525dcdbae18ffe44b04124066692159161e54681a163a6b5d5c6292f9f71cf7e9a5eb949878091f065f77188"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 03:37:12.165900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:37:12.166221) ========= 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=781b77c4c33858b1f599d705000001c1-1505297739.46272-972410451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:37:12.251859) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 03:37:12.252166) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 03:37:12.252480) ========= ========= Total master_lag: 0.74 =========