builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-022 starttime: 1505369971.28 results: success (0) revision: cb717386aec8601a301babd4156aabc4cccff0b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:31.281263) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:31.281665) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:31.281961) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:31.343002) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:31.343266) ========= 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=930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 23:19:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-09-13 23:19:31 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.072729 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:31.437496) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:31.437799) ========= 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=930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019705 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:31.505308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:31.505588) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb717386aec8601a301babd4156aabc4cccff0b0 --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 cb717386aec8601a301babd4156aabc4cccff0b0 --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=930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 23:19:31,690 truncating revision to first 12 chars 2017-09-13 23:19:31,690 Setting DEBUG logging. 2017-09-13 23:19:31,690 attempt 1/10 2017-09-13 23:19:31,690 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb717386aec8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 23:19:31,946 unpacking tar archive at: mozilla-central-cb717386aec8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.571345 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:32.097487) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:32.097753) ========= script_repo_revision: cb717386aec8601a301babd4156aabc4cccff0b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:32.098093) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:32.098389) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 23:19:32.114650) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-13 23:19:32.114974) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:19:32 INFO - MultiFileLogger online at 20170913 23:19:32 in /builds/slave/test 23:19:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 23:19:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:19:32 INFO - {'append_to_log': False, 23:19:32 INFO - 'base_work_dir': '/builds/slave/test', 23:19:32 INFO - 'blob_upload_branch': 'Firefox', 23:19:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:19:32 INFO - 'branch': 'Firefox', 23:19:32 INFO - 'buildbot_json_path': 'buildprops.json', 23:19:32 INFO - 'code_coverage': False, 23:19:32 INFO - 'config_files': ('talos/linux_config.py',), 23:19:32 INFO - 'default_actions': ('clobber', 23:19:32 INFO - 'read-buildbot-config', 23:19:32 INFO - 'download-and-extract', 23:19:32 INFO - 'populate-webroot', 23:19:32 INFO - 'create-virtualenv', 23:19:32 INFO - 'install', 23:19:32 INFO - 'setup-mitmproxy', 23:19:32 INFO - 'run-tests'), 23:19:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:19:32 INFO - 'disable_ccov_upload': False, 23:19:32 INFO - 'disable_stylo': False, 23:19:32 INFO - 'download_minidump_stackwalk': True, 23:19:32 INFO - 'download_symbols': 'ondemand', 23:19:32 INFO - 'enable_stylo': False, 23:19:32 INFO - 'enable_webrender': False, 23:19:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 23:19:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:19:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:19:32 INFO - 'gecko_profile': False, 23:19:32 INFO - 'gecko_profile_interval': 0, 23:19:32 INFO - 'installer_path': 'installer.exe', 23:19:32 INFO - 'log_level': 'info', 23:19:32 INFO - 'log_name': 'talos', 23:19:32 INFO - 'log_to_console': True, 23:19:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:19:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:19:32 INFO - 'opt_config_files': (), 23:19:32 INFO - 'pip_index': False, 23:19:32 INFO - 'suite': 'dromaeojs-e10s', 23:19:32 INFO - 'system_bits': '32', 23:19:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:19:32 INFO - 'title': 'talos-linux64-ix-022', 23:19:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:19:32 INFO - 'use_talos_json': True, 23:19:32 INFO - 'verify': 'False', 23:19:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:19:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:19:32 INFO - 'work_dir': 'build'} 23:19:32 INFO - [mozharness: 2017-09-14 06:19:32.432350Z] Running clobber step. 23:19:32 INFO - Running pre-action listener: _resource_record_pre_action 23:19:32 INFO - Running main action method: clobber 23:19:32 INFO - rmtree: /builds/slave/test/build 23:19:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:19:33 INFO - Running post-action listener: _resource_record_post_action 23:19:33 INFO - [mozharness: 2017-09-14 06:19:33.960236Z] Finished clobber step (success) 23:19:33 INFO - [mozharness: 2017-09-14 06:19:33.960332Z] Running read-buildbot-config step. 23:19:33 INFO - Running pre-action listener: _resource_record_pre_action 23:19:33 INFO - Running main action method: read_buildbot_config 23:19:33 INFO - Using buildbot properties: 23:19:33 INFO - { 23:19:33 INFO - "project": "", 23:19:33 INFO - "product": "firefox", 23:19:33 INFO - "who": "jdolske@mozilla.com", 23:19:33 INFO - "installer_path": "public/build/target.tar.bz2", 23:19:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 23:19:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 23:19:33 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 23:19:33 INFO - "stage_platform": "linux64-qr", 23:19:33 INFO - "basedir": "/builds/slave/test", 23:19:33 INFO - "buildnumber": 53, 23:19:33 INFO - "platform": "ubuntu64_hw_qr", 23:19:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:19:33 INFO - "slavebuilddir": "test", 23:19:33 INFO - "taskId": "deCcwANfSA-3TgsUAfBzhw", 23:19:33 INFO - "branch": "mozilla-central", 23:19:33 INFO - "script_repo_revision": "production", 23:19:33 INFO - "revision": "cb717386aec8601a301babd4156aabc4cccff0b0", 23:19:33 INFO - "slavename": "talos-linux64-ix-022", 23:19:33 INFO - "repo_path": "mozilla-central" 23:19:33 INFO - } 23:19:33 INFO - Finding installer, test and symbols from parent task. 23:19:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/deCcwANfSA-3TgsUAfBzhw'}, attempt #1 23:19:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/deCcwANfSA-3TgsUAfBzhw'}, attempt #1 23:19:34 INFO - Task dependencies: Zv5HYUSPS56G-8wZXuIJvw 23:19:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw'}, attempt #1 23:19:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.tar.bz2 23:19:35 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.test_packages.json 23:19:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.crashreporter-symbols.zip 23:19:35 INFO - Running post-action listener: _resource_record_post_action 23:19:35 INFO - [mozharness: 2017-09-14 06:19:35.434242Z] Finished read-buildbot-config step (success) 23:19:35 INFO - [mozharness: 2017-09-14 06:19:35.434500Z] Running download-and-extract step. 23:19:35 INFO - Running pre-action listener: _resource_record_pre_action 23:19:35 INFO - Running main action method: download_and_extract 23:19:35 INFO - mkdir: /builds/slave/test/build/tests 23:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:35 INFO - trying https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.test_packages.json 23:19:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 23:19:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 23:19:36 INFO - Downloaded 989 bytes. 23:19:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 23:19:36 INFO - Using the following test package requirements: 23:19:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 23:19:36 INFO - u'common': [u'target.common.tests.zip'], 23:19:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 23:19:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 23:19:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 23:19:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 23:19:36 INFO - u'mozbase': [u'target.common.tests.zip'], 23:19:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 23:19:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 23:19:36 INFO - u'web-platform': [u'target.common.tests.zip', 23:19:36 INFO - u'target.web-platform.tests.tar.gz'], 23:19:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 23:19:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 23:19:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.common.tests.zip 23:19:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 23:19:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.common.tests.zip into memory 23:19:38 INFO - Content-Length response header: 54572613 23:19:38 INFO - Bytes received: 54572613 23:19:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.talos.tests.zip 23:19:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 23:19:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.talos.tests.zip into memory 23:19:49 INFO - Content-Length response header: 14500356 23:19:49 INFO - Bytes received: 14500356 23:19: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')]} 23:19:50 INFO - trying https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.tar.bz2 23:19:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 23:19:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 23:19:51 INFO - Downloaded 61337791 bytes. 23:19:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.tar.bz2 23:19:52 INFO - mkdir: /builds/slave/test/properties 23:19:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:19:52 INFO - Writing to file /builds/slave/test/properties/build_url 23:19:52 INFO - Contents: 23:19:52 INFO - build_url:https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.tar.bz2 23:19:52 INFO - Running post-action listener: _resource_record_post_action 23:19:52 INFO - Running post-action listener: find_tests_for_verification 23:19:52 INFO - Running post-action listener: set_extra_try_arguments 23:19:52 INFO - [mozharness: 2017-09-14 06:19:52.154597Z] Finished download-and-extract step (success) 23:19:52 INFO - [mozharness: 2017-09-14 06:19:52.154827Z] Running populate-webroot step. 23:19:52 INFO - Running pre-action listener: _resource_record_pre_action 23:19:52 INFO - Running main action method: populate_webroot 23:19:52 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:19:52 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['tresize', 23:19:52 INFO - 'tcanvasmark']}, 23:19:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:19:52 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['dromaeo_css', 23:19:52 INFO - 'kraken']}, 23:19:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:19:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:19:52 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 23:19:52 INFO - 'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:19:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:19:52 INFO - 'tests': ['damp', 'tps']}, 23:19:52 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 23:19:52 INFO - 'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['damp', 'tps']}, 23:19:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:19:52 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['dromaeo_dom']}, 23:19:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 23:19:52 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['basic_compositor_video', 23:19:52 INFO - 'glvideo']}, 23:19:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 23:19:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 23:19:52 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 23:19:52 INFO - 'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['ts_paint_webext', 23:19:52 INFO - 'tp5o_webext']}, 23:19:52 INFO - 'other-e10s': {'tests': ['a11yr', 23:19:52 INFO - 'ts_paint', 23:19:52 INFO - 'tpaint', 23:19:52 INFO - 'sessionrestore', 23:19:52 INFO - 'sessionrestore_many_windows', 23:19:52 INFO - 'sessionrestore_no_auto_restore', 23:19:52 INFO - 'tabpaint']}, 23:19:52 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['a11yr', 23:19:52 INFO - 'ts_paint', 23:19:52 INFO - 'tpaint', 23:19:52 INFO - 'sessionrestore', 23:19:52 INFO - 'sessionrestore_many_windows', 23:19:52 INFO - 'sessionrestore_no_auto_restore', 23:19:52 INFO - 'tabpaint']}, 23:19:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 23:19:52 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 23:19:52 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['bloom_basic_singleton']}, 23:19:52 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['bloom_basic']}, 23:19:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:19:52 INFO - 'tsvgr_opacity', 23:19:52 INFO - 'tart', 23:19:52 INFO - 'tscrollx', 23:19:52 INFO - 'tsvg_static']}, 23:19:52 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['tsvgx', 23:19:52 INFO - 'tsvgr_opacity', 23:19:52 INFO - 'tart', 23:19:52 INFO - 'tscrollx', 23:19:52 INFO - 'tsvg_static']}, 23:19:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:19:52 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 23:19:52 INFO - 'talos_options': ['--disable-stylo'], 23:19:52 INFO - 'tests': ['tp5o']}, 23:19:52 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 23:19:52 INFO - 'talos_options': ['--mitmproxy', 23:19:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 23:19:52 INFO - '--firstNonBlankPaint'], 23:19:52 INFO - 'tests': ['tp6_google', 23:19:52 INFO - 'tp6_youtube', 23:19:52 INFO - 'tp6_amazon', 23:19:52 INFO - 'tp6_facebook']}, 23:19:52 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 23:19:52 INFO - 'talos_options': ['--disable-stylo', 23:19:52 INFO - '--mitmproxy', 23:19:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 23:19:52 INFO - '--firstNonBlankPaint'], 23:19:52 INFO - 'tests': ['tp6_google', 23:19:52 INFO - 'tp6_youtube', 23:19:52 INFO - 'tp6_amazon', 23:19:52 INFO - 'tp6_facebook']}, 23:19:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 23:19:52 INFO - 'talos_options': ['--stylo-threads=1', 23:19:52 INFO - '--mitmproxy', 23:19:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 23:19:52 INFO - '--firstNonBlankPaint'], 23:19:52 INFO - 'tests': ['tp6_google', 23:19:52 INFO - 'tp6_youtube', 23:19:52 INFO - 'tp6_amazon', 23:19:52 INFO - 'tp6_facebook']}, 23:19:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:19:52 INFO - 'talos_options': ['--xperf_path', 23:19:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:19:52 INFO - 'tests': ['tp5n']}, 23:19:52 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 23:19:52 INFO - 'talos_options': ['--disable-stylo', 23:19:52 INFO - '--xperf_path', 23:19:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:19:52 INFO - 'tests': ['tp5n']}}} 23:19:52 INFO - Running post-action listener: _resource_record_post_action 23:19:52 INFO - [mozharness: 2017-09-14 06:19:52.175182Z] Finished populate-webroot step (success) 23:19:52 INFO - [mozharness: 2017-09-14 06:19:52.175270Z] Running create-virtualenv step. 23:19:52 INFO - Running pre-action listener: _resource_record_pre_action 23:19:52 INFO - Running main action method: create_virtualenv 23:19:52 INFO - Creating virtualenv /builds/slave/test/build/venv 23:19:52 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:19:52 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 23:19:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 23:19:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:19:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:19:52 INFO - Installing distribute.............................................................................................................................................................................................done. 23:19:53 INFO - Installing pip...............done. 23:19:53 INFO - Return code: 0 23:19:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:19:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:19:53 INFO - Reading from file tmpfile_stdout 23:19:53 INFO - Output received: 23:19:53 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 23:19:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:19:53 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')]} 23:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:53 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')]} 23:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:53 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 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:19:53 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 23:19:53 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 23:19:53 INFO - Using env: {'DISPLAY': ':0', 23:19:53 INFO - 'HOME': '/home/cltbld', 23:19:53 INFO - 'LANG': 'en_US.UTF-8', 23:19:53 INFO - 'LANGUAGE': 'en_US:en', 23:19:53 INFO - 'LOGNAME': 'cltbld', 23:19:53 INFO - 'MAIL': '/var/mail/cltbld', 23:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:53 INFO - 'MOZ_NO_REMOTE': '1', 23:19:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:53 INFO - 'NO_EM_RESTART': '1', 23:19:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:53 INFO - 'PWD': '/builds/slave/test', 23:19:53 INFO - 'SHELL': '/bin/bash', 23:19:53 INFO - 'SHLVL': '1', 23:19:53 INFO - 'TERM': 'linux', 23:19:53 INFO - 'TMOUT': '86400', 23:19:53 INFO - 'USER': 'cltbld', 23:19:53 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:53 INFO - '_': '/tools/buildbot/bin/python'} 23:19:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 23:19:54 INFO - Downloading/unpacking pip>=1.5 23:19:54 INFO - Running setup.py egg_info for package pip 23:19:54 INFO - warning: no files found matching 'pip/cacert.pem' 23:19:54 INFO - warning: no files found matching '*.html' under directory 'docs' 23:19:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 23:19:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 23:19:54 INFO - Installing collected packages: pip 23:19:54 INFO - Found existing installation: pip 1.1 23:19:54 INFO - Uninstalling pip: 23:19:54 INFO - Successfully uninstalled pip 23:19:54 INFO - Running setup.py install for pip 23:19:54 INFO - warning: no files found matching 'pip/cacert.pem' 23:19:54 INFO - warning: no files found matching '*.html' under directory 'docs' 23:19:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 23:19:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 23:19:54 INFO - Installing pip script to /builds/slave/test/build/venv/bin 23:19:54 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 23:19:54 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 23:19:54 INFO - Successfully installed pip 23:19:54 INFO - Cleaning up... 23:19:54 INFO - Return code: 0 23:19:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:19: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')]} 23:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19: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')]} 23:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19: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', '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 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:19: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', 'psutil>=3.1.1'] in /builds/slave/test/build 23:19: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 psutil>=3.1.1 23:19:54 INFO - Using env: {'DISPLAY': ':0', 23:19:54 INFO - 'HOME': '/home/cltbld', 23:19:54 INFO - 'LANG': 'en_US.UTF-8', 23:19:54 INFO - 'LANGUAGE': 'en_US:en', 23:19:54 INFO - 'LOGNAME': 'cltbld', 23:19:54 INFO - 'MAIL': '/var/mail/cltbld', 23:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:54 INFO - 'MOZ_NO_REMOTE': '1', 23:19:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:54 INFO - 'NO_EM_RESTART': '1', 23:19:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:54 INFO - 'PWD': '/builds/slave/test', 23:19:54 INFO - 'SHELL': '/bin/bash', 23:19:54 INFO - 'SHLVL': '1', 23:19:54 INFO - 'TERM': 'linux', 23:19:54 INFO - 'TMOUT': '86400', 23:19:54 INFO - 'USER': 'cltbld', 23:19:54 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:54 INFO - '_': '/tools/buildbot/bin/python'} 23:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:54 INFO - Downloading/unpacking psutil>=3.1.1 23:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:19:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:19:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:19:55 INFO - Installing collected packages: psutil 23:19:55 INFO - Running setup.py install for psutil 23:19:55 INFO - building 'psutil._psutil_linux' extension 23:19:55 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 23:19:56 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 23:19:56 INFO - building 'psutil._psutil_posix' extension 23:19:56 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 23:19:56 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 23:19:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:19:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:19:56 INFO - Successfully installed psutil 23:19:56 INFO - Cleaning up... 23:19:56 INFO - Return code: 0 23:19:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:19:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:19:56 INFO - Using env: {'DISPLAY': ':0', 23:19:56 INFO - 'HOME': '/home/cltbld', 23:19:56 INFO - 'LANG': 'en_US.UTF-8', 23:19:56 INFO - 'LANGUAGE': 'en_US:en', 23:19:56 INFO - 'LOGNAME': 'cltbld', 23:19:56 INFO - 'MAIL': '/var/mail/cltbld', 23:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:19:56 INFO - 'MOZ_NO_REMOTE': '1', 23:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:19:56 INFO - 'NO_EM_RESTART': '1', 23:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:19:56 INFO - 'PWD': '/builds/slave/test', 23:19:56 INFO - 'SHELL': '/bin/bash', 23:19:56 INFO - 'SHLVL': '1', 23:19:56 INFO - 'TERM': 'linux', 23:19:56 INFO - 'TMOUT': '86400', 23:19:56 INFO - 'USER': 'cltbld', 23:19:56 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:19:56 INFO - '_': '/tools/buildbot/bin/python'} 23:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:19:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:19: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 23:19: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 23:19: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 23:19: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 23:19:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:19:59 INFO - Installing collected packages: mozsystemmonitor 23:19:59 INFO - Running setup.py install for mozsystemmonitor 23:19:59 INFO - Successfully installed mozsystemmonitor 23:19:59 INFO - Cleaning up... 23:20:00 INFO - Return code: 0 23:20:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:20: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')]} 23:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:20: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')]} 23:20:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:20:00 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 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:20:00 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 23:20:00 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 23:20:00 INFO - Using env: {'DISPLAY': ':0', 23:20:00 INFO - 'HOME': '/home/cltbld', 23:20:00 INFO - 'LANG': 'en_US.UTF-8', 23:20:00 INFO - 'LANGUAGE': 'en_US:en', 23:20:00 INFO - 'LOGNAME': 'cltbld', 23:20:00 INFO - 'MAIL': '/var/mail/cltbld', 23:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:00 INFO - 'MOZ_NO_REMOTE': '1', 23:20:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:00 INFO - 'NO_EM_RESTART': '1', 23:20:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:00 INFO - 'PWD': '/builds/slave/test', 23:20:00 INFO - 'SHELL': '/bin/bash', 23:20:00 INFO - 'SHLVL': '1', 23:20:00 INFO - 'TERM': 'linux', 23:20:00 INFO - 'TMOUT': '86400', 23:20:00 INFO - 'USER': 'cltbld', 23:20:00 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:00 INFO - '_': '/tools/buildbot/bin/python'} 23:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:20:00 INFO - Downloading/unpacking jsonschema==2.5.1 23:20:00 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 23:20:00 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 23:20:00 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 23:20:00 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 23:20:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:20:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:20:01 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 23:20:01 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 23:20:01 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 23:20:01 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 23:20:02 INFO - Downloading functools32-3.2.3-2.tar.gz 23:20:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:20:02 INFO - warning: no files found matching '*.txt' 23:20:02 INFO - no previously-included directories found matching 'build' 23:20:02 INFO - no previously-included directories found matching 'dist' 23:20:02 INFO - no previously-included directories found matching '.git*' 23:20:02 INFO - Installing collected packages: jsonschema, functools32 23:20:02 INFO - Running setup.py install for functools32 23:20:02 INFO - warning: no files found matching '*.txt' 23:20:02 INFO - no previously-included directories found matching 'build' 23:20:02 INFO - no previously-included directories found matching 'dist' 23:20:02 INFO - no previously-included directories found matching '.git*' 23:20:02 INFO - Successfully installed jsonschema functools32 23:20:02 INFO - Cleaning up... 23:20:02 INFO - Return code: 0 23:20:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:20:02 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')]} 23:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:20:02 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')]} 23:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:20:02 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 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:20:02 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 23:20:02 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 23:20:02 INFO - Using env: {'DISPLAY': ':0', 23:20:02 INFO - 'HOME': '/home/cltbld', 23:20:02 INFO - 'LANG': 'en_US.UTF-8', 23:20:02 INFO - 'LANGUAGE': 'en_US:en', 23:20:02 INFO - 'LOGNAME': 'cltbld', 23:20:02 INFO - 'MAIL': '/var/mail/cltbld', 23:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:02 INFO - 'MOZ_NO_REMOTE': '1', 23:20:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:02 INFO - 'NO_EM_RESTART': '1', 23:20:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:02 INFO - 'PWD': '/builds/slave/test', 23:20:02 INFO - 'SHELL': '/bin/bash', 23:20:02 INFO - 'SHLVL': '1', 23:20:02 INFO - 'TERM': 'linux', 23:20:02 INFO - 'TMOUT': '86400', 23:20:02 INFO - 'USER': 'cltbld', 23:20:02 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:02 INFO - '_': '/tools/buildbot/bin/python'} 23:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:20:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:20:02 INFO - Cleaning up... 23:20:02 INFO - Return code: 0 23:20:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:20:02 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')]} 23:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:20:02 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')]} 23:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:20:02 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 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:20:02 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 23:20:02 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 23:20:02 INFO - Using env: {'DISPLAY': ':0', 23:20:02 INFO - 'HOME': '/home/cltbld', 23:20:02 INFO - 'LANG': 'en_US.UTF-8', 23:20:02 INFO - 'LANGUAGE': 'en_US:en', 23:20:02 INFO - 'LOGNAME': 'cltbld', 23:20:02 INFO - 'MAIL': '/var/mail/cltbld', 23:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:02 INFO - 'MOZ_NO_REMOTE': '1', 23:20:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:02 INFO - 'NO_EM_RESTART': '1', 23:20:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:02 INFO - 'PWD': '/builds/slave/test', 23:20:02 INFO - 'SHELL': '/bin/bash', 23:20:02 INFO - 'SHLVL': '1', 23:20:02 INFO - 'TERM': 'linux', 23:20:02 INFO - 'TMOUT': '86400', 23:20:02 INFO - 'USER': 'cltbld', 23:20:02 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:02 INFO - '_': '/tools/buildbot/bin/python'} 23:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:20:02 INFO - Downloading/unpacking blobuploader==1.2.4 23:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:20:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:20:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:20:03 INFO - Downloading blobuploader-1.2.4.tar.gz 23:20:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:20:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:20:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:20:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:20:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:20:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:20:04 INFO - Downloading docopt-0.6.1.tar.gz 23:20:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:20:04 INFO - Installing collected packages: blobuploader, requests, docopt 23:20:04 INFO - Running setup.py install for blobuploader 23:20:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:20:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:20:04 INFO - Running setup.py install for requests 23:20:04 INFO - Running setup.py install for docopt 23:20:04 INFO - Successfully installed blobuploader requests docopt 23:20:04 INFO - Cleaning up... 23:20:05 INFO - Return code: 0 23:20:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:20:05 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 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:20:05 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 23:20:05 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 23:20:05 INFO - Using env: {'DISPLAY': ':0', 23:20:05 INFO - 'HOME': '/home/cltbld', 23:20:05 INFO - 'LANG': 'en_US.UTF-8', 23:20:05 INFO - 'LANGUAGE': 'en_US:en', 23:20:05 INFO - 'LOGNAME': 'cltbld', 23:20:05 INFO - 'MAIL': '/var/mail/cltbld', 23:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:05 INFO - 'MOZ_NO_REMOTE': '1', 23:20:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:05 INFO - 'NO_EM_RESTART': '1', 23:20:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:05 INFO - 'PWD': '/builds/slave/test', 23:20:05 INFO - 'SHELL': '/bin/bash', 23:20:05 INFO - 'SHLVL': '1', 23:20:05 INFO - 'TERM': 'linux', 23:20:05 INFO - 'TMOUT': '86400', 23:20:05 INFO - 'USER': 'cltbld', 23:20:05 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:05 INFO - '_': '/tools/buildbot/bin/python'} 23:20:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:20:05 INFO - Running setup.py (path:/tmp/pip-0dSxOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:20:05 INFO - Running setup.py (path:/tmp/pip-zJAT8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:20:05 INFO - Running setup.py (path:/tmp/pip-5GahCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:20:05 INFO - Running setup.py (path:/tmp/pip-n7I5Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:20:05 INFO - Running setup.py (path:/tmp/pip-eMhiw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:20:05 INFO - Running setup.py (path:/tmp/pip-Bv_kBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:20:05 INFO - Running setup.py (path:/tmp/pip-IrMJtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:20:05 INFO - Running setup.py (path:/tmp/pip-IRlp6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:20:05 INFO - Running setup.py (path:/tmp/pip-S9HyHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:20:05 INFO - Running setup.py (path:/tmp/pip-edKGvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:20:05 INFO - Running setup.py (path:/tmp/pip-0ugGMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:20:06 INFO - Running setup.py (path:/tmp/pip-i_tI6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:20:06 INFO - Running setup.py (path:/tmp/pip-rhue_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:20:06 INFO - Running setup.py (path:/tmp/pip-RQDbsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:20:06 INFO - Running setup.py (path:/tmp/pip-KcZdUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:20:06 INFO - Running setup.py (path:/tmp/pip-gWFxht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:20:06 INFO - Running setup.py (path:/tmp/pip-TfbNhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:20:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:20:06 INFO - Running setup.py install for manifestparser 23:20:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:20:06 INFO - Running setup.py install for mozcrash 23:20:06 INFO - Running setup.py install for mozdebug 23:20:06 INFO - Running setup.py install for mozdevice 23:20:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:20:06 INFO - Running setup.py install for mozfile 23:20:06 INFO - Running setup.py install for mozhttpd 23:20:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Running setup.py install for mozinfo 23:20:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Running setup.py install for mozInstall 23:20:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Running setup.py install for mozleak 23:20:07 INFO - Running setup.py install for mozlog 23:20:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Running setup.py install for moznetwork 23:20:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Running setup.py install for mozprocess 23:20:07 INFO - Running setup.py install for mozprofile 23:20:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Running setup.py install for mozrunner 23:20:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:20:07 INFO - Running setup.py install for mozscreenshot 23:20:08 INFO - Running setup.py install for moztest 23:20:08 INFO - Running setup.py install for mozversion 23:20:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:20:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:20:08 INFO - Cleaning up... 23:20:08 INFO - Return code: 0 23:20:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:20:08 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 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:20:08 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 23:20:08 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 23:20:08 INFO - Using env: {'DISPLAY': ':0', 23:20:08 INFO - 'HOME': '/home/cltbld', 23:20:08 INFO - 'LANG': 'en_US.UTF-8', 23:20:08 INFO - 'LANGUAGE': 'en_US:en', 23:20:08 INFO - 'LOGNAME': 'cltbld', 23:20:08 INFO - 'MAIL': '/var/mail/cltbld', 23:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:08 INFO - 'MOZ_NO_REMOTE': '1', 23:20:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:08 INFO - 'NO_EM_RESTART': '1', 23:20:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:08 INFO - 'PWD': '/builds/slave/test', 23:20:08 INFO - 'SHELL': '/bin/bash', 23:20:08 INFO - 'SHLVL': '1', 23:20:08 INFO - 'TERM': 'linux', 23:20:08 INFO - 'TMOUT': '86400', 23:20:08 INFO - 'USER': 'cltbld', 23:20:08 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:08 INFO - '_': '/tools/buildbot/bin/python'} 23:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:20:08 INFO - Running setup.py (path:/tmp/pip-oOel_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:20:08 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)) 23:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:20:08 INFO - Running setup.py (path:/tmp/pip-V23RH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:20:08 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)) 23:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:20:08 INFO - Running setup.py (path:/tmp/pip-SOeQLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:20:08 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)) 23:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:20:08 INFO - Running setup.py (path:/tmp/pip-qIs5zU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:20:08 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)) 23:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:20:08 INFO - Running setup.py (path:/tmp/pip-UKqCsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:20:08 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)) 23:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:20:08 INFO - Running setup.py (path:/tmp/pip-brfu2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:20:08 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)) 23:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:20:08 INFO - Running setup.py (path:/tmp/pip-yBvvKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:20:08 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)) 23:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:20:08 INFO - Running setup.py (path:/tmp/pip-7MZnQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:20:09 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)) 23:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:20:09 INFO - Running setup.py (path:/tmp/pip-Fa1Mtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:20:09 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)) 23:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:20:09 INFO - Running setup.py (path:/tmp/pip-rfACbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:20:09 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)) 23:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:20:09 INFO - Running setup.py (path:/tmp/pip-OqXKXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:20:09 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)) 23:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:20:09 INFO - Running setup.py (path:/tmp/pip-JN27AW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:20:09 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)) 23:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:20:09 INFO - Running setup.py (path:/tmp/pip-J2rgpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:20:09 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)) 23:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:20:09 INFO - Running setup.py (path:/tmp/pip-FGKeyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:20:09 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)) 23:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:20:09 INFO - Running setup.py (path:/tmp/pip-Smr78u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:20:09 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)) 23:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:20:09 INFO - Running setup.py (path:/tmp/pip-GdQfUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:20:09 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)) 23:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:20:09 INFO - Running setup.py (path:/tmp/pip-_m49Rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:20:09 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)) 23:20:09 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)) 23:20:09 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)) 23:20:09 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)) 23:20:09 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)) 23:20:09 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)) 23:20:09 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)) 23:20:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:20:09 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 23:20:09 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 23:20:09 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 23:20:09 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 23:20:10 INFO - Downloading blessings-1.6.tar.gz 23:20:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:20:10 INFO - Installing collected packages: blessings 23:20:10 INFO - Running setup.py install for blessings 23:20:10 INFO - Successfully installed blessings 23:20:10 INFO - Cleaning up... 23:20:11 INFO - Return code: 0 23:20:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:20:11 INFO - Reading from file tmpfile_stdout 23:20:11 INFO - Current package versions: 23:20:11 INFO - argparse == 1.2.1 23:20:11 INFO - blessings == 1.6 23:20:11 INFO - blobuploader == 1.2.4 23:20:11 INFO - docopt == 0.6.1 23:20:11 INFO - functools32 == 3.2.3-2 23:20:11 INFO - jsonschema == 2.5.1 23:20:11 INFO - manifestparser == 1.1 23:20:11 INFO - mozInstall == 1.13 23:20:11 INFO - mozcrash == 1.0 23:20:11 INFO - mozdebug == 0.1 23:20:11 INFO - mozdevice == 0.51 23:20:11 INFO - mozfile == 1.2 23:20:11 INFO - mozhttpd == 0.7 23:20:11 INFO - mozinfo == 0.10 23:20:11 INFO - mozleak == 0.1 23:20:11 INFO - mozlog == 3.5 23:20:11 INFO - moznetwork == 0.27 23:20:11 INFO - mozprocess == 0.25 23:20:11 INFO - mozprofile == 0.28 23:20:11 INFO - mozrunner == 6.13 23:20:11 INFO - mozscreenshot == 0.1 23:20:11 INFO - mozsystemmonitor == 0.3 23:20:11 INFO - moztest == 0.8 23:20:11 INFO - mozversion == 1.4 23:20:11 INFO - psutil == 3.1.1 23:20:11 INFO - requests == 1.2.3 23:20:11 INFO - wsgiref == 0.1.2 23:20:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:20:11 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 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:20:11 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 23:20:11 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 23:20:11 INFO - Using env: {'DISPLAY': ':0', 23:20:11 INFO - 'HOME': '/home/cltbld', 23:20:11 INFO - 'LANG': 'en_US.UTF-8', 23:20:11 INFO - 'LANGUAGE': 'en_US:en', 23:20:11 INFO - 'LOGNAME': 'cltbld', 23:20:11 INFO - 'MAIL': '/var/mail/cltbld', 23:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:11 INFO - 'MOZ_NO_REMOTE': '1', 23:20:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:11 INFO - 'NO_EM_RESTART': '1', 23:20:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:11 INFO - 'PWD': '/builds/slave/test', 23:20:11 INFO - 'SHELL': '/bin/bash', 23:20:11 INFO - 'SHLVL': '1', 23:20:11 INFO - 'TERM': 'linux', 23:20:11 INFO - 'TMOUT': '86400', 23:20:11 INFO - 'USER': 'cltbld', 23:20:11 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:11 INFO - '_': '/tools/buildbot/bin/python'} 23:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:20:11 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)) 23:20:11 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)) 23:20:11 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)) 23:20:11 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)) 23:20:11 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)) 23:20:11 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)) 23:20:11 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)) 23:20:11 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)) 23:20:11 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)) 23:20:11 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 23:20:11 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 23:20:11 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 23:20:11 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 23:20:11 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 23:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 23:20:12 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)) 23:20:12 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)) 23:20:12 INFO - Installing collected packages: simplejson 23:20:12 INFO - Running setup.py install for simplejson 23:20:12 INFO - building 'simplejson._speedups' extension 23:20:12 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 23:20:13 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 23:20:13 INFO - Successfully installed simplejson 23:20:13 INFO - Cleaning up... 23:20:13 INFO - Return code: 0 23:20:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:20:13 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')]} 23:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:20:13 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')]} 23:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:20:13 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 0x12b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, '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': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', '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 23:20:13 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 23:20:13 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 23:20:13 INFO - Using env: {'DISPLAY': ':0', 23:20:13 INFO - 'HOME': '/home/cltbld', 23:20:13 INFO - 'LANG': 'en_US.UTF-8', 23:20:13 INFO - 'LANGUAGE': 'en_US:en', 23:20:13 INFO - 'LOGNAME': 'cltbld', 23:20:13 INFO - 'MAIL': '/var/mail/cltbld', 23:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:13 INFO - 'MOZ_NO_REMOTE': '1', 23:20:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:13 INFO - 'NO_EM_RESTART': '1', 23:20:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:13 INFO - 'PWD': '/builds/slave/test', 23:20:13 INFO - 'SHELL': '/bin/bash', 23:20:13 INFO - 'SHLVL': '1', 23:20:13 INFO - 'TERM': 'linux', 23:20:13 INFO - 'TMOUT': '86400', 23:20:13 INFO - 'USER': 'cltbld', 23:20:13 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:13 INFO - '_': '/tools/buildbot/bin/python'} 23:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:20:13 INFO - Cleaning up... 23:20:13 INFO - Return code: 0 23:20:13 INFO - Running post-action listener: _resource_record_post_action 23:20:13 INFO - Running post-action listener: _start_resource_monitoring 23:20:13 INFO - Starting resource monitoring. 23:20:13 INFO - [mozharness: 2017-09-14 06:20:13.903984Z] Finished create-virtualenv step (success) 23:20:13 INFO - [mozharness: 2017-09-14 06:20:13.904447Z] Running install step. 23:20:13 INFO - Running pre-action listener: _resource_record_pre_action 23:20:13 INFO - Running main action method: install 23:20:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:20:14 INFO - Reading from file tmpfile_stdout 23:20:14 INFO - Detecting whether we're running mozinstall >=1.0... 23:20:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:20:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:20:14 INFO - Reading from file tmpfile_stdout 23:20:14 INFO - Output received: 23:20:14 INFO - Usage: mozinstall [options] installer 23:20:14 INFO - Options: 23:20:14 INFO - -h, --help show this help message and exit 23:20:14 INFO - -d DEST, --destination=DEST 23:20:14 INFO - Directory to install application into. [default: 23:20:14 INFO - "/builds/slave/test"] 23:20:14 INFO - --app=APP Application being installed. [default: firefox] 23:20:14 INFO - mkdir: /builds/slave/test/build/application 23:20:14 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'] 23:20:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 23:20:31 INFO - Reading from file tmpfile_stdout 23:20:31 INFO - Output received: 23:20:31 INFO - /builds/slave/test/build/application/firefox/firefox 23:20:31 INFO - Running post-action listener: _resource_record_post_action 23:20:31 INFO - [mozharness: 2017-09-14 06:20:31.944152Z] Finished install step (success) 23:20:31 INFO - [mozharness: 2017-09-14 06:20:31.944406Z] Running setup-mitmproxy step. 23:20:31 INFO - Running pre-action listener: _resource_record_pre_action 23:20:31 INFO - Running main action method: setup_mitmproxy 23:20:31 INFO - Skipping: mitmproxy is not required 23:20:31 INFO - Running post-action listener: _resource_record_post_action 23:20:31 INFO - [mozharness: 2017-09-14 06:20:31.945533Z] Finished setup-mitmproxy step (success) 23:20:31 INFO - [mozharness: 2017-09-14 06:20:31.945753Z] Running run-tests step. 23:20:31 INFO - Running pre-action listener: _resource_record_pre_action 23:20:31 INFO - Running pre-action listener: _set_gcov_prefix 23:20:31 INFO - Running main action method: run_tests 23:20:31 WARNING - Try message not found. 23:20:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:20:31 INFO - Python 2.7.3 23:20:31 INFO - Return code: 0 23:20:31 INFO - grabbing minidump binary from tooltool 23:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:20:31 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 0x1621090>, '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 0x1621d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x161bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:20:31 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 23:20:31 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 23:20:31 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 23:20:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:20:32 INFO - Return code: 0 23:20:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:20:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:20:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:20:32 INFO - ENV: RUST_BACKTRACE is now full 23:20:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:20:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 23:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 23:20:32 INFO - Using env: {'DISPLAY': ':0', 23:20:32 INFO - 'HOME': '/home/cltbld', 23:20:32 INFO - 'LANG': 'en_US.UTF-8', 23:20:32 INFO - 'LANGUAGE': 'en_US:en', 23:20:32 INFO - 'LOGNAME': 'cltbld', 23:20:32 INFO - 'MAIL': '/var/mail/cltbld', 23:20:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:20:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:20:32 INFO - 'MOZ_ACCELERATED': '1', 23:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:32 INFO - 'MOZ_NO_REMOTE': '1', 23:20:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:20:32 INFO - 'MOZ_WEBRENDER': '1', 23:20:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:32 INFO - 'NO_EM_RESTART': '1', 23:20:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:32 INFO - 'PWD': '/builds/slave/test', 23:20:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:20:32 INFO - 'RUST_BACKTRACE': 'full', 23:20:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 23:20:32 INFO - 'SHELL': '/bin/bash', 23:20:32 INFO - 'SHLVL': '1', 23:20:32 INFO - 'TERM': 'linux', 23:20:32 INFO - 'TMOUT': '86400', 23:20:32 INFO - 'USER': 'cltbld', 23:20:32 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:32 INFO - '_': '/tools/buildbot/bin/python'} 23:20:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 23:20:32 INFO - mozversion application_buildid: 20170914052917 23:20:32 INFO - mozversion application_changeset: cb717386aec8601a301babd4156aabc4cccff0b0 23:20:32 INFO - mozversion application_display_name: Nightly 23:20:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:20:32 INFO - mozversion application_name: Firefox 23:20:32 INFO - mozversion application_remotingname: firefox 23:20:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:20:32 INFO - mozversion application_vendor: Mozilla 23:20:32 INFO - mozversion application_version: 57.0a1 23:20:32 INFO - mozversion platform_buildid: 20170914052917 23:20:32 INFO - mozversion platform_changeset: cb717386aec8601a301babd4156aabc4cccff0b0 23:20:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:20:32 INFO - mozversion platform_version: 57.0a1 23:20:32 INFO - using testdate: 1505370032 23:20:32 INFO - actual date: 1505370032 23:20:32 INFO - starting webserver on 'localhost:37638' 23:20:32 INFO - SUITE-START | Running 2 tests 23:20:32 INFO - TEST-START | dromaeo_css 23:20:32 INFO - Initialising browser for dromaeo_css test... 23:20:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37638/getInfo.html -profile /tmp/tmpKMwpXs/profile 23:20:32 INFO - TEST-INFO | started process 11732 (/builds/slave/test/build/application/firefox/firefox http://localhost:37638/getInfo.html) 23:20:40 INFO - TEST-INFO | 11732: exit 0 23:20:40 INFO - Browser initialized. 23:20:40 INFO - Running cycle 1/1 for dromaeo_css test... 23:20:40 INFO - Using env: {'DISPLAY': ':0', 23:20:40 INFO - 'HOME': '/home/cltbld', 23:20:40 INFO - 'JSGC_DISABLE_POISONING': '1', 23:20:40 INFO - 'LANG': 'en_US.UTF-8', 23:20:40 INFO - 'LANGUAGE': 'en_US:en', 23:20:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 23:20:40 INFO - 'LOGNAME': 'cltbld', 23:20:40 INFO - 'MAIL': '/var/mail/cltbld', 23:20:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:20:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:20:40 INFO - 'MOZ_ACCELERATED': '1', 23:20:40 INFO - 'MOZ_CRASHREPORTER': '1', 23:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:20:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 23:20:40 INFO - 'MOZ_NO_REMOTE': '1', 23:20:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:20:40 INFO - 'MOZ_WEBRENDER': '1', 23:20:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:20:40 INFO - 'NO_EM_RESTART': '1', 23:20:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:20:40 INFO - 'PWD': '/builds/slave/test', 23:20:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:20:40 INFO - 'RUST_BACKTRACE': 'full', 23:20:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 23:20:40 INFO - 'SHELL': '/bin/bash', 23:20:40 INFO - 'SHLVL': '1', 23:20:40 INFO - 'TERM': 'linux', 23:20:40 INFO - 'TMOUT': '86400', 23:20:40 INFO - 'USER': 'cltbld', 23:20:40 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:20:40 INFO - '_': '/tools/buildbot/bin/python'} 23:20:40 INFO - TEST-INFO | started process 11978 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKMwpXs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 23:20:41 INFO - PID 11978 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 23:20:41 INFO - PID 11978 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 23:20:41 INFO - PID 11978 | 23:20:41 INFO - PID 11978 | (/builds/slave/test/build/application/firefox/firefox:12038): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 23:20:41 INFO - PID 11978 | 23:20:52 INFO - PID 11978 | RSS: Main: 216281088 23:20:52 INFO - PID 11978 | 23:23:49 INFO - PID 11978 | Cycle 1(1): loaded http://localhost:37638/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37638/tests/dromaeo/cssquery-ext.html) 23:23:51 INFO - PID 11978 | RSS: Main: 226983936 23:23:51 INFO - PID 11978 | 23:26:53 INFO - PID 11978 | Cycle 1(1): loaded http://localhost:37638/tests/dromaeo/cssquery-ext.html (next: http://localhost:37638/tests/dromaeo/cssquery-jquery.html) 23:26:54 INFO - PID 11978 | RSS: Main: 225738752 23:26:54 INFO - PID 11978 | 23:29:56 INFO - PID 11978 | Cycle 1(1): loaded http://localhost:37638/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37638/tests/dromaeo/cssquery-mootools.html) 23:29:57 INFO - PID 11978 | RSS: Main: 228655104 23:29:57 INFO - PID 11978 | 23:32:59 INFO - PID 11978 | Cycle 1(1): loaded http://localhost:37638/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37638/tests/dromaeo/cssquery-prototype.html) 23:32:59 INFO - PID 11978 | RSS: Main: 228216832 23:32:59 INFO - PID 11978 | 23:36:03 INFO - PID 11978 | Cycle 1(1): loaded http://localhost:37638/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37638/tests/dromaeo/cssquery-yui.html) 23:36:03 INFO - PID 11978 | RSS: Main: 231477248 23:36:03 INFO - PID 11978 | 23:39:06 INFO - PID 11978 | Cycle 1(1): loaded http://localhost:37638/tests/dromaeo/cssquery-yui.html (next: http://localhost:37638/tests/dromaeo/cssquery-dojo.html) 23:39:06 INFO - PID 11978 | RSS: Main: 232333312 23:39:06 INFO - PID 11978 | 23:39:06 INFO - PID 11978 | __start_tp_report 23:39:06 INFO - PID 11978 | _x_x_mozilla_page_load 23:39:06 INFO - PID 11978 | _x_x_mozilla_page_load_details 23:39:06 INFO - PID 11978 | |i|pagename|runs| 23:39:06 INFO - PID 11978 | |0;dojo.html;3090.737287330577;3162.20944763809;3168.0495851244636;3173.9365212695734;3177.95233071504;6222.346653601375;6250.8124756257275;6260.84347891303;6290.528210384222;6346;6282.120503129566;6303.054541818729;6305.085762564437;6342.6511541865175;6365.52258580606;5762.3373312069225;5801.709914504279;5808.041673123929;5809.448102430262;5847.619904706202;5885.941140588604;5908.645481271137;5964.463198312151;5983.401659834003;6001.159837622737;5881.588288819784;5897.705114744267;5943.613665111776;5947.791827286046;5969.253843269591;5649.322081350223;5716.799912003102;5762.135679648044;5944.4352786485215;5968.045112781978;5787.13193021046;5818.272715910512;5874.882502349929;5889.116632505115;5899.439553242435;6302.054691796221;6344.968275158641;6345.87308253837;6375.4262116409745;6415.550911436201;6669.23303820062;6699.062131301622;6756.054152418666;6759.898601520981;6766.424853887425;6799;6823.761168359084;6842.589444633338;6843.520953533254;6912.308769123048;6389.648569328685;6404.871902561969;6432.485401167942;6434.324395938406;6437.066625339393;6945.687444065029;6991.615461149686;7009.824754381079;7017;7024.192217895007;6560.343965603497;6697.966510167418;6712.53012289135;6807.285235050347;6816.727330906808;6758.189017317855;6789.762358317534;6832.897506537406;6842.965785171043;6849.04113424121;6792.728290868409;6797.694103765342;6835.14560679915;6861.416779573692;6877.690503927335;6840.41856442208;6852.965735171292;6918.446524278046;6922.342377474132;6926.896096558556;5169.4572069932865;5281.762320695501;5311.893762124813;5368.355797304349;5439.755211015514;4662.230731929194;4707.693999889996;4711.52884711533;4727.479977202505;4733.077049975291;5782.855428614234;5840.766369345179;5842.59101862874;5845.883082338414;5847.883042339129;6848;6848.965755171292;6905.205901321312;6906.102206713095;6960.095187625576;5800.129980502874;5811.912821307684;5861.32594751609;5879.7060146993335;5895.793647222326;5692.544596432275;5752.36723960364;5803.187553742479;5896.233489646319;5915.053591425351;6583.572067815577;6588.538802283794;6589.571677840925;6618.669066546653;6648.900266496006;6798.694058767268;6870.690818913061;6928.688209030605;6961.6519174042105;6968.65156742171;6391.552591318749;6573.769918052845;6582.703778329891;6673.265940746611;6693.732250710112;6652.800415987528;6666.899996499959;6669.7665581704405;6674.365935236145;6689.866202675822;6712.630805305755;6729.394354508117;6751.257361690308;6787.321267873173;6788.355106264897;6607.372299631527;6676.632785196666;6693.464522838259;6697;6706.631135287606;6616.735330586916;6625.138731964814;6702.430293425114;6702.832429189114;6712.127423434922;6394;6517.674116294261;6534.640594767334;6638.17022872141;6642.4686025118845;6577.276499585138;6635.966820165868;6673.199216093905;6736.225334086643;6737.528373013842;6490.350964903472;6495.642739649175;6509.446697030806;6516.315786842313;6552.6723663817575 23:39:06 INFO - PID 11978 | |1;ext.html;868.4441957147425;878.8462019146648;881.0924747510066;884.2969838978014;888.6756333938115;38902.221955560875;42482.36276455848;42668.14663706724;42765.572344276545;42805.78597107018;39286;39392.80303598485;39774.20451590967;39820.402693959615;39841.80079099601;7072.752453664123;7173.497855150142;7234.746783862566;7245.673944672502;7251.3111254431005;7061.152661680606;7754.573498457583;7939.603019849013;7939.722109726161;7958.761237162895;7905.407094467909;7935.365170786352;7955.284024437799;7974.760757177294;7990.240927111915;7765.262300081483;7880.960595197016;7959.880601790977;7986.440949133563;8026.558539280337;7684.385249180053;7703.306702396755;7724.845503089907;7734.922650773477;7769.805754856117;38862.80568597139;39145.21709565821;39156;39280.60719392826;39371;3425.2122011937145;3432.2963792422493;3433.5879694436576;3433.8454769535433;3434.295969326282;32146;32220.194495137574;32246.516302255484;32338.6766132338;32426.351472970407;5198.792048318101;5212.139996900507;5214.582833373321;5233.241180028875;5325.840224793263;185127;195285;196493;197662;197718.01140994346;42318.1536369277;48414;48635;49050;49052.50947490479;45965;50320.496795031584;53372.46627533752;53742.46257537453;53796;16153.676926461405;16279.593010174603;16316;16331.42840000618;16423.589410264838;13176.407061682246;13325.466981320833;13332.666683332995;13365.198088114745;13370.532031378854;14574.34415451286;14639.853601464061;14665.633359166106;14665.946767452566;14742;5704.600677952587;5737.3688894221605;5779.393163717833;5826.737796799154;5937.70311484424;1006.3760468509571;1008.4050410257983;1026.958921643141;1027.1474676018886;1033.7622346860114;23098.191563295204;23344.7665523346;23595.174168904006;23673.28980130599;23771.7622823773;1099.14816017587;1111.5164903267105;1127.1264769803304;1129.7006293332274;1138.1065863297272;12328.876711232951;12423.068269879795;12466.626001219978;12496.25022498653;12576.993840492738;220150;221809;222462;222601;223728;53499.73250133725;53641.46358536443;53683;53909.730451347496;53911;1443.2422977936667;1446.1323206076481;1476.269246722879;1493.4847477620203;1497.0344128037354;23144.4213894654;23261.88369058144;23301.53396932086;23348.64977025312;23391.298261052194;37354;37415;37838.62161378351;37987;38046.66836660704;202337;202794;203193;203602;203847;32385;32615.67384326221;33698.66301336955;35093;35436.82281588575;43993.340099899164;44016.11967760563;44122.338164928195;44477.555224447344;44859.327110094026;32357.35285294328;32530;32865.67134328626;32914.34171316608;33150.17124571905;31308.373832522768;31589.52615710812;31743;31812.84093579517;31830.363392732477;35808.820955895055;36084.639153609176;36120.27759444849;36235.27529449343;36321.27357452889;31066.223344416572;31071.39467624343;31124.066278011695;31555.36889262156;31570.684293156773;23386.883065584563;23670.763292366857;23817.880910595337;23907;23918.162864299666 23:39:06 INFO - PID 11978 | |2;jquery.html;558.290970467506;560.4647561578693;564.6414526775496;565.4571611253197;582.965022098674;21918.342449726515;22468.662970055462;22494.88752556239;22777;22784.658230126563;20972.37082887512;21347.466313342175;21575.46061348467;21580.784192158073;21596.460088497755;6424.3575642435735;6588.341165883421;6646;6651.93348066518;6674.032265321525;6611.3719196676475;6704.564203326792;6710.899336509956;6716.328367163269;6718.260991290978;6342.778002769904;6602.834929126786;6614.404703576677;6674.365935236145;6676.165479315086;6523.804285871408;6561.704723287464;6563.638999855006;6588;6591.967040164816;5832.1834943108;6069.635821850703;6076.848078798022;6079.452849243589;6099.329073801879;28675.56986645202;29115;29182.41635167306;29245.85377073101;29433.264168395748;2030.9289174878809;2031.7663468700994;2043.0806137238303;2052.7639321478073;2056.845736569763;18128.365507207316;18768.62462750751;19022.144003519876;19027;19057;2298.230092918874;2316.4903721181313;2385.9045638174453;2392.940176495584;2394.4013996500876;120956;126061;127397;129110;132456.33771831176;14537.764290035255;14553.648812619213;14692.045017073857;14727.410903563952;14816.259187040605;22619.773802261767;22741.545169096524;22757.772422275564;22774;22788.08847646109;9923.255755818342;10107.494625268708;10140.391576505433;10218.284720069523;10223.182145428347;9342.719718408458;9404.153626173676;9522.904865940367;9528.428294302365;9540.093691099335;9050.637974480947;9114.954425227832;9134.90865091354;9157.908420915839;9242.168204861595;2642.7621514063826;2682;2697.9190624281305;2780.666320041608;2847.38781162047;935.4527601353149;953.4755884263627;955.7992821507561;968.7045451137329;972.0959507657946;19191;19332.903335483516;19378.903105484384;19521.414357569294;19690.60618787616;1012.6860673191334;1013.5894962540183;1015.5988384588046;1020.6325722739808;1020.6886899495577;10816.513256903458;10862.293950893167;10863.348199108079;10922.890771092347;11022.50398732059;469739;482334;494153;504454;505523;105381;106601.46699266454;106645.46677266563;108111.45944270228;109877;1008.3798463944621;1062.7821296634172;1065.2064212161988;1125.5722825326488;1137.4426530999915;20338.694919575923;20454.284100056426;20765.896170519052;20819.68770468414;20875.791242087384;52570;52885;54386;55512.167317491076;56034;66414.66792666005;66566;66776.66611667105;67079.66460167668;67935;14265.072770269899;14398.712025759216;14634;14687.92656036713;14710.852891470948;22807.885960570093;22969.77030229676;23091;23348.416289592897;23694;17852.10739462996;18000.459986200436;18226.72659910129;18402.81597184011;18810.717839232148;16206.351745930502;16551.83448165503;17190.570235744206;17214.827851721322;17362.91318543399;18703.532411689815;18762.343317983807;18870.24519019224;18892;19008.71486927725;14233.074850134708;14319.57041288763;14333.78499322532;14492.637684057983;14503.1298122113;17928.19323130436;18215.45353639393;18364.540886477946;18376.632467350846;18602.627947441248 23:39:06 INFO - PID 11978 | |3;mootools.html;1303.2050449225972;1355.0717758335534;1363.9386227619761;1367.1045465220284;1408.2184387664843;14747.483838065657;15372.84627153728;15418.69162616747;15439.228038598067;15452.227388630567;15040.398384064625;15072.171030593314;15190.392384304641;15202.695946081072;15205.54383368498;2568.833025853323;2626.697929738076;2644.5107655083775;2644.9471010579778;2670.7195744446844;2693.676758788948;2723.7412445817617;2745.094118940755;2748.0244513197827;2748.573971034493;2593.377589378545;2672.973270267292;2678.08944958714;2678.9062382816605;2696.12375977807;2701.972980270202;2714.0772137473255;2719.510488112149;2722.1425251045857;2731.808773385864;2585.50875333686;2605.4268061026546;2608.1523504860997;2614.843109413422;2630.5528060229617;15008.099514029193;15147.469838555598;15147.924260378628;15179.089254644647;15188.848111518853;7368.668409921566;7645.044369453818;7663.808404789869;7672.539647621161;7683.423743219281;4978.4025916890205;5102.668326558762;5178.326817513699;5187.04039752647;5195.974020129913;1892.4890279624562;1901.2680118154472;1932.062949469506;1955.1592815089505;1976.071246514136;26191.607125892937;26294.86852565744;26518;26602.46795064088;26669.73330266692;6565;6601.405873471411;6602.14172157626;6743.629100399427;6798.184217893884;17172.484825455256;17236.482905512854;17257.56856078608;17269.39557115495;17362.47912562625;7697.807554811063;7831.960840195877;7853.8821917671285;7857.567833769198;7864.685412583547;6790.626515541692;6793.422559082534;6996.195437524648;7124.536905101256;7245.529040612343;5113.8721531962;5187.455317191716;5342.8664283392445;5394.811181608625;5422.15956526739;1506.947256846005;1557.9610509737347;1565.2643257668879;1569.1683407793887;1582.1614544291544;901.8602116671926;916.6150216908915;925.8888933328044;926.935114541989;932.5011119051275;8290.253877151084;8394;8483.872741908875;8484.151584841466;8515.318774498026;973.4937832327291;995.4475266227313;996.3025881882711;997.7156510394502;1010.9595616175418;5281.920771188358;5362.27609272751;5373.543248823816;5423.132298832168;5524;21177.258795942067;21333.67999480009;21427.78572214258;21627.2430464933;22055;20614.484637883874;21093.156273749184;21329.146834126772;21430.142794288367;21565.245216417665;9924.404535727735;10206.132478739242;10319.535620896928;10347.482625868677;10347.534360953776;7957.363410927228;8138.918610814053;8164.877526837221;8247.711330103417;8358.707445239388;18281.45155645333;18323.35868244605;18483.630327393646;18647.533811654273;18933.148008339384;18850.34023809215;18955.62088758245;19071.618567628848;19234.326798561982;19237.13432895551;10367.27429079957;10726.785464290515;10763.569457221623;10775.94612026935;10792.406417647106;13317.933410332886;13685.178889266675;13746;13753.724925501634;13766;10090.243231757795;10417.47912604382;10504.369737815756;10526.947365263124;10697.090747286567;9658.565364558752;9807.06832850878;9870.901290986998;9960.900390996287;10020.198384129106;8679.91320086791;8894.777630559287;8950.82098358016;8961.596728147118;8979.371443998858;6938.340857618518;7041.049458323061;7066.399356054793;7069.257727938492;7170.67731952053;6584.934150658432;6625.238097618835;6629.668516574248;6642.900356494753;6711.530192886451 23:39:06 INFO - PID 11978 | |4;prototype.html;478.91858384074703;495.32882943611406;510.28560015977627;515.6957395136868;523.3614989712552;6181.227346581677;6209.037599172136;6250.156228909096;6271.466925311354;6291.370862913712;6034.577579569432;6117.602355846867;6125.356837532062;6158.969205153968;6192.349803270659;5030.371203599541;5110.974445127769;5138.023775482665;5156.407013193474;5185.766640501187;5139.614528910328;5183.429822719517;5194.246834209038;5212.973935130319;5243.79024839006;4758.809647614108;4929.33453983713;4930.285108659242;5062.417821950467;5091.516305950929;4914.385701787277;4924.039812236615;4951.826686066006;4952.0838644850455;4972.975135124338;4400.889977750549;4411.933820992687;4426.273753236241;4436.7116137451285;4441.178381999308;5833.358330583633;6028.879422411527;6064.3935606439445;6075.422834830684;6115.327313995457;5597.804076857331;5653.971730141323;5667.4049224831215;5689.743961521742;5715.857103572403;6489.383508566679;6517.305500569839;6559.934400656028;6608.834779130512;6645.501587380966;4636.605888499482;4818.9157439576165;4859.562639362474;4863.367762190893;4876.634252431082;6673.833154171136;6745.797626071226;6749.12261406019;6787.287334829871;6798.912174052178;4934.9753251233515;4969.6394212748655;4976.104301225814;4993.875153121201;5001.199808030698;5009.273655319958;5031.138478762808;5033.119204139334;5035.84892453227;5056.494350564914;5491.945080549143;5549.116777236007;5635.378989228793;5649;5692;6587.670616469158;6636.203655561363;6637.084120369005;6651;6666.600003999775;1740.25169177255;1745.502531778437;1748.038578781665;1766.0904634113442;1768.4606195110614;1130.2483848240827;1180.5808937826948;1192.648168790209;1214.8299238106672;1219.8170274458903;5621.0444224481935;5633.239512665819;5683.630564013326;5687.544996400278;5704;6658.533902626769;6699.832504187434;6710.396064354231;6710.798676039727;6761.619190404797;5510.614257001971;5533.391326954032;5598.636088654224;5645.463680950304;5646.522872813364;3479.304139172176;3536.6109727929907;3537.6486921086694;3563.6792688658147;3572.4819901114192;6403.359664033559;6420.5184611154355;6478.319776423407;6478.384553467507;6513.609183449008;6706.329367063255;6726.966365168143;6753.662316884136;6755.459563234929;6778.593284402853;6317.515383884784;6358.682065896779;6359.713812878339;6373.776917807947;6398.616083035033;6130.724117414817;6496.545241833026;6533.150690410216;6589.24223714279;6653.534252602271;6351;6442.774502892376;6447.6453795041725;6481.865673507212;6522.543421960418;6369.1401660777055;6380.968095159495;6393.6483493408305;6395.42441180296;6501.479881609365;4783.7129772213775;4937.703737775601;4943.35736354286;4951.72765497901;4957.231629097496;6324.430801227728;6391.58454700443;6577.80266592003;6650.268470468148;6676.699548520425;6309.810705678837;6371.267304259884;6436.8390790230615;6461.224653041665;6491.837704057248;6455.837294506206;6476.902846457401;6490.967545162244;6524.608523488606;6575.4410875076155;6387.616742995435;6390.329015453496;6397.936020639734;6442.967785161044;6446.613203207822;6480.416762491398;6500.187476565429;6505.902411463927;6592.17597800275;6593.670316484253;6297.118403423433;6297.338779428277;6314.936850631435;6367.681615919279;6401.87196256063 23:39:06 INFO - PID 11978 | |5;yui.html;901.6663834381278;906.6418764587984;911.1571796088616;911.4212475078323;921.8156368726256;18130;18179.727304090415;18598.535036624093;19432.708509372354;19449.99999999998;16537.173141342944;16665.25006374713;16702.164891755423;17084.658306833855;18332.908335458338;955.8088382323529;960.2366118935446;966.3960024984385;967.8306296398138;970.7961328121103;978.3005151316808;981.3817295104075;983.5377372634886;983.5819776594955;984.5963155106408;997.7505623594102;1024.5748014573942;1027.5170669785227;1035.5340096956363;1036.6475398364555;1019.4902548725637;1023.0332335942537;1025.8615086963277;1032.4218437674926;1033.3128469567728;746.4289818289013;749.0636704119851;751.2449987762319;751.8759404698218;752.0974830203777;18989.620207595908;19560.51098722529;19630.607387852164;19655.705164422547;19694.80305196944;20607.175712971464;20651.4837129073;20736.688949665768;20768;20819.89590052055;17273.39543115985;17511.73732394015;17649.7352539712;17681.11594420274;17762.733558996624;3742.8689995850013;3753.3056384568667;3757.755745876509;3761.962380376216;3763.9623603763885;140703;141367.29316353454;143570.28214858964;143638;144407.27796361057;15477.767833482507;15529.611759705871;15752.684946301239;15809.525714228592;15877.047377157407;27832.582511262346;28079.298017549725;28327.433451330857;28589.285267868054;28687;8367.790805229917;8368.37237207212;8392.20274073962;8431.620577074047;8476.110008449024;4223.640990515779;4259.467566554181;4291.721038132511;4301.720388174758;4306.956930430718;5421.83734487966;5598.384177740445;5603.80386686464;5605.047141985872;5664.68844213564;857.7469646454311;878;899.0874262623443;899.7570655922864;900.3292547052357;667.4426853577318;677.678102901118;700.1353328639127;703.7677566403074;706.8762966480948;11505;11702.180847340775;11742.70643233909;11833.171677982458;12067.577634782916;670.1522573943964;672.314239475732;683.0710234081645;689.327905292346;690.526989012528;5224.869378265497;5275.709835959059;5281.709505977208;5325.547328477045;5379.300690910157;120884;121648;122220;122758;126104;86886.56556717175;94588;96397.51801240949;109899;113639.43180284045;92373.61439578411;94019.52990235006;94787.52606236924;96573;100388;11418.600348987746;11557.88442115568;11789.351585662871;11855.407229638657;11861;42414.36378454387;44483.777581111885;44647;44919.77540112279;45767;55472.167917482075;56385.71807140938;56464.15303770529;56618.716906415204;58427.70786146042;18248.817511825244;18557.164927578022;18639.813601863807;18691.626167476847;18830.435086947415;21125.577488450454;21305.14779408806;21335.146594136062;21343.573128537653;21684;19315.710264346326;19470.513237169183;19487.805121949164;19589.80410195937;19668.311609093613;16966.745498817774;17128.057956812496;17153.399631012853;17228.397006104726;17377.391791287246;17636.911815440842;18002.279908803503;18008.189631466354;18108.094595270446;18116.909415453367;11874.703132421759;11990.760184796429;12228.266304021787;12316.630501084981;12360.567380141652;12532.812007820072;12832.358382080673;12943.649204593949;13034.152780069266;13084.214947103204 23:39:06 INFO - PID 11978 | __end_tp_report 23:39:06 INFO - PID 11978 | __start_cc_report 23:39:06 INFO - PID 11978 | _x_x_mozilla_cycle_collect,376 23:39:06 INFO - PID 11978 | __end_cc_report 23:39:06 INFO - PID 11978 | __startTimestamp1505371146249__endTimestamp 23:39:06 INFO - PID 11978 | 23:39:06 INFO - PID 11978 | ------- Summary: start ------- 23:39:06 INFO - PID 11978 | Number of tests: 6 23:39:06 INFO - PID 11978 | 23:39:06 INFO - PID 11978 | [#0] dojo.html Cycles:165 Average:6263.86 Median:6534.64 stddev:758.69 (11.6%) stddev-sans-first:719.02 23:39:06 INFO - PID 11978 | Values: 3090.7 3162.2 3168.0 3173.9 3178.0 6222.3 6250.8 6260.8 6290.5 6346.0 6282.1 6303.1 6305.1 6342.7 6365.5 5762.3 5801.7 5808.0 5809.4 5847.6 5885.9 5908.6 5964.5 5983.4 6001.2 5881.6 5897.7 5943.6 5947.8 5969.3 5649.3 5716.8 5762.1 5944.4 5968.0 5787.1 5818.3 5874.9 5889.1 5899.4 6302.1 6345.0 6345.9 6375.4 6415.6 6669.2 6699.1 6756.1 6759.9 6766.4 6799.0 6823.8 6842.6 6843.5 6912.3 6389.6 6404.9 6432.5 6434.3 6437.1 6945.7 6991.6 7009.8 7017.0 7024.2 6560.3 6698.0 6712.5 6807.3 6816.7 6758.2 6789.8 6832.9 6843.0 6849.0 6792.7 6797.7 6835.1 6861.4 6877.7 6840.4 6853.0 6918.4 6922.3 6926.9 5169.5 5281.8 5311.9 5368.4 5439.8 4662.2 4707.7 4711.5 4727.5 4733.1 5782.9 5840.8 5842.6 5845.9 5847.9 6848.0 6849.0 6905.2 6906.1 6960.1 5800.1 5811.9 5861.3 5879.7 5895.8 5692.5 5752.4 5803.2 5896.2 5915.1 6583.6 6588.5 6589.6 6618.7 6648.9 6798.7 6870.7 6928.7 6961.7 6968.7 6391.6 6573.8 6582.7 6673.3 6693.7 6652.8 6666.9 6669.8 6674.4 6689.9 6712.6 6729.4 6751.3 6787.3 6788.4 6607.4 6676.6 6693.5 6697.0 6706.6 6616.7 6625.1 6702.4 6702.8 6712.1 6394.0 6517.7 6534.6 6638.2 6642.5 6577.3 6636.0 6673.2 6736.2 6737.5 6490.4 6495.6 6509.4 6516.3 6552.7 23:39:06 INFO - PID 11978 | 23:39:06 INFO - PID 11978 | [#1] ext.html Cycles:180 Average:37717.56 Median:3433.72 stddev:53558.73 (1559.8%) stddev-sans-first:53637.50 23:39:06 INFO - PID 11978 | Values: 868.4 878.8 881.1 884.3 888.7 38902.2 42482.4 42668.1 42765.6 42805.8 39286.0 39392.8 39774.2 39820.4 39841.8 7072.8 7173.5 7234.7 7245.7 7251.3 7061.2 7754.6 7939.6 7939.7 7958.8 7905.4 7935.4 7955.3 7974.8 7990.2 7765.3 7881.0 7959.9 7986.4 8026.6 7684.4 7703.3 7724.8 7734.9 7769.8 38862.8 39145.2 39156.0 39280.6 39371.0 3425.2 3432.3 3433.6 3433.8 3434.3 32146.0 32220.2 32246.5 32338.7 32426.4 5198.8 5212.1 5214.6 5233.2 5325.8 185127.0 195285.0 196493.0 197662.0 197718.0 42318.2 48414.0 48635.0 49050.0 49052.5 45965.0 50320.5 53372.5 53742.5 53796.0 16153.7 16279.6 16316.0 16331.4 16423.6 13176.4 13325.5 13332.7 13365.2 13370.5 14574.3 14639.9 14665.6 14665.9 14742.0 5704.6 5737.4 5779.4 5826.7 5937.7 1006.4 1008.4 1027.0 1027.1 1033.8 23098.2 23344.8 23595.2 23673.3 23771.8 1099.1 1111.5 1127.1 1129.7 1138.1 12328.9 12423.1 12466.6 12496.3 12577.0 220150.0 221809.0 222462.0 222601.0 223728.0 53499.7 53641.5 53683.0 53909.7 53911.0 1443.2 1446.1 1476.3 1493.5 1497.0 23144.4 23261.9 23301.5 23348.6 23391.3 37354.0 37415.0 37838.6 37987.0 38046.7 202337.0 202794.0 203193.0 203602.0 203847.0 32385.0 32615.7 33698.7 35093.0 35436.8 43993.3 44016.1 44122.3 44477.6 44859.3 32357.4 32530.0 32865.7 32914.3 33150.2 31308.4 31589.5 31743.0 31812.8 31830.4 35808.8 36084.6 36120.3 36235.3 36321.3 31066.2 31071.4 31124.1 31555.4 31570.7 23386.9 23670.8 23817.9 23907.0 23918.2 23:39:06 INFO - PID 11978 | 23:39:06 INFO - PID 11978 | [#2] jquery.html Cycles:180 Average:33987.65 Median:22481.78 stddev:82159.43 (365.4%) stddev-sans-first:82351.57 23:39:06 INFO - PID 11978 | Values: 558.3 560.5 564.6 565.5 583.0 21918.3 22468.7 22494.9 22777.0 22784.7 20972.4 21347.5 21575.5 21580.8 21596.5 6424.4 6588.3 6646.0 6651.9 6674.0 6611.4 6704.6 6710.9 6716.3 6718.3 6342.8 6602.8 6614.4 6674.4 6676.2 6523.8 6561.7 6563.6 6588.0 6592.0 5832.2 6069.6 6076.8 6079.5 6099.3 28675.6 29115.0 29182.4 29245.9 29433.3 2030.9 2031.8 2043.1 2052.8 2056.8 18128.4 18768.6 19022.1 19027.0 19057.0 2298.2 2316.5 2385.9 2392.9 2394.4 120956.0 126061.0 127397.0 129110.0 132456.3 14537.8 14553.6 14692.0 14727.4 14816.3 22619.8 22741.5 22757.8 22774.0 22788.1 9923.3 10107.5 10140.4 10218.3 10223.2 9342.7 9404.2 9522.9 9528.4 9540.1 9050.6 9115.0 9134.9 9157.9 9242.2 2642.8 2682.0 2697.9 2780.7 2847.4 935.5 953.5 955.8 968.7 972.1 19191.0 19332.9 19378.9 19521.4 19690.6 1012.7 1013.6 1015.6 1020.6 1020.7 10816.5 10862.3 10863.3 10922.9 11022.5 469739.0 482334.0 494153.0 504454.0 505523.0 105381.0 106601.5 106645.5 108111.5 109877.0 1008.4 1062.8 1065.2 1125.6 1137.4 20338.7 20454.3 20765.9 20819.7 20875.8 52570.0 52885.0 54386.0 55512.2 56034.0 66414.7 66566.0 66776.7 67079.7 67935.0 14265.1 14398.7 14634.0 14687.9 14710.9 22807.9 22969.8 23091.0 23348.4 23694.0 17852.1 18000.5 18226.7 18402.8 18810.7 16206.4 16551.8 17190.6 17214.8 17362.9 18703.5 18762.3 18870.2 18892.0 19008.7 14233.1 14319.6 14333.8 14492.6 14503.1 17928.2 18215.5 18364.5 18376.6 18602.6 23:39:06 INFO - PID 11978 | 23:39:06 INFO - PID 11978 | [#3] mootools.html Cycles:180 Average:9130.47 Median:14670.23 stddev:6629.05 (45.2%) stddev-sans-first:6621.56 23:39:06 INFO - PID 11978 | Values: 1303.2 1355.1 1363.9 1367.1 1408.2 14747.5 15372.8 15418.7 15439.2 15452.2 15040.4 15072.2 15190.4 15202.7 15205.5 2568.8 2626.7 2644.5 2644.9 2670.7 2693.7 2723.7 2745.1 2748.0 2748.6 2593.4 2673.0 2678.1 2678.9 2696.1 2702.0 2714.1 2719.5 2722.1 2731.8 2585.5 2605.4 2608.2 2614.8 2630.6 15008.1 15147.5 15147.9 15179.1 15188.8 7368.7 7645.0 7663.8 7672.5 7683.4 4978.4 5102.7 5178.3 5187.0 5196.0 1892.5 1901.3 1932.1 1955.2 1976.1 26191.6 26294.9 26518.0 26602.5 26669.7 6565.0 6601.4 6602.1 6743.6 6798.2 17172.5 17236.5 17257.6 17269.4 17362.5 7697.8 7832.0 7853.9 7857.6 7864.7 6790.6 6793.4 6996.2 7124.5 7245.5 5113.9 5187.5 5342.9 5394.8 5422.2 1506.9 1558.0 1565.3 1569.2 1582.2 901.9 916.6 925.9 926.9 932.5 8290.3 8394.0 8483.9 8484.2 8515.3 973.5 995.4 996.3 997.7 1011.0 5281.9 5362.3 5373.5 5423.1 5524.0 21177.3 21333.7 21427.8 21627.2 22055.0 20614.5 21093.2 21329.1 21430.1 21565.2 9924.4 10206.1 10319.5 10347.5 10347.5 7957.4 8138.9 8164.9 8247.7 8358.7 18281.5 18323.4 18483.6 18647.5 18933.1 18850.3 18955.6 19071.6 19234.3 19237.1 10367.3 10726.8 10763.6 10775.9 10792.4 13317.9 13685.2 13746.0 13753.7 13766.0 10090.2 10417.5 10504.4 10526.9 10697.1 9658.6 9807.1 9870.9 9960.9 10020.2 8679.9 8894.8 8950.8 8961.6 8979.4 6938.3 7041.0 7066.4 7069.3 7170.7 6584.9 6625.2 6629.7 6642.9 6711.5 23:39:06 INFO - PID 11978 | 23:39:06 INFO - PID 11978 | [#4] prototype.html Cycles:180 Average:5459.93 Median:6116.46 stddev:1522.23 (24.9%) stddev-sans-first:1479.88 23:39:06 INFO - PID 11978 | Values: 478.9 495.3 510.3 515.7 523.4 6181.2 6209.0 6250.2 6271.5 6291.4 6034.6 6117.6 6125.4 6159.0 6192.3 5030.4 5111.0 5138.0 5156.4 5185.8 5139.6 5183.4 5194.2 5213.0 5243.8 4758.8 4929.3 4930.3 5062.4 5091.5 4914.4 4924.0 4951.8 4952.1 4973.0 4400.9 4411.9 4426.3 4436.7 4441.2 5833.4 6028.9 6064.4 6075.4 6115.3 5597.8 5654.0 5667.4 5689.7 5715.9 6489.4 6517.3 6559.9 6608.8 6645.5 4636.6 4818.9 4859.6 4863.4 4876.6 6673.8 6745.8 6749.1 6787.3 6798.9 4935.0 4969.6 4976.1 4993.9 5001.2 5009.3 5031.1 5033.1 5035.8 5056.5 5491.9 5549.1 5635.4 5649.0 5692.0 6587.7 6636.2 6637.1 6651.0 6666.6 1740.3 1745.5 1748.0 1766.1 1768.5 1130.2 1180.6 1192.6 1214.8 1219.8 5621.0 5633.2 5683.6 5687.5 5704.0 6658.5 6699.8 6710.4 6710.8 6761.6 5510.6 5533.4 5598.6 5645.5 5646.5 3479.3 3536.6 3537.6 3563.7 3572.5 6403.4 6420.5 6478.3 6478.4 6513.6 6706.3 6727.0 6753.7 6755.5 6778.6 6317.5 6358.7 6359.7 6373.8 6398.6 6130.7 6496.5 6533.2 6589.2 6653.5 6351.0 6442.8 6447.6 6481.9 6522.5 6369.1 6381.0 6393.6 6395.4 6501.5 4783.7 4937.7 4943.4 4951.7 4957.2 6324.4 6391.6 6577.8 6650.3 6676.7 6309.8 6371.3 6436.8 6461.2 6491.8 6455.8 6476.9 6491.0 6524.6 6575.4 6387.6 6390.3 6397.9 6443.0 6446.6 6480.4 6500.2 6505.9 6592.2 6593.7 6297.1 6297.3 6314.9 6367.7 6401.9 23:39:06 INFO - PID 11978 | 23:39:06 INFO - PID 11978 | [#5] yui.html Cycles:180 Average:24401.34 Median:20793.95 stddev:35006.64 (168.4%) stddev-sans-first:35060.37 23:39:06 INFO - PID 11978 | Values: 901.7 906.6 911.2 911.4 921.8 18130.0 18179.7 18598.5 19432.7 19450.0 16537.2 16665.3 16702.2 17084.7 18332.9 955.8 960.2 966.4 967.8 970.8 978.3 981.4 983.5 983.6 984.6 997.8 1024.6 1027.5 1035.5 1036.6 1019.5 1023.0 1025.9 1032.4 1033.3 746.4 749.1 751.2 751.9 752.1 18989.6 19560.5 19630.6 19655.7 19694.8 20607.2 20651.5 20736.7 20768.0 20819.9 17273.4 17511.7 17649.7 17681.1 17762.7 3742.9 3753.3 3757.8 3762.0 3764.0 140703.0 141367.3 143570.3 143638.0 144407.3 15477.8 15529.6 15752.7 15809.5 15877.0 27832.6 28079.3 28327.4 28589.3 28687.0 8367.8 8368.4 8392.2 8431.6 8476.1 4223.6 4259.5 4291.7 4301.7 4307.0 5421.8 5598.4 5603.8 5605.0 5664.7 857.7 878.0 899.1 899.8 900.3 667.4 677.7 700.1 703.8 706.9 11505.0 11702.2 11742.7 11833.2 12067.6 670.2 672.3 683.1 689.3 690.5 5224.9 5275.7 5281.7 5325.5 5379.3 120884.0 121648.0 122220.0 122758.0 126104.0 86886.6 94588.0 96397.5 109899.0 113639.4 92373.6 94019.5 94787.5 96573.0 100388.0 11418.6 11557.9 11789.4 11855.4 11861.0 42414.4 44483.8 44647.0 44919.8 45767.0 55472.2 56385.7 56464.2 56618.7 58427.7 18248.8 18557.2 18639.8 18691.6 18830.4 21125.6 21305.1 21335.1 21343.6 21684.0 19315.7 19470.5 19487.8 19589.8 19668.3 16966.7 17128.1 17153.4 17228.4 17377.4 17636.9 18002.3 18008.2 18108.1 18116.9 11874.7 11990.8 12228.3 12316.6 12360.6 12532.8 12832.4 12943.6 13034.2 13084.2 23:39:06 INFO - PID 11978 | -------- Summary: end -------- 23:39:06 INFO - PID 11978 | 23:39:07 INFO - TEST-INFO | 11978: exit 0 23:39:07 INFO - TEST-OK | dromaeo_css | took 1115074ms 23:39:07 INFO - TEST-START | kraken 23:39:07 INFO - Initialising browser for kraken test... 23:39:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37638/getInfo.html -profile /tmp/tmpoWVlGa/profile 23:39:07 INFO - TEST-INFO | started process 12359 (/builds/slave/test/build/application/firefox/firefox http://localhost:37638/getInfo.html) 23:39:12 INFO - TEST-INFO | 12359: exit 0 23:39:12 INFO - Browser initialized. 23:39:12 INFO - Running cycle 1/1 for kraken test... 23:39:12 INFO - Using env: {'DISPLAY': ':0', 23:39:12 INFO - 'HOME': '/home/cltbld', 23:39:12 INFO - 'JSGC_DISABLE_POISONING': '1', 23:39:12 INFO - 'LANG': 'en_US.UTF-8', 23:39:12 INFO - 'LANGUAGE': 'en_US:en', 23:39:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 23:39:12 INFO - 'LOGNAME': 'cltbld', 23:39:12 INFO - 'MAIL': '/var/mail/cltbld', 23:39:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:39:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:39:12 INFO - 'MOZ_ACCELERATED': '1', 23:39:12 INFO - 'MOZ_CRASHREPORTER': '1', 23:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 23:39:12 INFO - 'MOZ_NO_REMOTE': '1', 23:39:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:39:12 INFO - 'MOZ_WEBRENDER': '1', 23:39:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:39:12 INFO - 'NO_EM_RESTART': '1', 23:39:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:12 INFO - 'PWD': '/builds/slave/test', 23:39:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:39:12 INFO - 'RUST_BACKTRACE': 'full', 23:39:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 23:39:12 INFO - 'SHELL': '/bin/bash', 23:39:12 INFO - 'SHLVL': '1', 23:39:12 INFO - 'TERM': 'linux', 23:39:12 INFO - 'TMOUT': '86400', 23:39:12 INFO - 'USER': 'cltbld', 23:39:12 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999', 23:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:12 INFO - '_': '/tools/buildbot/bin/python'} 23:39:12 INFO - TEST-INFO | started process 12600 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoWVlGa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 23:39:13 INFO - PID 12600 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 23:39:14 INFO - PID 12600 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 23:39:14 INFO - PID 12600 | 23:39:14 INFO - PID 12600 | (/builds/slave/test/build/application/firefox/firefox:12659): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 23:39:14 INFO - PID 12600 | 23:39:24 INFO - PID 12600 | RSS: Main: 222093312 23:39:24 INFO - PID 12600 | 23:39:27 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37638/tests/kraken/driver.html?testName=audio-beat-detection) 23:39:28 INFO - PID 12600 | RSS: Main: 228499456 23:39:28 INFO - PID 12600 | 23:39:31 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37638/tests/kraken/driver.html?testName=audio-dft) 23:39:31 INFO - PID 12600 | RSS: Main: 228335616 23:39:31 INFO - PID 12600 | 23:39:34 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37638/tests/kraken/driver.html?testName=audio-fft) 23:39:35 INFO - PID 12600 | RSS: Main: 222900224 23:39:35 INFO - PID 12600 | 23:39:37 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37638/tests/kraken/driver.html?testName=audio-oscillator) 23:39:38 INFO - PID 12600 | RSS: Main: 224681984 23:39:38 INFO - PID 12600 | 23:39:40 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37638/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:39:41 INFO - PID 12600 | RSS: Main: 224743424 23:39:41 INFO - PID 12600 | 23:39:44 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37638/tests/kraken/driver.html?testName=imaging-darkroom) 23:39:45 INFO - PID 12600 | RSS: Main: 226168832 23:39:45 INFO - PID 12600 | 23:39:48 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37638/tests/kraken/driver.html?testName=imaging-desaturate) 23:39:49 INFO - PID 12600 | RSS: Main: 225120256 23:39:49 INFO - PID 12600 | 23:39:52 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37638/tests/kraken/driver.html?testName=json-parse-financial) 23:39:53 INFO - PID 12600 | RSS: Main: 227381248 23:39:53 INFO - PID 12600 | 23:39:54 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37638/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:39:55 INFO - PID 12600 | RSS: Main: 227381248 23:39:55 INFO - PID 12600 | 23:39:57 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37638/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:39:57 INFO - PID 12600 | RSS: Main: 223879168 23:39:57 INFO - PID 12600 | 23:39:59 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37638/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:40:00 INFO - PID 12600 | RSS: Main: 224161792 23:40:00 INFO - PID 12600 | 23:40:03 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37638/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:40:03 INFO - PID 12600 | RSS: Main: 225873920 23:40:03 INFO - PID 12600 | 23:40:06 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37638/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:40:07 INFO - PID 12600 | RSS: Main: 224178176 23:40:07 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | Cycle 1(1): loaded http://localhost:37638/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37638/tests/kraken/driver.html?testName=ai-astar) 23:40:09 INFO - PID 12600 | RSS: Main: 228171776 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | __start_tp_report 23:40:09 INFO - PID 12600 | _x_x_mozilla_page_load 23:40:09 INFO - PID 12600 | _x_x_mozilla_page_load_details 23:40:09 INFO - PID 12600 | |i|pagename|runs| 23:40:09 INFO - PID 12600 | |0;ai-astar;116;109;111;108;110;108;119;108;109;115 23:40:09 INFO - PID 12600 | |1;audio-beat-detection;120;116;123;123;117;143;126;118;125;117 23:40:09 INFO - PID 12600 | |2;audio-dft;173;169;169;169;166;166;169;165;170;163 23:40:09 INFO - PID 12600 | |3;audio-fft;78;75;75;74;82;81;81;75;75;74 23:40:09 INFO - PID 12600 | |4;audio-oscillator;87;83;85;82;78;79;79;86;83;92 23:40:09 INFO - PID 12600 | |5;imaging-gaussian-blur;114;115;115;121;122;119;115;115;111;115 23:40:09 INFO - PID 12600 | |6;imaging-darkroom;154;149;160;152;148;150;148;155;156;153 23:40:09 INFO - PID 12600 | |7;imaging-desaturate;94;100;97;95;98;98;100;100;94;95 23:40:09 INFO - PID 12600 | |8;json-parse-financial;55;54;54;54;55;55;54;54;52;54 23:40:09 INFO - PID 12600 | |9;json-stringify-tinderbox;38;41;45;38;45;40;38;39;39;45 23:40:09 INFO - PID 12600 | |10;stanford-crypto-aes;83;77;75;76;92;83;78;76;78;80 23:40:09 INFO - PID 12600 | |11;stanford-crypto-ccm;127;126;122;119;114;116;116;116;123;119 23:40:09 INFO - PID 12600 | |12;stanford-crypto-pbkdf2;169;171;170;171;176;170;169;169;173;168 23:40:09 INFO - PID 12600 | |13;stanford-crypto-sha256-iterative;70;68;64;66;67;67;68;68;70;71 23:40:09 INFO - PID 12600 | __end_tp_report 23:40:09 INFO - PID 12600 | __start_cc_report 23:40:09 INFO - PID 12600 | _x_x_mozilla_cycle_collect,929 23:40:09 INFO - PID 12600 | __end_cc_report 23:40:09 INFO - PID 12600 | __startTimestamp1505371209593__endTimestamp 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | ------- Summary: start ------- 23:40:09 INFO - PID 12600 | Number of tests: 14 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#0] ai-astar Cycles:10 Average:111.30 Median:110.50 stddev:3.95 (3.6%) stddev-sans-first:3.80 23:40:09 INFO - PID 12600 | Values: 116.0 109.0 111.0 108.0 110.0 108.0 119.0 108.0 109.0 115.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:123.00 stddev:7.94 (6.5%) stddev-sans-first:8.36 23:40:09 INFO - PID 12600 | Values: 120.0 116.0 123.0 123.0 117.0 143.0 126.0 118.0 125.0 117.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#2] audio-dft Cycles:10 Average:167.90 Median:169.00 stddev:2.88 (1.7%) stddev-sans-first:2.40 23:40:09 INFO - PID 12600 | Values: 173.0 169.0 169.0 169.0 166.0 166.0 169.0 165.0 170.0 163.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#3] audio-fft Cycles:10 Average:77.00 Median:76.50 stddev:3.20 (4.2%) stddev-sans-first:3.37 23:40:09 INFO - PID 12600 | Values: 78.0 75.0 75.0 74.0 82.0 81.0 81.0 75.0 75.0 74.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:84.00 stddev:4.30 (5.1%) stddev-sans-first:4.36 23:40:09 INFO - PID 12600 | Values: 87.0 83.0 85.0 82.0 78.0 79.0 79.0 86.0 83.0 92.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#5] imaging-gaussian-blur Cycles:10 Average:116.20 Median:115.00 stddev:3.39 (3.0%) stddev-sans-first:3.50 23:40:09 INFO - PID 12600 | Values: 114.0 115.0 115.0 121.0 122.0 119.0 115.0 115.0 111.0 115.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#6] imaging-darkroom Cycles:10 Average:152.50 Median:153.50 stddev:3.89 (2.5%) stddev-sans-first:4.09 23:40:09 INFO - PID 12600 | Values: 154.0 149.0 160.0 152.0 148.0 150.0 148.0 155.0 156.0 153.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:95.00 stddev:2.47 (2.6%) stddev-sans-first:2.35 23:40:09 INFO - PID 12600 | Values: 94.0 100.0 97.0 95.0 98.0 98.0 100.0 100.0 94.0 95.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.88 (1.6%) stddev-sans-first:0.87 23:40:09 INFO - PID 12600 | Values: 55.0 54.0 54.0 54.0 55.0 55.0 54.0 54.0 52.0 54.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#9] json-stringify-tinderbox Cycles:10 Average:40.80 Median:40.50 stddev:3.05 (7.5%) stddev-sans-first:3.06 23:40:09 INFO - PID 12600 | Values: 38.0 41.0 45.0 38.0 45.0 40.0 38.0 39.0 39.0 45.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.00 stddev:5.12 (6.5%) stddev-sans-first:5.29 23:40:09 INFO - PID 12600 | Values: 83.0 77.0 75.0 76.0 92.0 83.0 78.0 76.0 78.0 80.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#11] stanford-crypto-ccm Cycles:10 Average:119.80 Median:120.50 stddev:4.52 (3.7%) stddev-sans-first:3.97 23:40:09 INFO - PID 12600 | Values: 127.0 126.0 122.0 119.0 114.0 116.0 116.0 116.0 123.0 119.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:170.50 stddev:2.37 (1.4%) stddev-sans-first:2.44 23:40:09 INFO - PID 12600 | Values: 169.0 171.0 170.0 171.0 176.0 170.0 169.0 169.0 173.0 168.0 23:40:09 INFO - PID 12600 | 23:40:09 INFO - PID 12600 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:2.08 (3.1%) stddev-sans-first:2.06 23:40:09 INFO - PID 12600 | Values: 70.0 68.0 64.0 66.0 67.0 67.0 68.0 68.0 70.0 71.0 23:40:09 INFO - PID 12600 | -------- Summary: end -------- 23:40:09 INFO - PID 12600 | 23:40:10 INFO - PID 12600 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 23:40:10 INFO - TEST-INFO | 12600: exit 0 23:40:10 INFO - TEST-OK | kraken | took 63170ms 23:40:10 INFO - SUITE-END | took 1178s 23:40:10 INFO - Completed test suite (00:19:38) 23:40:10 INFO - javascript benchmark 23:40:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8488.575994399444, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3090.737287330577, 3162.20944763809, 3168.0495851244636, 3173.9365212695734, 3177.95233071504, 6222.346653601375, 6250.8124756257275, 6260.84347891303, 6290.528210384222, 6346.0, 6282.120503129566, 6303.054541818729, 6305.085762564437, 6342.6511541865175, 6365.52258580606, 5762.3373312069225, 5801.709914504279, 5808.041673123929, 5809.448102430262, 5847.619904706202, 5885.941140588604, 5908.645481271137, 5964.463198312151, 5983.401659834003, 6001.159837622737, 5881.588288819784, 5897.705114744267, 5943.613665111776, 5947.791827286046, 5969.253843269591, 5649.322081350223, 5716.799912003102, 5762.135679648044, 5944.4352786485215, 5968.045112781978, 5787.13193021046, 5818.272715910512, 5874.882502349929, 5889.116632505115, 5899.439553242435, 6302.054691796221, 6344.968275158641, 6345.87308253837, 6375.4262116409745, 6415.550911436201, 6669.23303820062, 6699.062131301622, 6756.054152418666, 6759.898601520981, 6766.424853887425, 6799.0, 6823.761168359084, 6842.589444633338, 6843.520953533254, 6912.308769123048, 6389.648569328685, 6404.871902561969, 6432.485401167942, 6434.324395938406, 6437.066625339393, 6945.687444065029, 6991.615461149686, 7009.824754381079, 7017.0, 7024.192217895007, 6560.343965603497, 6697.966510167418, 6712.53012289135, 6807.285235050347, 6816.727330906808, 6758.189017317855, 6789.762358317534, 6832.897506537406, 6842.965785171043, 6849.04113424121, 6792.728290868409, 6797.694103765342, 6835.14560679915, 6861.416779573692, 6877.690503927335, 6840.41856442208, 6852.965735171292, 6918.446524278046, 6922.342377474132, 6926.896096558556, 5169.4572069932865, 5281.762320695501, 5311.893762124813, 5368.355797304349, 5439.755211015514, 4662.230731929194, 4707.693999889996, 4711.52884711533, 4727.479977202505, 4733.077049975291, 5782.855428614234, 5840.766369345179, 5842.59101862874, 5845.883082338414, 5847.883042339129, 6848.0, 6848.965755171292, 6905.205901321312, 6906.102206713095, 6960.095187625576, 5800.129980502874, 5811.912821307684, 5861.32594751609, 5879.7060146993335, 5895.793647222326, 5692.544596432275, 5752.36723960364, 5803.187553742479, 5896.233489646319, 5915.053591425351, 6583.572067815577, 6588.538802283794, 6589.571677840925, 6618.669066546653, 6648.900266496006, 6798.694058767268, 6870.690818913061, 6928.688209030605, 6961.6519174042105, 6968.65156742171, 6391.552591318749, 6573.769918052845, 6582.703778329891, 6673.265940746611, 6693.732250710112, 6652.800415987528, 6666.899996499959, 6669.7665581704405, 6674.365935236145, 6689.866202675822, 6712.630805305755, 6729.394354508117, 6751.257361690308, 6787.321267873173, 6788.355106264897, 6607.372299631527, 6676.632785196666, 6693.464522838259, 6697.0, 6706.631135287606, 6616.735330586916, 6625.138731964814, 6702.430293425114, 6702.832429189114, 6712.127423434922, 6394.0, 6517.674116294261, 6534.640594767334, 6638.17022872141, 6642.4686025118845, 6577.276499585138, 6635.966820165868, 6673.199216093905, 6736.225334086643, 6737.528373013842, 6490.350964903472, 6495.642739649175, 6509.446697030806, 6516.315786842313, 6552.6723663817575], "value": 6205.100157501541, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [868.4441957147425, 878.8462019146648, 881.0924747510066, 884.2969838978014, 888.6756333938115, 38902.221955560875, 42482.36276455848, 42668.14663706724, 42765.572344276545, 42805.78597107018, 39286.0, 39392.80303598485, 39774.20451590967, 39820.402693959615, 39841.80079099601, 7072.752453664123, 7173.497855150142, 7234.746783862566, 7245.673944672502, 7251.3111254431005, 7061.152661680606, 7754.573498457583, 7939.603019849013, 7939.722109726161, 7958.761237162895, 7905.407094467909, 7935.365170786352, 7955.284024437799, 7974.760757177294, 7990.240927111915, 7765.262300081483, 7880.960595197016, 7959.880601790977, 7986.440949133563, 8026.558539280337, 7684.385249180053, 7703.306702396755, 7724.845503089907, 7734.922650773477, 7769.805754856117, 38862.80568597139, 39145.21709565821, 39156.0, 39280.60719392826, 39371.0, 3425.2122011937145, 3432.2963792422493, 3433.5879694436576, 3433.8454769535433, 3434.295969326282, 32146.0, 32220.194495137574, 32246.516302255484, 32338.6766132338, 32426.351472970407, 5198.792048318101, 5212.139996900507, 5214.582833373321, 5233.241180028875, 5325.840224793263, 185127.0, 195285.0, 196493.0, 197662.0, 197718.01140994346, 42318.1536369277, 48414.0, 48635.0, 49050.0, 49052.50947490479, 45965.0, 50320.496795031584, 53372.46627533752, 53742.46257537453, 53796.0, 16153.676926461405, 16279.593010174603, 16316.0, 16331.42840000618, 16423.589410264838, 13176.407061682246, 13325.466981320833, 13332.666683332995, 13365.198088114745, 13370.532031378854, 14574.34415451286, 14639.853601464061, 14665.633359166106, 14665.946767452566, 14742.0, 5704.600677952587, 5737.3688894221605, 5779.393163717833, 5826.737796799154, 5937.70311484424, 1006.3760468509571, 1008.4050410257983, 1026.958921643141, 1027.1474676018886, 1033.7622346860114, 23098.191563295204, 23344.7665523346, 23595.174168904006, 23673.28980130599, 23771.7622823773, 1099.14816017587, 1111.5164903267105, 1127.1264769803304, 1129.7006293332274, 1138.1065863297272, 12328.876711232951, 12423.068269879795, 12466.626001219978, 12496.25022498653, 12576.993840492738, 220150.0, 221809.0, 222462.0, 222601.0, 223728.0, 53499.73250133725, 53641.46358536443, 53683.0, 53909.730451347496, 53911.0, 1443.2422977936667, 1446.1323206076481, 1476.269246722879, 1493.4847477620203, 1497.0344128037354, 23144.4213894654, 23261.88369058144, 23301.53396932086, 23348.64977025312, 23391.298261052194, 37354.0, 37415.0, 37838.62161378351, 37987.0, 38046.66836660704, 202337.0, 202794.0, 203193.0, 203602.0, 203847.0, 32385.0, 32615.67384326221, 33698.66301336955, 35093.0, 35436.82281588575, 43993.340099899164, 44016.11967760563, 44122.338164928195, 44477.555224447344, 44859.327110094026, 32357.35285294328, 32530.0, 32865.67134328626, 32914.34171316608, 33150.17124571905, 31308.373832522768, 31589.52615710812, 31743.0, 31812.84093579517, 31830.363392732477, 35808.820955895055, 36084.639153609176, 36120.27759444849, 36235.27529449343, 36321.27357452889, 31066.223344416572, 31071.39467624343, 31124.066278011695, 31555.36889262156, 31570.684293156773, 23386.883065584563, 23670.763292366857, 23817.880910595337, 23907.0, 23918.162864299666], "value": 17266.446053076274, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.290970467506, 560.4647561578693, 564.6414526775496, 565.4571611253197, 582.965022098674, 21918.342449726515, 22468.662970055462, 22494.88752556239, 22777.0, 22784.658230126563, 20972.37082887512, 21347.466313342175, 21575.46061348467, 21580.784192158073, 21596.460088497755, 6424.3575642435735, 6588.341165883421, 6646.0, 6651.93348066518, 6674.032265321525, 6611.3719196676475, 6704.564203326792, 6710.899336509956, 6716.328367163269, 6718.260991290978, 6342.778002769904, 6602.834929126786, 6614.404703576677, 6674.365935236145, 6676.165479315086, 6523.804285871408, 6561.704723287464, 6563.638999855006, 6588.0, 6591.967040164816, 5832.1834943108, 6069.635821850703, 6076.848078798022, 6079.452849243589, 6099.329073801879, 28675.56986645202, 29115.0, 29182.41635167306, 29245.85377073101, 29433.264168395748, 2030.9289174878809, 2031.7663468700994, 2043.0806137238303, 2052.7639321478073, 2056.845736569763, 18128.365507207316, 18768.62462750751, 19022.144003519876, 19027.0, 19057.0, 2298.230092918874, 2316.4903721181313, 2385.9045638174453, 2392.940176495584, 2394.4013996500876, 120956.0, 126061.0, 127397.0, 129110.0, 132456.33771831176, 14537.764290035255, 14553.648812619213, 14692.045017073857, 14727.410903563952, 14816.259187040605, 22619.773802261767, 22741.545169096524, 22757.772422275564, 22774.0, 22788.08847646109, 9923.255755818342, 10107.494625268708, 10140.391576505433, 10218.284720069523, 10223.182145428347, 9342.719718408458, 9404.153626173676, 9522.904865940367, 9528.428294302365, 9540.093691099335, 9050.637974480947, 9114.954425227832, 9134.90865091354, 9157.908420915839, 9242.168204861595, 2642.7621514063826, 2682.0, 2697.9190624281305, 2780.666320041608, 2847.38781162047, 935.4527601353149, 953.4755884263627, 955.7992821507561, 968.7045451137329, 972.0959507657946, 19191.0, 19332.903335483516, 19378.903105484384, 19521.414357569294, 19690.60618787616, 1012.6860673191334, 1013.5894962540183, 1015.5988384588046, 1020.6325722739808, 1020.6886899495577, 10816.513256903458, 10862.293950893167, 10863.348199108079, 10922.890771092347, 11022.50398732059, 469739.0, 482334.0, 494153.0, 504454.0, 505523.0, 105381.0, 106601.46699266454, 106645.46677266563, 108111.45944270228, 109877.0, 1008.3798463944621, 1062.7821296634172, 1065.2064212161988, 1125.5722825326488, 1137.4426530999915, 20338.694919575923, 20454.284100056426, 20765.896170519052, 20819.68770468414, 20875.791242087384, 52570.0, 52885.0, 54386.0, 55512.167317491076, 56034.0, 66414.66792666005, 66566.0, 66776.66611667105, 67079.66460167668, 67935.0, 14265.072770269899, 14398.712025759216, 14634.0, 14687.92656036713, 14710.852891470948, 22807.885960570093, 22969.77030229676, 23091.0, 23348.416289592897, 23694.0, 17852.10739462996, 18000.459986200436, 18226.72659910129, 18402.81597184011, 18810.717839232148, 16206.351745930502, 16551.83448165503, 17190.570235744206, 17214.827851721322, 17362.91318543399, 18703.532411689815, 18762.343317983807, 18870.24519019224, 18892.0, 19008.71486927725, 14233.074850134708, 14319.57041288763, 14333.78499322532, 14492.637684057983, 14503.1298122113, 17928.19323130436, 18215.45353639393, 18364.540886477946, 18376.632467350846, 18602.627947441248], "value": 11796.640564920704, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.2050449225972, 1355.0717758335534, 1363.9386227619761, 1367.1045465220284, 1408.2184387664843, 14747.483838065657, 15372.84627153728, 15418.69162616747, 15439.228038598067, 15452.227388630567, 15040.398384064625, 15072.171030593314, 15190.392384304641, 15202.695946081072, 15205.54383368498, 2568.833025853323, 2626.697929738076, 2644.5107655083775, 2644.9471010579778, 2670.7195744446844, 2693.676758788948, 2723.7412445817617, 2745.094118940755, 2748.0244513197827, 2748.573971034493, 2593.377589378545, 2672.973270267292, 2678.08944958714, 2678.9062382816605, 2696.12375977807, 2701.972980270202, 2714.0772137473255, 2719.510488112149, 2722.1425251045857, 2731.808773385864, 2585.50875333686, 2605.4268061026546, 2608.1523504860997, 2614.843109413422, 2630.5528060229617, 15008.099514029193, 15147.469838555598, 15147.924260378628, 15179.089254644647, 15188.848111518853, 7368.668409921566, 7645.044369453818, 7663.808404789869, 7672.539647621161, 7683.423743219281, 4978.4025916890205, 5102.668326558762, 5178.326817513699, 5187.04039752647, 5195.974020129913, 1892.4890279624562, 1901.2680118154472, 1932.062949469506, 1955.1592815089505, 1976.071246514136, 26191.607125892937, 26294.86852565744, 26518.0, 26602.46795064088, 26669.73330266692, 6565.0, 6601.405873471411, 6602.14172157626, 6743.629100399427, 6798.184217893884, 17172.484825455256, 17236.482905512854, 17257.56856078608, 17269.39557115495, 17362.47912562625, 7697.807554811063, 7831.960840195877, 7853.8821917671285, 7857.567833769198, 7864.685412583547, 6790.626515541692, 6793.422559082534, 6996.195437524648, 7124.536905101256, 7245.529040612343, 5113.8721531962, 5187.455317191716, 5342.8664283392445, 5394.811181608625, 5422.15956526739, 1506.947256846005, 1557.9610509737347, 1565.2643257668879, 1569.1683407793887, 1582.1614544291544, 901.8602116671926, 916.6150216908915, 925.8888933328044, 926.935114541989, 932.5011119051275, 8290.253877151084, 8394.0, 8483.872741908875, 8484.151584841466, 8515.318774498026, 973.4937832327291, 995.4475266227313, 996.3025881882711, 997.7156510394502, 1010.9595616175418, 5281.920771188358, 5362.27609272751, 5373.543248823816, 5423.132298832168, 5524.0, 21177.258795942067, 21333.67999480009, 21427.78572214258, 21627.2430464933, 22055.0, 20614.484637883874, 21093.156273749184, 21329.146834126772, 21430.142794288367, 21565.245216417665, 9924.404535727735, 10206.132478739242, 10319.535620896928, 10347.482625868677, 10347.534360953776, 7957.363410927228, 8138.918610814053, 8164.877526837221, 8247.711330103417, 8358.707445239388, 18281.45155645333, 18323.35868244605, 18483.630327393646, 18647.533811654273, 18933.148008339384, 18850.34023809215, 18955.62088758245, 19071.618567628848, 19234.326798561982, 19237.13432895551, 10367.27429079957, 10726.785464290515, 10763.569457221623, 10775.94612026935, 10792.406417647106, 13317.933410332886, 13685.178889266675, 13746.0, 13753.724925501634, 13766.0, 10090.243231757795, 10417.47912604382, 10504.369737815756, 10526.947365263124, 10697.090747286567, 9658.565364558752, 9807.06832850878, 9870.901290986998, 9960.900390996287, 10020.198384129106, 8679.91320086791, 8894.777630559287, 8950.82098358016, 8961.596728147118, 8979.371443998858, 6938.340857618518, 7041.049458323061, 7066.399356054793, 7069.257727938492, 7170.67731952053, 6584.934150658432, 6625.238097618835, 6629.668516574248, 6642.900356494753, 6711.530192886451], "value": 6544.76550839625, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.91858384074703, 495.32882943611406, 510.28560015977627, 515.6957395136868, 523.3614989712552, 6181.227346581677, 6209.037599172136, 6250.156228909096, 6271.466925311354, 6291.370862913712, 6034.577579569432, 6117.602355846867, 6125.356837532062, 6158.969205153968, 6192.349803270659, 5030.371203599541, 5110.974445127769, 5138.023775482665, 5156.407013193474, 5185.766640501187, 5139.614528910328, 5183.429822719517, 5194.246834209038, 5212.973935130319, 5243.79024839006, 4758.809647614108, 4929.33453983713, 4930.285108659242, 5062.417821950467, 5091.516305950929, 4914.385701787277, 4924.039812236615, 4951.826686066006, 4952.0838644850455, 4972.975135124338, 4400.889977750549, 4411.933820992687, 4426.273753236241, 4436.7116137451285, 4441.178381999308, 5833.358330583633, 6028.879422411527, 6064.3935606439445, 6075.422834830684, 6115.327313995457, 5597.804076857331, 5653.971730141323, 5667.4049224831215, 5689.743961521742, 5715.857103572403, 6489.383508566679, 6517.305500569839, 6559.934400656028, 6608.834779130512, 6645.501587380966, 4636.605888499482, 4818.9157439576165, 4859.562639362474, 4863.367762190893, 4876.634252431082, 6673.833154171136, 6745.797626071226, 6749.12261406019, 6787.287334829871, 6798.912174052178, 4934.9753251233515, 4969.6394212748655, 4976.104301225814, 4993.875153121201, 5001.199808030698, 5009.273655319958, 5031.138478762808, 5033.119204139334, 5035.84892453227, 5056.494350564914, 5491.945080549143, 5549.116777236007, 5635.378989228793, 5649.0, 5692.0, 6587.670616469158, 6636.203655561363, 6637.084120369005, 6651.0, 6666.600003999775, 1740.25169177255, 1745.502531778437, 1748.038578781665, 1766.0904634113442, 1768.4606195110614, 1130.2483848240827, 1180.5808937826948, 1192.648168790209, 1214.8299238106672, 1219.8170274458903, 5621.0444224481935, 5633.239512665819, 5683.630564013326, 5687.544996400278, 5704.0, 6658.533902626769, 6699.832504187434, 6710.396064354231, 6710.798676039727, 6761.619190404797, 5510.614257001971, 5533.391326954032, 5598.636088654224, 5645.463680950304, 5646.522872813364, 3479.304139172176, 3536.6109727929907, 3537.6486921086694, 3563.6792688658147, 3572.4819901114192, 6403.359664033559, 6420.5184611154355, 6478.319776423407, 6478.384553467507, 6513.609183449008, 6706.329367063255, 6726.966365168143, 6753.662316884136, 6755.459563234929, 6778.593284402853, 6317.515383884784, 6358.682065896779, 6359.713812878339, 6373.776917807947, 6398.616083035033, 6130.724117414817, 6496.545241833026, 6533.150690410216, 6589.24223714279, 6653.534252602271, 6351.0, 6442.774502892376, 6447.6453795041725, 6481.865673507212, 6522.543421960418, 6369.1401660777055, 6380.968095159495, 6393.6483493408305, 6395.42441180296, 6501.479881609365, 4783.7129772213775, 4937.703737775601, 4943.35736354286, 4951.72765497901, 4957.231629097496, 6324.430801227728, 6391.58454700443, 6577.80266592003, 6650.268470468148, 6676.699548520425, 6309.810705678837, 6371.267304259884, 6436.8390790230615, 6461.224653041665, 6491.837704057248, 6455.837294506206, 6476.902846457401, 6490.967545162244, 6524.608523488606, 6575.4410875076155, 6387.616742995435, 6390.329015453496, 6397.936020639734, 6442.967785161044, 6446.613203207822, 6480.416762491398, 6500.187476565429, 6505.902411463927, 6592.17597800275, 6593.670316484253, 6297.118403423433, 6297.338779428277, 6314.936850631435, 6367.681615919279, 6401.87196256063], "value": 5009.464804497349, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [901.6663834381278, 906.6418764587984, 911.1571796088616, 911.4212475078323, 921.8156368726256, 18130.0, 18179.727304090415, 18598.535036624093, 19432.708509372354, 19449.99999999998, 16537.173141342944, 16665.25006374713, 16702.164891755423, 17084.658306833855, 18332.908335458338, 955.8088382323529, 960.2366118935446, 966.3960024984385, 967.8306296398138, 970.7961328121103, 978.3005151316808, 981.3817295104075, 983.5377372634886, 983.5819776594955, 984.5963155106408, 997.7505623594102, 1024.5748014573942, 1027.5170669785227, 1035.5340096956363, 1036.6475398364555, 1019.4902548725637, 1023.0332335942537, 1025.8615086963277, 1032.4218437674926, 1033.3128469567728, 746.4289818289013, 749.0636704119851, 751.2449987762319, 751.8759404698218, 752.0974830203777, 18989.620207595908, 19560.51098722529, 19630.607387852164, 19655.705164422547, 19694.80305196944, 20607.175712971464, 20651.4837129073, 20736.688949665768, 20768.0, 20819.89590052055, 17273.39543115985, 17511.73732394015, 17649.7352539712, 17681.11594420274, 17762.733558996624, 3742.8689995850013, 3753.3056384568667, 3757.755745876509, 3761.962380376216, 3763.9623603763885, 140703.0, 141367.29316353454, 143570.28214858964, 143638.0, 144407.27796361057, 15477.767833482507, 15529.611759705871, 15752.684946301239, 15809.525714228592, 15877.047377157407, 27832.582511262346, 28079.298017549725, 28327.433451330857, 28589.285267868054, 28687.0, 8367.790805229917, 8368.37237207212, 8392.20274073962, 8431.620577074047, 8476.110008449024, 4223.640990515779, 4259.467566554181, 4291.721038132511, 4301.720388174758, 4306.956930430718, 5421.83734487966, 5598.384177740445, 5603.80386686464, 5605.047141985872, 5664.68844213564, 857.7469646454311, 878.0, 899.0874262623443, 899.7570655922864, 900.3292547052357, 667.4426853577318, 677.678102901118, 700.1353328639127, 703.7677566403074, 706.8762966480948, 11505.0, 11702.180847340775, 11742.70643233909, 11833.171677982458, 12067.577634782916, 670.1522573943964, 672.314239475732, 683.0710234081645, 689.327905292346, 690.526989012528, 5224.869378265497, 5275.709835959059, 5281.709505977208, 5325.547328477045, 5379.300690910157, 120884.0, 121648.0, 122220.0, 122758.0, 126104.0, 86886.56556717175, 94588.0, 96397.51801240949, 109899.0, 113639.43180284045, 92373.61439578411, 94019.52990235006, 94787.52606236924, 96573.0, 100388.0, 11418.600348987746, 11557.88442115568, 11789.351585662871, 11855.407229638657, 11861.0, 42414.36378454387, 44483.777581111885, 44647.0, 44919.77540112279, 45767.0, 55472.167917482075, 56385.71807140938, 56464.15303770529, 56618.716906415204, 58427.70786146042, 18248.817511825244, 18557.164927578022, 18639.813601863807, 18691.626167476847, 18830.435086947415, 21125.577488450454, 21305.14779408806, 21335.146594136062, 21343.573128537653, 21684.0, 19315.710264346326, 19470.513237169183, 19487.805121949164, 19589.80410195937, 19668.311609093613, 16966.745498817774, 17128.057956812496, 17153.399631012853, 17228.397006104726, 17377.391791287246, 17636.911815440842, 18002.279908803503, 18008.189631466354, 18108.094595270446, 18116.909415453367, 11874.703132421759, 11990.760184796429, 12228.266304021787, 12316.630501084981, 12360.567380141652, 12532.812007820072, 12832.358382080673, 12943.649204593949, 13034.152780069266, 13084.214947103204], "value": 9027.913531643882, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1461.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 109.0, 111.0, 108.0, 110.0, 108.0, 119.0, 108.0, 109.0, 115.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 116.0, 123.0, 123.0, 117.0, 143.0, 126.0, 118.0, 125.0, 117.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 169.0, 169.0, 169.0, 166.0, 166.0, 169.0, 165.0, 170.0, 163.0], "value": 167.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 75.0, 74.0, 82.0, 81.0, 81.0, 75.0, 75.0, 74.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 85.0, 82.0, 78.0, 79.0, 79.0, 86.0, 83.0, 92.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 121.0, 122.0, 119.0, 115.0, 115.0, 111.0, 115.0], "value": 116.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 149.0, 160.0, 152.0, 148.0, 150.0, 148.0, 155.0, 156.0, 153.0], "value": 152.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 97.0, 95.0, 98.0, 98.0, 100.0, 100.0, 94.0, 95.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 55.0, 55.0, 54.0, 54.0, 52.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 41.0, 45.0, 38.0, 45.0, 40.0, 38.0, 39.0, 39.0, 45.0], "value": 40.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 77.0, 75.0, 76.0, 92.0, 83.0, 78.0, 76.0, 78.0, 80.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 126.0, 122.0, 119.0, 114.0, 116.0, 116.0, 116.0, 123.0, 119.0], "value": 119.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 170.0, 171.0, 176.0, 170.0, 169.0, 169.0, 173.0, 168.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 64.0, 66.0, 67.0, 67.0, 68.0, 68.0, 70.0, 71.0], "value": 67.9, "unit": "score"}]}]} 23:40:10 INFO - Return code: 0 23:40:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:40:10 INFO - # TBPL SUCCESS # 23:40:10 INFO - Running post-action listener: _package_coverage_data 23:40:10 INFO - Running post-action listener: _resource_record_post_action 23:40:10 INFO - [mozharness: 2017-09-14 06:40:10.888877Z] Finished run-tests step (success) 23:40:10 INFO - Running post-run listener: _resource_record_post_run 23:40:10 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 7389184; Write bytes: 332013568; Read time: 7616; Write time: 227220 23:40:10 INFO - TinderboxPrint: CPU usage
13.3% 23:40:10 INFO - TinderboxPrint: I/O read bytes / time
7,389,184 / 7,616 23:40:10 INFO - TinderboxPrint: I/O write bytes / time
332,013,568 / 227,220 23:40:10 INFO - TinderboxPrint: CPU idle
8,264.2 (86.7%) 23:40:10 INFO - TinderboxPrint: CPU user
1,204.5 (12.6%) 23:40:10 INFO - TinderboxPrint: Swap in / out
0 / 0 23:40:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13135872; Read time: 0; Write time: 800 23:40:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 23:40:10 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 7385088; Write bytes: 318877696; Read time: 7608; Write time: 226420 23:40:11 INFO - Running post-run listener: _upload_blobber_files 23:40:11 INFO - Blob upload gear active. 23:40:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:40:11 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 23:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:40:11 INFO - (blobuploader) - INFO - Open directory for files ... 23:40:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:40:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:40:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:40:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:40:12 INFO - (blobuploader) - INFO - Done attempting. 23:40:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:40:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:40:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:40:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:40:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:40:20 INFO - (blobuploader) - INFO - Done attempting. 23:40:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:40:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:40:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:40:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:40:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:40:20 INFO - (blobuploader) - INFO - Done attempting. 23:40:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:40:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:40:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:40:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:40:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:40:21 INFO - (blobuploader) - INFO - Done attempting. 23:40:21 INFO - (blobuploader) - INFO - Iteration through files over. 23:40:21 INFO - Return code: 0 23:40:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:40:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:40:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1377df573c7f6acda6c2d5622ac6e30914e8f5591f3c60b08b356a7abaefde28d211ea7cb327b34288f952a1e685aeac36370e7bee8f0ea3a05d8dabd443f79b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3bb9357a97e2a86eca490805e3b9cac8875a3e32278872af39c14efb09e525af6147830ddd6229b53033fdfadb7d0a0ba9206b6bc13a54fbb2d45c697689494", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55cdc3be110abb0d7a8869cfd2c98e95d44040961ee9c5005208f7e613de02101dd18141bb0c23f1a548537595eb47c89857cd08a56fe2ae52c0e2cd6d7968d3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e92661eac8caa01702a3f63214d55973691e31a676c237409e8cc1c8060300a5fb6a47d369dc6b19b611ce70b45e53e1650e03b8e42919647fa9d0dbd625764"} 23:40:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:40:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:40:21 INFO - Contents: 23:40:21 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1377df573c7f6acda6c2d5622ac6e30914e8f5591f3c60b08b356a7abaefde28d211ea7cb327b34288f952a1e685aeac36370e7bee8f0ea3a05d8dabd443f79b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3bb9357a97e2a86eca490805e3b9cac8875a3e32278872af39c14efb09e525af6147830ddd6229b53033fdfadb7d0a0ba9206b6bc13a54fbb2d45c697689494", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55cdc3be110abb0d7a8869cfd2c98e95d44040961ee9c5005208f7e613de02101dd18141bb0c23f1a548537595eb47c89857cd08a56fe2ae52c0e2cd6d7968d3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e92661eac8caa01702a3f63214d55973691e31a676c237409e8cc1c8060300a5fb6a47d369dc6b19b611ce70b45e53e1650e03b8e42919647fa9d0dbd625764"} 23:40:21 INFO - Running post-run listener: copy_logs_to_upload_dir 23:40:21 INFO - Copying logs to upload dir... 23:40:21 INFO - mkdir: /builds/slave/test/build/upload/logs 23:40:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.781394 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-13 23:40:22.011288) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 23:40:22.018598) ========= 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=930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1377df573c7f6acda6c2d5622ac6e30914e8f5591f3c60b08b356a7abaefde28d211ea7cb327b34288f952a1e685aeac36370e7bee8f0ea3a05d8dabd443f79b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3bb9357a97e2a86eca490805e3b9cac8875a3e32278872af39c14efb09e525af6147830ddd6229b53033fdfadb7d0a0ba9206b6bc13a54fbb2d45c697689494", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55cdc3be110abb0d7a8869cfd2c98e95d44040961ee9c5005208f7e613de02101dd18141bb0c23f1a548537595eb47c89857cd08a56fe2ae52c0e2cd6d7968d3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e92661eac8caa01702a3f63214d55973691e31a676c237409e8cc1c8060300a5fb6a47d369dc6b19b611ce70b45e53e1650e03b8e42919647fa9d0dbd625764"} build_url:https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/Zv5HYUSPS56G-8wZXuIJvw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1377df573c7f6acda6c2d5622ac6e30914e8f5591f3c60b08b356a7abaefde28d211ea7cb327b34288f952a1e685aeac36370e7bee8f0ea3a05d8dabd443f79b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3bb9357a97e2a86eca490805e3b9cac8875a3e32278872af39c14efb09e525af6147830ddd6229b53033fdfadb7d0a0ba9206b6bc13a54fbb2d45c697689494", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55cdc3be110abb0d7a8869cfd2c98e95d44040961ee9c5005208f7e613de02101dd18141bb0c23f1a548537595eb47c89857cd08a56fe2ae52c0e2cd6d7968d3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e92661eac8caa01702a3f63214d55973691e31a676c237409e8cc1c8060300a5fb6a47d369dc6b19b611ce70b45e53e1650e03b8e42919647fa9d0dbd625764"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 23:40:22.047903) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:40:22.048246) ========= 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=930c87414c89fc706ee2024c000001c6-1505363191.449634-286094999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004597 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:40:22.128926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 23:40:22.129433) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 23:40:22.129750) ========= ========= Total master_lag: 0.30 =========