builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-052 starttime: 1505621464.28 results: success (0) revision: be5bddf32db0a1ed27251295b7711d06f9189b11 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:04.282512) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:04.282949) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:04.283219) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:04.319252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:04.319608) ========= 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=826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-16 21:11:04-- 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% 225K=0.05s 2017-09-16 21:11:04 (225 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.202550 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:04.540235) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:04.543629) ========= 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=826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015814 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:04.596391) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-16 21:11:04.596786) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev be5bddf32db0a1ed27251295b7711d06f9189b11 --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 be5bddf32db0a1ed27251295b7711d06f9189b11 --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=826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-16 21:11:04,631 truncating revision to first 12 chars 2017-09-16 21:11:04,631 Setting DEBUG logging. 2017-09-16 21:11:04,631 attempt 1/10 2017-09-16 21:11:04,631 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/be5bddf32db0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-16 21:11:07,230 unpacking tar archive at: mozilla-central-be5bddf32db0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.960395 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-09-16 21:11:07.578505) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:07.578847) ========= script_repo_revision: be5bddf32db0a1ed27251295b7711d06f9189b11 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:07.579225) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:07.579495) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 21:11:07.592703) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-16 21:11:07.593001) ========= /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=826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:11:07 INFO - MultiFileLogger online at 20170916 21:11:07 in /builds/slave/test 21:11:07 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 21:11:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:11:07 INFO - {'append_to_log': False, 21:11:07 INFO - 'base_work_dir': '/builds/slave/test', 21:11:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:11:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:11:07 INFO - 'branch': 'Firefox-Non-PGO', 21:11:07 INFO - 'buildbot_json_path': 'buildprops.json', 21:11:07 INFO - 'code_coverage': False, 21:11:07 INFO - 'config_files': ('talos/linux_config.py',), 21:11:07 INFO - 'default_actions': ('clobber', 21:11:07 INFO - 'read-buildbot-config', 21:11:07 INFO - 'download-and-extract', 21:11:07 INFO - 'populate-webroot', 21:11:07 INFO - 'create-virtualenv', 21:11:07 INFO - 'install', 21:11:07 INFO - 'setup-mitmproxy', 21:11:07 INFO - 'run-tests'), 21:11:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:11:07 INFO - 'disable_ccov_upload': False, 21:11:07 INFO - 'disable_stylo': False, 21:11:07 INFO - 'download_minidump_stackwalk': True, 21:11:07 INFO - 'download_symbols': 'ondemand', 21:11:07 INFO - 'enable_stylo': False, 21:11:07 INFO - 'enable_webrender': False, 21:11:07 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 21:11:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:11:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:11:07 INFO - 'gecko_profile': False, 21:11:07 INFO - 'gecko_profile_interval': 0, 21:11:07 INFO - 'installer_path': 'installer.exe', 21:11:07 INFO - 'log_level': 'info', 21:11:07 INFO - 'log_name': 'talos', 21:11:07 INFO - 'log_to_console': True, 21:11:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:11:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:11:07 INFO - 'opt_config_files': (), 21:11:07 INFO - 'pip_index': False, 21:11:07 INFO - 'suite': 'dromaeojs-e10s', 21:11:07 INFO - 'system_bits': '32', 21:11:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:11:07 INFO - 'title': 'talos-linux64-ix-052', 21:11:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:11:07 INFO - 'use_talos_json': True, 21:11:07 INFO - 'verify': 'False', 21:11:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:11:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:11:07 INFO - 'work_dir': 'build'} 21:11:07 INFO - [mozharness: 2017-09-17 04:11:07.848382Z] Running clobber step. 21:11:07 INFO - Running pre-action listener: _resource_record_pre_action 21:11:07 INFO - Running main action method: clobber 21:11:07 INFO - rmtree: /builds/slave/test/build 21:11:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:11:08 INFO - Running post-action listener: _resource_record_post_action 21:11:08 INFO - [mozharness: 2017-09-17 04:11:08.793003Z] Finished clobber step (success) 21:11:08 INFO - [mozharness: 2017-09-17 04:11:08.793158Z] Running read-buildbot-config step. 21:11:08 INFO - Running pre-action listener: _resource_record_pre_action 21:11:08 INFO - Running main action method: read_buildbot_config 21:11:08 INFO - Using buildbot properties: 21:11:08 INFO - { 21:11:08 INFO - "project": "", 21:11:08 INFO - "product": "firefox", 21:11:08 INFO - "who": "ryanvm@gmail.com", 21:11:08 INFO - "installer_path": "public/build/target.tar.bz2", 21:11:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:11:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 21:11:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 21:11:08 INFO - "stage_platform": "linux64", 21:11:08 INFO - "basedir": "/builds/slave/test", 21:11:08 INFO - "buildnumber": 838, 21:11:08 INFO - "platform": "ubuntu64_hw", 21:11:08 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:11:08 INFO - "slavebuilddir": "test", 21:11:08 INFO - "taskId": "W8ZD5f-bR6Wg1RjyxDLdXw", 21:11:08 INFO - "branch": "mozilla-central", 21:11:08 INFO - "script_repo_revision": "production", 21:11:08 INFO - "revision": "be5bddf32db0a1ed27251295b7711d06f9189b11", 21:11:08 INFO - "slavename": "talos-linux64-ix-052", 21:11:08 INFO - "repo_path": "mozilla-central" 21:11:08 INFO - } 21:11:08 INFO - Finding installer, test and symbols from parent task. 21:11:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W8ZD5f-bR6Wg1RjyxDLdXw'}, attempt #1 21:11:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W8ZD5f-bR6Wg1RjyxDLdXw'}, attempt #1 21:11:09 INFO - Task dependencies: MNqMn-AVSA2YJrdiCeGBdQ 21:11:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ'}, attempt #1 21:11:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.tar.bz2 21:11:10 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.test_packages.json 21:11:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.crashreporter-symbols.zip 21:11:10 INFO - Running post-action listener: _resource_record_post_action 21:11:10 INFO - [mozharness: 2017-09-17 04:11:10.172533Z] Finished read-buildbot-config step (success) 21:11:10 INFO - [mozharness: 2017-09-17 04:11:10.172751Z] Running download-and-extract step. 21:11:10 INFO - Running pre-action listener: _resource_record_pre_action 21:11:10 INFO - Running main action method: download_and_extract 21:11:10 INFO - mkdir: /builds/slave/test/build/tests 21:11: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')]} 21:11:10 INFO - trying https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.test_packages.json 21:11:10 INFO - Downloading https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:11:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:11:10 INFO - Downloaded 989 bytes. 21:11:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:11:10 INFO - Using the following test package requirements: 21:11:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:11:10 INFO - u'common': [u'target.common.tests.zip'], 21:11:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:11:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:11:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:11:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:11:10 INFO - u'mozbase': [u'target.common.tests.zip'], 21:11:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:11:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:11:10 INFO - u'web-platform': [u'target.common.tests.zip', 21:11:10 INFO - u'target.web-platform.tests.tar.gz'], 21:11:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:11:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 21:11:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.common.tests.zip 21:11:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:11:10 INFO - Fetch https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.common.tests.zip into memory 21:11:11 INFO - Content-Length response header: 54731170 21:11:11 INFO - Bytes received: 54731170 21:11:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.talos.tests.zip 21:11:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:11:23 INFO - Fetch https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.talos.tests.zip into memory 21:11:24 INFO - Content-Length response header: 14508748 21:11:24 INFO - Bytes received: 14508748 21:11:24 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')]} 21:11:24 INFO - trying https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.tar.bz2 21:11:24 INFO - Downloading https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:11:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:11:25 INFO - Downloaded 61343440 bytes. 21:11:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.tar.bz2 21:11:25 INFO - mkdir: /builds/slave/test/properties 21:11:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:11:25 INFO - Writing to file /builds/slave/test/properties/build_url 21:11:25 INFO - Contents: 21:11:25 INFO - build_url:https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.tar.bz2 21:11:25 INFO - Running post-action listener: _resource_record_post_action 21:11:25 INFO - Running post-action listener: find_tests_for_verification 21:11:25 INFO - Running post-action listener: set_extra_try_arguments 21:11:25 INFO - [mozharness: 2017-09-17 04:11:25.420485Z] Finished download-and-extract step (success) 21:11:25 INFO - [mozharness: 2017-09-17 04:11:25.420582Z] Running populate-webroot step. 21:11:25 INFO - Running pre-action listener: _resource_record_pre_action 21:11:25 INFO - Running main action method: populate_webroot 21:11:25 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:11:25 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['tresize', 21:11:25 INFO - 'tcanvasmark']}, 21:11:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:11:25 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['dromaeo_css', 21:11:25 INFO - 'kraken']}, 21:11:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:11:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:11:25 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:11:25 INFO - 'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:11:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:11:25 INFO - 'tests': ['damp', 'tps']}, 21:11:25 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:11:25 INFO - 'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['damp', 'tps']}, 21:11:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:11:25 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['dromaeo_dom']}, 21:11:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:11:25 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['basic_compositor_video', 21:11:25 INFO - 'glvideo']}, 21:11:25 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:11:25 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:11:25 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:11:25 INFO - 'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['ts_paint_webext', 21:11:25 INFO - 'tp5o_webext']}, 21:11:25 INFO - 'other-e10s': {'tests': ['a11yr', 21:11:25 INFO - 'ts_paint', 21:11:25 INFO - 'tpaint', 21:11:25 INFO - 'sessionrestore', 21:11:25 INFO - 'sessionrestore_many_windows', 21:11:25 INFO - 'sessionrestore_no_auto_restore', 21:11:25 INFO - 'tabpaint']}, 21:11:25 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['a11yr', 21:11:25 INFO - 'ts_paint', 21:11:25 INFO - 'tpaint', 21:11:25 INFO - 'sessionrestore', 21:11:25 INFO - 'sessionrestore_many_windows', 21:11:25 INFO - 'sessionrestore_no_auto_restore', 21:11:25 INFO - 'tabpaint']}, 21:11:25 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 21:11:25 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 21:11:25 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['perf_reftest_singletons']}, 21:11:25 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['bloom_basic']}, 21:11:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:11:25 INFO - 'tsvgr_opacity', 21:11:25 INFO - 'tart', 21:11:25 INFO - 'tscrollx', 21:11:25 INFO - 'tsvg_static']}, 21:11:25 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['tsvgx', 21:11:25 INFO - 'tsvgr_opacity', 21:11:25 INFO - 'tart', 21:11:25 INFO - 'tscrollx', 21:11:25 INFO - 'tsvg_static']}, 21:11:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:11:25 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:11:25 INFO - 'talos_options': ['--disable-stylo'], 21:11:25 INFO - 'tests': ['tp5o']}, 21:11:25 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:11:25 INFO - 'talos_options': ['--mitmproxy', 21:11:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:11:25 INFO - '--firstNonBlankPaint'], 21:11:25 INFO - 'tests': ['tp6_google', 21:11:25 INFO - 'tp6_youtube', 21:11:25 INFO - 'tp6_amazon', 21:11:25 INFO - 'tp6_facebook']}, 21:11:25 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:11:25 INFO - 'talos_options': ['--disable-stylo', 21:11:25 INFO - '--mitmproxy', 21:11:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:11:25 INFO - '--firstNonBlankPaint'], 21:11:25 INFO - 'tests': ['tp6_google', 21:11:25 INFO - 'tp6_youtube', 21:11:25 INFO - 'tp6_amazon', 21:11:25 INFO - 'tp6_facebook']}, 21:11:25 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:11:25 INFO - 'talos_options': ['--stylo-threads=1', 21:11:25 INFO - '--mitmproxy', 21:11:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:11:25 INFO - '--firstNonBlankPaint'], 21:11:25 INFO - 'tests': ['tp6_google', 21:11:25 INFO - 'tp6_youtube', 21:11:25 INFO - 'tp6_amazon', 21:11:25 INFO - 'tp6_facebook']}, 21:11:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:11:25 INFO - 'talos_options': ['--xperf_path', 21:11:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:11:25 INFO - 'tests': ['tp5n']}, 21:11:25 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:11:25 INFO - 'talos_options': ['--disable-stylo', 21:11:25 INFO - '--xperf_path', 21:11:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:11:25 INFO - 'tests': ['tp5n']}}} 21:11:25 INFO - Running post-action listener: _resource_record_post_action 21:11:25 INFO - [mozharness: 2017-09-17 04:11:25.435501Z] Finished populate-webroot step (success) 21:11:25 INFO - [mozharness: 2017-09-17 04:11:25.435589Z] Running create-virtualenv step. 21:11:25 INFO - Running pre-action listener: _resource_record_pre_action 21:11:25 INFO - Running main action method: create_virtualenv 21:11:25 INFO - Creating virtualenv /builds/slave/test/build/venv 21:11:25 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:11:25 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 21:11:25 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:11:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:11:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:11:27 INFO - Installing distribute.............................................................................................................................................................................................done. 21:11:27 INFO - Installing pip...............done. 21:11:27 INFO - Return code: 0 21:11:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:11:27 INFO - Reading from file tmpfile_stdout 21:11:27 INFO - Output received: 21:11:27 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 21:11:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:11:27 INFO - Using env: {'DISPLAY': ':0', 21:11:27 INFO - 'HOME': '/home/cltbld', 21:11:27 INFO - 'LANG': 'en_US.UTF-8', 21:11:27 INFO - 'LANGUAGE': 'en_US:en', 21:11:27 INFO - 'LOGNAME': 'cltbld', 21:11:27 INFO - 'MAIL': '/var/mail/cltbld', 21:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:27 INFO - 'MOZ_NO_REMOTE': '1', 21:11:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:27 INFO - 'NO_EM_RESTART': '1', 21:11:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:27 INFO - 'PWD': '/builds/slave/test', 21:11:27 INFO - 'SHELL': '/bin/bash', 21:11:27 INFO - 'SHLVL': '1', 21:11:27 INFO - 'TERM': 'linux', 21:11:27 INFO - 'TMOUT': '86400', 21:11:27 INFO - 'USER': 'cltbld', 21:11:27 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:27 INFO - '_': '/tools/buildbot/bin/python'} 21:11:29 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:11:29 INFO - Downloading/unpacking pip>=1.5 21:11:29 INFO - Running setup.py egg_info for package pip 21:11:29 INFO - warning: no files found matching 'pip/cacert.pem' 21:11:29 INFO - warning: no files found matching '*.html' under directory 'docs' 21:11:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:11:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:11:29 INFO - Installing collected packages: pip 21:11:29 INFO - Found existing installation: pip 1.1 21:11:29 INFO - Uninstalling pip: 21:11:29 INFO - Successfully uninstalled pip 21:11:29 INFO - Running setup.py install for pip 21:11:29 INFO - warning: no files found matching 'pip/cacert.pem' 21:11:29 INFO - warning: no files found matching '*.html' under directory 'docs' 21:11:29 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:11:29 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:11:29 INFO - Installing pip script to /builds/slave/test/build/venv/bin 21:11:29 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 21:11:29 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 21:11:29 INFO - Successfully installed pip 21:11:29 INFO - Cleaning up... 21:11:29 INFO - Return code: 0 21:11:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:11: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')]} 21:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11: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')]} 21:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:29 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 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:29 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 21:11:29 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 21:11:29 INFO - Using env: {'DISPLAY': ':0', 21:11:29 INFO - 'HOME': '/home/cltbld', 21:11:29 INFO - 'LANG': 'en_US.UTF-8', 21:11:29 INFO - 'LANGUAGE': 'en_US:en', 21:11:29 INFO - 'LOGNAME': 'cltbld', 21:11:29 INFO - 'MAIL': '/var/mail/cltbld', 21:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:29 INFO - 'MOZ_NO_REMOTE': '1', 21:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:29 INFO - 'NO_EM_RESTART': '1', 21:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:29 INFO - 'PWD': '/builds/slave/test', 21:11:29 INFO - 'SHELL': '/bin/bash', 21:11:29 INFO - 'SHLVL': '1', 21:11:29 INFO - 'TERM': 'linux', 21:11:29 INFO - 'TMOUT': '86400', 21:11:29 INFO - 'USER': 'cltbld', 21:11:29 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:29 INFO - '_': '/tools/buildbot/bin/python'} 21:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:29 INFO - Downloading/unpacking psutil>=3.1.1 21:11:29 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 21:11:29 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 21:11:29 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 21:11:29 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 21:11:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:11:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:11:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:11:30 INFO - Installing collected packages: psutil 21:11:30 INFO - Running setup.py install for psutil 21:11:30 INFO - building 'psutil._psutil_linux' extension 21:11:30 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 21:11:31 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 21:11:31 INFO - building 'psutil._psutil_posix' extension 21:11:31 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 21:11:32 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 21:11:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:11:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:11:32 INFO - Successfully installed psutil 21:11:32 INFO - Cleaning up... 21:11:32 INFO - Return code: 0 21:11:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:11:32 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')]} 21:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:32 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')]} 21:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:32 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 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:32 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 21:11:32 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 21:11:32 INFO - Using env: {'DISPLAY': ':0', 21:11:32 INFO - 'HOME': '/home/cltbld', 21:11:32 INFO - 'LANG': 'en_US.UTF-8', 21:11:32 INFO - 'LANGUAGE': 'en_US:en', 21:11:32 INFO - 'LOGNAME': 'cltbld', 21:11:32 INFO - 'MAIL': '/var/mail/cltbld', 21:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:32 INFO - 'MOZ_NO_REMOTE': '1', 21:11:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:32 INFO - 'NO_EM_RESTART': '1', 21:11:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:32 INFO - 'PWD': '/builds/slave/test', 21:11:32 INFO - 'SHELL': '/bin/bash', 21:11:32 INFO - 'SHLVL': '1', 21:11:32 INFO - 'TERM': 'linux', 21:11:32 INFO - 'TMOUT': '86400', 21:11:32 INFO - 'USER': 'cltbld', 21:11:32 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:32 INFO - '_': '/tools/buildbot/bin/python'} 21:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:11:32 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 21:11:32 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 21:11:32 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 21:11:32 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 21:11:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:11:33 INFO - Installing collected packages: mozsystemmonitor 21:11:33 INFO - Running setup.py install for mozsystemmonitor 21:11:33 INFO - Successfully installed mozsystemmonitor 21:11:33 INFO - Cleaning up... 21:11:33 INFO - Return code: 0 21:11:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:11:33 INFO - Using env: {'DISPLAY': ':0', 21:11:33 INFO - 'HOME': '/home/cltbld', 21:11:33 INFO - 'LANG': 'en_US.UTF-8', 21:11:33 INFO - 'LANGUAGE': 'en_US:en', 21:11:33 INFO - 'LOGNAME': 'cltbld', 21:11:33 INFO - 'MAIL': '/var/mail/cltbld', 21:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:33 INFO - 'MOZ_NO_REMOTE': '1', 21:11:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:33 INFO - 'NO_EM_RESTART': '1', 21:11:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:33 INFO - 'PWD': '/builds/slave/test', 21:11:33 INFO - 'SHELL': '/bin/bash', 21:11:33 INFO - 'SHLVL': '1', 21:11:33 INFO - 'TERM': 'linux', 21:11:33 INFO - 'TMOUT': '86400', 21:11:33 INFO - 'USER': 'cltbld', 21:11:33 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:33 INFO - '_': '/tools/buildbot/bin/python'} 21:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:34 INFO - Downloading/unpacking jsonschema==2.5.1 21:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:11:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:35 INFO - Downloading functools32-3.2.3-2.tar.gz 21:11:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:11:35 INFO - warning: no files found matching '*.txt' 21:11:35 INFO - no previously-included directories found matching 'build' 21:11:35 INFO - no previously-included directories found matching 'dist' 21:11:35 INFO - no previously-included directories found matching '.git*' 21:11:35 INFO - Installing collected packages: jsonschema, functools32 21:11:35 INFO - Running setup.py install for functools32 21:11:35 INFO - warning: no files found matching '*.txt' 21:11:35 INFO - no previously-included directories found matching 'build' 21:11:35 INFO - no previously-included directories found matching 'dist' 21:11:35 INFO - no previously-included directories found matching '.git*' 21:11:35 INFO - Successfully installed jsonschema functools32 21:11:35 INFO - Cleaning up... 21:11:35 INFO - Return code: 0 21:11:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:11: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')]} 21:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11: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')]} 21:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:35 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 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:35 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 21:11:35 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 21:11:35 INFO - Using env: {'DISPLAY': ':0', 21:11:35 INFO - 'HOME': '/home/cltbld', 21:11:35 INFO - 'LANG': 'en_US.UTF-8', 21:11:35 INFO - 'LANGUAGE': 'en_US:en', 21:11:35 INFO - 'LOGNAME': 'cltbld', 21:11:35 INFO - 'MAIL': '/var/mail/cltbld', 21:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:35 INFO - 'MOZ_NO_REMOTE': '1', 21:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:35 INFO - 'NO_EM_RESTART': '1', 21:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:35 INFO - 'PWD': '/builds/slave/test', 21:11:35 INFO - 'SHELL': '/bin/bash', 21:11:35 INFO - 'SHLVL': '1', 21:11:35 INFO - 'TERM': 'linux', 21:11:35 INFO - 'TMOUT': '86400', 21:11:35 INFO - 'USER': 'cltbld', 21:11:35 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:35 INFO - '_': '/tools/buildbot/bin/python'} 21:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:11:35 INFO - Cleaning up... 21:11:35 INFO - Return code: 0 21:11:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:11: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')]} 21:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11: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')]} 21:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:35 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 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:35 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 21:11:35 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 21:11:35 INFO - Using env: {'DISPLAY': ':0', 21:11:35 INFO - 'HOME': '/home/cltbld', 21:11:35 INFO - 'LANG': 'en_US.UTF-8', 21:11:35 INFO - 'LANGUAGE': 'en_US:en', 21:11:35 INFO - 'LOGNAME': 'cltbld', 21:11:35 INFO - 'MAIL': '/var/mail/cltbld', 21:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:35 INFO - 'MOZ_NO_REMOTE': '1', 21:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:35 INFO - 'NO_EM_RESTART': '1', 21:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:35 INFO - 'PWD': '/builds/slave/test', 21:11:35 INFO - 'SHELL': '/bin/bash', 21:11:35 INFO - 'SHLVL': '1', 21:11:35 INFO - 'TERM': 'linux', 21:11:35 INFO - 'TMOUT': '86400', 21:11:35 INFO - 'USER': 'cltbld', 21:11:35 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:35 INFO - '_': '/tools/buildbot/bin/python'} 21:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:35 INFO - Downloading/unpacking blobuploader==1.2.4 21:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:37 INFO - Downloading blobuploader-1.2.4.tar.gz 21:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:11:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:11:37 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 21:11:37 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 21:11:37 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 21:11:37 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 21:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:11:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:11:37 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 21:11:37 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 21:11:37 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 21:11:37 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 21:11:37 INFO - Downloading docopt-0.6.1.tar.gz 21:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:11:37 INFO - Installing collected packages: blobuploader, requests, docopt 21:11:37 INFO - Running setup.py install for blobuploader 21:11:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:11:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:11:37 INFO - Running setup.py install for requests 21:11:38 INFO - Running setup.py install for docopt 21:11:38 INFO - Successfully installed blobuploader requests docopt 21:11:38 INFO - Cleaning up... 21:11:38 INFO - Return code: 0 21:11:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:11:38 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')]} 21:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:38 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')]} 21:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:38 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 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:38 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 21:11:38 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 21:11:38 INFO - Using env: {'DISPLAY': ':0', 21:11:38 INFO - 'HOME': '/home/cltbld', 21:11:38 INFO - 'LANG': 'en_US.UTF-8', 21:11:38 INFO - 'LANGUAGE': 'en_US:en', 21:11:38 INFO - 'LOGNAME': 'cltbld', 21:11:38 INFO - 'MAIL': '/var/mail/cltbld', 21:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:38 INFO - 'MOZ_NO_REMOTE': '1', 21:11:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:38 INFO - 'NO_EM_RESTART': '1', 21:11:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:38 INFO - 'PWD': '/builds/slave/test', 21:11:38 INFO - 'SHELL': '/bin/bash', 21:11:38 INFO - 'SHLVL': '1', 21:11:38 INFO - 'TERM': 'linux', 21:11:38 INFO - 'TMOUT': '86400', 21:11:38 INFO - 'USER': 'cltbld', 21:11:38 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:38 INFO - '_': '/tools/buildbot/bin/python'} 21:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:11:38 INFO - Running setup.py (path:/tmp/pip-dQl7Z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:11:38 INFO - Running setup.py (path:/tmp/pip-thZqvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:11:38 INFO - Running setup.py (path:/tmp/pip-_Vx5Nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:11:38 INFO - Running setup.py (path:/tmp/pip-6N3iHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:11:38 INFO - Running setup.py (path:/tmp/pip-T4ggee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:11:38 INFO - Running setup.py (path:/tmp/pip-1gzDXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:11:38 INFO - Running setup.py (path:/tmp/pip-0ose_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:11:38 INFO - Running setup.py (path:/tmp/pip-ZCyCUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:11:38 INFO - Running setup.py (path:/tmp/pip-hIbJpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:11:39 INFO - Running setup.py (path:/tmp/pip-OOW0BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:11:39 INFO - Running setup.py (path:/tmp/pip-dwVaQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:11:39 INFO - Running setup.py (path:/tmp/pip-MSPCRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:11:39 INFO - Running setup.py (path:/tmp/pip-9t_10g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:11:39 INFO - Running setup.py (path:/tmp/pip-9oZXYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:11:39 INFO - Running setup.py (path:/tmp/pip-5lcy31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:11:39 INFO - Running setup.py (path:/tmp/pip-Yqp_M6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:11:39 INFO - Running setup.py (path:/tmp/pip-QE3ejC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:11:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:11:39 INFO - Running setup.py install for manifestparser 21:11:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:11:39 INFO - Running setup.py install for mozcrash 21:11:39 INFO - Running setup.py install for mozdebug 21:11:39 INFO - Running setup.py install for mozdevice 21:11:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:11:39 INFO - Running setup.py install for mozfile 21:11:40 INFO - Running setup.py install for mozhttpd 21:11:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Running setup.py install for mozinfo 21:11:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Running setup.py install for mozInstall 21:11:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Running setup.py install for mozleak 21:11:40 INFO - Running setup.py install for mozlog 21:11:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Running setup.py install for moznetwork 21:11:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Running setup.py install for mozprocess 21:11:40 INFO - Running setup.py install for mozprofile 21:11:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Running setup.py install for mozrunner 21:11:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:11:40 INFO - Running setup.py install for mozscreenshot 21:11:41 INFO - Running setup.py install for moztest 21:11:41 INFO - Running setup.py install for mozversion 21:11:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:11:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:11:41 INFO - Cleaning up... 21:11:41 INFO - Return code: 0 21:11:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:11:41 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')]} 21:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:41 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')]} 21:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:41 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 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:41 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 21:11:41 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 21:11:41 INFO - Using env: {'DISPLAY': ':0', 21:11:41 INFO - 'HOME': '/home/cltbld', 21:11:41 INFO - 'LANG': 'en_US.UTF-8', 21:11:41 INFO - 'LANGUAGE': 'en_US:en', 21:11:41 INFO - 'LOGNAME': 'cltbld', 21:11:41 INFO - 'MAIL': '/var/mail/cltbld', 21:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:41 INFO - 'MOZ_NO_REMOTE': '1', 21:11:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:41 INFO - 'NO_EM_RESTART': '1', 21:11:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:41 INFO - 'PWD': '/builds/slave/test', 21:11:41 INFO - 'SHELL': '/bin/bash', 21:11:41 INFO - 'SHLVL': '1', 21:11:41 INFO - 'TERM': 'linux', 21:11:41 INFO - 'TMOUT': '86400', 21:11:41 INFO - 'USER': 'cltbld', 21:11:41 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:41 INFO - '_': '/tools/buildbot/bin/python'} 21:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:11:41 INFO - Running setup.py (path:/tmp/pip-HTpPBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:11:41 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)) 21:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:11:41 INFO - Running setup.py (path:/tmp/pip-gJPYQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:11:41 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)) 21:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:11:41 INFO - Running setup.py (path:/tmp/pip-doTR6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:11:41 INFO - Running setup.py (path:/tmp/pip-YpV62N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:11:41 INFO - Running setup.py (path:/tmp/pip-0SVHLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:11:41 INFO - Running setup.py (path:/tmp/pip-EE4vjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:11:41 INFO - Running setup.py (path:/tmp/pip-09w7vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:11:41 INFO - Running setup.py (path:/tmp/pip-c3Fqy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:11:42 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)) 21:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:11:42 INFO - Running setup.py (path:/tmp/pip-ox7GdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:11:42 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)) 21:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:11:42 INFO - Running setup.py (path:/tmp/pip-9422jX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:11:42 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)) 21:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:11:42 INFO - Running setup.py (path:/tmp/pip-59fudL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:11:42 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)) 21:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:11:42 INFO - Running setup.py (path:/tmp/pip-TG4CYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:11:42 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)) 21:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:11:42 INFO - Running setup.py (path:/tmp/pip-vpTC3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:11:42 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)) 21:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:11:42 INFO - Running setup.py (path:/tmp/pip-acgCfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:11:42 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)) 21:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:11:42 INFO - Running setup.py (path:/tmp/pip-JMsq5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:11:42 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)) 21:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:11:42 INFO - Running setup.py (path:/tmp/pip-uSzLfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:11:42 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)) 21:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:11:42 INFO - Running setup.py (path:/tmp/pip-8CnWN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:11:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:43 INFO - Downloading blessings-1.6.tar.gz 21:11:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:11:43 INFO - Installing collected packages: blessings 21:11:43 INFO - Running setup.py install for blessings 21:11:43 INFO - Successfully installed blessings 21:11:43 INFO - Cleaning up... 21:11:43 INFO - Return code: 0 21:11:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:11:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:11:44 INFO - Reading from file tmpfile_stdout 21:11:44 INFO - Current package versions: 21:11:44 INFO - argparse == 1.2.1 21:11:44 INFO - blessings == 1.6 21:11:44 INFO - blobuploader == 1.2.4 21:11:44 INFO - docopt == 0.6.1 21:11:44 INFO - functools32 == 3.2.3-2 21:11:44 INFO - jsonschema == 2.5.1 21:11:44 INFO - manifestparser == 1.1 21:11:44 INFO - mozInstall == 1.13 21:11:44 INFO - mozcrash == 1.0 21:11:44 INFO - mozdebug == 0.1 21:11:44 INFO - mozdevice == 0.51 21:11:44 INFO - mozfile == 1.2 21:11:44 INFO - mozhttpd == 0.7 21:11:44 INFO - mozinfo == 0.10 21:11:44 INFO - mozleak == 0.1 21:11:44 INFO - mozlog == 3.5 21:11:44 INFO - moznetwork == 0.27 21:11:44 INFO - mozprocess == 0.25 21:11:44 INFO - mozprofile == 0.28 21:11:44 INFO - mozrunner == 6.13 21:11:44 INFO - mozscreenshot == 0.1 21:11:44 INFO - mozsystemmonitor == 0.3 21:11:44 INFO - moztest == 0.8 21:11:44 INFO - mozversion == 1.4 21:11:44 INFO - psutil == 3.1.1 21:11:44 INFO - requests == 1.2.3 21:11:44 INFO - wsgiref == 0.1.2 21:11:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:44 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 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:44 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 21:11:44 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 21:11:44 INFO - Using env: {'DISPLAY': ':0', 21:11:44 INFO - 'HOME': '/home/cltbld', 21:11:44 INFO - 'LANG': 'en_US.UTF-8', 21:11:44 INFO - 'LANGUAGE': 'en_US:en', 21:11:44 INFO - 'LOGNAME': 'cltbld', 21:11:44 INFO - 'MAIL': '/var/mail/cltbld', 21:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:44 INFO - 'MOZ_NO_REMOTE': '1', 21:11:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:44 INFO - 'NO_EM_RESTART': '1', 21:11:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:44 INFO - 'PWD': '/builds/slave/test', 21:11:44 INFO - 'SHELL': '/bin/bash', 21:11:44 INFO - 'SHLVL': '1', 21:11:44 INFO - 'TERM': 'linux', 21:11:44 INFO - 'TMOUT': '86400', 21:11:44 INFO - 'USER': 'cltbld', 21:11:44 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:44 INFO - '_': '/tools/buildbot/bin/python'} 21:11:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:44 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)) 21:11:44 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)) 21:11:44 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)) 21:11:44 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)) 21:11:44 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)) 21:11:44 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)) 21:11:44 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)) 21:11:44 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)) 21:11:44 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)) 21:11:44 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:11:44 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 21:11:44 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 21:11:44 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 21:11:44 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 21:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 21:11:45 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:11:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:11:45 INFO - Installing collected packages: simplejson 21:11:45 INFO - Running setup.py install for simplejson 21:11:45 INFO - building 'simplejson._speedups' extension 21:11:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 21:11:46 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 21:11:46 INFO - Successfully installed simplejson 21:11:46 INFO - Cleaning up... 21:11:46 INFO - Return code: 0 21:11:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24791f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2642a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27e5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', '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 21:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:11:46 INFO - Using env: {'DISPLAY': ':0', 21:11:46 INFO - 'HOME': '/home/cltbld', 21:11:46 INFO - 'LANG': 'en_US.UTF-8', 21:11:46 INFO - 'LANGUAGE': 'en_US:en', 21:11:46 INFO - 'LOGNAME': 'cltbld', 21:11:46 INFO - 'MAIL': '/var/mail/cltbld', 21:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:46 INFO - 'MOZ_NO_REMOTE': '1', 21:11:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:11:46 INFO - 'NO_EM_RESTART': '1', 21:11:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:46 INFO - 'PWD': '/builds/slave/test', 21:11:46 INFO - 'SHELL': '/bin/bash', 21:11:46 INFO - 'SHLVL': '1', 21:11:46 INFO - 'TERM': 'linux', 21:11:46 INFO - 'TMOUT': '86400', 21:11:46 INFO - 'USER': 'cltbld', 21:11:46 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:46 INFO - '_': '/tools/buildbot/bin/python'} 21:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:11:46 INFO - Cleaning up... 21:11:46 INFO - Return code: 0 21:11:46 INFO - Running post-action listener: _resource_record_post_action 21:11:46 INFO - Running post-action listener: _start_resource_monitoring 21:11:46 INFO - Starting resource monitoring. 21:11:46 INFO - [mozharness: 2017-09-17 04:11:46.915927Z] Finished create-virtualenv step (success) 21:11:46 INFO - [mozharness: 2017-09-17 04:11:46.916937Z] Running install step. 21:11:46 INFO - Running pre-action listener: _resource_record_pre_action 21:11:46 INFO - Running main action method: install 21:11:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:11:47 INFO - Reading from file tmpfile_stdout 21:11:47 INFO - Detecting whether we're running mozinstall >=1.0... 21:11:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:11:47 INFO - Reading from file tmpfile_stdout 21:11:47 INFO - Output received: 21:11:47 INFO - Usage: mozinstall [options] installer 21:11:47 INFO - Options: 21:11:47 INFO - -h, --help show this help message and exit 21:11:47 INFO - -d DEST, --destination=DEST 21:11:47 INFO - Directory to install application into. [default: 21:11:47 INFO - "/builds/slave/test"] 21:11:47 INFO - --app=APP Application being installed. [default: firefox] 21:11:47 INFO - mkdir: /builds/slave/test/build/application 21:11:47 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'] 21:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:12:04 INFO - Reading from file tmpfile_stdout 21:12:04 INFO - Output received: 21:12:04 INFO - /builds/slave/test/build/application/firefox/firefox 21:12:04 INFO - Running post-action listener: _resource_record_post_action 21:12:04 INFO - [mozharness: 2017-09-17 04:12:04.993998Z] Finished install step (success) 21:12:04 INFO - [mozharness: 2017-09-17 04:12:04.994248Z] Running setup-mitmproxy step. 21:12:04 INFO - Running pre-action listener: _resource_record_pre_action 21:12:04 INFO - Running main action method: setup_mitmproxy 21:12:04 INFO - Skipping: mitmproxy is not required 21:12:04 INFO - Running post-action listener: _resource_record_post_action 21:12:04 INFO - [mozharness: 2017-09-17 04:12:04.995371Z] Finished setup-mitmproxy step (success) 21:12:04 INFO - [mozharness: 2017-09-17 04:12:04.995591Z] Running run-tests step. 21:12:04 INFO - Running pre-action listener: _resource_record_pre_action 21:12:04 INFO - Running pre-action listener: _set_gcov_prefix 21:12:04 INFO - Running main action method: run_tests 21:12:04 WARNING - Try message not found. 21:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:12:05 INFO - Python 2.7.3 21:12:05 INFO - Return code: 0 21:12:05 INFO - grabbing minidump binary from tooltool 21:12: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')]} 21:12:05 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 0x27e9090>, '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 0x27e9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27e3f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:12:05 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 21:12:05 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 21:12:05 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 21:12:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:12:05 INFO - Return code: 0 21:12:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:12:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:12:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:12:05 INFO - ENV: RUST_BACKTRACE is now full 21:12:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:12:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:12:05 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/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 21:12:05 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/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 21:12:05 INFO - Using env: {'DISPLAY': ':0', 21:12:05 INFO - 'HOME': '/home/cltbld', 21:12:05 INFO - 'LANG': 'en_US.UTF-8', 21:12:05 INFO - 'LANGUAGE': 'en_US:en', 21:12:05 INFO - 'LOGNAME': 'cltbld', 21:12:05 INFO - 'MAIL': '/var/mail/cltbld', 21:12:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:12:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:05 INFO - 'MOZ_NO_REMOTE': '1', 21:12:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:05 INFO - 'NO_EM_RESTART': '1', 21:12:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:05 INFO - 'PWD': '/builds/slave/test', 21:12:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:12:05 INFO - 'RUST_BACKTRACE': 'full', 21:12:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:12:05 INFO - 'SHELL': '/bin/bash', 21:12:05 INFO - 'SHLVL': '1', 21:12:05 INFO - 'TERM': 'linux', 21:12:05 INFO - 'TMOUT': '86400', 21:12:05 INFO - 'USER': 'cltbld', 21:12:05 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:05 INFO - '_': '/tools/buildbot/bin/python'} 21:12:05 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/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 21:12:05 INFO - mozversion application_buildid: 20170917031344 21:12:05 INFO - mozversion application_changeset: be5bddf32db0a1ed27251295b7711d06f9189b11 21:12:05 INFO - mozversion application_display_name: Nightly 21:12:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:12:05 INFO - mozversion application_name: Firefox 21:12:05 INFO - mozversion application_remotingname: firefox 21:12:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:12:05 INFO - mozversion application_vendor: Mozilla 21:12:05 INFO - mozversion application_version: 57.0a1 21:12:05 INFO - mozversion platform_buildid: 20170917031344 21:12:05 INFO - mozversion platform_changeset: be5bddf32db0a1ed27251295b7711d06f9189b11 21:12:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:12:05 INFO - mozversion platform_version: 57.0a1 21:12:05 INFO - using testdate: 1505621525 21:12:05 INFO - actual date: 1505621525 21:12:05 INFO - starting webserver on 'localhost:49761' 21:12:05 INFO - SUITE-START | Running 2 tests 21:12:05 INFO - TEST-START | dromaeo_css 21:12:05 INFO - Initialising browser for dromaeo_css test... 21:12:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49761/getInfo.html -profile /tmp/tmpFhoBsp/profile 21:12:05 INFO - TEST-INFO | started process 3536 (/builds/slave/test/build/application/firefox/firefox http://localhost:49761/getInfo.html) 21:12:12 INFO - TEST-INFO | 3536: exit 0 21:12:13 INFO - Browser initialized. 21:12:13 INFO - Running cycle 1/1 for dromaeo_css test... 21:12:13 INFO - Using env: {'DISPLAY': ':0', 21:12:13 INFO - 'HOME': '/home/cltbld', 21:12:13 INFO - 'JSGC_DISABLE_POISONING': '1', 21:12:13 INFO - 'LANG': 'en_US.UTF-8', 21:12:13 INFO - 'LANGUAGE': 'en_US:en', 21:12:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:12:13 INFO - 'LOGNAME': 'cltbld', 21:12:13 INFO - 'MAIL': '/var/mail/cltbld', 21:12:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:12:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:12:13 INFO - 'MOZ_CRASHREPORTER': '1', 21:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:12:13 INFO - 'MOZ_NO_REMOTE': '1', 21:12:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:12:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:13 INFO - 'NO_EM_RESTART': '1', 21:12:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:13 INFO - 'PWD': '/builds/slave/test', 21:12:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:12:13 INFO - 'RUST_BACKTRACE': 'full', 21:12:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:12:13 INFO - 'SHELL': '/bin/bash', 21:12:13 INFO - 'SHLVL': '1', 21:12:13 INFO - 'TERM': 'linux', 21:12:13 INFO - 'TMOUT': '86400', 21:12:13 INFO - 'USER': 'cltbld', 21:12:13 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:13 INFO - '_': '/tools/buildbot/bin/python'} 21:12:13 INFO - TEST-INFO | started process 3772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFhoBsp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:12:13 INFO - PID 3772 | 21:12:13 INFO - PID 3772 | (/builds/slave/test/build/application/firefox/firefox:3821): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:12:13 INFO - PID 3772 | 21:12:24 INFO - PID 3772 | RSS: Main: 151183360 21:12:24 INFO - PID 3772 | 21:15:22 INFO - PID 3772 | Cycle 1(1): loaded http://localhost:49761/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49761/tests/dromaeo/cssquery-ext.html) 21:15:23 INFO - PID 3772 | RSS: Main: 154931200 21:15:23 INFO - PID 3772 | 21:18:25 INFO - PID 3772 | Cycle 1(1): loaded http://localhost:49761/tests/dromaeo/cssquery-ext.html (next: http://localhost:49761/tests/dromaeo/cssquery-jquery.html) 21:18:25 INFO - PID 3772 | RSS: Main: 155389952 21:18:25 INFO - PID 3772 | 21:21:27 INFO - PID 3772 | Cycle 1(1): loaded http://localhost:49761/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49761/tests/dromaeo/cssquery-mootools.html) 21:21:28 INFO - PID 3772 | RSS: Main: 157372416 21:21:28 INFO - PID 3772 | 21:24:29 INFO - PID 3772 | Cycle 1(1): loaded http://localhost:49761/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49761/tests/dromaeo/cssquery-prototype.html) 21:24:30 INFO - PID 3772 | RSS: Main: 153726976 21:24:30 INFO - PID 3772 | 21:27:33 INFO - PID 3772 | Cycle 1(1): loaded http://localhost:49761/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49761/tests/dromaeo/cssquery-yui.html) 21:27:34 INFO - PID 3772 | RSS: Main: 156528640 21:27:34 INFO - PID 3772 | 21:30:35 INFO - PID 3772 | Cycle 1(1): loaded http://localhost:49761/tests/dromaeo/cssquery-yui.html (next: http://localhost:49761/tests/dromaeo/cssquery-dojo.html) 21:30:36 INFO - PID 3772 | RSS: Main: 159739904 21:30:36 INFO - PID 3772 | 21:30:36 INFO - PID 3772 | __start_tp_report 21:30:36 INFO - PID 3772 | _x_x_mozilla_page_load 21:30:36 INFO - PID 3772 | _x_x_mozilla_page_load_details 21:30:36 INFO - PID 3772 | |i|pagename|runs| 21:30:36 INFO - PID 3772 | |0;dojo.html;3152.1174071260048;3152.668969758177;3155.968440315596;3185.7769956103066;3187.713105820476;6170.722317495713;6264.9060264096015;6276.592021518598;6315.715792789324;6359.618422894624;6210.068489726544;6222.315545290015;6228.688565571726;6244.875102497947;6245.219347581552;5751.338596061444;5845.3862344453855;5847.736851841656;5874.647521148745;5878.088896221093;5588.692621905793;5696;5780.393058728836;5894.734736936848;5904.143899134623;5668.546516278709;5731.340895796995;5787.045137552299;5802.82591522255;5825.737841797109;5750.683712395817;5763.567732420085;5825.533957283407;5828.504577110951;5831.883362332772;5636.718164091779;5707.800226992077;5730.885382292373;5734.541236701054;5739.053056245714;6124.234470691164;6162.137300777895;6165.815025549241;6217.067439884008;6319.936800631981;6764.9323506764795;6815.659217039178;6838.350356716104;6839.931600683979;6843.3840954313855;6776.830579235559;6781.72873085076;6867.725290988355;6873;6880.621565813878;6105.32841387447;6194.946859033975;6200.503959683169;6206.565540412173;6264.686765661744;6761.492888033319;6839.760608378683;6847.554908930903;6856.828579285458;6867.656617169122;6524.6737663116655;6534.771283005072;6542.574732642363;6554.115194448688;6554.4101030906495;6660.200775906826;6666.133402657623;6666.266710661824;6672.799816005528;6674.466042716668;6628.436582890508;6630.469562434993;6712.463002959751;6773.525853190229;6799.932000680029;6682.699278532477;6768.69540870662;6771.424428923597;6785.42323902464;6819.31806819324;5257.421683614799;5305.575553955674;5310.575153987672;5320.813771517978;5345.278387417727;4471.239889218776;4583.427071616048;4584.679072464895;4686.062787442525;4706.435227772689;5861.88276234473;5895.380984996599;5899.823005309848;5900.20347253124;5913.29040515141;6706.865862682719;6778.966105169541;6781.762638307636;6820.35206655367;6849.2465828758795;5689.7724091036725;5779.479846813807;5821.214136091573;5909.763609455659;5938.673372964528;5713;5733.827985160452;5745.626534275259;5751.741171647286;5806.68063256525;6543.247526534415;6596.86806263872;6627.3372662733345;6657.23441804189;6657.800265992028;6645.60126392418;6745.561538499982;6792.354726300993;6819.386255237053;6823.283555226729;6378.6810659467765;6537.313582073813;6559.344065593594;6630.9668451657435;6631.635260060743;6623.933760662524;6649.501287403561;6697.430718388991;6704.396604305636;6719.260881302955;6723.226828914541;6799.898001529882;6810.080639113854;6813.3867951882585;6818.079559259437;6482.546221764431;6488.870222595616;6494.545381823227;6596.439302659328;6608.70260838273;6371.93628063732;6450.387213214737;6463.224413070461;6488.156539649815;6595.934040659532;6401.583897046677;6401.839954001187;6405.19935008124;6432;6527.05357723118;6591.604503729792;6614;6619.66901654925;6628.767993120217;6710.563813352116;6333.96833015832;6348.746050158126;6358.904616430665;6416.518761092843;6429.903551446639 21:30:36 INFO - PID 3772 | |1;ext.html;845.0704225352114;873.0396563779843;879.0286733159858;880.5333173418082;881.9470831750092;41921.58078419215;42648.57351426485;42692.359614605804;42763.78618106909;42837.35743963843;39167.41248881269;39463;39492.407613885814;39528.60471395285;39637.20725585487;6924.930750692504;7085.645717714119;7088.4683648726295;7111.644417779116;7123.145222573298;6980.406665433445;7050.083489146404;7057.576545407266;7064.222935477093;7067.823304417379;7015.929840701604;7737;7908.762737117867;7912.406569507281;7920.48516846406;7803.960980195091;7822.647980840848;7845.725399610987;7863.292303692695;7865.960670196641;7549.962250188714;7697.384209263301;7751.573663448508;7772.300492955661;7788.493747906425;37623.435648465296;37994.81002594969;38002.23995520074;38044;38139.427908581114;3578.7673801202836;3627.818609069536;3640.4357324614725;3640.9271814563826;3660.267946410702;31506.054818355486;31524.842375788205;31570.210744731336;31687.366252674816;31759.84120079385;5246.763895624668;5278.868028299285;5298;5300.284461597712;5336.43967383423;179595;184934;187081;187788;188204;43431.56568434338;49145;49934.25098623524;49952;49991.25013124806;51102;51432.74283628633;51436.74281628568;51495.22757158645;51612.22581661278;16413.097279649734;16452.588685282964;16581.336746530003;16600.169991500617;16605.83394166067;13620.455181792815;13638.181709097486;13642.045056845926;13644.590662280147;13650.93174534121;14139.293035348192;14145.868330533533;14196.219207943663;14211.715765684628;14298.785518217235;5543.417941116206;5626.943730562723;5703.315602127688;5755.913661295084;5763.596548241667;998.6953979036318;1005.7485628592851;1009.7223263602568;1010.4291075542295;1025.3642741500319;22777.202797902326;22978;23015.309540713806;23048.193313234293;23134.652980205312;1092.6012005617893;1111.7387413957713;1114.453917580396;1115.8493603363604;1128.8081026225561;12061.577844775391;12150.149489535825;12232.449539770732;12313.630591082281;12342.506299747909;228282;229189;229259;229369;230298;52399.47600524022;52678.473215268124;52829;52977;52985;1418.1774570748942;1451.3686546352371;1458.9343479543445;1459.489428441579;1461.4373466215584;22839;22866.885665571564;22936.197233096762;23086.653700194845;23327.18354857572;37165.442518361706;37418.251634967695;37444.62555374411;37460.06349841167;37466.43800342942;186120;186407;187230;187705;187995;34612.826935866164;36511.45232821457;37027.62972370242;37258;37365.626343736214;46445.3033204509;46736.29895551501;46799.29801053055;46827.53172468368;46933;34195;34466.655333446346;34519.654803451645;34916;35141.47287790634;33309.83345083259;33775.83112084522;34220.48669269913;34391.48412773861;34609.13477163091;37323.253534928604;38279;38340.80829595834;38611;38653.226935460574;32666.510002349507;32995.175120621214;33233.66766332306;33254.50118248277;33515.66484335125;24542.631860522462;24691.62962555599;24703.87648061748;24774;25023.249302520224 21:30:36 INFO - PID 3772 | |2;jquery.html;543.9972800135998;544.2869840508934;549.9065158922982;579.2556564814213;587.1632923084604;21964.560708785815;22826.885865570668;22845.543089138206;22896.885515572398;22916.427089322777;24185.274441766775;24423;24728.134515291968;24748.876255618743;25660.23019309419;6644.43522300605;6831.555948863308;6847.041414202015;6852.040714300002;6852.246252912176;6755.054292399043;6852.349026842442;6854.931450685504;6862.485313601474;6863.622500762456;6827.965860170692;7040.436765058808;7106.964465177667;7113.573185608854;7118.822029449253;6906.551074180213;7105.715771369165;7161.033260509843;7161.677724502358;7164.21193668696;7001;7040.04959330489;7047.295270472963;7047.330503602201;7063.6115013674225;30905.536416953764;31648.892288770014;31664.525032124537;31679.524807127913;31683.524747128813;2098.7586427560873;2124.0866427436194;2124.6069477146775;2124.787521247863;2126.9042893069695;20180.495487612927;20906.895465522728;21318.360449186395;21402.357929262147;21491;2502.987485062581;2518.8866501007496;2584.2118153963033;2588.2752829207666;2622.934426639349;124582;131204;136241;141883.29058354642;148517.25741371332;20312.898435507726;20582.27962021322;22107.673539587675;22242.777572224393;22506;42838.78580607077;42947.141057178684;43057;43092.56907430948;43173.784131079774;10859.40273284961;10930.344179349106;10960.56157753681;10975.560977560968;11029.779404411867;8946.284297256203;9023.593938272794;9035.909640903506;9115.589798459085;9157.404768690147;11028.44857757125;11280.887191127984;11424.314541127558;11436;11491.253068550517;3577.499150118985;3589.6410358963894;3815.332316844597;3994.8601798936897;4198.895027624334;956.3974695941512;975.6536429567543;978.7748817771952;979.8922118566952;998.825205589019;18593.628127437645;18670.90664546696;18879.056047197584;19247.90376048111;19324.1304141314;1018.8115198688266;1019.6635110413546;1025.4616326428536;1027.6999595511684;1044.154235069596;10813.351198927932;10883.67348979532;10902.400367979679;10931.344119352705;10965.287256328313;453367;466633;468085;469866;471438;114243.42878285555;114910.42544787223;115999.42000289944;116165;116290.41854790672;1054.5518154784186;1105.0606984063643;1148.3856136966683;1184.1947475716422;1216.3310179401471;19722.112504937024;20392.694109588665;20410.69383959212;20436.160745532972;20499.692504612143;55910.44089559215;56781;56999.43000569941;57417;58058.7097064512;70006.29993699997;71464.28535714785;71698.28301717124;72182.27817721755;72352.63823680847;19356.128974196476;19821.20715171377;19854.40436786899;19941.700874487186;20043.498912527302;22472.77527224707;22480.550388992455;22693.205737799097;22936.77063229413;23067.76932230656;17190;17569.385071522436;17882;18316.725249121006;18583.907080464513;15400.46098386596;16283;16656.750148748022;16680.582985425463;16804.24380902838;18681.626367472847;18743.531411714815;18798.06009699537;18922.905385472983;18943.621127577648;19096.75871068376;19176.71234931505;19294.324698636043;19326.516837079187;19331.903340483208;18008.36970706019;18017.639647207245;18075.186616602023;18228.270869165084;18299 21:30:36 INFO - PID 3772 | |3;mootools.html;1321.8347706536683;1412.0468683638555;1412.1456518806121;1421.680121972556;1429.7140571885625;14853.628659283524;15432.922835385807;15523.990940588868;15556.688866222668;15571.68856622867;15185.696286074271;15198.240087995611;15247.923760381183;15285.235738213101;15316.081035137871;2659.2155314182264;2690.233283514199;2729.263098963279;2743.684476285223;2744.163030275765;2680.8391496510176;2683.2084535062204;2704.594310853368;2711.3221694576355;2711.8779654915575;2558.411565339973;2660.055680233518;2757.54500507416;2759.268793769653;2760.5169095408323;2686.2478506018347;2698.2040298112006;2711.4848178845955;2716.1036857836966;2724.346156922344;2578.8122710394423;2583.1604728463326;2585.327814768155;2593.351662084479;2593.455374371384;15166.924165379103;15178.165200913945;15316.923415382964;15366.462173823975;15400.691986160215;8130.308923741489;8401.243888050043;8428.536430496382;8450.83098338036;8460.153984601553;5559.777608895639;5568.784581197792;5681.375048744635;5771.769129234825;5781.8265452036085;1921.6829223178158;1922.5770330527262;1940.2723978508059;1956.3641816409724;1979.5447047179086;26234.86882565575;26431.73568264312;26470;26534;26568.46863062747;6708.765193218214;6830.453563714891;6832.863342733217;6993.125859267591;6998.580085194904;16588.6682266354;16667.16664166811;16689.415870444474;16698.332066717438;16709;7830.6084695764985;8085.555294458743;8085.919140808634;8092.433529652868;8093.109757926623;7289.635518224068;7356.632168391559;7414.295641914009;7453.850922981618;7456.888146677804;5144.279800827887;5306.602004849652;5322.28149199853;5386.461353864583;5390.056740070472;1403.4105675616265;1452.847451017649;1453.3750487290567;1474.3070756744312;1491.2990894279671;746.6490749347798;921.8568974471607;927.2072378116748;930.55798495714;937.4469063252577;8198.221168988937;8468.872966905501;8490.066092729794;8551.700690475804;8559.058503564604;965.5896244096231;966.3139171188394;971.1696499492973;973.4354074636693;998.2114129837347;4523.886902827456;4937.703737775745;4983.6013118950395;5080.390353157571;5099.031184075088;20695.379138625864;21663;21832.89083554572;21838.45403864916;21848.89075554612;20834.1666333348;21029.89485052565;21312.147514099262;21342.89328553347;21384.14463421477;10794.946025269981;10802.297850639683;10855;10925.399103049249;10953.561857525769;7878.369730421437;7887.369010479033;7928.484648497829;7973.20267973198;8014.759557213293;18193.545161371072;18218.18018189158;18289.451316460527;18333.54166145857;18356.357527486474;18605.90697046506;18739.156737947098;18761.1557479911;18871.433856984313;18886.811131889055;10819.67540973772;11063.502142403771;11068.778624427305;11071.501782419971;11077.612283570037;13915.58253252404;14014.159150451005;14014.579562613138;14048.508302209373;14142.22217778032;11463.770724585629;11569.768604628029;11705.180637355303;11757.647270581896;11900.80991900803;10766.407847568458;11171.66485005451;11228.10175185967;11231.887681123084;11397.544098236309;9590.568424421057;9605.087516685904;9619.422834629944;9725.367851089657;9750.512474376395;6980.371766541036;6989.860202795814;6999.335063168991;7066.041500399083;7221.169565499825;6880.0711903894335;6928.480363972823;6970.581765094111;7004.719811207495;7089.326513981164 21:30:36 INFO - PID 3772 | |4;prototype.html;508.5791631832479;531.1872834563119;534.1507003863856;534.9438308977554;539.3150698612762;6359.26868410133;6415.839604009902;6462.838429039276;6482.416582507573;6504.83737906552;6271.4669253113425;6341.619502829833;6349.238091429023;6376.840578985528;6389.361063893608;5273.288106105674;5280.287161233242;5297.841064768063;5309.522143007129;5312.043832110218;4874.244492103712;5188.768829403355;5283.947160528403;5297.311349524556;5313.22958171065;4941.332920055783;4999;5034.798608055692;5045.318881950926;5069.619778516608;5004.349434573519;5062.392512898439;5092.337996060525;5095.872603184913;5096.643234973553;4502.144592527409;4529.433820772403;4542.114287713897;4564.863054108382;4569.634429245652;6236.875262494725;6284.780032698834;6291.62250264981;6296.87406251877;6304.779332723333;5838.2118414013985;5846.649201047908;5847.3275573308765;5853.560982926255;5871.2073870027425;6570.868582628321;6622;6645.900311495331;6685.665716714145;6689.397954184147;4890.755462226874;4914.336564563774;4919.655624106314;4922.950770492285;4938.851834444972;6656.171127270891;6670.266270710218;6810.557313774639;6933.930660693379;7006.159260888624;5082.364704411949;5093.796248150033;5106.131957567222;5107.719075450886;5109.38687357512;5149.562287205555;5158.97420512895;5178.689278643294;5182.119039763249;5193.740312984336;5758.62568933018;5762.1068734346245;5764.071718680551;5765.4522820332;5772.278465191851;6679.532432729759;6738.696758645775;6741.696623651947;6765.560238584476;6771.255161932184;1751.9386821461187;1757.66604345174;1783.2078760845268;1785.3929663914332;1798.1009495252374;1148.3397046698014;1202.2627303546467;1213.7147770273978;1236.8948639365574;1250.4185553717566;5755.59710820247;5771.7402716877;5816.7784765199785;5821.563382746311;5871.647701137946;6831.146106736658;6836.4530837532875;6899.75850845218;6911.75808846688;6934.682410342019;5814.709264536757;5848.736806843703;5870.618409803349;5921;5921.230240068763;3566.5720113586535;3578.141246100917;3592.76647017943;3600.7299452541165;3605.7656252343513;6604.207495100619;6607.603543787388;6699.899501507482;6703.530752847254;6707.128073350434;6564.901526477107;6848.9657551711925;6860.382565569123;6867.416269617039;6878.7248510059035;6527.5757075789925;6643.335666433415;6667.3997141115005;6672.199336079604;6744.865102698017;6683.398494135551;6800.217974932946;6854.3145685433055;6860.796164027851;6873.518853680194;6602.359056377488;6680.799576012728;6742.797716068526;6770.695318710768;6861.862762744618;6563.540552161303;6673.599584024974;6681.398674119353;6693.631850248283;6693.665316734046;5058.671186372874;5074.553752180758;5103.591712663052;5105.923411148761;5109.08036553424;6448.516361272829;6492.565288016994;6495.1556297681;6852.417544508573;6909.481788865955;6535.640539770358;6626.801195964129;6632.1709786276715;6638.20341559016;6669.866602668017;6578.309277525982;6615.17310336208;6659.7003134860015;6674.566153200027;6736.191657000995;6365.777197798055;6442.581232219891;6448.1295025171;6463.515236357386;6543.1821022372205;6572.4084832365315;6666.6000039995815;6686.7993960181275;6716.932830671826;6749.5612785168805;6457.806265812033;6469.320721324381;6536.803895883131;6549.869002619826;6569.9961511739375 21:30:36 INFO - PID 3772 | |5;yui.html;911.2163539356153;920.5719340506663;924.8612708093785;925.3846192282136;935.2004036548749;18640.813591864076;19002.14490347934;19054.14256358464;19061.23755049798;19070.809291907077;17053.232604532826;17725.379611713593;17786.377476788315;17800.82199178011;17855.821441785578;977.110829145476;990.5938565188275;992.2756387836884;992.5087081894472;993.3940296419215;971.9659811906585;976.0434773921561;978.5254151736402;978.6427953796884;979;1025.7589466475372;1027.1679939249232;1029.325791606499;1030.428112397619;1030.8608337874402;1029.1560920045565;1029.3772267778;1030.1810061001522;1030.5105075089348;1031.4017869635593;719.2591630620469;740.7555506682781;746.0748671647173;757.7802437293234;758.4614923404389;18796.718049229272;18901.7164742529;18955.62088758231;19019.61960760791;19058.714119288223;20897.686534701992;21158.576828463498;21338;22170.334889953167;22340.10639574415;16724.24740886663;17013.659726805396;17094.230759615846;17097.401590944257;17132;3758.755680880734;3775.9056023599355;3782.243551289753;3801.543814742221;3808.27642747877;141883;142842;143562.28218858837;144006;147562;16178.433754818525;16279.430219942245;16484;16517.256723447474;16569.6686066278;28824.42351152965;28880.711192888222;29187.270318242216;29192.708072919424;29468.410631787243;8263.173682631808;8332.541710205876;8343.08226095129;8365.623546940402;8381.16188381157;4172.895677608084;4175.394567787654;4268.743875367426;4271.9359209611885;4298.8280468780895;5244.868878278074;5376.4085950545405;5391.029614669397;5406.675599464044;5410.97294513525;1014.4623349624711;1041.9114375278186;1047.1047254597363;1050.837120246363;1052.6894566103017;660.9306022867626;683.8051155420682;695.8156088636515;709.1383968478324;709.4927127104145;11389.487473063731;11562.364069976064;11593.536258549564;11980.820287695691;12208.633740987785;667.1960287853125;672.5998031171445;683.8495530983175;684.2353174504777;695.0408436357794;5138.38339399267;5242.213667949838;5245.9508098380475;5290.5238528531845;5357.437469065692;120002;121802;122710.38644806719;124476;126927.36536317259;106899.46550267354;108822;110240;114753.42623286997;115720.42139789247;93299;94993.5250323744;95664.52167739256;96921;99404.50297748465;10039.69880903574;10172.237082218715;10399.27205095636;10405.427701476492;10514.369137851754;44597.331040035075;44779.552204477535;45569.31646025379;46015.76992115152;46407.535924640324;58449.70775146097;58697.70651146717;58791;59635;59950;14484.275786210437;14540.41838326455;14555.854441455449;14657.63355916068;14659.48691795782;16630.417935372203;17030.148492575074;17137.571560711083;17252.482425527254;17291.308347666454;15274;15339;15442.768358474857;15558.222088895283;15708.450204242796;13570.525031623845;13742.244176570384;13753.449862005407;13834.446622135001;13848.723025539633;13087.934560327138;13096.803547946598;13155.342232888508;13233.669158271121;13259.071864969359;11083.334999900031;11098.445077745919;11122.777544448903;11218.55125794959;11578.710532236424;11550.133739969368;11650.941745291218;11928.940355298168;11953.282803031845;12058.216215945935 21:30:36 INFO - PID 3772 | __end_tp_report 21:30:36 INFO - PID 3772 | __start_cc_report 21:30:36 INFO - PID 3772 | _x_x_mozilla_cycle_collect,282 21:30:36 INFO - PID 3772 | __end_cc_report 21:30:36 INFO - PID 3772 | __startTimestamp1505622636069__endTimestamp 21:30:36 INFO - PID 3772 | 21:30:36 INFO - PID 3772 | ------- Summary: start ------- 21:30:36 INFO - PID 3772 | Number of tests: 6 21:30:36 INFO - PID 3772 | 21:30:36 INFO - PID 3772 | [#0] dojo.html Cycles:165 Average:6186.51 Median:6405.20 stddev:742.40 (11.6%) stddev-sans-first:705.49 21:30:36 INFO - PID 3772 | Values: 3152.1 3152.7 3156.0 3185.8 3187.7 6170.7 6264.9 6276.6 6315.7 6359.6 6210.1 6222.3 6228.7 6244.9 6245.2 5751.3 5845.4 5847.7 5874.6 5878.1 5588.7 5696.0 5780.4 5894.7 5904.1 5668.5 5731.3 5787.0 5802.8 5825.7 5750.7 5763.6 5825.5 5828.5 5831.9 5636.7 5707.8 5730.9 5734.5 5739.1 6124.2 6162.1 6165.8 6217.1 6319.9 6764.9 6815.7 6838.4 6839.9 6843.4 6776.8 6781.7 6867.7 6873.0 6880.6 6105.3 6194.9 6200.5 6206.6 6264.7 6761.5 6839.8 6847.6 6856.8 6867.7 6524.7 6534.8 6542.6 6554.1 6554.4 6660.2 6666.1 6666.3 6672.8 6674.5 6628.4 6630.5 6712.5 6773.5 6799.9 6682.7 6768.7 6771.4 6785.4 6819.3 5257.4 5305.6 5310.6 5320.8 5345.3 4471.2 4583.4 4584.7 4686.1 4706.4 5861.9 5895.4 5899.8 5900.2 5913.3 6706.9 6779.0 6781.8 6820.4 6849.2 5689.8 5779.5 5821.2 5909.8 5938.7 5713.0 5733.8 5745.6 5751.7 5806.7 6543.2 6596.9 6627.3 6657.2 6657.8 6645.6 6745.6 6792.4 6819.4 6823.3 6378.7 6537.3 6559.3 6631.0 6631.6 6623.9 6649.5 6697.4 6704.4 6719.3 6723.2 6799.9 6810.1 6813.4 6818.1 6482.5 6488.9 6494.5 6596.4 6608.7 6371.9 6450.4 6463.2 6488.2 6595.9 6401.6 6401.8 6405.2 6432.0 6527.1 6591.6 6614.0 6619.7 6628.8 6710.6 6334.0 6348.7 6358.9 6416.5 6429.9 21:30:36 INFO - PID 3772 | 21:30:36 INFO - PID 3772 | [#1] ext.html Cycles:180 Average:37519.29 Median:20343.95 stddev:52214.06 (256.7%) stddev-sans-first:52287.92 21:30:36 INFO - PID 3772 | Values: 845.1 873.0 879.0 880.5 881.9 41921.6 42648.6 42692.4 42763.8 42837.4 39167.4 39463.0 39492.4 39528.6 39637.2 6924.9 7085.6 7088.5 7111.6 7123.1 6980.4 7050.1 7057.6 7064.2 7067.8 7015.9 7737.0 7908.8 7912.4 7920.5 7804.0 7822.6 7845.7 7863.3 7866.0 7550.0 7697.4 7751.6 7772.3 7788.5 37623.4 37994.8 38002.2 38044.0 38139.4 3578.8 3627.8 3640.4 3640.9 3660.3 31506.1 31524.8 31570.2 31687.4 31759.8 5246.8 5278.9 5298.0 5300.3 5336.4 179595.0 184934.0 187081.0 187788.0 188204.0 43431.6 49145.0 49934.3 49952.0 49991.3 51102.0 51432.7 51436.7 51495.2 51612.2 16413.1 16452.6 16581.3 16600.2 16605.8 13620.5 13638.2 13642.0 13644.6 13650.9 14139.3 14145.9 14196.2 14211.7 14298.8 5543.4 5626.9 5703.3 5755.9 5763.6 998.7 1005.7 1009.7 1010.4 1025.4 22777.2 22978.0 23015.3 23048.2 23134.7 1092.6 1111.7 1114.5 1115.8 1128.8 12061.6 12150.1 12232.4 12313.6 12342.5 228282.0 229189.0 229259.0 229369.0 230298.0 52399.5 52678.5 52829.0 52977.0 52985.0 1418.2 1451.4 1458.9 1459.5 1461.4 22839.0 22866.9 22936.2 23086.7 23327.2 37165.4 37418.3 37444.6 37460.1 37466.4 186120.0 186407.0 187230.0 187705.0 187995.0 34612.8 36511.5 37027.6 37258.0 37365.6 46445.3 46736.3 46799.3 46827.5 46933.0 34195.0 34466.7 34519.7 34916.0 35141.5 33309.8 33775.8 34220.5 34391.5 34609.1 37323.3 38279.0 38340.8 38611.0 38653.2 32666.5 32995.2 33233.7 33254.5 33515.7 24542.6 24691.6 24703.9 24774.0 25023.2 21:30:36 INFO - PID 3772 | 21:30:36 INFO - PID 3772 | [#2] jquery.html Cycles:180 Average:35349.08 Median:22476.66 stddev:78647.61 (349.9%) stddev-sans-first:78824.82 21:30:36 INFO - PID 3772 | Values: 544.0 544.3 549.9 579.3 587.2 21964.6 22826.9 22845.5 22896.9 22916.4 24185.3 24423.0 24728.1 24748.9 25660.2 6644.4 6831.6 6847.0 6852.0 6852.2 6755.1 6852.3 6854.9 6862.5 6863.6 6828.0 7040.4 7107.0 7113.6 7118.8 6906.6 7105.7 7161.0 7161.7 7164.2 7001.0 7040.0 7047.3 7047.3 7063.6 30905.5 31648.9 31664.5 31679.5 31683.5 2098.8 2124.1 2124.6 2124.8 2126.9 20180.5 20906.9 21318.4 21402.4 21491.0 2503.0 2518.9 2584.2 2588.3 2622.9 124582.0 131204.0 136241.0 141883.3 148517.3 20312.9 20582.3 22107.7 22242.8 22506.0 42838.8 42947.1 43057.0 43092.6 43173.8 10859.4 10930.3 10960.6 10975.6 11029.8 8946.3 9023.6 9035.9 9115.6 9157.4 11028.4 11280.9 11424.3 11436.0 11491.3 3577.5 3589.6 3815.3 3994.9 4198.9 956.4 975.7 978.8 979.9 998.8 18593.6 18670.9 18879.1 19247.9 19324.1 1018.8 1019.7 1025.5 1027.7 1044.2 10813.4 10883.7 10902.4 10931.3 10965.3 453367.0 466633.0 468085.0 469866.0 471438.0 114243.4 114910.4 115999.4 116165.0 116290.4 1054.6 1105.1 1148.4 1184.2 1216.3 19722.1 20392.7 20410.7 20436.2 20499.7 55910.4 56781.0 56999.4 57417.0 58058.7 70006.3 71464.3 71698.3 72182.3 72352.6 19356.1 19821.2 19854.4 19941.7 20043.5 22472.8 22480.6 22693.2 22936.8 23067.8 17190.0 17569.4 17882.0 18316.7 18583.9 15400.5 16283.0 16656.8 16680.6 16804.2 18681.6 18743.5 18798.1 18922.9 18943.6 19096.8 19176.7 19294.3 19326.5 19331.9 18008.4 18017.6 18075.2 18228.3 18299.0 21:30:36 INFO - PID 3772 | 21:30:36 INFO - PID 3772 | [#3] mootools.html Cycles:180 Average:9286.94 Median:14613.84 stddev:6615.48 (45.3%) stddev-sans-first:6606.97 21:30:36 INFO - PID 3772 | Values: 1321.8 1412.0 1412.1 1421.7 1429.7 14853.6 15432.9 15524.0 15556.7 15571.7 15185.7 15198.2 15247.9 15285.2 15316.1 2659.2 2690.2 2729.3 2743.7 2744.2 2680.8 2683.2 2704.6 2711.3 2711.9 2558.4 2660.1 2757.5 2759.3 2760.5 2686.2 2698.2 2711.5 2716.1 2724.3 2578.8 2583.2 2585.3 2593.4 2593.5 15166.9 15178.2 15316.9 15366.5 15400.7 8130.3 8401.2 8428.5 8450.8 8460.2 5559.8 5568.8 5681.4 5771.8 5781.8 1921.7 1922.6 1940.3 1956.4 1979.5 26234.9 26431.7 26470.0 26534.0 26568.5 6708.8 6830.5 6832.9 6993.1 6998.6 16588.7 16667.2 16689.4 16698.3 16709.0 7830.6 8085.6 8085.9 8092.4 8093.1 7289.6 7356.6 7414.3 7453.9 7456.9 5144.3 5306.6 5322.3 5386.5 5390.1 1403.4 1452.8 1453.4 1474.3 1491.3 746.6 921.9 927.2 930.6 937.4 8198.2 8468.9 8490.1 8551.7 8559.1 965.6 966.3 971.2 973.4 998.2 4523.9 4937.7 4983.6 5080.4 5099.0 20695.4 21663.0 21832.9 21838.5 21848.9 20834.2 21029.9 21312.1 21342.9 21384.1 10794.9 10802.3 10855.0 10925.4 10953.6 7878.4 7887.4 7928.5 7973.2 8014.8 18193.5 18218.2 18289.5 18333.5 18356.4 18605.9 18739.2 18761.2 18871.4 18886.8 10819.7 11063.5 11068.8 11071.5 11077.6 13915.6 14014.2 14014.6 14048.5 14142.2 11463.8 11569.8 11705.2 11757.6 11900.8 10766.4 11171.7 11228.1 11231.9 11397.5 9590.6 9605.1 9619.4 9725.4 9750.5 6980.4 6989.9 6999.3 7066.0 7221.2 6880.1 6928.5 6970.6 7004.7 7089.3 21:30:36 INFO - PID 3772 | 21:30:36 INFO - PID 3772 | [#4] prototype.html Cycles:180 Average:5606.68 Median:6323.20 stddev:1567.38 (24.8%) stddev-sans-first:1524.35 21:30:36 INFO - PID 3772 | Values: 508.6 531.2 534.2 534.9 539.3 6359.3 6415.8 6462.8 6482.4 6504.8 6271.5 6341.6 6349.2 6376.8 6389.4 5273.3 5280.3 5297.8 5309.5 5312.0 4874.2 5188.8 5283.9 5297.3 5313.2 4941.3 4999.0 5034.8 5045.3 5069.6 5004.3 5062.4 5092.3 5095.9 5096.6 4502.1 4529.4 4542.1 4564.9 4569.6 6236.9 6284.8 6291.6 6296.9 6304.8 5838.2 5846.6 5847.3 5853.6 5871.2 6570.9 6622.0 6645.9 6685.7 6689.4 4890.8 4914.3 4919.7 4923.0 4938.9 6656.2 6670.3 6810.6 6933.9 7006.2 5082.4 5093.8 5106.1 5107.7 5109.4 5149.6 5159.0 5178.7 5182.1 5193.7 5758.6 5762.1 5764.1 5765.5 5772.3 6679.5 6738.7 6741.7 6765.6 6771.3 1751.9 1757.7 1783.2 1785.4 1798.1 1148.3 1202.3 1213.7 1236.9 1250.4 5755.6 5771.7 5816.8 5821.6 5871.6 6831.1 6836.5 6899.8 6911.8 6934.7 5814.7 5848.7 5870.6 5921.0 5921.2 3566.6 3578.1 3592.8 3600.7 3605.8 6604.2 6607.6 6699.9 6703.5 6707.1 6564.9 6849.0 6860.4 6867.4 6878.7 6527.6 6643.3 6667.4 6672.2 6744.9 6683.4 6800.2 6854.3 6860.8 6873.5 6602.4 6680.8 6742.8 6770.7 6861.9 6563.5 6673.6 6681.4 6693.6 6693.7 5058.7 5074.6 5103.6 5105.9 5109.1 6448.5 6492.6 6495.2 6852.4 6909.5 6535.6 6626.8 6632.2 6638.2 6669.9 6578.3 6615.2 6659.7 6674.6 6736.2 6365.8 6442.6 6448.1 6463.5 6543.2 6572.4 6666.6 6686.8 6716.9 6749.6 6457.8 6469.3 6536.8 6549.9 6570.0 21:30:36 INFO - PID 3772 | 21:30:36 INFO - PID 3772 | [#5] yui.html Cycles:180 Average:24248.92 Median:18718.77 stddev:36084.97 (192.8%) stddev-sans-first:36143.65 21:30:36 INFO - PID 3772 | Values: 911.2 920.6 924.9 925.4 935.2 18640.8 19002.1 19054.1 19061.2 19070.8 17053.2 17725.4 17786.4 17800.8 17855.8 977.1 990.6 992.3 992.5 993.4 972.0 976.0 978.5 978.6 979.0 1025.8 1027.2 1029.3 1030.4 1030.9 1029.2 1029.4 1030.2 1030.5 1031.4 719.3 740.8 746.1 757.8 758.5 18796.7 18901.7 18955.6 19019.6 19058.7 20897.7 21158.6 21338.0 22170.3 22340.1 16724.2 17013.7 17094.2 17097.4 17132.0 3758.8 3775.9 3782.2 3801.5 3808.3 141883.0 142842.0 143562.3 144006.0 147562.0 16178.4 16279.4 16484.0 16517.3 16569.7 28824.4 28880.7 29187.3 29192.7 29468.4 8263.2 8332.5 8343.1 8365.6 8381.2 4172.9 4175.4 4268.7 4271.9 4298.8 5244.9 5376.4 5391.0 5406.7 5411.0 1014.5 1041.9 1047.1 1050.8 1052.7 660.9 683.8 695.8 709.1 709.5 11389.5 11562.4 11593.5 11980.8 12208.6 667.2 672.6 683.8 684.2 695.0 5138.4 5242.2 5246.0 5290.5 5357.4 120002.0 121802.0 122710.4 124476.0 126927.4 106899.5 108822.0 110240.0 114753.4 115720.4 93299.0 94993.5 95664.5 96921.0 99404.5 10039.7 10172.2 10399.3 10405.4 10514.4 44597.3 44779.6 45569.3 46015.8 46407.5 58449.7 58697.7 58791.0 59635.0 59950.0 14484.3 14540.4 14555.9 14657.6 14659.5 16630.4 17030.1 17137.6 17252.5 17291.3 15274.0 15339.0 15442.8 15558.2 15708.5 13570.5 13742.2 13753.4 13834.4 13848.7 13087.9 13096.8 13155.3 13233.7 13259.1 11083.3 11098.4 11122.8 11218.6 11578.7 11550.1 11650.9 11928.9 11953.3 12058.2 21:30:36 INFO - PID 3772 | -------- Summary: end -------- 21:30:36 INFO - PID 3772 | 21:30:36 INFO - TEST-INFO | 3772: exit 0 21:30:36 INFO - TEST-OK | dromaeo_css | took 1111234ms 21:30:36 INFO - TEST-START | kraken 21:30:36 INFO - Initialising browser for kraken test... 21:30:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49761/getInfo.html -profile /tmp/tmp60AheR/profile 21:30:36 INFO - TEST-INFO | started process 4151 (/builds/slave/test/build/application/firefox/firefox http://localhost:49761/getInfo.html) 21:30:42 INFO - TEST-INFO | 4151: exit 0 21:30:42 INFO - Browser initialized. 21:30:42 INFO - Running cycle 1/1 for kraken test... 21:30:42 INFO - Using env: {'DISPLAY': ':0', 21:30:42 INFO - 'HOME': '/home/cltbld', 21:30:42 INFO - 'JSGC_DISABLE_POISONING': '1', 21:30:42 INFO - 'LANG': 'en_US.UTF-8', 21:30:42 INFO - 'LANGUAGE': 'en_US:en', 21:30:42 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:30:42 INFO - 'LOGNAME': 'cltbld', 21:30:42 INFO - 'MAIL': '/var/mail/cltbld', 21:30:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:30:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:30:42 INFO - 'MOZ_CRASHREPORTER': '1', 21:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:42 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:30:42 INFO - 'MOZ_NO_REMOTE': '1', 21:30:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:30:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:42 INFO - 'NO_EM_RESTART': '1', 21:30:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:42 INFO - 'PWD': '/builds/slave/test', 21:30:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:30:42 INFO - 'RUST_BACKTRACE': 'full', 21:30:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:30:42 INFO - 'SHELL': '/bin/bash', 21:30:42 INFO - 'SHLVL': '1', 21:30:42 INFO - 'TERM': 'linux', 21:30:42 INFO - 'TMOUT': '86400', 21:30:42 INFO - 'USER': 'cltbld', 21:30:42 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814', 21:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:42 INFO - '_': '/tools/buildbot/bin/python'} 21:30:42 INFO - TEST-INFO | started process 4384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp60AheR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:30:43 INFO - PID 4384 | 21:30:43 INFO - PID 4384 | (/builds/slave/test/build/application/firefox/firefox:4433): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:30:43 INFO - PID 4384 | 21:30:53 INFO - PID 4384 | RSS: Main: 155115520 21:30:53 INFO - PID 4384 | 21:30:56 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49761/tests/kraken/driver.html?testName=audio-beat-detection) 21:30:57 INFO - PID 4384 | RSS: Main: 161353728 21:30:57 INFO - PID 4384 | 21:30:59 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49761/tests/kraken/driver.html?testName=audio-dft) 21:31:00 INFO - PID 4384 | RSS: Main: 161579008 21:31:00 INFO - PID 4384 | 21:31:03 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49761/tests/kraken/driver.html?testName=audio-fft) 21:31:04 INFO - PID 4384 | RSS: Main: 157278208 21:31:04 INFO - PID 4384 | 21:31:06 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49761/tests/kraken/driver.html?testName=audio-oscillator) 21:31:06 INFO - PID 4384 | RSS: Main: 157483008 21:31:06 INFO - PID 4384 | 21:31:08 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49761/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:31:09 INFO - PID 4384 | RSS: Main: 157626368 21:31:09 INFO - PID 4384 | 21:31:12 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49761/tests/kraken/driver.html?testName=imaging-darkroom) 21:31:13 INFO - PID 4384 | RSS: Main: 160206848 21:31:13 INFO - PID 4384 | 21:31:17 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49761/tests/kraken/driver.html?testName=imaging-desaturate) 21:31:17 INFO - PID 4384 | RSS: Main: 160600064 21:31:17 INFO - PID 4384 | 21:31:20 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49761/tests/kraken/driver.html?testName=json-parse-financial) 21:31:21 INFO - PID 4384 | RSS: Main: 160219136 21:31:21 INFO - PID 4384 | 21:31:22 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49761/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:31:23 INFO - PID 4384 | RSS: Main: 160296960 21:31:23 INFO - PID 4384 | 21:31:25 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49761/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:31:25 INFO - PID 4384 | RSS: Main: 157384704 21:31:25 INFO - PID 4384 | 21:31:28 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49761/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:31:28 INFO - PID 4384 | RSS: Main: 156803072 21:31:28 INFO - PID 4384 | 21:31:31 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49761/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:31:32 INFO - PID 4384 | RSS: Main: 157376512 21:31:32 INFO - PID 4384 | 21:31:34 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49761/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:31:35 INFO - PID 4384 | RSS: Main: 157941760 21:31:35 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | Cycle 1(1): loaded http://localhost:49761/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49761/tests/kraken/driver.html?testName=ai-astar) 21:31:37 INFO - PID 4384 | RSS: Main: 159064064 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | __start_tp_report 21:31:37 INFO - PID 4384 | _x_x_mozilla_page_load 21:31:37 INFO - PID 4384 | _x_x_mozilla_page_load_details 21:31:37 INFO - PID 4384 | |i|pagename|runs| 21:31:37 INFO - PID 4384 | |0;ai-astar;107;107;106;107;106;107;109;106;109;109 21:31:37 INFO - PID 4384 | |1;audio-beat-detection;115;115;118;116;117;116;115;117;117;139 21:31:37 INFO - PID 4384 | |2;audio-dft;164;164;165;162;162;165;164;164;165;165 21:31:37 INFO - PID 4384 | |3;audio-fft;75;77;73;76;75;74;73;73;74;73 21:31:37 INFO - PID 4384 | |4;audio-oscillator;80;80;79;79;79;79;80;87;81;78 21:31:37 INFO - PID 4384 | |5;imaging-gaussian-blur;113;115;115;114;114;113;113;113;114;115 21:31:37 INFO - PID 4384 | |6;imaging-darkroom;149;147;150;149;148;148;147;150;149;150 21:31:37 INFO - PID 4384 | |7;imaging-desaturate;94;94;94;94;94;95;93;94;94;93 21:31:37 INFO - PID 4384 | |8;json-parse-financial;55;53;55;55;57;54;54;55;54;53 21:31:37 INFO - PID 4384 | |9;json-stringify-tinderbox;38;43;38;37;38;38;37;38;38;38 21:31:37 INFO - PID 4384 | |10;stanford-crypto-aes;77;74;77;73;77;74;74;77;73;80 21:31:37 INFO - PID 4384 | |11;stanford-crypto-ccm;112;114;116;117;117;116;121;109;120;109 21:31:37 INFO - PID 4384 | |12;stanford-crypto-pbkdf2;172;172;175;173;175;169;165;169;170;167 21:31:37 INFO - PID 4384 | |13;stanford-crypto-sha256-iterative;68;66;66;68;65;64;70;66;65;66 21:31:37 INFO - PID 4384 | __end_tp_report 21:31:37 INFO - PID 4384 | __start_cc_report 21:31:37 INFO - PID 4384 | _x_x_mozilla_cycle_collect,775 21:31:37 INFO - PID 4384 | __end_cc_report 21:31:37 INFO - PID 4384 | __startTimestamp1505622697547__endTimestamp 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | ------- Summary: start ------- 21:31:37 INFO - PID 4384 | Number of tests: 14 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#0] ai-astar Cycles:10 Average:107.30 Median:107.00 stddev:1.25 (1.2%) stddev-sans-first:1.32 21:31:37 INFO - PID 4384 | Values: 107.0 107.0 106.0 107.0 106.0 107.0 109.0 106.0 109.0 109.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#1] audio-beat-detection Cycles:10 Average:118.50 Median:117.00 stddev:7.28 (6.2%) stddev-sans-first:7.61 21:31:37 INFO - PID 4384 | Values: 115.0 115.0 118.0 116.0 117.0 116.0 115.0 117.0 117.0 139.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.50 stddev:1.15 (0.7%) stddev-sans-first:1.22 21:31:37 INFO - PID 4384 | Values: 164.0 164.0 165.0 162.0 162.0 165.0 164.0 164.0 165.0 165.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#3] audio-fft Cycles:10 Average:74.30 Median:74.50 stddev:1.42 (1.9%) stddev-sans-first:1.48 21:31:37 INFO - PID 4384 | Values: 75.0 77.0 73.0 76.0 75.0 74.0 73.0 73.0 74.0 73.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:80.00 stddev:2.53 (3.2%) stddev-sans-first:2.68 21:31:37 INFO - PID 4384 | Values: 80.0 80.0 79.0 79.0 79.0 79.0 80.0 87.0 81.0 78.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.88 (0.8%) stddev-sans-first:0.87 21:31:37 INFO - PID 4384 | Values: 113.0 115.0 115.0 114.0 114.0 113.0 113.0 113.0 114.0 115.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:1.16 (0.8%) stddev-sans-first:1.22 21:31:37 INFO - PID 4384 | Values: 149.0 147.0 150.0 149.0 148.0 148.0 147.0 150.0 149.0 150.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 21:31:37 INFO - PID 4384 | Values: 94.0 94.0 94.0 94.0 94.0 95.0 93.0 94.0 94.0 93.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.18 (2.1%) stddev-sans-first:1.24 21:31:37 INFO - PID 4384 | Values: 55.0 53.0 55.0 55.0 57.0 54.0 54.0 55.0 54.0 53.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:1.70 (4.5%) stddev-sans-first:1.80 21:31:37 INFO - PID 4384 | Values: 38.0 43.0 38.0 37.0 38.0 38.0 37.0 38.0 38.0 38.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:77.00 stddev:2.32 (3.0%) stddev-sans-first:2.40 21:31:37 INFO - PID 4384 | Values: 77.0 74.0 77.0 73.0 77.0 74.0 74.0 77.0 73.0 80.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#11] stanford-crypto-ccm Cycles:10 Average:115.10 Median:116.50 stddev:4.12 (3.5%) stddev-sans-first:4.22 21:31:37 INFO - PID 4384 | Values: 112.0 114.0 116.0 117.0 117.0 116.0 121.0 109.0 120.0 109.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:172.00 stddev:3.30 (1.9%) stddev-sans-first:3.47 21:31:37 INFO - PID 4384 | Values: 172.0 172.0 175.0 173.0 175.0 169.0 165.0 169.0 170.0 167.0 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.00 stddev:1.78 (2.7%) stddev-sans-first:1.79 21:31:37 INFO - PID 4384 | Values: 68.0 66.0 66.0 68.0 65.0 64.0 70.0 66.0 65.0 66.0 21:31:37 INFO - PID 4384 | -------- Summary: end -------- 21:31:37 INFO - PID 4384 | 21:31:37 INFO - PID 4384 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:31:38 INFO - TEST-INFO | 4384: exit 0 21:31:38 INFO - TEST-OK | kraken | took 61481ms 21:31:38 INFO - SUITE-END | took 1172s 21:31:38 INFO - Completed test suite (00:19:33) 21:31:38 INFO - javascript benchmark 21:31:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8617.167602896956, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3152.1174071260048, 3152.668969758177, 3155.968440315596, 3185.7769956103066, 3187.713105820476, 6170.722317495713, 6264.9060264096015, 6276.592021518598, 6315.715792789324, 6359.618422894624, 6210.068489726544, 6222.315545290015, 6228.688565571726, 6244.875102497947, 6245.219347581552, 5751.338596061444, 5845.3862344453855, 5847.736851841656, 5874.647521148745, 5878.088896221093, 5588.692621905793, 5696.0, 5780.393058728836, 5894.734736936848, 5904.143899134623, 5668.546516278709, 5731.340895796995, 5787.045137552299, 5802.82591522255, 5825.737841797109, 5750.683712395817, 5763.567732420085, 5825.533957283407, 5828.504577110951, 5831.883362332772, 5636.718164091779, 5707.800226992077, 5730.885382292373, 5734.541236701054, 5739.053056245714, 6124.234470691164, 6162.137300777895, 6165.815025549241, 6217.067439884008, 6319.936800631981, 6764.9323506764795, 6815.659217039178, 6838.350356716104, 6839.931600683979, 6843.3840954313855, 6776.830579235559, 6781.72873085076, 6867.725290988355, 6873.0, 6880.621565813878, 6105.32841387447, 6194.946859033975, 6200.503959683169, 6206.565540412173, 6264.686765661744, 6761.492888033319, 6839.760608378683, 6847.554908930903, 6856.828579285458, 6867.656617169122, 6524.6737663116655, 6534.771283005072, 6542.574732642363, 6554.115194448688, 6554.4101030906495, 6660.200775906826, 6666.133402657623, 6666.266710661824, 6672.799816005528, 6674.466042716668, 6628.436582890508, 6630.469562434993, 6712.463002959751, 6773.525853190229, 6799.932000680029, 6682.699278532477, 6768.69540870662, 6771.424428923597, 6785.42323902464, 6819.31806819324, 5257.421683614799, 5305.575553955674, 5310.575153987672, 5320.813771517978, 5345.278387417727, 4471.239889218776, 4583.427071616048, 4584.679072464895, 4686.062787442525, 4706.435227772689, 5861.88276234473, 5895.380984996599, 5899.823005309848, 5900.20347253124, 5913.29040515141, 6706.865862682719, 6778.966105169541, 6781.762638307636, 6820.35206655367, 6849.2465828758795, 5689.7724091036725, 5779.479846813807, 5821.214136091573, 5909.763609455659, 5938.673372964528, 5713.0, 5733.827985160452, 5745.626534275259, 5751.741171647286, 5806.68063256525, 6543.247526534415, 6596.86806263872, 6627.3372662733345, 6657.23441804189, 6657.800265992028, 6645.60126392418, 6745.561538499982, 6792.354726300993, 6819.386255237053, 6823.283555226729, 6378.6810659467765, 6537.313582073813, 6559.344065593594, 6630.9668451657435, 6631.635260060743, 6623.933760662524, 6649.501287403561, 6697.430718388991, 6704.396604305636, 6719.260881302955, 6723.226828914541, 6799.898001529882, 6810.080639113854, 6813.3867951882585, 6818.079559259437, 6482.546221764431, 6488.870222595616, 6494.545381823227, 6596.439302659328, 6608.70260838273, 6371.93628063732, 6450.387213214737, 6463.224413070461, 6488.156539649815, 6595.934040659532, 6401.583897046677, 6401.839954001187, 6405.19935008124, 6432.0, 6527.05357723118, 6591.604503729792, 6614.0, 6619.66901654925, 6628.767993120217, 6710.563813352116, 6333.96833015832, 6348.746050158126, 6358.904616430665, 6416.518761092843, 6429.903551446639], "value": 6129.990557239464, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.0704225352114, 873.0396563779843, 879.0286733159858, 880.5333173418082, 881.9470831750092, 41921.58078419215, 42648.57351426485, 42692.359614605804, 42763.78618106909, 42837.35743963843, 39167.41248881269, 39463.0, 39492.407613885814, 39528.60471395285, 39637.20725585487, 6924.930750692504, 7085.645717714119, 7088.4683648726295, 7111.644417779116, 7123.145222573298, 6980.406665433445, 7050.083489146404, 7057.576545407266, 7064.222935477093, 7067.823304417379, 7015.929840701604, 7737.0, 7908.762737117867, 7912.406569507281, 7920.48516846406, 7803.960980195091, 7822.647980840848, 7845.725399610987, 7863.292303692695, 7865.960670196641, 7549.962250188714, 7697.384209263301, 7751.573663448508, 7772.300492955661, 7788.493747906425, 37623.435648465296, 37994.81002594969, 38002.23995520074, 38044.0, 38139.427908581114, 3578.7673801202836, 3627.818609069536, 3640.4357324614725, 3640.9271814563826, 3660.267946410702, 31506.054818355486, 31524.842375788205, 31570.210744731336, 31687.366252674816, 31759.84120079385, 5246.763895624668, 5278.868028299285, 5298.0, 5300.284461597712, 5336.43967383423, 179595.0, 184934.0, 187081.0, 187788.0, 188204.0, 43431.56568434338, 49145.0, 49934.25098623524, 49952.0, 49991.25013124806, 51102.0, 51432.74283628633, 51436.74281628568, 51495.22757158645, 51612.22581661278, 16413.097279649734, 16452.588685282964, 16581.336746530003, 16600.169991500617, 16605.83394166067, 13620.455181792815, 13638.181709097486, 13642.045056845926, 13644.590662280147, 13650.93174534121, 14139.293035348192, 14145.868330533533, 14196.219207943663, 14211.715765684628, 14298.785518217235, 5543.417941116206, 5626.943730562723, 5703.315602127688, 5755.913661295084, 5763.596548241667, 998.6953979036318, 1005.7485628592851, 1009.7223263602568, 1010.4291075542295, 1025.3642741500319, 22777.202797902326, 22978.0, 23015.309540713806, 23048.193313234293, 23134.652980205312, 1092.6012005617893, 1111.7387413957713, 1114.453917580396, 1115.8493603363604, 1128.8081026225561, 12061.577844775391, 12150.149489535825, 12232.449539770732, 12313.630591082281, 12342.506299747909, 228282.0, 229189.0, 229259.0, 229369.0, 230298.0, 52399.47600524022, 52678.473215268124, 52829.0, 52977.0, 52985.0, 1418.1774570748942, 1451.3686546352371, 1458.9343479543445, 1459.489428441579, 1461.4373466215584, 22839.0, 22866.885665571564, 22936.197233096762, 23086.653700194845, 23327.18354857572, 37165.442518361706, 37418.251634967695, 37444.62555374411, 37460.06349841167, 37466.43800342942, 186120.0, 186407.0, 187230.0, 187705.0, 187995.0, 34612.826935866164, 36511.45232821457, 37027.62972370242, 37258.0, 37365.626343736214, 46445.3033204509, 46736.29895551501, 46799.29801053055, 46827.53172468368, 46933.0, 34195.0, 34466.655333446346, 34519.654803451645, 34916.0, 35141.47287790634, 33309.83345083259, 33775.83112084522, 34220.48669269913, 34391.48412773861, 34609.13477163091, 37323.253534928604, 38279.0, 38340.80829595834, 38611.0, 38653.226935460574, 32666.510002349507, 32995.175120621214, 33233.66766332306, 33254.50118248277, 33515.66484335125, 24542.631860522462, 24691.62962555599, 24703.87648061748, 24774.0, 25023.249302520224], "value": 17301.319514472678, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.9972800135998, 544.2869840508934, 549.9065158922982, 579.2556564814213, 587.1632923084604, 21964.560708785815, 22826.885865570668, 22845.543089138206, 22896.885515572398, 22916.427089322777, 24185.274441766775, 24423.0, 24728.134515291968, 24748.876255618743, 25660.23019309419, 6644.43522300605, 6831.555948863308, 6847.041414202015, 6852.040714300002, 6852.246252912176, 6755.054292399043, 6852.349026842442, 6854.931450685504, 6862.485313601474, 6863.622500762456, 6827.965860170692, 7040.436765058808, 7106.964465177667, 7113.573185608854, 7118.822029449253, 6906.551074180213, 7105.715771369165, 7161.033260509843, 7161.677724502358, 7164.21193668696, 7001.0, 7040.04959330489, 7047.295270472963, 7047.330503602201, 7063.6115013674225, 30905.536416953764, 31648.892288770014, 31664.525032124537, 31679.524807127913, 31683.524747128813, 2098.7586427560873, 2124.0866427436194, 2124.6069477146775, 2124.787521247863, 2126.9042893069695, 20180.495487612927, 20906.895465522728, 21318.360449186395, 21402.357929262147, 21491.0, 2502.987485062581, 2518.8866501007496, 2584.2118153963033, 2588.2752829207666, 2622.934426639349, 124582.0, 131204.0, 136241.0, 141883.29058354642, 148517.25741371332, 20312.898435507726, 20582.27962021322, 22107.673539587675, 22242.777572224393, 22506.0, 42838.78580607077, 42947.141057178684, 43057.0, 43092.56907430948, 43173.784131079774, 10859.40273284961, 10930.344179349106, 10960.56157753681, 10975.560977560968, 11029.779404411867, 8946.284297256203, 9023.593938272794, 9035.909640903506, 9115.589798459085, 9157.404768690147, 11028.44857757125, 11280.887191127984, 11424.314541127558, 11436.0, 11491.253068550517, 3577.499150118985, 3589.6410358963894, 3815.332316844597, 3994.8601798936897, 4198.895027624334, 956.3974695941512, 975.6536429567543, 978.7748817771952, 979.8922118566952, 998.825205589019, 18593.628127437645, 18670.90664546696, 18879.056047197584, 19247.90376048111, 19324.1304141314, 1018.8115198688266, 1019.6635110413546, 1025.4616326428536, 1027.6999595511684, 1044.154235069596, 10813.351198927932, 10883.67348979532, 10902.400367979679, 10931.344119352705, 10965.287256328313, 453367.0, 466633.0, 468085.0, 469866.0, 471438.0, 114243.42878285555, 114910.42544787223, 115999.42000289944, 116165.0, 116290.41854790672, 1054.5518154784186, 1105.0606984063643, 1148.3856136966683, 1184.1947475716422, 1216.3310179401471, 19722.112504937024, 20392.694109588665, 20410.69383959212, 20436.160745532972, 20499.692504612143, 55910.44089559215, 56781.0, 56999.43000569941, 57417.0, 58058.7097064512, 70006.29993699997, 71464.28535714785, 71698.28301717124, 72182.27817721755, 72352.63823680847, 19356.128974196476, 19821.20715171377, 19854.40436786899, 19941.700874487186, 20043.498912527302, 22472.77527224707, 22480.550388992455, 22693.205737799097, 22936.77063229413, 23067.76932230656, 17190.0, 17569.385071522436, 17882.0, 18316.725249121006, 18583.907080464513, 15400.46098386596, 16283.0, 16656.750148748022, 16680.582985425463, 16804.24380902838, 18681.626367472847, 18743.531411714815, 18798.06009699537, 18922.905385472983, 18943.621127577648, 19096.75871068376, 19176.71234931505, 19294.324698636043, 19326.516837079187, 19331.903340483208, 18008.36970706019, 18017.639647207245, 18075.186616602023, 18228.270869165084, 18299.0], "value": 12890.14085783098, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.8347706536683, 1412.0468683638555, 1412.1456518806121, 1421.680121972556, 1429.7140571885625, 14853.628659283524, 15432.922835385807, 15523.990940588868, 15556.688866222668, 15571.68856622867, 15185.696286074271, 15198.240087995611, 15247.923760381183, 15285.235738213101, 15316.081035137871, 2659.2155314182264, 2690.233283514199, 2729.263098963279, 2743.684476285223, 2744.163030275765, 2680.8391496510176, 2683.2084535062204, 2704.594310853368, 2711.3221694576355, 2711.8779654915575, 2558.411565339973, 2660.055680233518, 2757.54500507416, 2759.268793769653, 2760.5169095408323, 2686.2478506018347, 2698.2040298112006, 2711.4848178845955, 2716.1036857836966, 2724.346156922344, 2578.8122710394423, 2583.1604728463326, 2585.327814768155, 2593.351662084479, 2593.455374371384, 15166.924165379103, 15178.165200913945, 15316.923415382964, 15366.462173823975, 15400.691986160215, 8130.308923741489, 8401.243888050043, 8428.536430496382, 8450.83098338036, 8460.153984601553, 5559.777608895639, 5568.784581197792, 5681.375048744635, 5771.769129234825, 5781.8265452036085, 1921.6829223178158, 1922.5770330527262, 1940.2723978508059, 1956.3641816409724, 1979.5447047179086, 26234.86882565575, 26431.73568264312, 26470.0, 26534.0, 26568.46863062747, 6708.765193218214, 6830.453563714891, 6832.863342733217, 6993.125859267591, 6998.580085194904, 16588.6682266354, 16667.16664166811, 16689.415870444474, 16698.332066717438, 16709.0, 7830.6084695764985, 8085.555294458743, 8085.919140808634, 8092.433529652868, 8093.109757926623, 7289.635518224068, 7356.632168391559, 7414.295641914009, 7453.850922981618, 7456.888146677804, 5144.279800827887, 5306.602004849652, 5322.28149199853, 5386.461353864583, 5390.056740070472, 1403.4105675616265, 1452.847451017649, 1453.3750487290567, 1474.3070756744312, 1491.2990894279671, 746.6490749347798, 921.8568974471607, 927.2072378116748, 930.55798495714, 937.4469063252577, 8198.221168988937, 8468.872966905501, 8490.066092729794, 8551.700690475804, 8559.058503564604, 965.5896244096231, 966.3139171188394, 971.1696499492973, 973.4354074636693, 998.2114129837347, 4523.886902827456, 4937.703737775745, 4983.6013118950395, 5080.390353157571, 5099.031184075088, 20695.379138625864, 21663.0, 21832.89083554572, 21838.45403864916, 21848.89075554612, 20834.1666333348, 21029.89485052565, 21312.147514099262, 21342.89328553347, 21384.14463421477, 10794.946025269981, 10802.297850639683, 10855.0, 10925.399103049249, 10953.561857525769, 7878.369730421437, 7887.369010479033, 7928.484648497829, 7973.20267973198, 8014.759557213293, 18193.545161371072, 18218.18018189158, 18289.451316460527, 18333.54166145857, 18356.357527486474, 18605.90697046506, 18739.156737947098, 18761.1557479911, 18871.433856984313, 18886.811131889055, 10819.67540973772, 11063.502142403771, 11068.778624427305, 11071.501782419971, 11077.612283570037, 13915.58253252404, 14014.159150451005, 14014.579562613138, 14048.508302209373, 14142.22217778032, 11463.770724585629, 11569.768604628029, 11705.180637355303, 11757.647270581896, 11900.80991900803, 10766.407847568458, 11171.66485005451, 11228.10175185967, 11231.887681123084, 11397.544098236309, 9590.568424421057, 9605.087516685904, 9619.422834629944, 9725.367851089657, 9750.512474376395, 6980.371766541036, 6989.860202795814, 6999.335063168991, 7066.041500399083, 7221.169565499825, 6880.0711903894335, 6928.480363972823, 6970.581765094111, 7004.719811207495, 7089.326513981164], "value": 6650.660340198333, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.5791631832479, 531.1872834563119, 534.1507003863856, 534.9438308977554, 539.3150698612762, 6359.26868410133, 6415.839604009902, 6462.838429039276, 6482.416582507573, 6504.83737906552, 6271.4669253113425, 6341.619502829833, 6349.238091429023, 6376.840578985528, 6389.361063893608, 5273.288106105674, 5280.287161233242, 5297.841064768063, 5309.522143007129, 5312.043832110218, 4874.244492103712, 5188.768829403355, 5283.947160528403, 5297.311349524556, 5313.22958171065, 4941.332920055783, 4999.0, 5034.798608055692, 5045.318881950926, 5069.619778516608, 5004.349434573519, 5062.392512898439, 5092.337996060525, 5095.872603184913, 5096.643234973553, 4502.144592527409, 4529.433820772403, 4542.114287713897, 4564.863054108382, 4569.634429245652, 6236.875262494725, 6284.780032698834, 6291.62250264981, 6296.87406251877, 6304.779332723333, 5838.2118414013985, 5846.649201047908, 5847.3275573308765, 5853.560982926255, 5871.2073870027425, 6570.868582628321, 6622.0, 6645.900311495331, 6685.665716714145, 6689.397954184147, 4890.755462226874, 4914.336564563774, 4919.655624106314, 4922.950770492285, 4938.851834444972, 6656.171127270891, 6670.266270710218, 6810.557313774639, 6933.930660693379, 7006.159260888624, 5082.364704411949, 5093.796248150033, 5106.131957567222, 5107.719075450886, 5109.38687357512, 5149.562287205555, 5158.97420512895, 5178.689278643294, 5182.119039763249, 5193.740312984336, 5758.62568933018, 5762.1068734346245, 5764.071718680551, 5765.4522820332, 5772.278465191851, 6679.532432729759, 6738.696758645775, 6741.696623651947, 6765.560238584476, 6771.255161932184, 1751.9386821461187, 1757.66604345174, 1783.2078760845268, 1785.3929663914332, 1798.1009495252374, 1148.3397046698014, 1202.2627303546467, 1213.7147770273978, 1236.8948639365574, 1250.4185553717566, 5755.59710820247, 5771.7402716877, 5816.7784765199785, 5821.563382746311, 5871.647701137946, 6831.146106736658, 6836.4530837532875, 6899.75850845218, 6911.75808846688, 6934.682410342019, 5814.709264536757, 5848.736806843703, 5870.618409803349, 5921.0, 5921.230240068763, 3566.5720113586535, 3578.141246100917, 3592.76647017943, 3600.7299452541165, 3605.7656252343513, 6604.207495100619, 6607.603543787388, 6699.899501507482, 6703.530752847254, 6707.128073350434, 6564.901526477107, 6848.9657551711925, 6860.382565569123, 6867.416269617039, 6878.7248510059035, 6527.5757075789925, 6643.335666433415, 6667.3997141115005, 6672.199336079604, 6744.865102698017, 6683.398494135551, 6800.217974932946, 6854.3145685433055, 6860.796164027851, 6873.518853680194, 6602.359056377488, 6680.799576012728, 6742.797716068526, 6770.695318710768, 6861.862762744618, 6563.540552161303, 6673.599584024974, 6681.398674119353, 6693.631850248283, 6693.665316734046, 5058.671186372874, 5074.553752180758, 5103.591712663052, 5105.923411148761, 5109.08036553424, 6448.516361272829, 6492.565288016994, 6495.1556297681, 6852.417544508573, 6909.481788865955, 6535.640539770358, 6626.801195964129, 6632.1709786276715, 6638.20341559016, 6669.866602668017, 6578.309277525982, 6615.17310336208, 6659.7003134860015, 6674.566153200027, 6736.191657000995, 6365.777197798055, 6442.581232219891, 6448.1295025171, 6463.515236357386, 6543.1821022372205, 6572.4084832365315, 6666.6000039995815, 6686.7993960181275, 6716.932830671826, 6749.5612785168805, 6457.806265812033, 6469.320721324381, 6536.803895883131, 6549.869002619826, 6569.9961511739375], "value": 5143.63094239287, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [911.2163539356153, 920.5719340506663, 924.8612708093785, 925.3846192282136, 935.2004036548749, 18640.813591864076, 19002.14490347934, 19054.14256358464, 19061.23755049798, 19070.809291907077, 17053.232604532826, 17725.379611713593, 17786.377476788315, 17800.82199178011, 17855.821441785578, 977.110829145476, 990.5938565188275, 992.2756387836884, 992.5087081894472, 993.3940296419215, 971.9659811906585, 976.0434773921561, 978.5254151736402, 978.6427953796884, 979.0, 1025.7589466475372, 1027.1679939249232, 1029.325791606499, 1030.428112397619, 1030.8608337874402, 1029.1560920045565, 1029.3772267778, 1030.1810061001522, 1030.5105075089348, 1031.4017869635593, 719.2591630620469, 740.7555506682781, 746.0748671647173, 757.7802437293234, 758.4614923404389, 18796.718049229272, 18901.7164742529, 18955.62088758231, 19019.61960760791, 19058.714119288223, 20897.686534701992, 21158.576828463498, 21338.0, 22170.334889953167, 22340.10639574415, 16724.24740886663, 17013.659726805396, 17094.230759615846, 17097.401590944257, 17132.0, 3758.755680880734, 3775.9056023599355, 3782.243551289753, 3801.543814742221, 3808.27642747877, 141883.0, 142842.0, 143562.28218858837, 144006.0, 147562.0, 16178.433754818525, 16279.430219942245, 16484.0, 16517.256723447474, 16569.6686066278, 28824.42351152965, 28880.711192888222, 29187.270318242216, 29192.708072919424, 29468.410631787243, 8263.173682631808, 8332.541710205876, 8343.08226095129, 8365.623546940402, 8381.16188381157, 4172.895677608084, 4175.394567787654, 4268.743875367426, 4271.9359209611885, 4298.8280468780895, 5244.868878278074, 5376.4085950545405, 5391.029614669397, 5406.675599464044, 5410.97294513525, 1014.4623349624711, 1041.9114375278186, 1047.1047254597363, 1050.837120246363, 1052.6894566103017, 660.9306022867626, 683.8051155420682, 695.8156088636515, 709.1383968478324, 709.4927127104145, 11389.487473063731, 11562.364069976064, 11593.536258549564, 11980.820287695691, 12208.633740987785, 667.1960287853125, 672.5998031171445, 683.8495530983175, 684.2353174504777, 695.0408436357794, 5138.38339399267, 5242.213667949838, 5245.9508098380475, 5290.5238528531845, 5357.437469065692, 120002.0, 121802.0, 122710.38644806719, 124476.0, 126927.36536317259, 106899.46550267354, 108822.0, 110240.0, 114753.42623286997, 115720.42139789247, 93299.0, 94993.5250323744, 95664.52167739256, 96921.0, 99404.50297748465, 10039.69880903574, 10172.237082218715, 10399.27205095636, 10405.427701476492, 10514.369137851754, 44597.331040035075, 44779.552204477535, 45569.31646025379, 46015.76992115152, 46407.535924640324, 58449.70775146097, 58697.70651146717, 58791.0, 59635.0, 59950.0, 14484.275786210437, 14540.41838326455, 14555.854441455449, 14657.63355916068, 14659.48691795782, 16630.417935372203, 17030.148492575074, 17137.571560711083, 17252.482425527254, 17291.308347666454, 15274.0, 15339.0, 15442.768358474857, 15558.222088895283, 15708.450204242796, 13570.525031623845, 13742.244176570384, 13753.449862005407, 13834.446622135001, 13848.723025539633, 13087.934560327138, 13096.803547946598, 13155.342232888508, 13233.669158271121, 13259.071864969359, 11083.334999900031, 11098.445077745919, 11122.777544448903, 11218.55125794959, 11578.710532236424, 11550.133739969368, 11650.941745291218, 11928.940355298168, 11953.282803031845, 12058.216215945935], "value": 8754.460567522356, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1421.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 106.0, 107.0, 106.0, 107.0, 109.0, 106.0, 109.0, 109.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 118.0, 116.0, 117.0, 116.0, 115.0, 117.0, 117.0, 139.0], "value": 118.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 165.0, 162.0, 162.0, 165.0, 164.0, 164.0, 165.0, 165.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 73.0, 76.0, 75.0, 74.0, 73.0, 73.0, 74.0, 73.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 79.0, 79.0, 79.0, 80.0, 87.0, 81.0, 78.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 115.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 150.0, 149.0, 148.0, 148.0, 147.0, 150.0, 149.0, 150.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 55.0, 55.0, 57.0, 54.0, 54.0, 55.0, 54.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 43.0, 38.0, 37.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 77.0, 73.0, 77.0, 74.0, 74.0, 77.0, 73.0, 80.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 116.0, 117.0, 117.0, 116.0, 121.0, 109.0, 120.0, 109.0], "value": 115.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 175.0, 173.0, 175.0, 169.0, 165.0, 169.0, 170.0, 167.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 68.0, 65.0, 64.0, 70.0, 66.0, 65.0, 66.0], "value": 66.4, "unit": "score"}]}]} 21:31:38 INFO - Return code: 0 21:31:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:31:38 INFO - # TBPL SUCCESS # 21:31:38 INFO - Running post-action listener: _package_coverage_data 21:31:38 INFO - Running post-action listener: _resource_record_post_action 21:31:38 INFO - [mozharness: 2017-09-17 04:31:38.817823Z] Finished run-tests step (success) 21:31:38 INFO - Running post-run listener: _resource_record_post_run 21:31:38 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8916992; Write bytes: 528068608; Read time: 2524; Write time: 368064 21:31:38 INFO - TinderboxPrint: CPU usage
13.1% 21:31:38 INFO - TinderboxPrint: I/O read bytes / time
8,916,992 / 2,524 21:31:38 INFO - TinderboxPrint: I/O write bytes / time
528,068,608 / 368,064 21:31:38 INFO - TinderboxPrint: CPU idle
8,248.6 (86.9%) 21:31:38 INFO - TinderboxPrint: CPU user
1,192.5 (12.6%) 21:31:38 INFO - TinderboxPrint: Swap in / out
0 / 0 21:31:38 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 8192; Write bytes: 208904192; Read time: 324; Write time: 160968 21:31:38 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:31:38 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 6545408; Write bytes: 319164416; Read time: 1688; Write time: 207096 21:31:39 INFO - Running post-run listener: _upload_blobber_files 21:31:39 INFO - Blob upload gear active. 21:31:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:31:39 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 21:31:39 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'] 21:31:39 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 21:31:39 INFO - (blobuploader) - INFO - Open directory for files ... 21:31:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:31:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:31:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:31:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:31:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:31:40 INFO - (blobuploader) - INFO - Done attempting. 21:31:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:31:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:31:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:31:41 INFO - (blobuploader) - INFO - Done attempting. 21:31:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:31:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:31:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:31:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:31:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:31:48 INFO - (blobuploader) - INFO - Done attempting. 21:31:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:31:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:31:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:31:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:31:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:31:50 INFO - (blobuploader) - INFO - Done attempting. 21:31:50 INFO - (blobuploader) - INFO - Iteration through files over. 21:31:50 INFO - Return code: 0 21:31:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:31:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:31:50 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33a6c5a866c9ea5456ec22837fa22f14f05531d7833f6bfc7e474dedfae488378017eb815b36cfb865b9a99b3c1ec5c636c19d3fcf3aa9530c81b95a720e9049", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71827ef97cf6d008fb550550c9c4bef7b18954cc7ebbd6b3b5907bd46c5dab5481b5c2a9aff082aca18e464968ab20bca963a5168a4c494c455116dcf2d9c46e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/797b682b6f626241ec8cd2d0002e19b5c195bd902c11748401737546044af3084ee7b850cf97393acddb8ae15056269ea3fa468c3790fd21bd851c923b268503", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4e50b632e57e6c7a7427179b2f53cb4e90939ffa98581e2fd3203e94c3c64df8555d2e7863d69a99e0682c3c44bfad5dcdf3c0fc3f3a6fed3e498143bfa6429"} 21:31:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:31:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:31:50 INFO - Contents: 21:31:50 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33a6c5a866c9ea5456ec22837fa22f14f05531d7833f6bfc7e474dedfae488378017eb815b36cfb865b9a99b3c1ec5c636c19d3fcf3aa9530c81b95a720e9049", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71827ef97cf6d008fb550550c9c4bef7b18954cc7ebbd6b3b5907bd46c5dab5481b5c2a9aff082aca18e464968ab20bca963a5168a4c494c455116dcf2d9c46e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/797b682b6f626241ec8cd2d0002e19b5c195bd902c11748401737546044af3084ee7b850cf97393acddb8ae15056269ea3fa468c3790fd21bd851c923b268503", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4e50b632e57e6c7a7427179b2f53cb4e90939ffa98581e2fd3203e94c3c64df8555d2e7863d69a99e0682c3c44bfad5dcdf3c0fc3f3a6fed3e498143bfa6429"} 21:31:50 INFO - Running post-run listener: copy_logs_to_upload_dir 21:31:50 INFO - Copying logs to upload dir... 21:31:50 INFO - mkdir: /builds/slave/test/build/upload/logs 21:31:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.499360 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-16 21:31:50.079960) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 21:31:50.087739) ========= 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=826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814 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/33a6c5a866c9ea5456ec22837fa22f14f05531d7833f6bfc7e474dedfae488378017eb815b36cfb865b9a99b3c1ec5c636c19d3fcf3aa9530c81b95a720e9049", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71827ef97cf6d008fb550550c9c4bef7b18954cc7ebbd6b3b5907bd46c5dab5481b5c2a9aff082aca18e464968ab20bca963a5168a4c494c455116dcf2d9c46e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/797b682b6f626241ec8cd2d0002e19b5c195bd902c11748401737546044af3084ee7b850cf97393acddb8ae15056269ea3fa468c3790fd21bd851c923b268503", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4e50b632e57e6c7a7427179b2f53cb4e90939ffa98581e2fd3203e94c3c64df8555d2e7863d69a99e0682c3c44bfad5dcdf3c0fc3f3a6fed3e498143bfa6429"} build_url:https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/MNqMn-AVSA2YJrdiCeGBdQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33a6c5a866c9ea5456ec22837fa22f14f05531d7833f6bfc7e474dedfae488378017eb815b36cfb865b9a99b3c1ec5c636c19d3fcf3aa9530c81b95a720e9049", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71827ef97cf6d008fb550550c9c4bef7b18954cc7ebbd6b3b5907bd46c5dab5481b5c2a9aff082aca18e464968ab20bca963a5168a4c494c455116dcf2d9c46e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/797b682b6f626241ec8cd2d0002e19b5c195bd902c11748401737546044af3084ee7b850cf97393acddb8ae15056269ea3fa468c3790fd21bd851c923b268503", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b4e50b632e57e6c7a7427179b2f53cb4e90939ffa98581e2fd3203e94c3c64df8555d2e7863d69a99e0682c3c44bfad5dcdf3c0fc3f3a6fed3e498143bfa6429"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 21:31:50.115611) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:31:50.115911) ========= 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=826874ccc97bd7bf541b4875000001d5-1505621462.726485-1976619814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:31:50.199143) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 21:31:50.202470) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 21:31:50.202761) ========= ========= Total master_lag: 0.16 =========