builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-066 starttime: 1505903850.96 results: success (0) revision: c0769d228dd3ba666d6e9b02896bebef7e996ad5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:30.963997) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:30.964699) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:30.965175) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:30.983511) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:30.983904) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 03:37:30-- 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.5M=0s 2017-09-20 03:37:31 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099415 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:31.101017) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:31.101329) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015445 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:31.186917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-20 03:37:31.187253) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c0769d228dd3ba666d6e9b02896bebef7e996ad5 --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 c0769d228dd3ba666d6e9b02896bebef7e996ad5 --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=82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 03:37:31,234 truncating revision to first 12 chars 2017-09-20 03:37:31,234 Setting DEBUG logging. 2017-09-20 03:37:31,234 attempt 1/10 2017-09-20 03:37:31,235 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c0769d228dd3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 03:37:32,349 unpacking tar archive at: mozilla-central-c0769d228dd3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.420644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-20 03:37:32.624272) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:32.624604) ========= script_repo_revision: c0769d228dd3ba666d6e9b02896bebef7e996ad5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:32.625292) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:32.625748) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 03:37:32.641169) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-20 03:37:32.641785) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:37:32 INFO - MultiFileLogger online at 20170920 03:37:32 in /builds/slave/test 03:37:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:37:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:37:32 INFO - {'append_to_log': False, 03:37:32 INFO - 'base_work_dir': '/builds/slave/test', 03:37:32 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:37:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:37:32 INFO - 'branch': 'Firefox-Non-PGO', 03:37:32 INFO - 'buildbot_json_path': 'buildprops.json', 03:37:32 INFO - 'code_coverage': False, 03:37:32 INFO - 'config_files': ('talos/linux_config.py',), 03:37:32 INFO - 'default_actions': ('clobber', 03:37:32 INFO - 'read-buildbot-config', 03:37:32 INFO - 'download-and-extract', 03:37:32 INFO - 'populate-webroot', 03:37:32 INFO - 'create-virtualenv', 03:37:32 INFO - 'install', 03:37:32 INFO - 'setup-mitmproxy', 03:37:32 INFO - 'run-tests'), 03:37:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:37:32 INFO - 'disable_ccov_upload': False, 03:37:32 INFO - 'disable_stylo': False, 03:37:32 INFO - 'download_minidump_stackwalk': True, 03:37:32 INFO - 'download_symbols': 'ondemand', 03:37:32 INFO - 'enable_stylo': False, 03:37:32 INFO - 'enable_webrender': False, 03:37:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:37:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:37:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:37:32 INFO - 'gecko_profile': False, 03:37:32 INFO - 'gecko_profile_interval': 0, 03:37:32 INFO - 'installer_path': 'installer.exe', 03:37:32 INFO - 'log_level': 'info', 03:37:32 INFO - 'log_name': 'talos', 03:37:32 INFO - 'log_to_console': True, 03:37:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:37:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:37:32 INFO - 'opt_config_files': (), 03:37:32 INFO - 'pip_index': False, 03:37:32 INFO - 'suite': 'dromaeojs-e10s', 03:37:32 INFO - 'system_bits': '32', 03:37:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:37:32 INFO - 'title': 'talos-linux64-ix-066', 03:37:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:37:32 INFO - 'use_talos_json': True, 03:37:32 INFO - 'verify': 'False', 03:37:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:37:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:37:32 INFO - 'work_dir': 'build'} 03:37:32 INFO - [mozharness: 2017-09-20 10:37:32.826736Z] Running clobber step. 03:37:32 INFO - Running pre-action listener: _resource_record_pre_action 03:37:32 INFO - Running main action method: clobber 03:37:32 INFO - rmtree: /builds/slave/test/build 03:37:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:37:33 INFO - Running post-action listener: _resource_record_post_action 03:37:33 INFO - [mozharness: 2017-09-20 10:37:33.855463Z] Finished clobber step (success) 03:37:33 INFO - [mozharness: 2017-09-20 10:37:33.855562Z] Running read-buildbot-config step. 03:37:33 INFO - Running pre-action listener: _resource_record_pre_action 03:37:33 INFO - Running main action method: read_buildbot_config 03:37:33 INFO - Using buildbot properties: 03:37:33 INFO - { 03:37:33 INFO - "project": "", 03:37:33 INFO - "product": "firefox", 03:37:33 INFO - "who": "archaeopteryx@coole-files.de", 03:37:33 INFO - "installer_path": "public/build/target.tar.bz2", 03:37:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:37:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:37:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:37:33 INFO - "stage_platform": "linux64", 03:37:33 INFO - "basedir": "/builds/slave/test", 03:37:33 INFO - "buildnumber": 1161, 03:37:33 INFO - "platform": "ubuntu64_hw", 03:37:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:37:33 INFO - "slavebuilddir": "test", 03:37:33 INFO - "taskId": "c40icO8-RzKemAr5NO-hWQ", 03:37:33 INFO - "branch": "mozilla-central", 03:37:33 INFO - "script_repo_revision": "production", 03:37:33 INFO - "revision": "c0769d228dd3ba666d6e9b02896bebef7e996ad5", 03:37:33 INFO - "slavename": "talos-linux64-ix-066", 03:37:33 INFO - "repo_path": "mozilla-central" 03:37:33 INFO - } 03:37:33 INFO - Finding installer, test and symbols from parent task. 03:37:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c40icO8-RzKemAr5NO-hWQ'}, attempt #1 03:37:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c40icO8-RzKemAr5NO-hWQ'}, attempt #1 03:37:34 INFO - Task dependencies: Nm88MqzuS6G8_ult50Vhxg 03:37:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg'}, attempt #1 03:37:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.tar.bz2 03:37:35 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.test_packages.json 03:37:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.crashreporter-symbols.zip 03:37:35 INFO - Running post-action listener: _resource_record_post_action 03:37:35 INFO - [mozharness: 2017-09-20 10:37:35.331617Z] Finished read-buildbot-config step (success) 03:37:35 INFO - [mozharness: 2017-09-20 10:37:35.331834Z] Running download-and-extract step. 03:37:35 INFO - Running pre-action listener: _resource_record_pre_action 03:37:35 INFO - Running main action method: download_and_extract 03:37:35 INFO - mkdir: /builds/slave/test/build/tests 03:37: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')]} 03:37:35 INFO - trying https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.test_packages.json 03:37:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:37:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:37:35 INFO - Downloaded 989 bytes. 03:37:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:37:35 INFO - Using the following test package requirements: 03:37:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:37:35 INFO - u'common': [u'target.common.tests.zip'], 03:37:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:37:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:37:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:37:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:37:35 INFO - u'mozbase': [u'target.common.tests.zip'], 03:37:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:37:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:37:35 INFO - u'web-platform': [u'target.common.tests.zip', 03:37:35 INFO - u'target.web-platform.tests.tar.gz'], 03:37:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:37:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:37:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.common.tests.zip 03:37:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:37:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.common.tests.zip into memory 03:37:36 INFO - Content-Length response header: 54731844 03:37:36 INFO - Bytes received: 54731844 03:37:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.talos.tests.zip 03:37:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:37:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.talos.tests.zip into memory 03:37:48 INFO - Content-Length response header: 14508893 03:37:48 INFO - Bytes received: 14508893 03:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:48 INFO - trying https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.tar.bz2 03:37:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:37:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:37:49 INFO - Downloaded 61636503 bytes. 03:37:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.tar.bz2 03:37:49 INFO - mkdir: /builds/slave/test/properties 03:37:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:37:49 INFO - Writing to file /builds/slave/test/properties/build_url 03:37:49 INFO - Contents: 03:37:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.tar.bz2 03:37:49 INFO - Running post-action listener: _resource_record_post_action 03:37:49 INFO - Running post-action listener: find_tests_for_verification 03:37:49 INFO - Running post-action listener: set_extra_try_arguments 03:37:49 INFO - [mozharness: 2017-09-20 10:37:49.983018Z] Finished download-and-extract step (success) 03:37:49 INFO - [mozharness: 2017-09-20 10:37:49.983114Z] Running populate-webroot step. 03:37:49 INFO - Running pre-action listener: _resource_record_pre_action 03:37:49 INFO - Running main action method: populate_webroot 03:37:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:37:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['tresize', 03:37:49 INFO - 'tcanvasmark']}, 03:37:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:37:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['dromaeo_css', 03:37:49 INFO - 'kraken']}, 03:37:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:37:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:49 INFO - 'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:37:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:37:49 INFO - 'tests': ['damp', 'tps']}, 03:37:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:49 INFO - 'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['damp', 'tps']}, 03:37:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:37:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['dromaeo_dom']}, 03:37:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:37:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['basic_compositor_video', 03:37:49 INFO - 'glvideo']}, 03:37:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:37:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:37:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:49 INFO - 'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['ts_paint_webext', 03:37:49 INFO - 'tp5o_webext']}, 03:37:49 INFO - 'other-e10s': {'tests': ['a11yr', 03:37:49 INFO - 'ts_paint', 03:37:49 INFO - 'tpaint', 03:37:49 INFO - 'sessionrestore', 03:37:49 INFO - 'sessionrestore_many_windows', 03:37:49 INFO - 'sessionrestore_no_auto_restore', 03:37:49 INFO - 'tabpaint']}, 03:37:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['a11yr', 03:37:49 INFO - 'ts_paint', 03:37:49 INFO - 'tpaint', 03:37:49 INFO - 'sessionrestore', 03:37:49 INFO - 'sessionrestore_many_windows', 03:37:49 INFO - 'sessionrestore_no_auto_restore', 03:37:49 INFO - 'tabpaint']}, 03:37:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:37:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:37:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['perf_reftest_singletons']}, 03:37:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['bloom_basic']}, 03:37:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:37:49 INFO - 'tsvgr_opacity', 03:37:49 INFO - 'tart', 03:37:49 INFO - 'tscrollx', 03:37:49 INFO - 'tsvg_static']}, 03:37:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['tsvgx', 03:37:49 INFO - 'tsvgr_opacity', 03:37:49 INFO - 'tart', 03:37:49 INFO - 'tscrollx', 03:37:49 INFO - 'tsvg_static']}, 03:37:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:37:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:49 INFO - 'talos_options': ['--disable-stylo'], 03:37:49 INFO - 'tests': ['tp5o']}, 03:37:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:37:49 INFO - 'talos_options': ['--mitmproxy', 03:37:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:37:49 INFO - '--firstNonBlankPaint'], 03:37:49 INFO - 'tests': ['tp6_google', 03:37:49 INFO - 'tp6_youtube', 03:37:49 INFO - 'tp6_amazon', 03:37:49 INFO - 'tp6_facebook']}, 03:37:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:37:49 INFO - 'talos_options': ['--disable-stylo', 03:37:49 INFO - '--mitmproxy', 03:37:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:37:49 INFO - '--firstNonBlankPaint'], 03:37:49 INFO - 'tests': ['tp6_google', 03:37:49 INFO - 'tp6_youtube', 03:37:49 INFO - 'tp6_amazon', 03:37:49 INFO - 'tp6_facebook']}, 03:37:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:37:49 INFO - 'talos_options': ['--stylo-threads=1', 03:37:49 INFO - '--mitmproxy', 03:37:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:37:49 INFO - '--firstNonBlankPaint'], 03:37:49 INFO - 'tests': ['tp6_google', 03:37:49 INFO - 'tp6_youtube', 03:37:49 INFO - 'tp6_amazon', 03:37:49 INFO - 'tp6_facebook']}, 03:37:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:37:49 INFO - 'talos_options': ['--xperf_path', 03:37:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:49 INFO - 'tests': ['tp5n']}, 03:37:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:37:49 INFO - 'talos_options': ['--disable-stylo', 03:37:49 INFO - '--xperf_path', 03:37:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:37:49 INFO - 'tests': ['tp5n']}}} 03:37:49 INFO - Running post-action listener: _resource_record_post_action 03:37:49 INFO - [mozharness: 2017-09-20 10:37:49.998077Z] Finished populate-webroot step (success) 03:37:49 INFO - [mozharness: 2017-09-20 10:37:49.998167Z] Running create-virtualenv step. 03:37:49 INFO - Running pre-action listener: _resource_record_pre_action 03:37:49 INFO - Running main action method: create_virtualenv 03:37:49 INFO - Creating virtualenv /builds/slave/test/build/venv 03:37:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:37:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:37:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:37:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:37:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:37:50 INFO - Installing distribute.............................................................................................................................................................................................done. 03:37:50 INFO - Installing pip...............done. 03:37:50 INFO - Return code: 0 03:37:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:37:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:37:50 INFO - Reading from file tmpfile_stdout 03:37:50 INFO - Output received: 03:37:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:37:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:37:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:37:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:37:50 INFO - Using env: {'DISPLAY': ':0', 03:37:50 INFO - 'HOME': '/home/cltbld', 03:37:50 INFO - 'LANG': 'en_US.UTF-8', 03:37:50 INFO - 'LANGUAGE': 'en_US:en', 03:37:50 INFO - 'LOGNAME': 'cltbld', 03:37:50 INFO - 'MAIL': '/var/mail/cltbld', 03:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:50 INFO - 'MOZ_NO_REMOTE': '1', 03:37:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:50 INFO - 'NO_EM_RESTART': '1', 03:37:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:50 INFO - 'PWD': '/builds/slave/test', 03:37:50 INFO - 'SHELL': '/bin/bash', 03:37:50 INFO - 'SHLVL': '1', 03:37:50 INFO - 'TERM': 'linux', 03:37:50 INFO - 'TMOUT': '86400', 03:37:50 INFO - 'USER': 'cltbld', 03:37:50 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:50 INFO - '_': '/tools/buildbot/bin/python'} 03:37:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:37:53 INFO - Downloading/unpacking pip>=1.5 03:37:53 INFO - Running setup.py egg_info for package pip 03:37:53 INFO - warning: no files found matching 'pip/cacert.pem' 03:37:53 INFO - warning: no files found matching '*.html' under directory 'docs' 03:37:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:37:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:37:53 INFO - Installing collected packages: pip 03:37:53 INFO - Found existing installation: pip 1.1 03:37:53 INFO - Uninstalling pip: 03:37:53 INFO - Successfully uninstalled pip 03:37:53 INFO - Running setup.py install for pip 03:37:53 INFO - warning: no files found matching 'pip/cacert.pem' 03:37:53 INFO - warning: no files found matching '*.html' under directory 'docs' 03:37:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:37:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:37:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:37:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:37:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:37:53 INFO - Successfully installed pip 03:37:53 INFO - Cleaning up... 03:37:53 INFO - Return code: 0 03:37:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:37: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')]} 03:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37: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')]} 03:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37: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', '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 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:37: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:37: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 psutil>=3.1.1 03:37:53 INFO - Using env: {'DISPLAY': ':0', 03:37:53 INFO - 'HOME': '/home/cltbld', 03:37:53 INFO - 'LANG': 'en_US.UTF-8', 03:37:53 INFO - 'LANGUAGE': 'en_US:en', 03:37:53 INFO - 'LOGNAME': 'cltbld', 03:37:53 INFO - 'MAIL': '/var/mail/cltbld', 03:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:53 INFO - 'MOZ_NO_REMOTE': '1', 03:37:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:53 INFO - 'NO_EM_RESTART': '1', 03:37:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:53 INFO - 'PWD': '/builds/slave/test', 03:37:53 INFO - 'SHELL': '/bin/bash', 03:37:53 INFO - 'SHLVL': '1', 03:37:53 INFO - 'TERM': 'linux', 03:37:53 INFO - 'TMOUT': '86400', 03:37:53 INFO - 'USER': 'cltbld', 03:37:53 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:53 INFO - '_': '/tools/buildbot/bin/python'} 03:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:54 INFO - Downloading/unpacking psutil>=3.1.1 03:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:37:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:55 INFO - Installing collected packages: psutil 03:37:55 INFO - Running setup.py install for psutil 03:37:55 INFO - building 'psutil._psutil_linux' extension 03:37: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 03:37:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:37:55 INFO - building 'psutil._psutil_posix' extension 03:37:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:37:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:37:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:37:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:37:55 INFO - Successfully installed psutil 03:37:55 INFO - Cleaning up... 03:37:55 INFO - Return code: 0 03:37:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:55 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 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:37:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:37:55 INFO - Using env: {'DISPLAY': ':0', 03:37:55 INFO - 'HOME': '/home/cltbld', 03:37:55 INFO - 'LANG': 'en_US.UTF-8', 03:37:55 INFO - 'LANGUAGE': 'en_US:en', 03:37:55 INFO - 'LOGNAME': 'cltbld', 03:37:55 INFO - 'MAIL': '/var/mail/cltbld', 03:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:55 INFO - 'MOZ_NO_REMOTE': '1', 03:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:55 INFO - 'NO_EM_RESTART': '1', 03:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:55 INFO - 'PWD': '/builds/slave/test', 03:37:55 INFO - 'SHELL': '/bin/bash', 03:37:55 INFO - 'SHLVL': '1', 03:37:55 INFO - 'TERM': 'linux', 03:37:55 INFO - 'TMOUT': '86400', 03:37:55 INFO - 'USER': 'cltbld', 03:37:55 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:55 INFO - '_': '/tools/buildbot/bin/python'} 03:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:37:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:37:57 INFO - Installing collected packages: mozsystemmonitor 03:37:57 INFO - Running setup.py install for mozsystemmonitor 03:37:57 INFO - Successfully installed mozsystemmonitor 03:37:57 INFO - Cleaning up... 03:37:57 INFO - Return code: 0 03:37:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:37:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:37:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:37:57 INFO - Using env: {'DISPLAY': ':0', 03:37:57 INFO - 'HOME': '/home/cltbld', 03:37:57 INFO - 'LANG': 'en_US.UTF-8', 03:37:57 INFO - 'LANGUAGE': 'en_US:en', 03:37:57 INFO - 'LOGNAME': 'cltbld', 03:37:57 INFO - 'MAIL': '/var/mail/cltbld', 03:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:57 INFO - 'MOZ_NO_REMOTE': '1', 03:37:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:57 INFO - 'NO_EM_RESTART': '1', 03:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:57 INFO - 'PWD': '/builds/slave/test', 03:37:57 INFO - 'SHELL': '/bin/bash', 03:37:57 INFO - 'SHLVL': '1', 03:37:57 INFO - 'TERM': 'linux', 03:37:57 INFO - 'TMOUT': '86400', 03:37:57 INFO - 'USER': 'cltbld', 03:37:57 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:57 INFO - '_': '/tools/buildbot/bin/python'} 03:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:57 INFO - Downloading/unpacking jsonschema==2.5.1 03:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:37:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:37:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:37:59 INFO - warning: no files found matching '*.txt' 03:37:59 INFO - no previously-included directories found matching 'build' 03:37:59 INFO - no previously-included directories found matching 'dist' 03:37:59 INFO - no previously-included directories found matching '.git*' 03:37:59 INFO - Installing collected packages: jsonschema, functools32 03:37:59 INFO - Running setup.py install for functools32 03:37:59 INFO - warning: no files found matching '*.txt' 03:37:59 INFO - no previously-included directories found matching 'build' 03:37:59 INFO - no previously-included directories found matching 'dist' 03:37:59 INFO - no previously-included directories found matching '.git*' 03:37:59 INFO - Successfully installed jsonschema functools32 03:37:59 INFO - Cleaning up... 03:37:59 INFO - Return code: 0 03:37:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:59 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 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:37:59 INFO - Using env: {'DISPLAY': ':0', 03:37:59 INFO - 'HOME': '/home/cltbld', 03:37:59 INFO - 'LANG': 'en_US.UTF-8', 03:37:59 INFO - 'LANGUAGE': 'en_US:en', 03:37:59 INFO - 'LOGNAME': 'cltbld', 03:37:59 INFO - 'MAIL': '/var/mail/cltbld', 03:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:59 INFO - 'MOZ_NO_REMOTE': '1', 03:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:59 INFO - 'NO_EM_RESTART': '1', 03:37:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:59 INFO - 'PWD': '/builds/slave/test', 03:37:59 INFO - 'SHELL': '/bin/bash', 03:37:59 INFO - 'SHLVL': '1', 03:37:59 INFO - 'TERM': 'linux', 03:37:59 INFO - 'TMOUT': '86400', 03:37:59 INFO - 'USER': 'cltbld', 03:37:59 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:59 INFO - '_': '/tools/buildbot/bin/python'} 03:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:37:59 INFO - Cleaning up... 03:37:59 INFO - Return code: 0 03:37:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:37:59 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 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:37:59 INFO - Using env: {'DISPLAY': ':0', 03:37:59 INFO - 'HOME': '/home/cltbld', 03:37:59 INFO - 'LANG': 'en_US.UTF-8', 03:37:59 INFO - 'LANGUAGE': 'en_US:en', 03:37:59 INFO - 'LOGNAME': 'cltbld', 03:37:59 INFO - 'MAIL': '/var/mail/cltbld', 03:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:59 INFO - 'MOZ_NO_REMOTE': '1', 03:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:37:59 INFO - 'NO_EM_RESTART': '1', 03:37:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:59 INFO - 'PWD': '/builds/slave/test', 03:37:59 INFO - 'SHELL': '/bin/bash', 03:37:59 INFO - 'SHLVL': '1', 03:37:59 INFO - 'TERM': 'linux', 03:37:59 INFO - 'TMOUT': '86400', 03:37:59 INFO - 'USER': 'cltbld', 03:37:59 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:59 INFO - '_': '/tools/buildbot/bin/python'} 03:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:00 INFO - Downloading/unpacking blobuploader==1.2.4 03:38: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 03:38: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 03:38: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 03:38: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 03:38:01 INFO - Downloading blobuploader-1.2.4.tar.gz 03:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:38:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:38: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 03:38: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 03:38: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 03:38: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 03:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:38:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:38: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 03:38: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 03:38: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 03:38: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 03:38:01 INFO - Downloading docopt-0.6.1.tar.gz 03:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:38:01 INFO - Installing collected packages: blobuploader, requests, docopt 03:38:01 INFO - Running setup.py install for blobuploader 03:38:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:38:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:38:01 INFO - Running setup.py install for requests 03:38:02 INFO - Running setup.py install for docopt 03:38:02 INFO - Successfully installed blobuploader requests docopt 03:38:02 INFO - Cleaning up... 03:38:02 INFO - Return code: 0 03:38:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38: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')]} 03:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38: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')]} 03:38:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:02 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 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:38:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:38:02 INFO - Using env: {'DISPLAY': ':0', 03:38:02 INFO - 'HOME': '/home/cltbld', 03:38:02 INFO - 'LANG': 'en_US.UTF-8', 03:38:02 INFO - 'LANGUAGE': 'en_US:en', 03:38:02 INFO - 'LOGNAME': 'cltbld', 03:38:02 INFO - 'MAIL': '/var/mail/cltbld', 03:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:02 INFO - 'MOZ_NO_REMOTE': '1', 03:38:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:02 INFO - 'NO_EM_RESTART': '1', 03:38:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:02 INFO - 'PWD': '/builds/slave/test', 03:38:02 INFO - 'SHELL': '/bin/bash', 03:38:02 INFO - 'SHLVL': '1', 03:38:02 INFO - 'TERM': 'linux', 03:38:02 INFO - 'TMOUT': '86400', 03:38:02 INFO - 'USER': 'cltbld', 03:38:02 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:02 INFO - '_': '/tools/buildbot/bin/python'} 03:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:02 INFO - Running setup.py (path:/tmp/pip-L4DZTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:02 INFO - Running setup.py (path:/tmp/pip-hrfRn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:02 INFO - Running setup.py (path:/tmp/pip-PcZBY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:02 INFO - Running setup.py (path:/tmp/pip-vXVLIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:02 INFO - Running setup.py (path:/tmp/pip-cZuA70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:02 INFO - Running setup.py (path:/tmp/pip-w8XBXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:02 INFO - Running setup.py (path:/tmp/pip-GZOCUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:03 INFO - Running setup.py (path:/tmp/pip-fotTJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:03 INFO - Running setup.py (path:/tmp/pip-j6DC_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:03 INFO - Running setup.py (path:/tmp/pip-gX8MLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:03 INFO - Running setup.py (path:/tmp/pip-ajYjkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:03 INFO - Running setup.py (path:/tmp/pip-W3XHmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:03 INFO - Running setup.py (path:/tmp/pip-jRAjiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:03 INFO - Running setup.py (path:/tmp/pip-edJIG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:03 INFO - Running setup.py (path:/tmp/pip-WPdcHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:03 INFO - Running setup.py (path:/tmp/pip-Y98tqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:03 INFO - Running setup.py (path:/tmp/pip-w7snv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:38:03 INFO - Running setup.py install for manifestparser 03:38:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:38:03 INFO - Running setup.py install for mozcrash 03:38:03 INFO - Running setup.py install for mozdebug 03:38:04 INFO - Running setup.py install for mozdevice 03:38:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for mozfile 03:38:04 INFO - Running setup.py install for mozhttpd 03:38:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for mozinfo 03:38:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for mozInstall 03:38:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for mozleak 03:38:04 INFO - Running setup.py install for mozlog 03:38:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for moznetwork 03:38:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:38:04 INFO - Running setup.py install for mozprocess 03:38:04 INFO - Running setup.py install for mozprofile 03:38:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Running setup.py install for mozrunner 03:38:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Running setup.py install for mozscreenshot 03:38:05 INFO - Running setup.py install for moztest 03:38:05 INFO - Running setup.py install for mozversion 03:38:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:38:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:38:05 INFO - Cleaning up... 03:38:05 INFO - Return code: 0 03:38:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38: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')]} 03:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38: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')]} 03:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:05 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 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:38:05 INFO - Using env: {'DISPLAY': ':0', 03:38:05 INFO - 'HOME': '/home/cltbld', 03:38:05 INFO - 'LANG': 'en_US.UTF-8', 03:38:05 INFO - 'LANGUAGE': 'en_US:en', 03:38:05 INFO - 'LOGNAME': 'cltbld', 03:38:05 INFO - 'MAIL': '/var/mail/cltbld', 03:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:05 INFO - 'MOZ_NO_REMOTE': '1', 03:38:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:05 INFO - 'NO_EM_RESTART': '1', 03:38:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:05 INFO - 'PWD': '/builds/slave/test', 03:38:05 INFO - 'SHELL': '/bin/bash', 03:38:05 INFO - 'SHLVL': '1', 03:38:05 INFO - 'TERM': 'linux', 03:38:05 INFO - 'TMOUT': '86400', 03:38:05 INFO - 'USER': 'cltbld', 03:38:05 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:05 INFO - '_': '/tools/buildbot/bin/python'} 03:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:05 INFO - Running setup.py (path:/tmp/pip-rWiS_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:05 INFO - Running setup.py (path:/tmp/pip-jBvbWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:05 INFO - Running setup.py (path:/tmp/pip-X74u7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:05 INFO - Running setup.py (path:/tmp/pip-IJ4O54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:06 INFO - Running setup.py (path:/tmp/pip-aXuRAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:06 INFO - Running setup.py (path:/tmp/pip-srNA53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:06 INFO - Running setup.py (path:/tmp/pip-z6mNcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:06 INFO - Running setup.py (path:/tmp/pip-xjtjxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:06 INFO - Running setup.py (path:/tmp/pip-ZdQq8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:06 INFO - Running setup.py (path:/tmp/pip-qRgzn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:06 INFO - Running setup.py (path:/tmp/pip-W264iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:06 INFO - Running setup.py (path:/tmp/pip-tT8VH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:06 INFO - Running setup.py (path:/tmp/pip-eKLvXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:06 INFO - Running setup.py (path:/tmp/pip-jvn7Jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:06 INFO - Running setup.py (path:/tmp/pip-Eot_0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:06 INFO - Running setup.py (path:/tmp/pip-CvWeTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:06 INFO - Running setup.py (path:/tmp/pip-W3X39Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:38:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:08 INFO - Downloading blessings-1.6.tar.gz 03:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:38:08 INFO - Installing collected packages: blessings 03:38:08 INFO - Running setup.py install for blessings 03:38:08 INFO - Successfully installed blessings 03:38:08 INFO - Cleaning up... 03:38:08 INFO - Return code: 0 03:38:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:08 INFO - Reading from file tmpfile_stdout 03:38:08 INFO - Current package versions: 03:38:08 INFO - argparse == 1.2.1 03:38:08 INFO - blessings == 1.6 03:38:08 INFO - blobuploader == 1.2.4 03:38:08 INFO - docopt == 0.6.1 03:38:08 INFO - functools32 == 3.2.3-2 03:38:08 INFO - jsonschema == 2.5.1 03:38:08 INFO - manifestparser == 1.1 03:38:08 INFO - mozInstall == 1.13 03:38:08 INFO - mozcrash == 1.0 03:38:08 INFO - mozdebug == 0.1 03:38:08 INFO - mozdevice == 0.51 03:38:08 INFO - mozfile == 1.2 03:38:08 INFO - mozhttpd == 0.7 03:38:08 INFO - mozinfo == 0.10 03:38:08 INFO - mozleak == 0.1 03:38:08 INFO - mozlog == 3.5 03:38:08 INFO - moznetwork == 0.27 03:38:08 INFO - mozprocess == 0.25 03:38:08 INFO - mozprofile == 0.28 03:38:08 INFO - mozrunner == 6.13 03:38:08 INFO - mozscreenshot == 0.1 03:38:08 INFO - mozsystemmonitor == 0.3 03:38:08 INFO - moztest == 0.8 03:38:08 INFO - mozversion == 1.4 03:38:08 INFO - psutil == 3.1.1 03:38:08 INFO - requests == 1.2.3 03:38:08 INFO - wsgiref == 0.1.2 03:38:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38: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')]} 03:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38: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')]} 03:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:08 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 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:38:08 INFO - Using env: {'DISPLAY': ':0', 03:38:08 INFO - 'HOME': '/home/cltbld', 03:38:08 INFO - 'LANG': 'en_US.UTF-8', 03:38:08 INFO - 'LANGUAGE': 'en_US:en', 03:38:08 INFO - 'LOGNAME': 'cltbld', 03:38:08 INFO - 'MAIL': '/var/mail/cltbld', 03:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:08 INFO - 'MOZ_NO_REMOTE': '1', 03:38:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:08 INFO - 'NO_EM_RESTART': '1', 03:38:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:08 INFO - 'PWD': '/builds/slave/test', 03:38:08 INFO - 'SHELL': '/bin/bash', 03:38:08 INFO - 'SHLVL': '1', 03:38:08 INFO - 'TERM': 'linux', 03:38:08 INFO - 'TMOUT': '86400', 03:38:08 INFO - 'USER': 'cltbld', 03:38:08 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:08 INFO - '_': '/tools/buildbot/bin/python'} 03:38:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:38:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:38:09 INFO - Installing collected packages: simplejson 03:38:09 INFO - Running setup.py install for simplejson 03:38:10 INFO - building 'simplejson._speedups' extension 03:38:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:38:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:38:10 INFO - Successfully installed simplejson 03:38:10 INFO - Cleaning up... 03:38:10 INFO - Return code: 0 03:38:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:10 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 0x15aa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1773a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1916a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:38:10 INFO - Using env: {'DISPLAY': ':0', 03:38:10 INFO - 'HOME': '/home/cltbld', 03:38:10 INFO - 'LANG': 'en_US.UTF-8', 03:38:10 INFO - 'LANGUAGE': 'en_US:en', 03:38:10 INFO - 'LOGNAME': 'cltbld', 03:38:10 INFO - 'MAIL': '/var/mail/cltbld', 03:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:10 INFO - 'MOZ_NO_REMOTE': '1', 03:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:10 INFO - 'NO_EM_RESTART': '1', 03:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:10 INFO - 'PWD': '/builds/slave/test', 03:38:10 INFO - 'SHELL': '/bin/bash', 03:38:10 INFO - 'SHLVL': '1', 03:38:10 INFO - 'TERM': 'linux', 03:38:10 INFO - 'TMOUT': '86400', 03:38:10 INFO - 'USER': 'cltbld', 03:38:10 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:11 INFO - '_': '/tools/buildbot/bin/python'} 03:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:38:11 INFO - Cleaning up... 03:38:11 INFO - Return code: 0 03:38:11 INFO - Running post-action listener: _resource_record_post_action 03:38:11 INFO - Running post-action listener: _start_resource_monitoring 03:38:11 INFO - Starting resource monitoring. 03:38:11 INFO - [mozharness: 2017-09-20 10:38:11.238175Z] Finished create-virtualenv step (success) 03:38:11 INFO - [mozharness: 2017-09-20 10:38:11.238689Z] Running install step. 03:38:11 INFO - Running pre-action listener: _resource_record_pre_action 03:38:11 INFO - Running main action method: install 03:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:11 INFO - Reading from file tmpfile_stdout 03:38:11 INFO - Detecting whether we're running mozinstall >=1.0... 03:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:38:11 INFO - Reading from file tmpfile_stdout 03:38:11 INFO - Output received: 03:38:11 INFO - Usage: mozinstall [options] installer 03:38:11 INFO - Options: 03:38:11 INFO - -h, --help show this help message and exit 03:38:11 INFO - -d DEST, --destination=DEST 03:38:11 INFO - Directory to install application into. [default: 03:38:11 INFO - "/builds/slave/test"] 03:38:11 INFO - --app=APP Application being installed. [default: firefox] 03:38:11 INFO - mkdir: /builds/slave/test/build/application 03:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:38:29 INFO - Reading from file tmpfile_stdout 03:38:29 INFO - Output received: 03:38:29 INFO - /builds/slave/test/build/application/firefox/firefox 03:38:29 INFO - Running post-action listener: _resource_record_post_action 03:38:29 INFO - [mozharness: 2017-09-20 10:38:29.396147Z] Finished install step (success) 03:38:29 INFO - [mozharness: 2017-09-20 10:38:29.396398Z] Running setup-mitmproxy step. 03:38:29 INFO - Running pre-action listener: _resource_record_pre_action 03:38:29 INFO - Running main action method: setup_mitmproxy 03:38:29 INFO - Skipping: mitmproxy is not required 03:38:29 INFO - Running post-action listener: _resource_record_post_action 03:38:29 INFO - [mozharness: 2017-09-20 10:38:29.397549Z] Finished setup-mitmproxy step (success) 03:38:29 INFO - [mozharness: 2017-09-20 10:38:29.397771Z] Running run-tests step. 03:38:29 INFO - Running pre-action listener: _resource_record_pre_action 03:38:29 INFO - Running pre-action listener: _set_gcov_prefix 03:38:29 INFO - Running main action method: run_tests 03:38:29 WARNING - Try message not found. 03:38:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:38:29 INFO - Python 2.7.3 03:38:29 INFO - Return code: 0 03:38:29 INFO - grabbing minidump binary from tooltool 03:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:29 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 0x191a090>, '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 0x191ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1914f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:38:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:38:29 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:38:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:38:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:38:29 INFO - Return code: 0 03:38:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:38:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:38:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:38:29 INFO - ENV: RUST_BACKTRACE is now full 03:38:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:38:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:38:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:38:29 INFO - Using env: {'DISPLAY': ':0', 03:38:29 INFO - 'HOME': '/home/cltbld', 03:38:29 INFO - 'LANG': 'en_US.UTF-8', 03:38:29 INFO - 'LANGUAGE': 'en_US:en', 03:38:29 INFO - 'LOGNAME': 'cltbld', 03:38:29 INFO - 'MAIL': '/var/mail/cltbld', 03:38:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:38:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:29 INFO - 'MOZ_NO_REMOTE': '1', 03:38:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:38:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:29 INFO - 'NO_EM_RESTART': '1', 03:38:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:29 INFO - 'PWD': '/builds/slave/test', 03:38:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:38:29 INFO - 'RUST_BACKTRACE': 'full', 03:38:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:38:29 INFO - 'SHELL': '/bin/bash', 03:38:29 INFO - 'SHLVL': '1', 03:38:29 INFO - 'TERM': 'linux', 03:38:29 INFO - 'TMOUT': '86400', 03:38:29 INFO - 'USER': 'cltbld', 03:38:29 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:29 INFO - '_': '/tools/buildbot/bin/python'} 03:38:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:38:29 INFO - mozversion application_buildid: 20170920093924 03:38:29 INFO - mozversion application_changeset: c0769d228dd3ba666d6e9b02896bebef7e996ad5 03:38:29 INFO - mozversion application_display_name: Nightly 03:38:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:38:29 INFO - mozversion application_name: Firefox 03:38:29 INFO - mozversion application_remotingname: firefox 03:38:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:38:29 INFO - mozversion application_vendor: Mozilla 03:38:29 INFO - mozversion application_version: 57.0a1 03:38:29 INFO - mozversion platform_buildid: 20170920093924 03:38:29 INFO - mozversion platform_changeset: c0769d228dd3ba666d6e9b02896bebef7e996ad5 03:38:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:38:29 INFO - mozversion platform_version: 57.0a1 03:38:29 INFO - using testdate: 1505903909 03:38:29 INFO - actual date: 1505903909 03:38:29 INFO - starting webserver on 'localhost:44461' 03:38:29 INFO - SUITE-START | Running 2 tests 03:38:29 INFO - TEST-START | dromaeo_css 03:38:29 INFO - Initialising browser for dromaeo_css test... 03:38:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44461/getInfo.html -profile /tmp/tmpTwyOLn/profile 03:38:29 INFO - TEST-INFO | started process 7277 (/builds/slave/test/build/application/firefox/firefox http://localhost:44461/getInfo.html) 03:38:35 INFO - TEST-INFO | 7277: exit 0 03:38:35 INFO - Browser initialized. 03:38:35 INFO - Running cycle 1/1 for dromaeo_css test... 03:38:35 INFO - Using env: {'DISPLAY': ':0', 03:38:35 INFO - 'HOME': '/home/cltbld', 03:38:35 INFO - 'JSGC_DISABLE_POISONING': '1', 03:38:35 INFO - 'LANG': 'en_US.UTF-8', 03:38:35 INFO - 'LANGUAGE': 'en_US:en', 03:38:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:38:35 INFO - 'LOGNAME': 'cltbld', 03:38:35 INFO - 'MAIL': '/var/mail/cltbld', 03:38:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:38:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:38:35 INFO - 'MOZ_CRASHREPORTER': '1', 03:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:38:35 INFO - 'MOZ_NO_REMOTE': '1', 03:38:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:38:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:35 INFO - 'NO_EM_RESTART': '1', 03:38:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:35 INFO - 'PWD': '/builds/slave/test', 03:38:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:38:35 INFO - 'RUST_BACKTRACE': 'full', 03:38:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:38:35 INFO - 'SHELL': '/bin/bash', 03:38:35 INFO - 'SHLVL': '1', 03:38:35 INFO - 'TERM': 'linux', 03:38:35 INFO - 'TMOUT': '86400', 03:38:35 INFO - 'USER': 'cltbld', 03:38:35 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:35 INFO - '_': '/tools/buildbot/bin/python'} 03:38:35 INFO - TEST-INFO | started process 7512 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTwyOLn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:38:36 INFO - PID 7512 | 03:38:36 INFO - PID 7512 | (/builds/slave/test/build/application/firefox/firefox:7561): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:38:36 INFO - PID 7512 | 03:38:46 INFO - PID 7512 | RSS: Main: 154996736 03:38:46 INFO - PID 7512 | 03:41:44 INFO - PID 7512 | Cycle 1(1): loaded http://localhost:44461/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44461/tests/dromaeo/cssquery-ext.html) 03:41:45 INFO - PID 7512 | RSS: Main: 155541504 03:41:45 INFO - PID 7512 | 03:44:47 INFO - PID 7512 | Cycle 1(1): loaded http://localhost:44461/tests/dromaeo/cssquery-ext.html (next: http://localhost:44461/tests/dromaeo/cssquery-jquery.html) 03:44:47 INFO - PID 7512 | RSS: Main: 155009024 03:44:47 INFO - PID 7512 | 03:47:49 INFO - PID 7512 | Cycle 1(1): loaded http://localhost:44461/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44461/tests/dromaeo/cssquery-mootools.html) 03:47:50 INFO - PID 7512 | RSS: Main: 155725824 03:47:50 INFO - PID 7512 | 03:50:51 INFO - PID 7512 | Cycle 1(1): loaded http://localhost:44461/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44461/tests/dromaeo/cssquery-prototype.html) 03:50:52 INFO - PID 7512 | RSS: Main: 155312128 03:50:52 INFO - PID 7512 | 03:53:55 INFO - PID 7512 | Cycle 1(1): loaded http://localhost:44461/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44461/tests/dromaeo/cssquery-yui.html) 03:53:56 INFO - PID 7512 | RSS: Main: 155922432 03:53:56 INFO - PID 7512 | 03:56:57 INFO - PID 7512 | Cycle 1(1): loaded http://localhost:44461/tests/dromaeo/cssquery-yui.html (next: http://localhost:44461/tests/dromaeo/cssquery-dojo.html) 03:56:57 INFO - PID 7512 | RSS: Main: 159174656 03:56:57 INFO - PID 7512 | 03:56:57 INFO - PID 7512 | __start_tp_report 03:56:57 INFO - PID 7512 | _x_x_mozilla_page_load 03:56:57 INFO - PID 7512 | _x_x_mozilla_page_load_details 03:56:57 INFO - PID 7512 | |i|pagename|runs| 03:56:57 INFO - PID 7512 | |0;dojo.html;3140.9685903140967;3170.6195256569217;3176.317091825257;3192.3934452454046;3209.5185722141678;5809.796657117002;6196.473299769514;6361.872762544758;6396.648184349859;6478.870422591545;6255.749770009194;6301.621902685831;6313.400226978441;6326.65203413812;6338.492920566355;5852.531797456215;5898.941010589882;5906.704664766745;5935.584509084366;5964.165016897616;5898.056310990221;5917.704114794265;5944.970275148618;5987.910181347262;5988.790392336269;1275.829557949805;5569.8607534811545;5787.942120578803;5834;5918.644881307092;5822.446867547576;5828.0966450200285;5851.473367396912;5897.085951677497;5933.643981361132;5794.188813566104;5811.070228763378;5872.853178670524;5881.970590147064;5885.087811389241;6254.843628909268;6311.968440157816;6338.873222535523;6392.105105285264;6403.8399040023905;6797.626130562817;6844.726210951556;6882.346177113216;6900.413464855494;6933.37599616037;6666.300038495985;6762.560433571852;6771.966140169267;6774.424173945171;6784.982252662091;6309.747610095636;6320.968395158041;6341.460975817061;6403.711832967528;6425.871482570323;7056.953483489833;7119.750808721774;7129.108861392326;7133;7134.750283740044;6721.764738234237;6729.125213722283;6778.423833974069;6811;6811.318868113248;6709.798706038827;6756.121704178426;6829.52193346461;6844.384005439534;6939.306069393121;6799.796006119825;6842.349976752201;6845.418139458102;6886.621235831977;6917.481188910751;6884.173899132136;6941.028256044158;6963.09479767627;6964.129483814523;6979.581225126509;5364.517193452608;5370.113931201308;5423.267858839085;5424.891502169935;5425.077736784677;4589.5181005994555;4673.929891051637;4694.835680751157;4701.200795864643;4729.574338309569;5905.940940590625;5921.851953701106;5935.910961335583;5937.050071988461;5943.167956486095;6893.689783959734;6898;6991;6998.860022799618;7002.264762199998;5880.588358814842;5885.852853678693;5941.197938278277;5954.523638108941;5968.104784282393;5713.771449142071;5801.274840644919;5897.144913987448;5976.402359763989;5981.162637230791;6676.732930682719;6680.966595166993;6688.030235615905;6692.263850976388;6709.127813384229;6808.455323574003;6891.345322194384;6985.930140698528;6993.055937448481;7000.719971201095;6615.801525954229;6622.039804228263;6676.9332306676315;6699.39705426514;6714.899276510759;6577.276499585138;6682.231543372574;6743.629100399525;6804.115464989718;6825.761098361732;6824.454043676395;6954.165500140016;6955.234924158237;7000.929990700231;7004.474664400293;6670.099536562696;6676.265610782907;6767.154105736783;6774.085498457842;6778.356056174655;6593.406593406617;6636.435902948304;6703.631300278532;6709.194896612437;6714.362135597111;6636.900446493403;6659.334066593302;6660.167479065117;6661.600303981777;6663.766768163091;6643.335666433318;6656.7337306509135;6726.932730672631;6776.356246156607;6776.96611516959;6459.257185423548;6467.741290348334;6536.673166341759;6543.1821022372205;6590.044443555754 03:56:57 INFO - PID 7512 | |1;ext.html;857.4255248983181;880.3749337970041;880.9779755506112;881.0880738435718;889.3063410539777;41380.586194138086;41404.58595414045;41913.371299430495;41979.58020419795;42098.158036839246;37652;37885.810570947106;38003.61996380035;38099.23801523968;38143.809280953625;7036.226015138331;7102.609356485391;7111.679974401164;7130.393916517102;7163.14042314923;7079.61062141582;7185.993960845486;7202.387797037232;7209.1349038115495;7218.639068046603;7878.881816772753;8108.797280067986;8134.430589858713;8143.267105960492;8146.877796833052;7842.725504607311;7991.840163196762;8029.959850200741;8038.758837234892;8054.315383192377;7830.76507704764;7874.330681892046;7897.486663366863;7899.644515996794;7902.4863383879865;37907;38036;38153;38192.23615527674;38215.8089209555;3423.5891692997;3475.3570589441033;3483.390406678821;3484.0418884806627;3486.38988177068;31452.21369465759;31535.526967095513;31610;31644.841775791203;31706.524402133986;5165.74171291434;5218.634695571312;5221.582273418156;5235.842924712265;5286.577073834084;186034;187411;187692;187892;188132;42709;43352.78323608362;47937.520624794;48240.5175948236;48389.27416088762;50176.749116254185;50235.49764502308;50375.24437133446;50389.74805126024;50545.49454505481;15673.294701738221;15831.366745330288;15856.127912964666;15885.523434296989;15944.920275398548;12891.484340626457;13150.21098734079;13155.276459794803;13161.144525605996;13169.604911852659;13745.656358590915;13760.449582016807;13827;13865;13937.58187254384;5567.3040869891265;5647.237622920935;5652.717364131777;5658.924804287172;5683.318001839806;1022.5756311130798;1027.3476342522551;1045.850776653968;1046.2989796836139;1047.5652604169184;22950.770492295196;23176.768232317798;23181.88409057944;23240.53518929612;23368.883155584113;1132.881047490018;1146.306484576819;1147.8679951805482;1149.5114576305205;1152.2510368260698;12265.509379624893;12282.14025018258;12402.13185077036;12433.93783031079;12557.56048538315;224351;226598;227070;227198;227264;54071;54527.72736136294;54546.18180727292;54744.17883731747;54819;1386.6325423762712;1440.488626537585;1441.4018182454165;1454.9563513094624;1469.3387975411108;22993.77006229916;23015.654765178202;23079.88460057689;23286;23443.296701098996;37539.249215015;37675.2464950694;37835.05412364713;37863.43204851874;37872;189118;189157;189626;190011;190094;30977.225569360045;31435.214119647193;31482;33232.833835830665;34208;41917.58082419137;43118.56881431145;43457.3481397773;43799.56200437955;44074.77962610166;31703.84148079245;32039.83980080178;32057.67942320547;32347.514787277738;32396.352072957936;31240;31678.891238806533;31801.8409907949;31834.522482162323;32470;34909;35272;35348.82325588356;35709.285814284085;35720.464193037646;30781.384372312878;30977.69022309748;31091.84454077715;31225.687743123188;31238.531422028234;22703.659445108668;22991.007903658305;23206.883965580066;23691.881540592185;23782.643260351455 03:56:57 INFO - PID 7512 | |2;jquery.html;554.8474169603359;562.9746661400237;567.1719289836838;590.8079874040936;607.5868409481551;21998.99999999998;22782;22800.65799013014;22843.885780571116;22898.77101228987;23869.28392148234;24740.50518989619;24995.875020624917;25282.62076068856;25618;6563.671816409173;6758.155230596176;6762.729490820361;6763.357481039294;6768.898466523006;6575.4082132608055;6643.933560664404;6680.198376194863;6681.632510211936;6700.83247918801;6609.306022867602;6616.139901812758;6671.59970401775;6682.632455214961;6683.064370988066;6293.716782744765;6581.913984192626;6604.504662150358;6604.537682362236;6608.537402381835;6133.6319820810895;6144.477719393857;6158.1686472326555;6158.353372895826;6179.289381721114;28672.426551469063;28720.281992950135;29046.27384315388;29080.854595727098;29146;2007.9598408031904;2044.9795502044938;2045.1410407628832;2047.4471892588917;2048.201201531404;16842.157892105468;18470.907645461823;19051.714224286647;19086.61826763471;19155.329563465213;2341.297610716795;2371.6086845670443;2413.2760171948344;2423.491066875958;2424.078850036992;115555.42222288919;123196.38401808024;129361;132376.33811830977;141739.58260417366;14421;14460.2029467634;15145.848541514444;15260.618484537976;15312;22332.776672233394;22758.20346287872;22764.317070487912;22840.65739013916;22888.198913037962;10025.699229023141;10139.493025348704;10149.543270552695;10184.694459166238;10242.795144097225;9204.95397523008;9276.582553785096;9346.626134954526;9366.29752768545;9413.294002949806;8833.469991800512;8958.462492250486;9021.909780902239;9159.404638698597;9339.953300233592;2543.0537604359342;2561.8078644101765;2594.208766326251;2651.84644679565;2684.8389096654264;966.5022513405602;999.5551979369111;999.5801763259449;1008.7578981044643;1014.8173328800887;19508;19760.802391976184;19808.40574782759;19872.60254794925;20017.399478015683;1021.933574317667;1046.9267151299337;1065.9786804263872;1067.4502631144967;1071.9785604288027;10534.157267418588;10936;10973.451327433757;11101.333919964827;11229.887701123047;480533;481392;488449;493212;498529;105646.47176764067;106813.46593267139;107855;108024;109234;1076.8115579773507;1124.5895248234335;1187.6080893305189;1194.5341316886422;1231.963041108786;21086.578268434238;21182.682259765807;21220.151193952068;21221.7877821226;21323.893380532998;54848.725756370965;55105;55448.44551554433;56042.719786400805;56931;69705;69976;70156;70563.29436705772;71537.28462715514;14309.856901430852;14655;14733.116013039253;14736;14894.851051489346;23125.306240812803;23410;23599;23777.286681399586;24006;18726.25094996185;18744.343947961755;18862.339818106302;18864.6227075455;18922.810771892655;15816.76274855855;15970.680586387974;16921.746173807158;16959.830401695825;17492.300307987538;19079.809201907803;19133.425997220107;19203.519912001753;19280.517987050436;19320.227190912767;14455.421783128977;14502.637434064232;14506.492272770402;14506.927465362605;14550.927245363706;18252.634947301245;18295.17671704803;18403;18649;18651.72022419692 03:56:57 INFO - PID 7512 | |3;mootools.html;1364.983087599738;1427.471835420893;1429.5211104280063;1431.2485944878938;1436.6911114110464;15371.615709607251;15981;15981.760273595877;15988.12065336406;16019.279132439038;15660.765088523682;15713.92143039286;15721.60695982601;15764.448244311452;15772.21138943054;2722.9319267018286;2804.2288370698016;2816.5352716801704;2818.3658676797763;2822.111035023971;2693.5690289553677;2800.985995070022;2802.985985070072;2806.116073436871;2806.985965070182;2613.73862613739;2714.986425067872;2835.206142280165;2835.8440285784272;2835.9291017724518;2789.6512935883015;2814.352698879259;2842.019503271368;2846.615706879576;2856.5143925532607;2666.240121565364;2760.7791376690016;2767.33583939855;2813.3248020475144;2824.3503994081475;15328;15334.846651533453;15436.768448473282;15459.149746763924;15474.381024758995;8144.307733842571;8437.831243375098;8439.535825529594;8444.408891377545;8446.704365347183;5338.480096501809;5461.972690136524;5538.307711536058;5597.188407680864;5640.830775076754;1996.550776075386;2011.1352118589;2014.4158194123686;2047.682609195577;2059.5468996820678;27406.314842128908;27666.72333276682;27673.446531069265;27677.446451070868;27699.169024929284;6726.293739157416;6768.390844823891;6928.1339832520935;7229.313215244649;7254.056972593635;17967.55081122956;17993;18012.279508819764;18014.729779053323;18033.639327213383;7746.457747957702;7781.494202876795;7926.682932682628;8307.916920830836;8358.45670031446;7266.164391094986;7314.3782778463365;7316.304951029643;7324.194338622748;7397.075365579302;5125.948740512622;5283.9471605284225;5299.814506492255;5307.946920530822;5334.973325133427;1588.9602759931095;1590.8250092489816;1595.920203989796;1602.6857976459426;1605.3498333175082;826.1696994520588;836.0844874862016;838.2958315015417;839.8950131233596;854.3549620036832;6879.174499060045;6900.758473453405;6916.23921368649;7033.8944915826305;8894.86657700135;682.0825989044727;699.0388216202721;701.6458235605229;702.8067281497629;710.733474946895;5066.240063990431;5369.40936496985;5387.326584176978;5451.291332126798;5489.286392768914;21737.34787956364;22044.779552204272;22395.21616743406;22649;22683.20608778685;22436.887815561146;22695.205667801547;22823.543529129656;22840.65739013916;22872.31383058511;11008.449577521253;11613.187076904704;11621.418929053514;11696.824547631792;11734.295942243492;9042.276617870422;9145.131212534798;9170.128837760401;9180.770480738034;9202.447853128833;19355.61288774245;19389;19449.708254376477;19829.20683172657;20174.193032278545;19347.51631209231;20025.6996145055;20236.59526809426;20264.493387665425;20303.08636111349;12384.56654017105;12720.363981801058;12726.363681815687;12728.872711272768;12748.61754147377;13504.527341542998;14316.856831431553;14448.410674825971;14461.349239284467;14492.85507144915;11676.766464670829;11905.226160299553;12101.273923564615;12118.454669540068;12174.14780965324;10836.512356943798;11379.886201137882;11539.71150721205;11659.417029148339;11809.881901180877;9394.389364691273;9600.375975561567;9605.903940960501;9656;9669.323147379897;7269.454790890599;7371.262873712801;7378.114626244885;7421.888671670037;7459.36595389398;7278.563286202845;7493.213212612597;7499.850003000019;7510.849783004419;7549.1695913448375 03:56:57 INFO - PID 7512 | |4;prototype.html;513.6917849290426;516.7700373333865;531.9813806516772;536.4501386079265;542.2706459811554;6138.662373569452;6255.280642726082;6294.276158241803;6295.370462953702;6328.683565821713;6182.969085154579;6254.84362890928;6266.342034086424;6269.5297852661115;6270.278917924428;5181.378234611851;5182.533571978521;5191.5846732261325;5208.401033881114;5230.215467679859;5078.289039534468;5144.408393034792;5159.2003239497935;5166.974165129169;5173.948260517384;4749.358836557072;4889.413270407556;4967.950320496803;4968.254761785725;4985.977874535712;4861.82983595576;4915.606751459874;4956.578690811285;4960;4975.875603109933;4298.226319262531;4450.888727781799;4472.865814025585;4486.7083639563325;4507.323901414781;6014.51883849291;6040.57715959883;6056.182415373913;6077.908831367533;6132.908006379907;5611.494965453129;5623.847111342165;5636.859078523029;5666.093425051972;5678.971605141989;6495.415412612888;6516.446102081329;6527.902081468782;6538.705758240891;6565.80302590923;4634.281686338623;4859.829905953275;4869.586085182764;4884.12085824555;4885.413750349981;6651.634160121192;6659.267480577133;6691.464682825411;6856.965715171442;6888.965555172243;4924.975375123101;4925.359703238628;4955.801767929314;4988.6757360771435;5016.44819069902;4945.208766597327;4978.2034874419915;5038.395392552917;5077.923831142536;5094.108531007059;5653.152027195872;5655.89710006553;5667.574931880043;5685.459881311269;5700.572457065654;6624;6624.933750662528;6632.5688830226845;6655;6665.233498147775;1727.153694689618;1738.3394310162057;1740.4691569071554;1770.2299499717665;1775.2721384232661;1160.1530882455854;1198.017625547043;1209.1500004992376;1211.836402085707;1230.6492649594823;5621.510299770564;5624.128260119688;5674.148877668383;5676.25367257608;5684.801031963944;6583.177102862142;6583.275839657634;6611.471082313404;6640.667966601651;6685.197776266879;5494.450554944474;5547.25112109868;5556.611037227436;5635.5773317001385;5693.544516438675;3489.4940233666484;3515.402381595135;3520.1375662962228;3554.595934605816;3559.4126969050335;6393.520485963571;6398.776042838478;6419.550631455847;6424.582402143846;6477.611343319321;6849.349311815469;6856.142982127134;6871.518993670495;6905.654717264217;6919.8962015569805;5795.855103622359;6332.52506062038;6468.3855033771715;6487.610743355413;6525.836854078686;6398.648074355955;6455.806325810233;6487.994360874072;6529.347065293433;6611.8511483768925;6564.540482166202;6601.557423753242;6630.602163870183;6709.93290067093;6745.9662701686175;6407.615543067431;6446;6458.354164583504;6480.837979050561;6486.05952136947;4964.156093464192;5029.698218106925;5034.942662041012;5037.874053148701;5039.563724338546;6302.3215133568965;6580.868382632416;6590.143281373391;6733.508601817359;6769.153855768029;6368.840778980562;6427.582207156707;6463.547551671338;6546.672666366568;6631.602103873783;6448.9032664511005;6466.805995820133;6525.2169739631545;6529.869402611826;6546.476281897341;6350.428461438308;6396.136521569529;6429.164208653032;6450.451711604566;6457.225132984072;6524.67376631176;6613.867722645424;6637.734490620321;6648.301928297459;6691.1636045494315;6325.683715814283;6337.397947195009;6339.619622822645;6340.017297318923;6410.230772307353 03:56:57 INFO - PID 7512 | |5;yui.html;927.5547737086198;933.2440723014358;948.9335746497744;951.7620594851285;952.5142177489487;19945.401637950865;20541.38375848723;20552.280670176548;20561.588768224625;20619.587608247846;16435.424760133366;16444.917775411137;16523.50429487114;16547.83452165481;16580.66838663229;939.1876027236442;973.3964941736098;973.732223638498;978.0806042320232;978.0952618827591;986.990130098697;989.1048601016056;989.5596459575492;990.4700984973041;992.9205663546935;1001.1540248490014;1014.1278500489573;1032.1691038713832;1043.3114144664523;1044.6082718980383;1026.1329176845554;1026.2457094035879;1026.7176526455248;1027.4811220333759;1028.686250693538;718.881384571545;742.517363713585;750.9436792240604;752.3341842469454;753.8793792993094;20681;20878.686819697716;20897.268595599082;20902.059407326706;20903;21638.783612163836;21646.458838529004;22461.663075053722;22664.43338916524;22759.43101422461;17029.574260643458;17289.13554322279;17320.740188897176;17556;17559.385421510186;3763.5483741950866;3789.2232092420986;3796.7532110413013;3801.182745709658;3802.448644946495;107080;138828;139629;139679;141863;17154.39959601408;17807.19867605961;17824.643507129786;17896.105194740212;17918;32058.83970580132;32093.839530802663;32469.18827029343;32487.675123248937;32652.346953060805;8981.236594889377;9266.073392660815;9318.161365477139;9340.859887101564;9367.531623418801;4322.935155972663;4366.039471316369;4383.561643835655;4390.53899340571;4410.272305069628;5196.558292545176;5528.668279903219;5617.129344951539;5625.296837895263;5660.179274005246;1100.741325788439;1120.6413947536873;1130.5421304371744;1132.297975255347;1137.533611219396;595.0657467775551;603.3966033966034;605.881853038662;619.3249358199494;622.6233128957007;11630.88369116298;12406.25562466255;12712.364381780873;13132.934335328262;13178.341082945813;612.1674522649193;613.8772245550907;616.8797084568466;624.4255285137684;625.6465097220056;5867.765289388462;5914.556408269397;5942.940570594325;5947.732352044169;5982.4316689914385;127230;128374;128516;129281.70718292885;130428.347858262;108417;110904;111199.44400278109;112718.43640781744;115454;94363;96205.51897240609;97112.02887971171;100166.99833001723;101479.492602538;10966.616168434066;11113.277636953895;11180.217384782987;11261;11523.884761152281;44279.77860110679;46626.53373466358;46821.53178468172;47057.29414058723;47518;57591.712041439525;59294.70352648209;60146;60354;61175.388246118746;15090.622734431727;15253.23733813327;15254.08475491474;15404.998675086084;15469.767953480932;17347.56631084233;17481.47555573335;17552.12239388051;17608.38370657072;17718.46844594664;16035.118068506345;16256.105914174834;16262.10558419251;16272.91863540722;16438.424655137012;16496.67006659884;16539.50381488557;16590.336386544885;16693.332266709196;16795.748063779298;14789.334479948213;15394.615134621725;15644.765328520309;15975;16074.919625401799;11477.885221147682;11831.34927578957;11918.165728398928;11920.880791192323;12066.577669781516;12555.686107847378;12569;12612.873871261538;12748;12920.547780827626 03:56:57 INFO - PID 7512 | __end_tp_report 03:56:57 INFO - PID 7512 | __start_cc_report 03:56:57 INFO - PID 7512 | _x_x_mozilla_cycle_collect,272 03:56:57 INFO - PID 7512 | __end_cc_report 03:56:57 INFO - PID 7512 | __startTimestamp1505905017966__endTimestamp 03:56:57 INFO - PID 7512 | 03:56:57 INFO - PID 7512 | ------- Summary: start ------- 03:56:57 INFO - PID 7512 | Number of tests: 6 03:56:57 INFO - PID 7512 | 03:56:57 INFO - PID 7512 | [#0] dojo.html Cycles:165 Average:6274.02 Median:6615.80 stddev:864.58 (13.1%) stddev-sans-first:831.56 03:56:57 INFO - PID 7512 | Values: 3141.0 3170.6 3176.3 3192.4 3209.5 5809.8 6196.5 6361.9 6396.6 6478.9 6255.7 6301.6 6313.4 6326.7 6338.5 5852.5 5898.9 5906.7 5935.6 5964.2 5898.1 5917.7 5945.0 5987.9 5988.8 1275.8 5569.9 5787.9 5834.0 5918.6 5822.4 5828.1 5851.5 5897.1 5933.6 5794.2 5811.1 5872.9 5882.0 5885.1 6254.8 6312.0 6338.9 6392.1 6403.8 6797.6 6844.7 6882.3 6900.4 6933.4 6666.3 6762.6 6772.0 6774.4 6785.0 6309.7 6321.0 6341.5 6403.7 6425.9 7057.0 7119.8 7129.1 7133.0 7134.8 6721.8 6729.1 6778.4 6811.0 6811.3 6709.8 6756.1 6829.5 6844.4 6939.3 6799.8 6842.3 6845.4 6886.6 6917.5 6884.2 6941.0 6963.1 6964.1 6979.6 5364.5 5370.1 5423.3 5424.9 5425.1 4589.5 4673.9 4694.8 4701.2 4729.6 5905.9 5921.9 5935.9 5937.1 5943.2 6893.7 6898.0 6991.0 6998.9 7002.3 5880.6 5885.9 5941.2 5954.5 5968.1 5713.8 5801.3 5897.1 5976.4 5981.2 6676.7 6681.0 6688.0 6692.3 6709.1 6808.5 6891.3 6985.9 6993.1 7000.7 6615.8 6622.0 6676.9 6699.4 6714.9 6577.3 6682.2 6743.6 6804.1 6825.8 6824.5 6954.2 6955.2 7000.9 7004.5 6670.1 6676.3 6767.2 6774.1 6778.4 6593.4 6636.4 6703.6 6709.2 6714.4 6636.9 6659.3 6660.2 6661.6 6663.8 6643.3 6656.7 6726.9 6776.4 6777.0 6459.3 6467.7 6536.7 6543.2 6590.0 03:56:57 INFO - PID 7512 | 03:56:57 INFO - PID 7512 | [#1] ext.html Cycles:180 Average:36929.83 Median:3449.47 stddev:52298.31 (1516.1%) stddev-sans-first:52374.88 03:56:57 INFO - PID 7512 | Values: 857.4 880.4 881.0 881.1 889.3 41380.6 41404.6 41913.4 41979.6 42098.2 37652.0 37885.8 38003.6 38099.2 38143.8 7036.2 7102.6 7111.7 7130.4 7163.1 7079.6 7186.0 7202.4 7209.1 7218.6 7878.9 8108.8 8134.4 8143.3 8146.9 7842.7 7991.8 8030.0 8038.8 8054.3 7830.8 7874.3 7897.5 7899.6 7902.5 37907.0 38036.0 38153.0 38192.2 38215.8 3423.6 3475.4 3483.4 3484.0 3486.4 31452.2 31535.5 31610.0 31644.8 31706.5 5165.7 5218.6 5221.6 5235.8 5286.6 186034.0 187411.0 187692.0 187892.0 188132.0 42709.0 43352.8 47937.5 48240.5 48389.3 50176.7 50235.5 50375.2 50389.7 50545.5 15673.3 15831.4 15856.1 15885.5 15944.9 12891.5 13150.2 13155.3 13161.1 13169.6 13745.7 13760.4 13827.0 13865.0 13937.6 5567.3 5647.2 5652.7 5658.9 5683.3 1022.6 1027.3 1045.9 1046.3 1047.6 22950.8 23176.8 23181.9 23240.5 23368.9 1132.9 1146.3 1147.9 1149.5 1152.3 12265.5 12282.1 12402.1 12433.9 12557.6 224351.0 226598.0 227070.0 227198.0 227264.0 54071.0 54527.7 54546.2 54744.2 54819.0 1386.6 1440.5 1441.4 1455.0 1469.3 22993.8 23015.7 23079.9 23286.0 23443.3 37539.2 37675.2 37835.1 37863.4 37872.0 189118.0 189157.0 189626.0 190011.0 190094.0 30977.2 31435.2 31482.0 33232.8 34208.0 41917.6 43118.6 43457.3 43799.6 44074.8 31703.8 32039.8 32057.7 32347.5 32396.4 31240.0 31678.9 31801.8 31834.5 32470.0 34909.0 35272.0 35348.8 35709.3 35720.5 30781.4 30977.7 31091.8 31225.7 31238.5 22703.7 22991.0 23206.9 23691.9 23782.6 03:56:57 INFO - PID 7512 | 03:56:57 INFO - PID 7512 | [#2] jquery.html Cycles:180 Average:34250.40 Median:22773.16 stddev:81776.67 (359.1%) stddev-sans-first:81966.94 03:56:57 INFO - PID 7512 | Values: 554.8 563.0 567.2 590.8 607.6 21999.0 22782.0 22800.7 22843.9 22898.8 23869.3 24740.5 24995.9 25282.6 25618.0 6563.7 6758.2 6762.7 6763.4 6768.9 6575.4 6643.9 6680.2 6681.6 6700.8 6609.3 6616.1 6671.6 6682.6 6683.1 6293.7 6581.9 6604.5 6604.5 6608.5 6133.6 6144.5 6158.2 6158.4 6179.3 28672.4 28720.3 29046.3 29080.9 29146.0 2008.0 2045.0 2045.1 2047.4 2048.2 16842.2 18470.9 19051.7 19086.6 19155.3 2341.3 2371.6 2413.3 2423.5 2424.1 115555.4 123196.4 129361.0 132376.3 141739.6 14421.0 14460.2 15145.8 15260.6 15312.0 22332.8 22758.2 22764.3 22840.7 22888.2 10025.7 10139.5 10149.5 10184.7 10242.8 9205.0 9276.6 9346.6 9366.3 9413.3 8833.5 8958.5 9021.9 9159.4 9340.0 2543.1 2561.8 2594.2 2651.8 2684.8 966.5 999.6 999.6 1008.8 1014.8 19508.0 19760.8 19808.4 19872.6 20017.4 1021.9 1046.9 1066.0 1067.5 1072.0 10534.2 10936.0 10973.5 11101.3 11229.9 480533.0 481392.0 488449.0 493212.0 498529.0 105646.5 106813.5 107855.0 108024.0 109234.0 1076.8 1124.6 1187.6 1194.5 1232.0 21086.6 21182.7 21220.2 21221.8 21323.9 54848.7 55105.0 55448.4 56042.7 56931.0 69705.0 69976.0 70156.0 70563.3 71537.3 14309.9 14655.0 14733.1 14736.0 14894.9 23125.3 23410.0 23599.0 23777.3 24006.0 18726.3 18744.3 18862.3 18864.6 18922.8 15816.8 15970.7 16921.7 16959.8 17492.3 19079.8 19133.4 19203.5 19280.5 19320.2 14455.4 14502.6 14506.5 14506.9 14550.9 18252.6 18295.2 18403.0 18649.0 18651.7 03:56:57 INFO - PID 7512 | 03:56:57 INFO - PID 7512 | [#3] mootools.html Cycles:180 Average:9652.74 Median:27688.31 stddev:7002.92 (25.3%) stddev-sans-first:6994.88 03:56:57 INFO - PID 7512 | Values: 1365.0 1427.5 1429.5 1431.2 1436.7 15371.6 15981.0 15981.8 15988.1 16019.3 15660.8 15713.9 15721.6 15764.4 15772.2 2722.9 2804.2 2816.5 2818.4 2822.1 2693.6 2801.0 2803.0 2806.1 2807.0 2613.7 2715.0 2835.2 2835.8 2835.9 2789.7 2814.4 2842.0 2846.6 2856.5 2666.2 2760.8 2767.3 2813.3 2824.4 15328.0 15334.8 15436.8 15459.1 15474.4 8144.3 8437.8 8439.5 8444.4 8446.7 5338.5 5462.0 5538.3 5597.2 5640.8 1996.6 2011.1 2014.4 2047.7 2059.5 27406.3 27666.7 27673.4 27677.4 27699.2 6726.3 6768.4 6928.1 7229.3 7254.1 17967.6 17993.0 18012.3 18014.7 18033.6 7746.5 7781.5 7926.7 8307.9 8358.5 7266.2 7314.4 7316.3 7324.2 7397.1 5125.9 5283.9 5299.8 5307.9 5335.0 1589.0 1590.8 1595.9 1602.7 1605.3 826.2 836.1 838.3 839.9 854.4 6879.2 6900.8 6916.2 7033.9 8894.9 682.1 699.0 701.6 702.8 710.7 5066.2 5369.4 5387.3 5451.3 5489.3 21737.3 22044.8 22395.2 22649.0 22683.2 22436.9 22695.2 22823.5 22840.7 22872.3 11008.4 11613.2 11621.4 11696.8 11734.3 9042.3 9145.1 9170.1 9180.8 9202.4 19355.6 19389.0 19449.7 19829.2 20174.2 19347.5 20025.7 20236.6 20264.5 20303.1 12384.6 12720.4 12726.4 12728.9 12748.6 13504.5 14316.9 14448.4 14461.3 14492.9 11676.8 11905.2 12101.3 12118.5 12174.1 10836.5 11379.9 11539.7 11659.4 11809.9 9394.4 9600.4 9605.9 9656.0 9669.3 7269.5 7371.3 7378.1 7421.9 7459.4 7278.6 7493.2 7499.9 7510.8 7549.2 03:56:57 INFO - PID 7512 | 03:56:57 INFO - PID 7512 | [#4] prototype.html Cycles:180 Average:5482.11 Median:6135.79 stddev:1530.90 (25.0%) stddev-sans-first:1489.09 03:56:57 INFO - PID 7512 | Values: 513.7 516.8 532.0 536.5 542.3 6138.7 6255.3 6294.3 6295.4 6328.7 6183.0 6254.8 6266.3 6269.5 6270.3 5181.4 5182.5 5191.6 5208.4 5230.2 5078.3 5144.4 5159.2 5167.0 5173.9 4749.4 4889.4 4968.0 4968.3 4986.0 4861.8 4915.6 4956.6 4960.0 4975.9 4298.2 4450.9 4472.9 4486.7 4507.3 6014.5 6040.6 6056.2 6077.9 6132.9 5611.5 5623.8 5636.9 5666.1 5679.0 6495.4 6516.4 6527.9 6538.7 6565.8 4634.3 4859.8 4869.6 4884.1 4885.4 6651.6 6659.3 6691.5 6857.0 6889.0 4925.0 4925.4 4955.8 4988.7 5016.4 4945.2 4978.2 5038.4 5077.9 5094.1 5653.2 5655.9 5667.6 5685.5 5700.6 6624.0 6624.9 6632.6 6655.0 6665.2 1727.2 1738.3 1740.5 1770.2 1775.3 1160.2 1198.0 1209.2 1211.8 1230.6 5621.5 5624.1 5674.1 5676.3 5684.8 6583.2 6583.3 6611.5 6640.7 6685.2 5494.5 5547.3 5556.6 5635.6 5693.5 3489.5 3515.4 3520.1 3554.6 3559.4 6393.5 6398.8 6419.6 6424.6 6477.6 6849.3 6856.1 6871.5 6905.7 6919.9 5795.9 6332.5 6468.4 6487.6 6525.8 6398.6 6455.8 6488.0 6529.3 6611.9 6564.5 6601.6 6630.6 6709.9 6746.0 6407.6 6446.0 6458.4 6480.8 6486.1 4964.2 5029.7 5034.9 5037.9 5039.6 6302.3 6580.9 6590.1 6733.5 6769.2 6368.8 6427.6 6463.5 6546.7 6631.6 6448.9 6466.8 6525.2 6529.9 6546.5 6350.4 6396.1 6429.2 6450.5 6457.2 6524.7 6613.9 6637.7 6648.3 6691.2 6325.7 6337.4 6339.6 6340.0 6410.2 03:56:57 INFO - PID 7512 | 03:56:57 INFO - PID 7512 | [#5] yui.html Cycles:180 Average:24777.23 Median:17907.05 stddev:35706.29 (199.4%) stddev-sans-first:35761.55 03:56:57 INFO - PID 7512 | Values: 927.6 933.2 948.9 951.8 952.5 19945.4 20541.4 20552.3 20561.6 20619.6 16435.4 16444.9 16523.5 16547.8 16580.7 939.2 973.4 973.7 978.1 978.1 987.0 989.1 989.6 990.5 992.9 1001.2 1014.1 1032.2 1043.3 1044.6 1026.1 1026.2 1026.7 1027.5 1028.7 718.9 742.5 750.9 752.3 753.9 20681.0 20878.7 20897.3 20902.1 20903.0 21638.8 21646.5 22461.7 22664.4 22759.4 17029.6 17289.1 17320.7 17556.0 17559.4 3763.5 3789.2 3796.8 3801.2 3802.4 107080.0 138828.0 139629.0 139679.0 141863.0 17154.4 17807.2 17824.6 17896.1 17918.0 32058.8 32093.8 32469.2 32487.7 32652.3 8981.2 9266.1 9318.2 9340.9 9367.5 4322.9 4366.0 4383.6 4390.5 4410.3 5196.6 5528.7 5617.1 5625.3 5660.2 1100.7 1120.6 1130.5 1132.3 1137.5 595.1 603.4 605.9 619.3 622.6 11630.9 12406.3 12712.4 13132.9 13178.3 612.2 613.9 616.9 624.4 625.6 5867.8 5914.6 5942.9 5947.7 5982.4 127230.0 128374.0 128516.0 129281.7 130428.3 108417.0 110904.0 111199.4 112718.4 115454.0 94363.0 96205.5 97112.0 100167.0 101479.5 10966.6 11113.3 11180.2 11261.0 11523.9 44279.8 46626.5 46821.5 47057.3 47518.0 57591.7 59294.7 60146.0 60354.0 61175.4 15090.6 15253.2 15254.1 15405.0 15469.8 17347.6 17481.5 17552.1 17608.4 17718.5 16035.1 16256.1 16262.1 16272.9 16438.4 16496.7 16539.5 16590.3 16693.3 16795.7 14789.3 15394.6 15644.8 15975.0 16074.9 11477.9 11831.3 11918.2 11920.9 12066.6 12555.7 12569.0 12612.9 12748.0 12920.5 03:56:57 INFO - PID 7512 | -------- Summary: end -------- 03:56:57 INFO - PID 7512 | 03:56:58 INFO - TEST-INFO | 7512: exit 0 03:56:58 INFO - TEST-OK | dromaeo_css | took 1108892ms 03:56:58 INFO - TEST-START | kraken 03:56:58 INFO - Initialising browser for kraken test... 03:56:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44461/getInfo.html -profile /tmp/tmppG8LRS/profile 03:56:58 INFO - TEST-INFO | started process 7883 (/builds/slave/test/build/application/firefox/firefox http://localhost:44461/getInfo.html) 03:57:04 INFO - TEST-INFO | 7883: exit 0 03:57:04 INFO - Browser initialized. 03:57:04 INFO - Running cycle 1/1 for kraken test... 03:57:04 INFO - Using env: {'DISPLAY': ':0', 03:57:04 INFO - 'HOME': '/home/cltbld', 03:57:04 INFO - 'JSGC_DISABLE_POISONING': '1', 03:57:04 INFO - 'LANG': 'en_US.UTF-8', 03:57:04 INFO - 'LANGUAGE': 'en_US:en', 03:57:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:57:04 INFO - 'LOGNAME': 'cltbld', 03:57:04 INFO - 'MAIL': '/var/mail/cltbld', 03:57:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:57:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:57:04 INFO - 'MOZ_CRASHREPORTER': '1', 03:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:57:04 INFO - 'MOZ_NO_REMOTE': '1', 03:57:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:57:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:04 INFO - 'NO_EM_RESTART': '1', 03:57:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:04 INFO - 'PWD': '/builds/slave/test', 03:57:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:57:04 INFO - 'RUST_BACKTRACE': 'full', 03:57:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:57:04 INFO - 'SHELL': '/bin/bash', 03:57:04 INFO - 'SHLVL': '1', 03:57:04 INFO - 'TERM': 'linux', 03:57:04 INFO - 'TMOUT': '86400', 03:57:04 INFO - 'USER': 'cltbld', 03:57:04 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236', 03:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:04 INFO - '_': '/tools/buildbot/bin/python'} 03:57:04 INFO - TEST-INFO | started process 8121 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppG8LRS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:05 INFO - PID 8121 | 03:57:05 INFO - PID 8121 | (/builds/slave/test/build/application/firefox/firefox:8170): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:57:05 INFO - PID 8121 | 03:57:15 INFO - PID 8121 | RSS: Main: 154742784 03:57:15 INFO - PID 8121 | 03:57:18 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44461/tests/kraken/driver.html?testName=audio-beat-detection) 03:57:19 INFO - PID 8121 | RSS: Main: 159883264 03:57:19 INFO - PID 8121 | 03:57:21 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44461/tests/kraken/driver.html?testName=audio-dft) 03:57:22 INFO - PID 8121 | RSS: Main: 160182272 03:57:22 INFO - PID 8121 | 03:57:25 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44461/tests/kraken/driver.html?testName=audio-fft) 03:57:25 INFO - PID 8121 | RSS: Main: 156958720 03:57:25 INFO - PID 8121 | 03:57:28 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44461/tests/kraken/driver.html?testName=audio-oscillator) 03:57:28 INFO - PID 8121 | RSS: Main: 157364224 03:57:28 INFO - PID 8121 | 03:57:30 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44461/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:57:31 INFO - PID 8121 | RSS: Main: 157970432 03:57:31 INFO - PID 8121 | 03:57:34 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44461/tests/kraken/driver.html?testName=imaging-darkroom) 03:57:35 INFO - PID 8121 | RSS: Main: 160579584 03:57:35 INFO - PID 8121 | 03:57:38 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44461/tests/kraken/driver.html?testName=imaging-desaturate) 03:57:39 INFO - PID 8121 | RSS: Main: 160428032 03:57:39 INFO - PID 8121 | 03:57:42 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44461/tests/kraken/driver.html?testName=json-parse-financial) 03:57:43 INFO - PID 8121 | RSS: Main: 160387072 03:57:43 INFO - PID 8121 | 03:57:44 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44461/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:57:45 INFO - PID 8121 | RSS: Main: 160358400 03:57:45 INFO - PID 8121 | 03:57:46 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44461/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:57:47 INFO - PID 8121 | RSS: Main: 160509952 03:57:47 INFO - PID 8121 | 03:57:49 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44461/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:57:50 INFO - PID 8121 | RSS: Main: 157179904 03:57:50 INFO - PID 8121 | 03:57:52 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44461/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:57:53 INFO - PID 8121 | RSS: Main: 157265920 03:57:53 INFO - PID 8121 | 03:57:56 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44461/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:57:56 INFO - PID 8121 | RSS: Main: 157941760 03:57:56 INFO - PID 8121 | 03:57:58 INFO - PID 8121 | Cycle 1(1): loaded http://localhost:44461/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44461/tests/kraken/driver.html?testName=ai-astar) 03:57:59 INFO - PID 8121 | RSS: Main: 158973952 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | __start_tp_report 03:57:59 INFO - PID 8121 | _x_x_mozilla_page_load 03:57:59 INFO - PID 8121 | _x_x_mozilla_page_load_details 03:57:59 INFO - PID 8121 | |i|pagename|runs| 03:57:59 INFO - PID 8121 | |0;ai-astar;108;108;109;106;106;95;108;107;107;106 03:57:59 INFO - PID 8121 | |1;audio-beat-detection;117;119;114;116;122;116;140;117;117;119 03:57:59 INFO - PID 8121 | |2;audio-dft;163;165;162;162;162;163;162;164;168;166 03:57:59 INFO - PID 8121 | |3;audio-fft;73;75;75;73;75;75;77;77;78;76 03:57:59 INFO - PID 8121 | |4;audio-oscillator;79;80;84;78;78;78;78;77;78;78 03:57:59 INFO - PID 8121 | |5;imaging-gaussian-blur;110;114;113;113;114;114;114;114;113;114 03:57:59 INFO - PID 8121 | |6;imaging-darkroom;149;148;148;147;148;149;149;148;149;148 03:57:59 INFO - PID 8121 | |7;imaging-desaturate;93;94;95;95;94;95;94;94;96;95 03:57:59 INFO - PID 8121 | |8;json-parse-financial;56;56;53;54;54;54;53;54;54;53 03:57:59 INFO - PID 8121 | |9;json-stringify-tinderbox;38;38;38;38;38;39;38;39;38;38 03:57:59 INFO - PID 8121 | |10;stanford-crypto-aes;75;74;74;75;72;76;77;75;75;75 03:57:59 INFO - PID 8121 | |11;stanford-crypto-ccm;115;110;118;118;112;113;117;115;111;109 03:57:59 INFO - PID 8121 | |12;stanford-crypto-pbkdf2;170;171;170;170;171;181;171;174;178;170 03:57:59 INFO - PID 8121 | |13;stanford-crypto-sha256-iterative;66;68;65;69;68;65;65;65;64;74 03:57:59 INFO - PID 8121 | __end_tp_report 03:57:59 INFO - PID 8121 | __start_cc_report 03:57:59 INFO - PID 8121 | _x_x_mozilla_cycle_collect,731 03:57:59 INFO - PID 8121 | __end_cc_report 03:57:59 INFO - PID 8121 | __startTimestamp1505905079041__endTimestamp 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | ------- Summary: start ------- 03:57:59 INFO - PID 8121 | Number of tests: 14 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#0] ai-astar Cycles:10 Average:106.00 Median:108.00 stddev:4.00 (3.7%) stddev-sans-first:4.18 03:57:59 INFO - PID 8121 | Values: 108.0 108.0 109.0 106.0 106.0 95.0 108.0 107.0 107.0 106.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#1] audio-beat-detection Cycles:10 Average:119.70 Median:118.00 stddev:7.45 (6.3%) stddev-sans-first:7.84 03:57:59 INFO - PID 8121 | Values: 117.0 119.0 114.0 116.0 122.0 116.0 140.0 117.0 117.0 119.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#2] audio-dft Cycles:10 Average:163.70 Median:163.50 stddev:2.06 (1.3%) stddev-sans-first:2.17 03:57:59 INFO - PID 8121 | Values: 163.0 165.0 162.0 162.0 162.0 163.0 162.0 164.0 168.0 166.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#3] audio-fft Cycles:10 Average:75.40 Median:75.50 stddev:1.65 (2.2%) stddev-sans-first:1.50 03:57:59 INFO - PID 8121 | Values: 73.0 75.0 75.0 73.0 75.0 75.0 77.0 77.0 78.0 76.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:78.00 stddev:1.99 (2.5%) stddev-sans-first:2.11 03:57:59 INFO - PID 8121 | Values: 79.0 80.0 84.0 78.0 78.0 78.0 78.0 77.0 78.0 78.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:114.00 stddev:1.25 (1.1%) stddev-sans-first:0.50 03:57:59 INFO - PID 8121 | Values: 110.0 114.0 113.0 113.0 114.0 114.0 114.0 114.0 113.0 114.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 03:57:59 INFO - PID 8121 | Values: 149.0 148.0 148.0 147.0 148.0 149.0 149.0 148.0 149.0 148.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.71 03:57:59 INFO - PID 8121 | Values: 93.0 94.0 95.0 95.0 94.0 95.0 94.0 94.0 96.0 95.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:1.10 (2.0%) stddev-sans-first:0.93 03:57:59 INFO - PID 8121 | Values: 56.0 56.0 53.0 54.0 54.0 54.0 53.0 54.0 54.0 53.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 03:57:59 INFO - PID 8121 | Values: 38.0 38.0 38.0 38.0 38.0 39.0 38.0 39.0 38.0 38.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:75.00 stddev:1.32 (1.8%) stddev-sans-first:1.39 03:57:59 INFO - PID 8121 | Values: 75.0 74.0 74.0 75.0 72.0 76.0 77.0 75.0 75.0 75.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#11] stanford-crypto-ccm Cycles:10 Average:113.80 Median:115.00 stddev:3.29 (2.9%) stddev-sans-first:3.46 03:57:59 INFO - PID 8121 | Values: 115.0 110.0 118.0 118.0 112.0 113.0 117.0 115.0 111.0 109.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:171.00 stddev:3.89 (2.3%) stddev-sans-first:4.01 03:57:59 INFO - PID 8121 | Values: 170.0 171.0 170.0 170.0 171.0 181.0 171.0 174.0 178.0 170.0 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:3.00 (4.5%) stddev-sans-first:3.16 03:57:59 INFO - PID 8121 | Values: 66.0 68.0 65.0 69.0 68.0 65.0 65.0 65.0 64.0 74.0 03:57:59 INFO - PID 8121 | -------- Summary: end -------- 03:57:59 INFO - PID 8121 | 03:57:59 INFO - PID 8121 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:57:59 INFO - TEST-INFO | 8121: exit 0 03:57:59 INFO - TEST-OK | kraken | took 61005ms 03:57:59 INFO - SUITE-END | took 1169s 03:57:59 INFO - Completed test suite (00:19:29) 03:57:59 INFO - javascript benchmark 03:57:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8557.315988642298, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3140.9685903140967, 3170.6195256569217, 3176.317091825257, 3192.3934452454046, 3209.5185722141678, 5809.796657117002, 6196.473299769514, 6361.872762544758, 6396.648184349859, 6478.870422591545, 6255.749770009194, 6301.621902685831, 6313.400226978441, 6326.65203413812, 6338.492920566355, 5852.531797456215, 5898.941010589882, 5906.704664766745, 5935.584509084366, 5964.165016897616, 5898.056310990221, 5917.704114794265, 5944.970275148618, 5987.910181347262, 5988.790392336269, 1275.829557949805, 5569.8607534811545, 5787.942120578803, 5834.0, 5918.644881307092, 5822.446867547576, 5828.0966450200285, 5851.473367396912, 5897.085951677497, 5933.643981361132, 5794.188813566104, 5811.070228763378, 5872.853178670524, 5881.970590147064, 5885.087811389241, 6254.843628909268, 6311.968440157816, 6338.873222535523, 6392.105105285264, 6403.8399040023905, 6797.626130562817, 6844.726210951556, 6882.346177113216, 6900.413464855494, 6933.37599616037, 6666.300038495985, 6762.560433571852, 6771.966140169267, 6774.424173945171, 6784.982252662091, 6309.747610095636, 6320.968395158041, 6341.460975817061, 6403.711832967528, 6425.871482570323, 7056.953483489833, 7119.750808721774, 7129.108861392326, 7133.0, 7134.750283740044, 6721.764738234237, 6729.125213722283, 6778.423833974069, 6811.0, 6811.318868113248, 6709.798706038827, 6756.121704178426, 6829.52193346461, 6844.384005439534, 6939.306069393121, 6799.796006119825, 6842.349976752201, 6845.418139458102, 6886.621235831977, 6917.481188910751, 6884.173899132136, 6941.028256044158, 6963.09479767627, 6964.129483814523, 6979.581225126509, 5364.517193452608, 5370.113931201308, 5423.267858839085, 5424.891502169935, 5425.077736784677, 4589.5181005994555, 4673.929891051637, 4694.835680751157, 4701.200795864643, 4729.574338309569, 5905.940940590625, 5921.851953701106, 5935.910961335583, 5937.050071988461, 5943.167956486095, 6893.689783959734, 6898.0, 6991.0, 6998.860022799618, 7002.264762199998, 5880.588358814842, 5885.852853678693, 5941.197938278277, 5954.523638108941, 5968.104784282393, 5713.771449142071, 5801.274840644919, 5897.144913987448, 5976.402359763989, 5981.162637230791, 6676.732930682719, 6680.966595166993, 6688.030235615905, 6692.263850976388, 6709.127813384229, 6808.455323574003, 6891.345322194384, 6985.930140698528, 6993.055937448481, 7000.719971201095, 6615.801525954229, 6622.039804228263, 6676.9332306676315, 6699.39705426514, 6714.899276510759, 6577.276499585138, 6682.231543372574, 6743.629100399525, 6804.115464989718, 6825.761098361732, 6824.454043676395, 6954.165500140016, 6955.234924158237, 7000.929990700231, 7004.474664400293, 6670.099536562696, 6676.265610782907, 6767.154105736783, 6774.085498457842, 6778.356056174655, 6593.406593406617, 6636.435902948304, 6703.631300278532, 6709.194896612437, 6714.362135597111, 6636.900446493403, 6659.334066593302, 6660.167479065117, 6661.600303981777, 6663.766768163091, 6643.335666433318, 6656.7337306509135, 6726.932730672631, 6776.356246156607, 6776.96611516959, 6459.257185423548, 6467.741290348334, 6536.673166341759, 6543.1821022372205, 6590.044443555754], "value": 6209.324168231358, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [857.4255248983181, 880.3749337970041, 880.9779755506112, 881.0880738435718, 889.3063410539777, 41380.586194138086, 41404.58595414045, 41913.371299430495, 41979.58020419795, 42098.158036839246, 37652.0, 37885.810570947106, 38003.61996380035, 38099.23801523968, 38143.809280953625, 7036.226015138331, 7102.609356485391, 7111.679974401164, 7130.393916517102, 7163.14042314923, 7079.61062141582, 7185.993960845486, 7202.387797037232, 7209.1349038115495, 7218.639068046603, 7878.881816772753, 8108.797280067986, 8134.430589858713, 8143.267105960492, 8146.877796833052, 7842.725504607311, 7991.840163196762, 8029.959850200741, 8038.758837234892, 8054.315383192377, 7830.76507704764, 7874.330681892046, 7897.486663366863, 7899.644515996794, 7902.4863383879865, 37907.0, 38036.0, 38153.0, 38192.23615527674, 38215.8089209555, 3423.5891692997, 3475.3570589441033, 3483.390406678821, 3484.0418884806627, 3486.38988177068, 31452.21369465759, 31535.526967095513, 31610.0, 31644.841775791203, 31706.524402133986, 5165.74171291434, 5218.634695571312, 5221.582273418156, 5235.842924712265, 5286.577073834084, 186034.0, 187411.0, 187692.0, 187892.0, 188132.0, 42709.0, 43352.78323608362, 47937.520624794, 48240.5175948236, 48389.27416088762, 50176.749116254185, 50235.49764502308, 50375.24437133446, 50389.74805126024, 50545.49454505481, 15673.294701738221, 15831.366745330288, 15856.127912964666, 15885.523434296989, 15944.920275398548, 12891.484340626457, 13150.21098734079, 13155.276459794803, 13161.144525605996, 13169.604911852659, 13745.656358590915, 13760.449582016807, 13827.0, 13865.0, 13937.58187254384, 5567.3040869891265, 5647.237622920935, 5652.717364131777, 5658.924804287172, 5683.318001839806, 1022.5756311130798, 1027.3476342522551, 1045.850776653968, 1046.2989796836139, 1047.5652604169184, 22950.770492295196, 23176.768232317798, 23181.88409057944, 23240.53518929612, 23368.883155584113, 1132.881047490018, 1146.306484576819, 1147.8679951805482, 1149.5114576305205, 1152.2510368260698, 12265.509379624893, 12282.14025018258, 12402.13185077036, 12433.93783031079, 12557.56048538315, 224351.0, 226598.0, 227070.0, 227198.0, 227264.0, 54071.0, 54527.72736136294, 54546.18180727292, 54744.17883731747, 54819.0, 1386.6325423762712, 1440.488626537585, 1441.4018182454165, 1454.9563513094624, 1469.3387975411108, 22993.77006229916, 23015.654765178202, 23079.88460057689, 23286.0, 23443.296701098996, 37539.249215015, 37675.2464950694, 37835.05412364713, 37863.43204851874, 37872.0, 189118.0, 189157.0, 189626.0, 190011.0, 190094.0, 30977.225569360045, 31435.214119647193, 31482.0, 33232.833835830665, 34208.0, 41917.58082419137, 43118.56881431145, 43457.3481397773, 43799.56200437955, 44074.77962610166, 31703.84148079245, 32039.83980080178, 32057.67942320547, 32347.514787277738, 32396.352072957936, 31240.0, 31678.891238806533, 31801.8409907949, 31834.522482162323, 32470.0, 34909.0, 35272.0, 35348.82325588356, 35709.285814284085, 35720.464193037646, 30781.384372312878, 30977.69022309748, 31091.84454077715, 31225.687743123188, 31238.531422028234, 22703.659445108668, 22991.007903658305, 23206.883965580066, 23691.881540592185, 23782.643260351455], "value": 17016.88503766311, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.8474169603359, 562.9746661400237, 567.1719289836838, 590.8079874040936, 607.5868409481551, 21998.99999999998, 22782.0, 22800.65799013014, 22843.885780571116, 22898.77101228987, 23869.28392148234, 24740.50518989619, 24995.875020624917, 25282.62076068856, 25618.0, 6563.671816409173, 6758.155230596176, 6762.729490820361, 6763.357481039294, 6768.898466523006, 6575.4082132608055, 6643.933560664404, 6680.198376194863, 6681.632510211936, 6700.83247918801, 6609.306022867602, 6616.139901812758, 6671.59970401775, 6682.632455214961, 6683.064370988066, 6293.716782744765, 6581.913984192626, 6604.504662150358, 6604.537682362236, 6608.537402381835, 6133.6319820810895, 6144.477719393857, 6158.1686472326555, 6158.353372895826, 6179.289381721114, 28672.426551469063, 28720.281992950135, 29046.27384315388, 29080.854595727098, 29146.0, 2007.9598408031904, 2044.9795502044938, 2045.1410407628832, 2047.4471892588917, 2048.201201531404, 16842.157892105468, 18470.907645461823, 19051.714224286647, 19086.61826763471, 19155.329563465213, 2341.297610716795, 2371.6086845670443, 2413.2760171948344, 2423.491066875958, 2424.078850036992, 115555.42222288919, 123196.38401808024, 129361.0, 132376.33811830977, 141739.58260417366, 14421.0, 14460.2029467634, 15145.848541514444, 15260.618484537976, 15312.0, 22332.776672233394, 22758.20346287872, 22764.317070487912, 22840.65739013916, 22888.198913037962, 10025.699229023141, 10139.493025348704, 10149.543270552695, 10184.694459166238, 10242.795144097225, 9204.95397523008, 9276.582553785096, 9346.626134954526, 9366.29752768545, 9413.294002949806, 8833.469991800512, 8958.462492250486, 9021.909780902239, 9159.404638698597, 9339.953300233592, 2543.0537604359342, 2561.8078644101765, 2594.208766326251, 2651.84644679565, 2684.8389096654264, 966.5022513405602, 999.5551979369111, 999.5801763259449, 1008.7578981044643, 1014.8173328800887, 19508.0, 19760.802391976184, 19808.40574782759, 19872.60254794925, 20017.399478015683, 1021.933574317667, 1046.9267151299337, 1065.9786804263872, 1067.4502631144967, 1071.9785604288027, 10534.157267418588, 10936.0, 10973.451327433757, 11101.333919964827, 11229.887701123047, 480533.0, 481392.0, 488449.0, 493212.0, 498529.0, 105646.47176764067, 106813.46593267139, 107855.0, 108024.0, 109234.0, 1076.8115579773507, 1124.5895248234335, 1187.6080893305189, 1194.5341316886422, 1231.963041108786, 21086.578268434238, 21182.682259765807, 21220.151193952068, 21221.7877821226, 21323.893380532998, 54848.725756370965, 55105.0, 55448.44551554433, 56042.719786400805, 56931.0, 69705.0, 69976.0, 70156.0, 70563.29436705772, 71537.28462715514, 14309.856901430852, 14655.0, 14733.116013039253, 14736.0, 14894.851051489346, 23125.306240812803, 23410.0, 23599.0, 23777.286681399586, 24006.0, 18726.25094996185, 18744.343947961755, 18862.339818106302, 18864.6227075455, 18922.810771892655, 15816.76274855855, 15970.680586387974, 16921.746173807158, 16959.830401695825, 17492.300307987538, 19079.809201907803, 19133.425997220107, 19203.519912001753, 19280.517987050436, 19320.227190912767, 14455.421783128977, 14502.637434064232, 14506.492272770402, 14506.927465362605, 14550.927245363706, 18252.634947301245, 18295.17671704803, 18403.0, 18649.0, 18651.72022419692], "value": 11967.65239155787, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.983087599738, 1427.471835420893, 1429.5211104280063, 1431.2485944878938, 1436.6911114110464, 15371.615709607251, 15981.0, 15981.760273595877, 15988.12065336406, 16019.279132439038, 15660.765088523682, 15713.92143039286, 15721.60695982601, 15764.448244311452, 15772.21138943054, 2722.9319267018286, 2804.2288370698016, 2816.5352716801704, 2818.3658676797763, 2822.111035023971, 2693.5690289553677, 2800.985995070022, 2802.985985070072, 2806.116073436871, 2806.985965070182, 2613.73862613739, 2714.986425067872, 2835.206142280165, 2835.8440285784272, 2835.9291017724518, 2789.6512935883015, 2814.352698879259, 2842.019503271368, 2846.615706879576, 2856.5143925532607, 2666.240121565364, 2760.7791376690016, 2767.33583939855, 2813.3248020475144, 2824.3503994081475, 15328.0, 15334.846651533453, 15436.768448473282, 15459.149746763924, 15474.381024758995, 8144.307733842571, 8437.831243375098, 8439.535825529594, 8444.408891377545, 8446.704365347183, 5338.480096501809, 5461.972690136524, 5538.307711536058, 5597.188407680864, 5640.830775076754, 1996.550776075386, 2011.1352118589, 2014.4158194123686, 2047.682609195577, 2059.5468996820678, 27406.314842128908, 27666.72333276682, 27673.446531069265, 27677.446451070868, 27699.169024929284, 6726.293739157416, 6768.390844823891, 6928.1339832520935, 7229.313215244649, 7254.056972593635, 17967.55081122956, 17993.0, 18012.279508819764, 18014.729779053323, 18033.639327213383, 7746.457747957702, 7781.494202876795, 7926.682932682628, 8307.916920830836, 8358.45670031446, 7266.164391094986, 7314.3782778463365, 7316.304951029643, 7324.194338622748, 7397.075365579302, 5125.948740512622, 5283.9471605284225, 5299.814506492255, 5307.946920530822, 5334.973325133427, 1588.9602759931095, 1590.8250092489816, 1595.920203989796, 1602.6857976459426, 1605.3498333175082, 826.1696994520588, 836.0844874862016, 838.2958315015417, 839.8950131233596, 854.3549620036832, 6879.174499060045, 6900.758473453405, 6916.23921368649, 7033.8944915826305, 8894.86657700135, 682.0825989044727, 699.0388216202721, 701.6458235605229, 702.8067281497629, 710.733474946895, 5066.240063990431, 5369.40936496985, 5387.326584176978, 5451.291332126798, 5489.286392768914, 21737.34787956364, 22044.779552204272, 22395.21616743406, 22649.0, 22683.20608778685, 22436.887815561146, 22695.205667801547, 22823.543529129656, 22840.65739013916, 22872.31383058511, 11008.449577521253, 11613.187076904704, 11621.418929053514, 11696.824547631792, 11734.295942243492, 9042.276617870422, 9145.131212534798, 9170.128837760401, 9180.770480738034, 9202.447853128833, 19355.61288774245, 19389.0, 19449.708254376477, 19829.20683172657, 20174.193032278545, 19347.51631209231, 20025.6996145055, 20236.59526809426, 20264.493387665425, 20303.08636111349, 12384.56654017105, 12720.363981801058, 12726.363681815687, 12728.872711272768, 12748.61754147377, 13504.527341542998, 14316.856831431553, 14448.410674825971, 14461.349239284467, 14492.85507144915, 11676.766464670829, 11905.226160299553, 12101.273923564615, 12118.454669540068, 12174.14780965324, 10836.512356943798, 11379.886201137882, 11539.71150721205, 11659.417029148339, 11809.881901180877, 9394.389364691273, 9600.375975561567, 9605.903940960501, 9656.0, 9669.323147379897, 7269.454790890599, 7371.262873712801, 7378.114626244885, 7421.888671670037, 7459.36595389398, 7278.563286202845, 7493.213212612597, 7499.850003000019, 7510.849783004419, 7549.1695913448375], "value": 6801.079150656045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.6917849290426, 516.7700373333865, 531.9813806516772, 536.4501386079265, 542.2706459811554, 6138.662373569452, 6255.280642726082, 6294.276158241803, 6295.370462953702, 6328.683565821713, 6182.969085154579, 6254.84362890928, 6266.342034086424, 6269.5297852661115, 6270.278917924428, 5181.378234611851, 5182.533571978521, 5191.5846732261325, 5208.401033881114, 5230.215467679859, 5078.289039534468, 5144.408393034792, 5159.2003239497935, 5166.974165129169, 5173.948260517384, 4749.358836557072, 4889.413270407556, 4967.950320496803, 4968.254761785725, 4985.977874535712, 4861.82983595576, 4915.606751459874, 4956.578690811285, 4960.0, 4975.875603109933, 4298.226319262531, 4450.888727781799, 4472.865814025585, 4486.7083639563325, 4507.323901414781, 6014.51883849291, 6040.57715959883, 6056.182415373913, 6077.908831367533, 6132.908006379907, 5611.494965453129, 5623.847111342165, 5636.859078523029, 5666.093425051972, 5678.971605141989, 6495.415412612888, 6516.446102081329, 6527.902081468782, 6538.705758240891, 6565.80302590923, 4634.281686338623, 4859.829905953275, 4869.586085182764, 4884.12085824555, 4885.413750349981, 6651.634160121192, 6659.267480577133, 6691.464682825411, 6856.965715171442, 6888.965555172243, 4924.975375123101, 4925.359703238628, 4955.801767929314, 4988.6757360771435, 5016.44819069902, 4945.208766597327, 4978.2034874419915, 5038.395392552917, 5077.923831142536, 5094.108531007059, 5653.152027195872, 5655.89710006553, 5667.574931880043, 5685.459881311269, 5700.572457065654, 6624.0, 6624.933750662528, 6632.5688830226845, 6655.0, 6665.233498147775, 1727.153694689618, 1738.3394310162057, 1740.4691569071554, 1770.2299499717665, 1775.2721384232661, 1160.1530882455854, 1198.017625547043, 1209.1500004992376, 1211.836402085707, 1230.6492649594823, 5621.510299770564, 5624.128260119688, 5674.148877668383, 5676.25367257608, 5684.801031963944, 6583.177102862142, 6583.275839657634, 6611.471082313404, 6640.667966601651, 6685.197776266879, 5494.450554944474, 5547.25112109868, 5556.611037227436, 5635.5773317001385, 5693.544516438675, 3489.4940233666484, 3515.402381595135, 3520.1375662962228, 3554.595934605816, 3559.4126969050335, 6393.520485963571, 6398.776042838478, 6419.550631455847, 6424.582402143846, 6477.611343319321, 6849.349311815469, 6856.142982127134, 6871.518993670495, 6905.654717264217, 6919.8962015569805, 5795.855103622359, 6332.52506062038, 6468.3855033771715, 6487.610743355413, 6525.836854078686, 6398.648074355955, 6455.806325810233, 6487.994360874072, 6529.347065293433, 6611.8511483768925, 6564.540482166202, 6601.557423753242, 6630.602163870183, 6709.93290067093, 6745.9662701686175, 6407.615543067431, 6446.0, 6458.354164583504, 6480.837979050561, 6486.05952136947, 4964.156093464192, 5029.698218106925, 5034.942662041012, 5037.874053148701, 5039.563724338546, 6302.3215133568965, 6580.868382632416, 6590.143281373391, 6733.508601817359, 6769.153855768029, 6368.840778980562, 6427.582207156707, 6463.547551671338, 6546.672666366568, 6631.602103873783, 6448.9032664511005, 6466.805995820133, 6525.2169739631545, 6529.869402611826, 6546.476281897341, 6350.428461438308, 6396.136521569529, 6429.164208653032, 6450.451711604566, 6457.225132984072, 6524.67376631176, 6613.867722645424, 6637.734490620321, 6648.301928297459, 6691.1636045494315, 6325.683715814283, 6337.397947195009, 6339.619622822645, 6340.017297318923, 6410.230772307353], "value": 5034.2983763464, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [927.5547737086198, 933.2440723014358, 948.9335746497744, 951.7620594851285, 952.5142177489487, 19945.401637950865, 20541.38375848723, 20552.280670176548, 20561.588768224625, 20619.587608247846, 16435.424760133366, 16444.917775411137, 16523.50429487114, 16547.83452165481, 16580.66838663229, 939.1876027236442, 973.3964941736098, 973.732223638498, 978.0806042320232, 978.0952618827591, 986.990130098697, 989.1048601016056, 989.5596459575492, 990.4700984973041, 992.9205663546935, 1001.1540248490014, 1014.1278500489573, 1032.1691038713832, 1043.3114144664523, 1044.6082718980383, 1026.1329176845554, 1026.2457094035879, 1026.7176526455248, 1027.4811220333759, 1028.686250693538, 718.881384571545, 742.517363713585, 750.9436792240604, 752.3341842469454, 753.8793792993094, 20681.0, 20878.686819697716, 20897.268595599082, 20902.059407326706, 20903.0, 21638.783612163836, 21646.458838529004, 22461.663075053722, 22664.43338916524, 22759.43101422461, 17029.574260643458, 17289.13554322279, 17320.740188897176, 17556.0, 17559.385421510186, 3763.5483741950866, 3789.2232092420986, 3796.7532110413013, 3801.182745709658, 3802.448644946495, 107080.0, 138828.0, 139629.0, 139679.0, 141863.0, 17154.39959601408, 17807.19867605961, 17824.643507129786, 17896.105194740212, 17918.0, 32058.83970580132, 32093.839530802663, 32469.18827029343, 32487.675123248937, 32652.346953060805, 8981.236594889377, 9266.073392660815, 9318.161365477139, 9340.859887101564, 9367.531623418801, 4322.935155972663, 4366.039471316369, 4383.561643835655, 4390.53899340571, 4410.272305069628, 5196.558292545176, 5528.668279903219, 5617.129344951539, 5625.296837895263, 5660.179274005246, 1100.741325788439, 1120.6413947536873, 1130.5421304371744, 1132.297975255347, 1137.533611219396, 595.0657467775551, 603.3966033966034, 605.881853038662, 619.3249358199494, 622.6233128957007, 11630.88369116298, 12406.25562466255, 12712.364381780873, 13132.934335328262, 13178.341082945813, 612.1674522649193, 613.8772245550907, 616.8797084568466, 624.4255285137684, 625.6465097220056, 5867.765289388462, 5914.556408269397, 5942.940570594325, 5947.732352044169, 5982.4316689914385, 127230.0, 128374.0, 128516.0, 129281.70718292885, 130428.347858262, 108417.0, 110904.0, 111199.44400278109, 112718.43640781744, 115454.0, 94363.0, 96205.51897240609, 97112.02887971171, 100166.99833001723, 101479.492602538, 10966.616168434066, 11113.277636953895, 11180.217384782987, 11261.0, 11523.884761152281, 44279.77860110679, 46626.53373466358, 46821.53178468172, 47057.29414058723, 47518.0, 57591.712041439525, 59294.70352648209, 60146.0, 60354.0, 61175.388246118746, 15090.622734431727, 15253.23733813327, 15254.08475491474, 15404.998675086084, 15469.767953480932, 17347.56631084233, 17481.47555573335, 17552.12239388051, 17608.38370657072, 17718.46844594664, 16035.118068506345, 16256.105914174834, 16262.10558419251, 16272.91863540722, 16438.424655137012, 16496.67006659884, 16539.50381488557, 16590.336386544885, 16693.332266709196, 16795.748063779298, 14789.334479948213, 15394.615134621725, 15644.765328520309, 15975.0, 16074.919625401799, 11477.885221147682, 11831.34927578957, 11918.165728398928, 11920.880791192323, 12066.577669781516, 12555.686107847378, 12569.0, 12612.873871261538, 12748.0, 12920.547780827626], "value": 9068.804021130745, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1420.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 109.0, 106.0, 106.0, 95.0, 108.0, 107.0, 107.0, 106.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 114.0, 116.0, 122.0, 116.0, 140.0, 117.0, 117.0, 119.0], "value": 119.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 162.0, 162.0, 162.0, 163.0, 162.0, 164.0, 168.0, 166.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 75.0, 73.0, 75.0, 75.0, 77.0, 77.0, 78.0, 76.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 84.0, 78.0, 78.0, 78.0, 78.0, 77.0, 78.0, 78.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 147.0, 148.0, 149.0, 149.0, 148.0, 149.0, 148.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 96.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 39.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 75.0, 72.0, 76.0, 77.0, 75.0, 75.0, 75.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 110.0, 118.0, 118.0, 112.0, 113.0, 117.0, 115.0, 111.0, 109.0], "value": 113.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 170.0, 170.0, 171.0, 181.0, 171.0, 174.0, 178.0, 170.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 65.0, 69.0, 68.0, 65.0, 65.0, 65.0, 64.0, 74.0], "value": 66.9, "unit": "score"}]}]} 03:57:59 INFO - Return code: 0 03:57:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:57:59 INFO - # TBPL SUCCESS # 03:57:59 INFO - Running post-action listener: _package_coverage_data 03:57:59 INFO - Running post-action listener: _resource_record_post_action 03:57:59 INFO - [mozharness: 2017-09-20 10:57:59.806109Z] Finished run-tests step (success) 03:57:59 INFO - Running post-run listener: _resource_record_post_run 03:57:59 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 421888; Write bytes: 336928768; Read time: 28; Write time: 243852 03:57:59 INFO - TinderboxPrint: CPU usage
13.1% 03:57:59 INFO - TinderboxPrint: I/O read bytes / time
421,888 / 28 03:57:59 INFO - TinderboxPrint: I/O write bytes / time
336,928,768 / 243,852 03:57:59 INFO - TinderboxPrint: CPU idle
8,229.0 (86.9%) 03:57:59 INFO - TinderboxPrint: CPU user
1,191.4 (12.6%) 03:57:59 INFO - TinderboxPrint: Swap in / out
0 / 0 03:57:59 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 2830336; Read time: 0; Write time: 64 03:57:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:57:59 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 417792; Write bytes: 324509696; Read time: 24; Write time: 240904 03:58:00 INFO - Running post-run listener: _upload_blobber_files 03:58:00 INFO - Blob upload gear active. 03:58:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:58:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:58:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:58:00 INFO - (blobuploader) - INFO - Open directory for files ... 03:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:01 INFO - (blobuploader) - INFO - Done attempting. 03:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:02 INFO - (blobuploader) - INFO - Done attempting. 03:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:58:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:10 INFO - (blobuploader) - INFO - Done attempting. 03:58:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:58:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:58:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:11 INFO - (blobuploader) - INFO - Done attempting. 03:58:11 INFO - (blobuploader) - INFO - Iteration through files over. 03:58:11 INFO - Return code: 0 03:58:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:58:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:58:11 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2dbd8a102b60144cf6408b1ac708e5fcf4351c21ff62404bff24f53a9f3f0a44d2ae230a61bb355c4403a382186bea5bbadee82cd88e84e784f60a04d3f85886", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e705b5085cb35300117654350d8b7f3b50c666ec57420090adbc6b5dc13c24551c57f763b84607276396d6ee7cb1369c933886f5a97ac729aeb398637db8903", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3702a73e62860dd9cf0f109b84ebb6c3a73ea1bedb4e5ef4c4b8cb48260d79c65e3dc68d807a03466cd89875aa51aadda0f85018a91eb7b54c033c73965f0e59", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d951335b038143cd40886de49bc8414eb0376e0d8b123f9cfbc6dc971392a3d5bc0ad1fe46d84aa531625064259cfb5256671aaec394a20c8b592cc10dbf950"} 03:58:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:58:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:58:11 INFO - Contents: 03:58:11 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2dbd8a102b60144cf6408b1ac708e5fcf4351c21ff62404bff24f53a9f3f0a44d2ae230a61bb355c4403a382186bea5bbadee82cd88e84e784f60a04d3f85886", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e705b5085cb35300117654350d8b7f3b50c666ec57420090adbc6b5dc13c24551c57f763b84607276396d6ee7cb1369c933886f5a97ac729aeb398637db8903", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3702a73e62860dd9cf0f109b84ebb6c3a73ea1bedb4e5ef4c4b8cb48260d79c65e3dc68d807a03466cd89875aa51aadda0f85018a91eb7b54c033c73965f0e59", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d951335b038143cd40886de49bc8414eb0376e0d8b123f9cfbc6dc971392a3d5bc0ad1fe46d84aa531625064259cfb5256671aaec394a20c8b592cc10dbf950"} 03:58:11 INFO - Running post-run listener: copy_logs_to_upload_dir 03:58:11 INFO - Copying logs to upload dir... 03:58:11 INFO - mkdir: /builds/slave/test/build/upload/logs 03:58:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.497541 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-20 03:58:11.166294) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 03:58:11.170277) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236 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-Non-PGO/sha512/2dbd8a102b60144cf6408b1ac708e5fcf4351c21ff62404bff24f53a9f3f0a44d2ae230a61bb355c4403a382186bea5bbadee82cd88e84e784f60a04d3f85886", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e705b5085cb35300117654350d8b7f3b50c666ec57420090adbc6b5dc13c24551c57f763b84607276396d6ee7cb1369c933886f5a97ac729aeb398637db8903", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3702a73e62860dd9cf0f109b84ebb6c3a73ea1bedb4e5ef4c4b8cb48260d79c65e3dc68d807a03466cd89875aa51aadda0f85018a91eb7b54c033c73965f0e59", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d951335b038143cd40886de49bc8414eb0376e0d8b123f9cfbc6dc971392a3d5bc0ad1fe46d84aa531625064259cfb5256671aaec394a20c8b592cc10dbf950"} build_url:https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011384 build_url: 'https://queue.taskcluster.net/v1/task/Nm88MqzuS6G8_ult50Vhxg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2dbd8a102b60144cf6408b1ac708e5fcf4351c21ff62404bff24f53a9f3f0a44d2ae230a61bb355c4403a382186bea5bbadee82cd88e84e784f60a04d3f85886", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e705b5085cb35300117654350d8b7f3b50c666ec57420090adbc6b5dc13c24551c57f763b84607276396d6ee7cb1369c933886f5a97ac729aeb398637db8903", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3702a73e62860dd9cf0f109b84ebb6c3a73ea1bedb4e5ef4c4b8cb48260d79c65e3dc68d807a03466cd89875aa51aadda0f85018a91eb7b54c033c73965f0e59", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d951335b038143cd40886de49bc8414eb0376e0d8b123f9cfbc6dc971392a3d5bc0ad1fe46d84aa531625064259cfb5256671aaec394a20c8b592cc10dbf950"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 03:58:11.198824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 03:58:11.199172) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1505903850.50198-1867157236 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004522 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 03:58:11.281159) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 03:58:11.281473) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 03:58:11.281819) ========= ========= Total master_lag: 0.23 =========