builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-015 starttime: 1506021025.01 results: success (0) revision: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:25.006449) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:25.006822) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:25.007080) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:25.080417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:25.080658) ========= 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=20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 12:10:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2017-09-21 12:10:25 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120702 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:25.251308) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:25.251601) ========= 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=20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015638 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:25.317429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-21 12:10:25.317760) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 --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 ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 --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=20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 12:10:25,496 truncating revision to first 12 chars 2017-09-21 12:10:25,496 Setting DEBUG logging. 2017-09-21 12:10:25,496 attempt 1/10 2017-09-21 12:10:25,496 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ca7d18dbacbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 12:10:26,821 unpacking tar archive at: mozilla-central-ca7d18dbacbf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.598030 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-21 12:10:26.941977) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:26.942311) ========= script_repo_revision: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:26.942689) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:26.942952) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 12:10:26.957462) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-09-21 12:10:26.957847) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:10:27 INFO - MultiFileLogger online at 20170921 12:10:27 in /builds/slave/test 12:10:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:10:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:10:27 INFO - {'append_to_log': False, 12:10:27 INFO - 'base_work_dir': '/builds/slave/test', 12:10:27 INFO - 'blob_upload_branch': 'Firefox', 12:10:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:10:27 INFO - 'branch': 'Firefox', 12:10:27 INFO - 'buildbot_json_path': 'buildprops.json', 12:10:27 INFO - 'code_coverage': False, 12:10:27 INFO - 'config_files': ('talos/linux_config.py',), 12:10:27 INFO - 'default_actions': ('clobber', 12:10:27 INFO - 'read-buildbot-config', 12:10:27 INFO - 'download-and-extract', 12:10:27 INFO - 'populate-webroot', 12:10:27 INFO - 'create-virtualenv', 12:10:27 INFO - 'install', 12:10:27 INFO - 'setup-mitmproxy', 12:10:27 INFO - 'run-tests'), 12:10:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:10:27 INFO - 'disable_ccov_upload': False, 12:10:27 INFO - 'disable_stylo': False, 12:10:27 INFO - 'download_minidump_stackwalk': True, 12:10:27 INFO - 'download_symbols': 'ondemand', 12:10:27 INFO - 'enable_stylo': False, 12:10:27 INFO - 'enable_webrender': False, 12:10:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:10:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:10:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:10:27 INFO - 'gecko_profile': False, 12:10:27 INFO - 'gecko_profile_interval': 0, 12:10:27 INFO - 'installer_path': 'installer.exe', 12:10:27 INFO - 'log_level': 'info', 12:10:27 INFO - 'log_name': 'talos', 12:10:27 INFO - 'log_to_console': True, 12:10:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:10:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:10:27 INFO - 'opt_config_files': (), 12:10:27 INFO - 'pip_index': False, 12:10:27 INFO - 'suite': 'dromaeojs-e10s', 12:10:27 INFO - 'system_bits': '32', 12:10:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:10:27 INFO - 'title': 'talos-linux64-ix-015', 12:10:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:10:27 INFO - 'use_talos_json': True, 12:10:27 INFO - 'verify': 'False', 12:10:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:10:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:10:27 INFO - 'work_dir': 'build'} 12:10:27 INFO - [mozharness: 2017-09-21 19:10:27.255773Z] Running clobber step. 12:10:27 INFO - Running pre-action listener: _resource_record_pre_action 12:10:27 INFO - Running main action method: clobber 12:10:27 INFO - rmtree: /builds/slave/test/build 12:10:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:10:28 INFO - Running post-action listener: _resource_record_post_action 12:10:28 INFO - [mozharness: 2017-09-21 19:10:28.257848Z] Finished clobber step (success) 12:10:28 INFO - [mozharness: 2017-09-21 19:10:28.257946Z] Running read-buildbot-config step. 12:10:28 INFO - Running pre-action listener: _resource_record_pre_action 12:10:28 INFO - Running main action method: read_buildbot_config 12:10:28 INFO - Using buildbot properties: 12:10:28 INFO - { 12:10:28 INFO - "project": "", 12:10:28 INFO - "product": "firefox", 12:10:28 INFO - "who": "ffxbld@noreply.mozilla.org", 12:10:28 INFO - "installer_path": "public/build/target.tar.bz2", 12:10:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:10:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:10:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:10:28 INFO - "stage_platform": "linux64-pgo", 12:10:28 INFO - "basedir": "/builds/slave/test", 12:10:28 INFO - "buildnumber": 193, 12:10:28 INFO - "platform": "ubuntu64_hw", 12:10:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:10:28 INFO - "slavebuilddir": "test", 12:10:28 INFO - "taskId": "KipX_9VMRt2XkTIsFNSfLA", 12:10:28 INFO - "branch": "mozilla-central", 12:10:28 INFO - "script_repo_revision": "production", 12:10:28 INFO - "revision": "ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2", 12:10:28 INFO - "slavename": "talos-linux64-ix-015", 12:10:28 INFO - "repo_path": "mozilla-central" 12:10:28 INFO - } 12:10:28 INFO - Finding installer, test and symbols from parent task. 12:10:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KipX_9VMRt2XkTIsFNSfLA'}, attempt #1 12:10:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KipX_9VMRt2XkTIsFNSfLA'}, attempt #1 12:10:29 INFO - Task dependencies: TlqS4ZRNTFKzKKSYPo9p3Q 12:10:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q'}, attempt #1 12:10:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.tar.bz2 12:10:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.test_packages.json 12:10:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.crashreporter-symbols.zip 12:10:29 INFO - Running post-action listener: _resource_record_post_action 12:10:29 INFO - [mozharness: 2017-09-21 19:10:29.912876Z] Finished read-buildbot-config step (success) 12:10:29 INFO - [mozharness: 2017-09-21 19:10:29.913095Z] Running download-and-extract step. 12:10:29 INFO - Running pre-action listener: _resource_record_pre_action 12:10:29 INFO - Running main action method: download_and_extract 12:10:29 INFO - mkdir: /builds/slave/test/build/tests 12:10: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')]} 12:10:29 INFO - trying https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.test_packages.json 12:10:29 INFO - Downloading https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:10:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:10:30 INFO - Downloaded 989 bytes. 12:10:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:10:30 INFO - Using the following test package requirements: 12:10:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:10:30 INFO - u'common': [u'target.common.tests.zip'], 12:10:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:10:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:10:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:10:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:10:30 INFO - u'mozbase': [u'target.common.tests.zip'], 12:10:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:10:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:10:30 INFO - u'web-platform': [u'target.common.tests.zip', 12:10:30 INFO - u'target.web-platform.tests.tar.gz'], 12:10:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:10:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:10:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.common.tests.zip 12:10:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:10:30 INFO - Fetch https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.common.tests.zip into memory 12:10:31 INFO - Content-Length response header: 55330338 12:10:31 INFO - Bytes received: 55330338 12:10:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.talos.tests.zip 12:10:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:10:42 INFO - Fetch https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.talos.tests.zip into memory 12:10:43 INFO - Content-Length response header: 14509184 12:10:43 INFO - Bytes received: 14509184 12:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:43 INFO - trying https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.tar.bz2 12:10:43 INFO - Downloading https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:10:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:10:44 INFO - Downloaded 62347390 bytes. 12:10:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.tar.bz2 12:10:44 INFO - mkdir: /builds/slave/test/properties 12:10:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:10:44 INFO - Writing to file /builds/slave/test/properties/build_url 12:10:44 INFO - Contents: 12:10:44 INFO - build_url:https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.tar.bz2 12:10:44 INFO - Running post-action listener: _resource_record_post_action 12:10:44 INFO - Running post-action listener: find_tests_for_verification 12:10:44 INFO - Running post-action listener: set_extra_try_arguments 12:10:44 INFO - [mozharness: 2017-09-21 19:10:44.534596Z] Finished download-and-extract step (success) 12:10:44 INFO - [mozharness: 2017-09-21 19:10:44.534694Z] Running populate-webroot step. 12:10:44 INFO - Running pre-action listener: _resource_record_pre_action 12:10:44 INFO - Running main action method: populate_webroot 12:10:44 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:10:44 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['tresize', 12:10:44 INFO - 'tcanvasmark']}, 12:10:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:10:44 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['dromaeo_css', 12:10:44 INFO - 'kraken']}, 12:10:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:10:44 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:10:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'tests': ['damp', 'tps']}, 12:10:44 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['damp', 'tps']}, 12:10:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:10:44 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['dromaeo_dom']}, 12:10:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:10:44 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['basic_compositor_video', 12:10:44 INFO - 'glvideo']}, 12:10:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:10:44 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['ts_paint_webext', 12:10:44 INFO - 'tp5o_webext']}, 12:10:44 INFO - 'other-e10s': {'tests': ['a11yr', 12:10:44 INFO - 'ts_paint', 12:10:44 INFO - 'tpaint', 12:10:44 INFO - 'sessionrestore', 12:10:44 INFO - 'sessionrestore_many_windows', 12:10:44 INFO - 'sessionrestore_no_auto_restore', 12:10:44 INFO - 'tabpaint']}, 12:10:44 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['a11yr', 12:10:44 INFO - 'ts_paint', 12:10:44 INFO - 'tpaint', 12:10:44 INFO - 'sessionrestore', 12:10:44 INFO - 'sessionrestore_many_windows', 12:10:44 INFO - 'sessionrestore_no_auto_restore', 12:10:44 INFO - 'tabpaint']}, 12:10:44 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:10:44 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:10:44 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['perf_reftest_singletons']}, 12:10:44 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['bloom_basic']}, 12:10:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:10:44 INFO - 'tsvgr_opacity', 12:10:44 INFO - 'tart', 12:10:44 INFO - 'tscrollx', 12:10:44 INFO - 'tsvg_static']}, 12:10:44 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['tsvgx', 12:10:44 INFO - 'tsvgr_opacity', 12:10:44 INFO - 'tart', 12:10:44 INFO - 'tscrollx', 12:10:44 INFO - 'tsvg_static']}, 12:10:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:10:44 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['tp5o']}, 12:10:44 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:10:44 INFO - 'talos_options': ['--mitmproxy', 12:10:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:10:44 INFO - '--firstNonBlankPaint'], 12:10:44 INFO - 'tests': ['tp6_google', 12:10:44 INFO - 'tp6_youtube', 12:10:44 INFO - 'tp6_amazon', 12:10:44 INFO - 'tp6_facebook']}, 12:10:44 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo', 12:10:44 INFO - '--mitmproxy', 12:10:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:10:44 INFO - '--firstNonBlankPaint'], 12:10:44 INFO - 'tests': ['tp6_google', 12:10:44 INFO - 'tp6_youtube', 12:10:44 INFO - 'tp6_amazon', 12:10:44 INFO - 'tp6_facebook']}, 12:10:44 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:10:44 INFO - 'talos_options': ['--stylo-threads=1', 12:10:44 INFO - '--mitmproxy', 12:10:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:10:44 INFO - '--firstNonBlankPaint'], 12:10:44 INFO - 'tests': ['tp6_google', 12:10:44 INFO - 'tp6_youtube', 12:10:44 INFO - 'tp6_amazon', 12:10:44 INFO - 'tp6_facebook']}, 12:10:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--xperf_path', 12:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:10:44 INFO - 'tests': ['tp5n']}, 12:10:44 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo', 12:10:44 INFO - '--xperf_path', 12:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:10:44 INFO - 'tests': ['tp5n']}}} 12:10:44 INFO - Running post-action listener: _resource_record_post_action 12:10:44 INFO - [mozharness: 2017-09-21 19:10:44.549873Z] Finished populate-webroot step (success) 12:10:44 INFO - [mozharness: 2017-09-21 19:10:44.549963Z] Running create-virtualenv step. 12:10:44 INFO - Running pre-action listener: _resource_record_pre_action 12:10:44 INFO - Running main action method: create_virtualenv 12:10:44 INFO - Creating virtualenv /builds/slave/test/build/venv 12:10:44 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:10:44 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:10:44 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:10:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:10:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:10:45 INFO - Installing distribute.............................................................................................................................................................................................done. 12:10:45 INFO - Installing pip...............done. 12:10:45 INFO - Return code: 0 12:10:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:10:45 INFO - Reading from file tmpfile_stdout 12:10:45 INFO - Output received: 12:10:45 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:10:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:45 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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:10:45 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 12:10:45 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 12:10:45 INFO - Using env: {'DISPLAY': ':0', 12:10:45 INFO - 'HOME': '/home/cltbld', 12:10:45 INFO - 'LANG': 'en_US.UTF-8', 12:10:45 INFO - 'LANGUAGE': 'en_US:en', 12:10:45 INFO - 'LOGNAME': 'cltbld', 12:10:45 INFO - 'MAIL': '/var/mail/cltbld', 12:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:45 INFO - 'MOZ_NO_REMOTE': '1', 12:10:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:45 INFO - 'NO_EM_RESTART': '1', 12:10:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:45 INFO - 'PWD': '/builds/slave/test', 12:10:45 INFO - 'SHELL': '/bin/bash', 12:10:45 INFO - 'SHLVL': '1', 12:10:45 INFO - 'TERM': 'linux', 12:10:45 INFO - 'TMOUT': '86400', 12:10:45 INFO - 'USER': 'cltbld', 12:10:45 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:45 INFO - '_': '/tools/buildbot/bin/python'} 12:10:46 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:10:46 INFO - Downloading/unpacking pip>=1.5 12:10:46 INFO - Running setup.py egg_info for package pip 12:10:46 INFO - warning: no files found matching 'pip/cacert.pem' 12:10:46 INFO - warning: no files found matching '*.html' under directory 'docs' 12:10:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:10:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:10:46 INFO - Installing collected packages: pip 12:10:46 INFO - Found existing installation: pip 1.1 12:10:46 INFO - Uninstalling pip: 12:10:46 INFO - Successfully uninstalled pip 12:10:46 INFO - Running setup.py install for pip 12:10:46 INFO - warning: no files found matching 'pip/cacert.pem' 12:10:46 INFO - warning: no files found matching '*.html' under directory 'docs' 12:10:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:10:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:10:46 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:10:46 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:10:46 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:10:46 INFO - Successfully installed pip 12:10:46 INFO - Cleaning up... 12:10:46 INFO - Return code: 0 12:10:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:10: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')]} 12:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10: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')]} 12:10:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10: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', '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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:10: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', 'psutil>=3.1.1'] in /builds/slave/test/build 12:10: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 psutil>=3.1.1 12:10:46 INFO - Using env: {'DISPLAY': ':0', 12:10:46 INFO - 'HOME': '/home/cltbld', 12:10:46 INFO - 'LANG': 'en_US.UTF-8', 12:10:46 INFO - 'LANGUAGE': 'en_US:en', 12:10:46 INFO - 'LOGNAME': 'cltbld', 12:10:46 INFO - 'MAIL': '/var/mail/cltbld', 12:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:46 INFO - 'MOZ_NO_REMOTE': '1', 12:10:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:46 INFO - 'NO_EM_RESTART': '1', 12:10:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:46 INFO - 'PWD': '/builds/slave/test', 12:10:46 INFO - 'SHELL': '/bin/bash', 12:10:46 INFO - 'SHLVL': '1', 12:10:46 INFO - 'TERM': 'linux', 12:10:46 INFO - 'TMOUT': '86400', 12:10:46 INFO - 'USER': 'cltbld', 12:10:46 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:46 INFO - '_': '/tools/buildbot/bin/python'} 12:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:46 INFO - Downloading/unpacking psutil>=3.1.1 12:10:46 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 12:10:46 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 12:10:46 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 12:10:46 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 12:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:10:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:10:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:10:48 INFO - Installing collected packages: psutil 12:10:48 INFO - Running setup.py install for psutil 12:10:48 INFO - building 'psutil._psutil_linux' extension 12:10:48 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 12:10:48 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 12:10:48 INFO - building 'psutil._psutil_posix' extension 12:10:48 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 12:10:48 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 12:10:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:10:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:10:48 INFO - Successfully installed psutil 12:10:48 INFO - Cleaning up... 12:10:48 INFO - Return code: 0 12:10:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:48 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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:10:48 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 12:10:48 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 12:10:48 INFO - Using env: {'DISPLAY': ':0', 12:10:48 INFO - 'HOME': '/home/cltbld', 12:10:48 INFO - 'LANG': 'en_US.UTF-8', 12:10:48 INFO - 'LANGUAGE': 'en_US:en', 12:10:48 INFO - 'LOGNAME': 'cltbld', 12:10:48 INFO - 'MAIL': '/var/mail/cltbld', 12:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:48 INFO - 'MOZ_NO_REMOTE': '1', 12:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:48 INFO - 'NO_EM_RESTART': '1', 12:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:48 INFO - 'PWD': '/builds/slave/test', 12:10:48 INFO - 'SHELL': '/bin/bash', 12:10:48 INFO - 'SHLVL': '1', 12:10:48 INFO - 'TERM': 'linux', 12:10:48 INFO - 'TMOUT': '86400', 12:10:48 INFO - 'USER': 'cltbld', 12:10:48 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:48 INFO - '_': '/tools/buildbot/bin/python'} 12:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:10:48 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 12:10:48 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 12:10:48 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 12:10:48 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 12:10:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:10:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:10:50 INFO - Installing collected packages: mozsystemmonitor 12:10:50 INFO - Running setup.py install for mozsystemmonitor 12:10:50 INFO - Successfully installed mozsystemmonitor 12:10:50 INFO - Cleaning up... 12:10:51 INFO - Return code: 0 12:10:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:51 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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:10:51 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 12:10:51 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 12:10:51 INFO - Using env: {'DISPLAY': ':0', 12:10:51 INFO - 'HOME': '/home/cltbld', 12:10:51 INFO - 'LANG': 'en_US.UTF-8', 12:10:51 INFO - 'LANGUAGE': 'en_US:en', 12:10:51 INFO - 'LOGNAME': 'cltbld', 12:10:51 INFO - 'MAIL': '/var/mail/cltbld', 12:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:51 INFO - 'MOZ_NO_REMOTE': '1', 12:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:51 INFO - 'NO_EM_RESTART': '1', 12:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:51 INFO - 'PWD': '/builds/slave/test', 12:10:51 INFO - 'SHELL': '/bin/bash', 12:10:51 INFO - 'SHLVL': '1', 12:10:51 INFO - 'TERM': 'linux', 12:10:51 INFO - 'TMOUT': '86400', 12:10:51 INFO - 'USER': 'cltbld', 12:10:51 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:51 INFO - '_': '/tools/buildbot/bin/python'} 12:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:51 INFO - Downloading/unpacking jsonschema==2.5.1 12:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:10:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:53 INFO - Downloading functools32-3.2.3-2.tar.gz 12:10:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:10:53 INFO - warning: no files found matching '*.txt' 12:10:53 INFO - no previously-included directories found matching 'build' 12:10:53 INFO - no previously-included directories found matching 'dist' 12:10:53 INFO - no previously-included directories found matching '.git*' 12:10:53 INFO - Installing collected packages: jsonschema, functools32 12:10:53 INFO - Running setup.py install for functools32 12:10:53 INFO - warning: no files found matching '*.txt' 12:10:53 INFO - no previously-included directories found matching 'build' 12:10:53 INFO - no previously-included directories found matching 'dist' 12:10:53 INFO - no previously-included directories found matching '.git*' 12:10:53 INFO - Successfully installed jsonschema functools32 12:10:53 INFO - Cleaning up... 12:10:53 INFO - Return code: 0 12:10:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:10:53 INFO - Using env: {'DISPLAY': ':0', 12:10:53 INFO - 'HOME': '/home/cltbld', 12:10:53 INFO - 'LANG': 'en_US.UTF-8', 12:10:53 INFO - 'LANGUAGE': 'en_US:en', 12:10:53 INFO - 'LOGNAME': 'cltbld', 12:10:53 INFO - 'MAIL': '/var/mail/cltbld', 12:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:53 INFO - 'MOZ_NO_REMOTE': '1', 12:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:53 INFO - 'NO_EM_RESTART': '1', 12:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:53 INFO - 'PWD': '/builds/slave/test', 12:10:53 INFO - 'SHELL': '/bin/bash', 12:10:53 INFO - 'SHLVL': '1', 12:10:53 INFO - 'TERM': 'linux', 12:10:53 INFO - 'TMOUT': '86400', 12:10:53 INFO - 'USER': 'cltbld', 12:10:53 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:53 INFO - '_': '/tools/buildbot/bin/python'} 12:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:10:53 INFO - Cleaning up... 12:10:53 INFO - Return code: 0 12:10:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:10:53 INFO - Using env: {'DISPLAY': ':0', 12:10:53 INFO - 'HOME': '/home/cltbld', 12:10:53 INFO - 'LANG': 'en_US.UTF-8', 12:10:53 INFO - 'LANGUAGE': 'en_US:en', 12:10:53 INFO - 'LOGNAME': 'cltbld', 12:10:53 INFO - 'MAIL': '/var/mail/cltbld', 12:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:53 INFO - 'MOZ_NO_REMOTE': '1', 12:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:53 INFO - 'NO_EM_RESTART': '1', 12:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:53 INFO - 'PWD': '/builds/slave/test', 12:10:53 INFO - 'SHELL': '/bin/bash', 12:10:53 INFO - 'SHLVL': '1', 12:10:53 INFO - 'TERM': 'linux', 12:10:53 INFO - 'TMOUT': '86400', 12:10:53 INFO - 'USER': 'cltbld', 12:10:53 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:53 INFO - '_': '/tools/buildbot/bin/python'} 12:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:54 INFO - Downloading/unpacking blobuploader==1.2.4 12:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - Downloading blobuploader-1.2.4.tar.gz 12:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:10:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:10:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - Downloading docopt-0.6.1.tar.gz 12:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:10:55 INFO - Installing collected packages: blobuploader, requests, docopt 12:10:55 INFO - Running setup.py install for blobuploader 12:10:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:10:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:10:55 INFO - Running setup.py install for requests 12:10:56 INFO - Running setup.py install for docopt 12:10:56 INFO - Successfully installed blobuploader requests docopt 12:10:56 INFO - Cleaning up... 12:10:56 INFO - Return code: 0 12:10:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:56 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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:10:56 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 12:10:56 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 12:10:56 INFO - Using env: {'DISPLAY': ':0', 12:10:56 INFO - 'HOME': '/home/cltbld', 12:10:56 INFO - 'LANG': 'en_US.UTF-8', 12:10:56 INFO - 'LANGUAGE': 'en_US:en', 12:10:56 INFO - 'LOGNAME': 'cltbld', 12:10:56 INFO - 'MAIL': '/var/mail/cltbld', 12:10:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:56 INFO - 'MOZ_NO_REMOTE': '1', 12:10:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:56 INFO - 'NO_EM_RESTART': '1', 12:10:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:56 INFO - 'PWD': '/builds/slave/test', 12:10:56 INFO - 'SHELL': '/bin/bash', 12:10:56 INFO - 'SHLVL': '1', 12:10:56 INFO - 'TERM': 'linux', 12:10:56 INFO - 'TMOUT': '86400', 12:10:56 INFO - 'USER': 'cltbld', 12:10:56 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:10:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:56 INFO - '_': '/tools/buildbot/bin/python'} 12:10:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:10:56 INFO - Running setup.py (path:/tmp/pip-WuleYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:10:56 INFO - Running setup.py (path:/tmp/pip-bnPN1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:10:56 INFO - Running setup.py (path:/tmp/pip-GhuVu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:10:56 INFO - Running setup.py (path:/tmp/pip-PB9p7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:10:56 INFO - Running setup.py (path:/tmp/pip-5Xo5Z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:10:56 INFO - Running setup.py (path:/tmp/pip-bh9wnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:10:56 INFO - Running setup.py (path:/tmp/pip-k4hbYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:10:57 INFO - Running setup.py (path:/tmp/pip-p7U4rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:10:57 INFO - Running setup.py (path:/tmp/pip-ZzL62L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:10:57 INFO - Running setup.py (path:/tmp/pip-KkvkGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:10:57 INFO - Running setup.py (path:/tmp/pip-e8bb4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:10:57 INFO - Running setup.py (path:/tmp/pip-qy8Ojp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:10:57 INFO - Running setup.py (path:/tmp/pip-FLw3Rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:10:57 INFO - Running setup.py (path:/tmp/pip-5Jjb_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:57 INFO - Running setup.py (path:/tmp/pip-9KBc4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:10:57 INFO - Running setup.py (path:/tmp/pip-Pqv_S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:10:57 INFO - Running setup.py (path:/tmp/pip-ZvKmCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:10:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:10:57 INFO - Running setup.py install for manifestparser 12:10:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:10:57 INFO - Running setup.py install for mozcrash 12:10:57 INFO - Running setup.py install for mozdebug 12:10:58 INFO - Running setup.py install for mozdevice 12:10:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozfile 12:10:58 INFO - Running setup.py install for mozhttpd 12:10:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozinfo 12:10:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozInstall 12:10:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozleak 12:10:58 INFO - Running setup.py install for mozlog 12:10:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for moznetwork 12:10:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozprocess 12:10:58 INFO - Running setup.py install for mozprofile 12:10:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Running setup.py install for mozrunner 12:10:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Running setup.py install for mozscreenshot 12:10:59 INFO - Running setup.py install for moztest 12:10:59 INFO - Running setup.py install for mozversion 12:10:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:10:59 INFO - Cleaning up... 12:10:59 INFO - Return code: 0 12:10:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:59 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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:10:59 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 12:10:59 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 12:10:59 INFO - Using env: {'DISPLAY': ':0', 12:10:59 INFO - 'HOME': '/home/cltbld', 12:10:59 INFO - 'LANG': 'en_US.UTF-8', 12:10:59 INFO - 'LANGUAGE': 'en_US:en', 12:10:59 INFO - 'LOGNAME': 'cltbld', 12:10:59 INFO - 'MAIL': '/var/mail/cltbld', 12:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:59 INFO - 'MOZ_NO_REMOTE': '1', 12:10:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:59 INFO - 'NO_EM_RESTART': '1', 12:10:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:59 INFO - 'PWD': '/builds/slave/test', 12:10:59 INFO - 'SHELL': '/bin/bash', 12:10:59 INFO - 'SHLVL': '1', 12:10:59 INFO - 'TERM': 'linux', 12:10:59 INFO - 'TMOUT': '86400', 12:10:59 INFO - 'USER': 'cltbld', 12:10:59 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:59 INFO - '_': '/tools/buildbot/bin/python'} 12:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:10:59 INFO - Running setup.py (path:/tmp/pip-BZN2IX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:10:59 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)) 12:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:10:59 INFO - Running setup.py (path:/tmp/pip-vznX7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:10:59 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)) 12:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:10:59 INFO - Running setup.py (path:/tmp/pip-nyy8fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:10:59 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)) 12:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:10:59 INFO - Running setup.py (path:/tmp/pip-BEHxSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:10:59 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)) 12:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:00 INFO - Running setup.py (path:/tmp/pip-uQwjtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:00 INFO - Running setup.py (path:/tmp/pip-Aurq4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:00 INFO - Running setup.py (path:/tmp/pip-UMzAbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:00 INFO - Running setup.py (path:/tmp/pip-ZL7gs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:00 INFO - Running setup.py (path:/tmp/pip-RYK9J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:00 INFO - Running setup.py (path:/tmp/pip-9i3N0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:00 INFO - Running setup.py (path:/tmp/pip-3QuSuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:00 INFO - Running setup.py (path:/tmp/pip-Qxo8iQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:00 INFO - Running setup.py (path:/tmp/pip-0WKbPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:00 INFO - Running setup.py (path:/tmp/pip-zu_3I6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:00 INFO - Running setup.py (path:/tmp/pip-hTrmjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:00 INFO - Running setup.py (path:/tmp/pip-bXeFbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:00 INFO - Running setup.py (path:/tmp/pip-NKSUBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:02 INFO - Downloading blessings-1.6.tar.gz 12:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:02 INFO - Installing collected packages: blessings 12:11:02 INFO - Running setup.py install for blessings 12:11:02 INFO - Successfully installed blessings 12:11:02 INFO - Cleaning up... 12:11:02 INFO - Return code: 0 12:11:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:11:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:02 INFO - Reading from file tmpfile_stdout 12:11:02 INFO - Current package versions: 12:11:02 INFO - argparse == 1.2.1 12:11:02 INFO - blessings == 1.6 12:11:02 INFO - blobuploader == 1.2.4 12:11:02 INFO - docopt == 0.6.1 12:11:02 INFO - functools32 == 3.2.3-2 12:11:02 INFO - jsonschema == 2.5.1 12:11:02 INFO - manifestparser == 1.1 12:11:02 INFO - mozInstall == 1.13 12:11:02 INFO - mozcrash == 1.0 12:11:02 INFO - mozdebug == 0.1 12:11:02 INFO - mozdevice == 0.51 12:11:02 INFO - mozfile == 1.2 12:11:02 INFO - mozhttpd == 0.7 12:11:02 INFO - mozinfo == 0.10 12:11:02 INFO - mozleak == 0.1 12:11:02 INFO - mozlog == 3.5 12:11:02 INFO - moznetwork == 0.27 12:11:02 INFO - mozprocess == 0.25 12:11:02 INFO - mozprofile == 0.28 12:11:02 INFO - mozrunner == 6.13 12:11:02 INFO - mozscreenshot == 0.1 12:11:02 INFO - mozsystemmonitor == 0.3 12:11:02 INFO - moztest == 0.8 12:11:02 INFO - mozversion == 1.4 12:11:02 INFO - psutil == 3.1.1 12:11:02 INFO - requests == 1.2.3 12:11:02 INFO - wsgiref == 0.1.2 12:11:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:02 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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:11:02 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 12:11:02 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 12:11:02 INFO - Using env: {'DISPLAY': ':0', 12:11:02 INFO - 'HOME': '/home/cltbld', 12:11:02 INFO - 'LANG': 'en_US.UTF-8', 12:11:02 INFO - 'LANGUAGE': 'en_US:en', 12:11:02 INFO - 'LOGNAME': 'cltbld', 12:11:02 INFO - 'MAIL': '/var/mail/cltbld', 12:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:02 INFO - 'MOZ_NO_REMOTE': '1', 12:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:02 INFO - 'NO_EM_RESTART': '1', 12:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:02 INFO - 'PWD': '/builds/slave/test', 12:11:02 INFO - 'SHELL': '/bin/bash', 12:11:02 INFO - 'SHLVL': '1', 12:11:02 INFO - 'TERM': 'linux', 12:11:02 INFO - 'TMOUT': '86400', 12:11:02 INFO - 'USER': 'cltbld', 12:11:02 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:02 INFO - '_': '/tools/buildbot/bin/python'} 12:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:11:03 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)) 12:11:03 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)) 12:11:03 INFO - Installing collected packages: simplejson 12:11:03 INFO - Running setup.py install for simplejson 12:11:03 INFO - building 'simplejson._speedups' extension 12:11:03 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 12:11:04 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 12:11:04 INFO - Successfully installed simplejson 12:11:04 INFO - Cleaning up... 12:11:04 INFO - Return code: 0 12:11:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:11:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:04 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 0x1f351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, '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': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', '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 12:11:04 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 12:11:04 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 12:11:04 INFO - Using env: {'DISPLAY': ':0', 12:11:04 INFO - 'HOME': '/home/cltbld', 12:11:04 INFO - 'LANG': 'en_US.UTF-8', 12:11:04 INFO - 'LANGUAGE': 'en_US:en', 12:11:04 INFO - 'LOGNAME': 'cltbld', 12:11:04 INFO - 'MAIL': '/var/mail/cltbld', 12:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:04 INFO - 'MOZ_NO_REMOTE': '1', 12:11:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:04 INFO - 'NO_EM_RESTART': '1', 12:11:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:04 INFO - 'PWD': '/builds/slave/test', 12:11:04 INFO - 'SHELL': '/bin/bash', 12:11:04 INFO - 'SHLVL': '1', 12:11:04 INFO - 'TERM': 'linux', 12:11:04 INFO - 'TMOUT': '86400', 12:11:04 INFO - 'USER': 'cltbld', 12:11:04 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:04 INFO - '_': '/tools/buildbot/bin/python'} 12:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:11:05 INFO - Cleaning up... 12:11:05 INFO - Return code: 0 12:11:05 INFO - Running post-action listener: _resource_record_post_action 12:11:05 INFO - Running post-action listener: _start_resource_monitoring 12:11:05 INFO - Starting resource monitoring. 12:11:05 INFO - [mozharness: 2017-09-21 19:11:05.199982Z] Finished create-virtualenv step (success) 12:11:05 INFO - [mozharness: 2017-09-21 19:11:05.200504Z] Running install step. 12:11:05 INFO - Running pre-action listener: _resource_record_pre_action 12:11:05 INFO - Running main action method: install 12:11:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:05 INFO - Reading from file tmpfile_stdout 12:11:05 INFO - Detecting whether we're running mozinstall >=1.0... 12:11:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:11:05 INFO - Reading from file tmpfile_stdout 12:11:05 INFO - Output received: 12:11:05 INFO - Usage: mozinstall [options] installer 12:11:05 INFO - Options: 12:11:05 INFO - -h, --help show this help message and exit 12:11:05 INFO - -d DEST, --destination=DEST 12:11:05 INFO - Directory to install application into. [default: 12:11:05 INFO - "/builds/slave/test"] 12:11:05 INFO - --app=APP Application being installed. [default: firefox] 12:11:05 INFO - mkdir: /builds/slave/test/build/application 12:11:05 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'] 12:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:11:23 INFO - Reading from file tmpfile_stdout 12:11:23 INFO - Output received: 12:11:23 INFO - /builds/slave/test/build/application/firefox/firefox 12:11:23 INFO - Running post-action listener: _resource_record_post_action 12:11:23 INFO - [mozharness: 2017-09-21 19:11:23.155275Z] Finished install step (success) 12:11:23 INFO - [mozharness: 2017-09-21 19:11:23.155534Z] Running setup-mitmproxy step. 12:11:23 INFO - Running pre-action listener: _resource_record_pre_action 12:11:23 INFO - Running main action method: setup_mitmproxy 12:11:23 INFO - Skipping: mitmproxy is not required 12:11:23 INFO - Running post-action listener: _resource_record_post_action 12:11:23 INFO - [mozharness: 2017-09-21 19:11:23.156664Z] Finished setup-mitmproxy step (success) 12:11:23 INFO - [mozharness: 2017-09-21 19:11:23.156885Z] Running run-tests step. 12:11:23 INFO - Running pre-action listener: _resource_record_pre_action 12:11:23 INFO - Running pre-action listener: _set_gcov_prefix 12:11:23 INFO - Running main action method: run_tests 12:11:23 WARNING - Try message not found. 12:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:11:23 INFO - Python 2.7.3 12:11:23 INFO - Return code: 0 12:11:23 INFO - grabbing minidump binary from tooltool 12:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:23 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 0x22a5090>, '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 0x22a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229ff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:11:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:11:23 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:11:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:11:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:11:23 INFO - Return code: 0 12:11:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:11:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:11:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:11:23 INFO - ENV: RUST_BACKTRACE is now full 12:11:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:11:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:11:23 INFO - Using env: {'DISPLAY': ':0', 12:11:23 INFO - 'HOME': '/home/cltbld', 12:11:23 INFO - 'LANG': 'en_US.UTF-8', 12:11:23 INFO - 'LANGUAGE': 'en_US:en', 12:11:23 INFO - 'LOGNAME': 'cltbld', 12:11:23 INFO - 'MAIL': '/var/mail/cltbld', 12:11:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:11:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:23 INFO - 'MOZ_NO_REMOTE': '1', 12:11:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:23 INFO - 'NO_EM_RESTART': '1', 12:11:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:23 INFO - 'PWD': '/builds/slave/test', 12:11:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:11:23 INFO - 'RUST_BACKTRACE': 'full', 12:11:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:11:23 INFO - 'SHELL': '/bin/bash', 12:11:23 INFO - 'SHLVL': '1', 12:11:23 INFO - 'TERM': 'linux', 12:11:23 INFO - 'TMOUT': '86400', 12:11:23 INFO - 'USER': 'cltbld', 12:11:23 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:23 INFO - '_': '/tools/buildbot/bin/python'} 12:11:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:11:23 INFO - mozversion application_buildid: 20170921174618 12:11:23 INFO - mozversion application_changeset: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 12:11:23 INFO - mozversion application_display_name: Nightly 12:11:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:11:23 INFO - mozversion application_name: Firefox 12:11:23 INFO - mozversion application_remotingname: firefox 12:11:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:11:23 INFO - mozversion application_vendor: Mozilla 12:11:23 INFO - mozversion application_version: 58.0a1 12:11:23 INFO - mozversion platform_buildid: 20170921174618 12:11:23 INFO - mozversion platform_changeset: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 12:11:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:11:23 INFO - mozversion platform_version: 58.0a1 12:11:23 INFO - using testdate: 1506021083 12:11:23 INFO - actual date: 1506021083 12:11:23 INFO - starting webserver on 'localhost:33127' 12:11:23 INFO - SUITE-START | Running 2 tests 12:11:23 INFO - TEST-START | dromaeo_css 12:11:23 INFO - Initialising browser for dromaeo_css test... 12:11:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33127/getInfo.html -profile /tmp/tmpLWKUXy/profile 12:11:23 INFO - TEST-INFO | started process 4646 (/builds/slave/test/build/application/firefox/firefox http://localhost:33127/getInfo.html) 12:11:28 INFO - TEST-INFO | 4646: exit 0 12:11:28 INFO - Browser initialized. 12:11:28 INFO - Running cycle 1/1 for dromaeo_css test... 12:11:28 INFO - Using env: {'DISPLAY': ':0', 12:11:28 INFO - 'HOME': '/home/cltbld', 12:11:28 INFO - 'JSGC_DISABLE_POISONING': '1', 12:11:28 INFO - 'LANG': 'en_US.UTF-8', 12:11:28 INFO - 'LANGUAGE': 'en_US:en', 12:11:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:11:28 INFO - 'LOGNAME': 'cltbld', 12:11:28 INFO - 'MAIL': '/var/mail/cltbld', 12:11:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:11:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:11:28 INFO - 'MOZ_CRASHREPORTER': '1', 12:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:11:28 INFO - 'MOZ_NO_REMOTE': '1', 12:11:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:28 INFO - 'NO_EM_RESTART': '1', 12:11:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:28 INFO - 'PWD': '/builds/slave/test', 12:11:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:11:28 INFO - 'RUST_BACKTRACE': 'full', 12:11:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:11:28 INFO - 'SHELL': '/bin/bash', 12:11:28 INFO - 'SHLVL': '1', 12:11:28 INFO - 'TERM': 'linux', 12:11:28 INFO - 'TMOUT': '86400', 12:11:28 INFO - 'USER': 'cltbld', 12:11:28 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:28 INFO - '_': '/tools/buildbot/bin/python'} 12:11:28 INFO - TEST-INFO | started process 4889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLWKUXy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:29 INFO - PID 4889 | 12:11:29 INFO - PID 4889 | (/builds/slave/test/build/application/firefox/firefox:4939): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:11:29 INFO - PID 4889 | 12:11:39 INFO - PID 4889 | RSS: Main: 147365888 12:11:39 INFO - PID 4889 | 12:14:36 INFO - PID 4889 | Cycle 1(1): loaded http://localhost:33127/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33127/tests/dromaeo/cssquery-ext.html) 12:14:38 INFO - PID 4889 | RSS: Main: 148262912 12:14:38 INFO - PID 4889 | 12:17:40 INFO - PID 4889 | Cycle 1(1): loaded http://localhost:33127/tests/dromaeo/cssquery-ext.html (next: http://localhost:33127/tests/dromaeo/cssquery-jquery.html) 12:17:40 INFO - PID 4889 | RSS: Main: 148000768 12:17:40 INFO - PID 4889 | 12:20:42 INFO - PID 4889 | Cycle 1(1): loaded http://localhost:33127/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33127/tests/dromaeo/cssquery-mootools.html) 12:20:42 INFO - PID 4889 | RSS: Main: 150122496 12:20:42 INFO - PID 4889 | 12:23:44 INFO - PID 4889 | Cycle 1(1): loaded http://localhost:33127/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33127/tests/dromaeo/cssquery-prototype.html) 12:23:45 INFO - PID 4889 | RSS: Main: 148787200 12:23:45 INFO - PID 4889 | 12:26:48 INFO - PID 4889 | Cycle 1(1): loaded http://localhost:33127/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33127/tests/dromaeo/cssquery-yui.html) 12:26:48 INFO - PID 4889 | RSS: Main: 150380544 12:26:48 INFO - PID 4889 | 12:29:50 INFO - PID 4889 | Cycle 1(1): loaded http://localhost:33127/tests/dromaeo/cssquery-yui.html (next: http://localhost:33127/tests/dromaeo/cssquery-dojo.html) 12:29:50 INFO - PID 4889 | RSS: Main: 152924160 12:29:50 INFO - PID 4889 | 12:29:50 INFO - PID 4889 | __start_tp_report 12:29:50 INFO - PID 4889 | _x_x_mozilla_page_load 12:29:50 INFO - PID 4889 | _x_x_mozilla_page_load_details 12:29:50 INFO - PID 4889 | |i|pagename|runs| 12:29:50 INFO - PID 4889 | |0;dojo.html;3756.267527832072;3795.2599243147583;3839.2321535692868;3848.80755962202;3850.5186851643543;6030.66831324277;6108.633481991073;6173.1357609934585;6204.317525072244;6230.906536401958;6164.137020817089;6179.7837075702355;6190.195274614294;6212.565120441571;6215.875682486348;5483.424240454755;5529.142982837647;5545.445455454453;5547.11246200608;5647.01177293974;5643.548516118701;5657.405972372903;5689.544836413098;5690.174924635925;5753.137029445575;5499.312585926759;5546.057170281062;5570.415106413829;5625.296837895263;5661.5187709044785;5344.679319240858;5487.368952570426;5511.283533140666;5549.778008879681;5604.411536788619;5489.149181876775;5529.142982837667;5551.250581171571;5596.804111856066;5603.551715862721;6018.6689732064715;6063.605865618721;6123.785667501616;6182.258129024546;6214.471769899519;6563.245226798884;6625.138731964814;6626.966865165691;6647.434968027724;6659.63372014539;6799.5920244784975;6804.897926531106;6823.044773731682;6831.28271531487;6836.965815170893;6128.142060111588;6181.876362472725;6198.442140207403;6312.274088479838;6318.43134117927;6898.2756810535175;6932.37608615217;7012.894806577905;7030.050943122716;7033.437325013987;6640.402363787282;6643.30245324234;6654.86690266192;6768.458523318122;6777.152855893013;6686.297938716462;6746.22418421878;6759.357861003196;6768.255491895888;6844.452443804484;6648.7340506379205;6661.034150048215;6667.399934005963;6715.865682686319;6778.661066946732;6672.899906501407;6695.631740254333;6725.6973436195485;6729.158855143107;6739.022841687928;4851.781669824866;4852.102361063144;4874.073925954058;4932.950670493249;4935.309056731989;4390.3414487827085;4450.844220452269;4463.977680111643;4486.170058539181;4498.820047198141;6265.530085243625;6291.874162516724;6293.622382657055;6296.275928268308;6297.086922396227;6615.636140012345;6688.732450702015;6773.796786096425;6792.45660347171;6796.626185559743;6099.20710307666;6176.413240742122;6206.131141640174;6276.843078923063;6296.874062518724;5392.137258038695;5462.699551524624;5466.835994920079;5467.316585426822;5469.644473109235;6998.6150761708595;7047.577145371191;7082.18554866197;7088.149422069385;7109.217986021534;6608.339166083353;6682.198136223781;6712.39588437043;6787.253402125663;6790.728370865308;6879.415249703631;6958.756443524452;6969.337912898267;7067.469939754436;7069.116360454943;6607.834804129935;6609.20689517261;6634.535582509178;6637.435817955527;6638.236602790547;6658.467322614082;6734.427573656294;6737.562058466184;6746.392824645805;6815.89776153368;6490.545661803629;6562.343765623399;6567.179102612174;6583.604983700993;6585.835354115994;6470.935290647033;6481.254655714464;6553.377429144223;6558.311377305505;6576.408123268929;6227.595206311576;6332.968335158294;6407.967960160169;6437.098806167047;6540.444062254572;6468.15913931186;6520.543561950618;6534.117894084237;6543.967280163759;6565.80302590923;6272.059191121369;6354.6822658867795;6355.428011478989;6356.3325850785;6385.584936979081 12:29:50 INFO - PID 4889 | |1;ext.html;960.8943016268214;963.2053555816444;986.442659897158;1010.9940609093952;1017.0236572890022;42098.579014209885;43188;43293.35059974099;43313.783431082804;43317.56682433175;43963.3405498917;44555.3316700249;44610.33084503735;44619.553804461946;44811.32783008258;8411.621477033548;8550;8551.615177317004;8557.700480483185;8557.743267701979;7898.091719452251;7904.09102953162;7904.249096335839;7948.324392426651;8014.358851291882;7864.5281283123195;7891.289783919446;7928.80177995549;7978.242067003625;8032.036155661329;7797.298243158115;7836;7862.410319226053;7863.3316168125775;7886.250806173462;7905.446618736691;7964.681412743541;7977.082635496934;7997.520148791091;8012.719554815553;39267.60732392697;39468.60531394678;39515.40726889099;39697.60302396997;39719.20561588781;3748.625137486257;3793.3551296279697;3795.3358162321483;3796.6393192646656;3798.0315019669806;32524.349513009845;32861.50707739386;32902.83548582242;32903.17742056468;32905.50641740376;5301.6818990860675;5397.973010134924;5474.589405794542;5487.807926722546;5580.190872323531;214381;215381;216996;217416.9129154344;218335;50117.24824127641;50763.746181268856;51803;52872.73563632157;53111.46888531143;55798.16302755462;55976.440235597125;56049.43950560524;56066.159007614915;56098.439015610136;16203.270852811651;16304.184790760652;16487.34050637961;16490.422835200712;16573.41993030238;13063.804042939364;13163.802542961863;13180.143290686075;13189.868101318863;13198.802017969738;15500.68998620044;15573.532794016197;15751.976121552063;15781;15831.525054248392;6224.128621992924;6343.492520598341;6361.2048493938255;6417.582857114273;6423.903641445382;1042.0829669890447;1074.156786922278;1084.3981590217504;1091.307020042263;1092.7158938675843;25557.48885022289;25620.487590248464;25659;25726;25946.22161335163;1177.8233265010317;1189.2448295332354;1191.904647628205;1202.2786328203183;1204.5482943896038;13852;13889.861101388857;13986.44054237819;14065.929670351583;14144.85855141456;279151;279584;281598;282088;283663;56250.15624765632;61636;62866.37133628697;62876.68561657254;63067;1467.222372142766;1514.2504460292225;1515.4165646226281;1519.3618680154582;1534.1255484374024;30758;31069.53395699021;31224.531632025082;31740.841295793372;32316.676833231366;41457.1708565833;41749.37375939303;41763;41765.494070355875;41831.7908410456;258418.70790645925;260408;260463;261862;262087;35939.28121437609;37185.81407093055;37265.441018384205;37425;37544.43683344807;46112.30831537463;46545.301820473396;46837;47175;47358.76320618375;34464.31071378508;34627.48058779171;34845.47731784076;34909.301813964084;35043.82478087593;34398.484022739176;34464.483032754026;34506.309873802886;34646.65353346434;35420.46869296911;39109.41335879907;40371.1925761489;40595.59404405918;40716.592834072464;40773;33185.66814331826;33486.832565837016;33494.83252583721;33718;34228.315433691685;24984.625230621918;25468.363290917874;26260.60609090827;26466.603000955387;26571.601425979014 12:29:50 INFO - PID 4889 | |2;jquery.html;597.9252593425822;603.1224568253192;603.897337452633;604.9485793707535;622.9937700622993;23234.302970910856;24245.63631545526;24389.87805060972;24402.389940251458;24448.266552003446;24525.63211551828;26020.739792602068;26222.868885655545;26224.213273601774;26227;6785.050092986986;6834.829129271783;6878.621675807829;6933.965330173367;6935.098437203182;6562.245341785708;6810.931890681104;6812.352826481501;6818.897716534256;6819.556728812636;6737.663116844163;6744.966275168617;6775.796726098225;6778.322167783232;6782.186137663487;6766.492513061491;6772.525923185379;6774.661266936658;6775.424088952445;6798.592084474898;6313.589616674947;6316.08416779569;6316.1788967434395;6336.398042185985;6338.651374174418;28975.710242897723;29784.70215297841;30159.246018849484;30318.242043948856;30382.54426183609;2140.4541841830387;2149.0973791007814;2151.128792838903;2162.1135334512933;2162.178372218547;20295.289664861808;20747.89626051875;20765.792342076536;20773;20784.1686332548;2458.864762438065;2509.3099397665605;2569.0237709670205;2579.587266037444;2584.508943300767;134848;140074;141017;144974.27512862367;150661.9999999989;15442.845571544365;15962.201889905458;15990.441529355247;16399.244219176544;16519.09144997013;25804.61293080605;25842.224733257655;25852.8707356462;25872.8706356467;25886.35284117912;10429.9478502607;10437.165026797838;10492.108170805413;10555.525001374957;10562.260641755003;9387.90612093884;9404.576794044284;9508.381955172892;9536.523173841419;9595.712128636153;9511.334206605472;9667.564959576695;9846.704598862045;9979.750506237257;10057.145142662956;2934.471795076907;2941.7205365490245;2946.57274695168;2960.2007457986224;2962.289050627877;1044.3785947361307;1074.2104553153426;1081.1837063017529;1086.8478413022183;1100.5487750022453;21111.683324749833;21155.653304008734;21387.25144619931;21398.679019814714;21938.232161874257;1111.6720567432626;1114.9554017839357;1122.119136477861;1132.2074547816562;1132.5979277356585;11426.714332141597;11732.589359372381;11761.823572646417;11766.823497647541;11826.586069487526;600428;604887;612227;620125;620304;128285;129387;133678;133896;134188;1105.8949399807007;1137.9089672826153;1159.6231224851788;1202.6692659518528;1215.392303848076;21644.861679815862;22377.888110559343;22386.992585333373;22414.887925560266;22472.887635561718;63156.36843631689;63355.36644633495;63463.36536634759;65270;65555.67222163858;74718.2528174733;74758.25241747513;75159.62420187864;75981.24018759742;78637;15483.84516154824;15592.220388980733;15605.765913511534;15780.526584202493;15805.13071781063;23923;24014.15950441726;24185.879070604533;24477.020919163035;24814.37964050913;19639.509012274808;19792.703109453083;19907.900460498186;20066;20093.39719808408;17394;17479.82520174833;17909.373171938918;17948.55128621795;18169.818301816813;20411.79588204099;20561.89719051455;20700.896495517427;20736.688949665466;20762.688559671315;15447.691046179238;15540.922295388451;15554.455594054154;15585.844141558438;15648.061116333056;19238.903805480884;19331.323403680803;19427.4171774847;19935.302264420676;19937.20251189994 12:29:50 INFO - PID 4889 | |3;mootools.html;1383.7301726163396;1462.9780553291696;1468.5594321703486;1484.3765618440254;1497.4833682379578;16782.66434671306;17419.73870391945;17459.388921387737;17464;17489.82510174898;16987.320507179727;17193.312267509285;17258.913705431485;17303;17334.306627734877;2874.0363745228733;2876.755475784561;2920.3283244853696;2920.4451154280723;2927.458420192271;2753.504369213541;2793.469240844233;2796.706345833689;2811.8453485058417;2816.1833068410137;2768.8477133757633;2795.9580606290924;2803.565447355653;2806.0880213930454;2813.254487560798;2683.2486903667004;2687.4625074984983;2703.7296270372904;2713.0639929224385;2716.0765339784566;2638;2639.3005853448853;2658.6410834537282;2663.3607934095676;2665.2404064841426;17035.914820425944;17340.826591734174;17486.737698934397;17579.384721534814;17605;8554.101819308946;8892.555372231363;8893.555322233862;8899.288056955493;8939.955300223457;5692.316921969349;5833.095870140135;5873.794417195378;5924.229850119457;5942.7920022799;2061.2785525066256;2090.1606586204043;2096.5387614724737;2100.978990210094;2122.6603743400983;30594.541081883788;30686.693133068606;30878.382432351227;30893.691063089533;30919.845400773076;7238.457115716343;7249.093863267091;7250.528715633467;7416.25837416254;7479.439042071757;19444;19525.80474195268;19694.113764880614;19748.40754777359;19756.802431975782;7929.207079292025;8083.676652933935;8165.673373065129;8169.9591502043295;8176.01887773471;7306.671199796022;7461.067366579177;7508.474406791473;7643.1974642662835;7788.337991270692;5525.751341189656;5639.802606908738;5827.3298570664065;5860.150278209721;5885.852853678607;1638.3937942961181;1673.6401673640225;1689.4340395967488;1705.1815128738276;1712.1011468978886;972.6984634763246;997.7306127345576;1004.8743907011624;1029.7425643589104;1029.984550231747;8607.827843443096;8641.351898607629;8828.117188281249;8860.8670869937;9014.278857691368;791.133708589094;801.1267718187204;814.2834305810967;820.0364571628313;829.6432534010434;5877.23595932526;5992.940070599326;6118.694065296806;6164.198654175018;6252.531060170505;22755;23201.88399057994;23666.881665591907;24022.87988560046;24043.879780600986;23211.88394058019;23747.287581372242;23862.7613723864;24005.879970600035;24006.15978440746;11688.824667629993;12234.265944043207;12504.81242781359;12561.748765024831;12563.811542826865;9519.714408567754;9522.571484283086;9565.521723913917;9586.712398628053;9640.662576809778;20884.582308354053;21134.15463381509;21322.680159797306;21822.890885545472;22370.552588947805;22112.557748845255;22123.889380552995;22184.889075554518;22202.44493887666;22207.555848883254;11890.108241882066;12071;12124.818127728267;12163.45264463119;12171.33057681836;14975.550733478012;15019.32413041394;15229.619259518158;15234.771478428054;15256.160911149993;12477.438515267017;12800.295983720986;12964.675883102998;12976.416061277076;13002.609921702364;11778.882211177779;11861.5848445304;12044.819327710267;12243.75512489763;12418.813717794059;10409.115225205942;10537.157027437634;10648.787024259627;10650.414227217578;10650.73373165677;8163.346932245525;8209.466384684976;8283.37874659391;8283.917160828556;8359.540225287668;8115.391345649218;8157.469764465291;8172.019357677117;8318.126596707258;8349.373796965374 12:29:50 INFO - PID 4889 | |4;prototype.html;546.814083211708;576.7289373994224;579.94200579942;582.5756026515172;593.545937357921;6302.716377762995;6305.9684701576425;6308.558400911938;6372.904406433907;6383.840403989902;6108.664023478706;6194.566380353377;6265.874682506358;6278.434940855322;6292.874142517158;5138.845834624948;5148.794048238066;5169.250458683488;5202.219667049935;5213.791448342062;5103.515166059237;5146.614003949719;5160.92258616121;5172.172452407615;5192.247124166994;5167.9224811627855;5173.663711858736;5193.039967218687;5196.92204616931;5240.633155679104;5139.588832893378;5163.225516172567;5233.947660523422;5234.659747116444;5240.895182096337;4728.219843725816;4866.391701037371;4869.902601947977;4880.951190488121;4881.167644032383;6086.052463211774;6106.358832322587;6157.230346206724;6178.814635560941;6182.938170618326;6134.141220229171;6139.907901381483;6146.508279337687;6173.536984726119;6194.690265486753;6690.765823196213;6751.527393082486;6756.952672335796;6759.695813688347;6765.966170169167;5438.7280635968045;5504.614676972614;5505.229267902497;5508.173773933902;5519.834404967857;6687.157957867936;6766.3233676632435;6865.862682746367;6916.23921368649;7020.66607344608;5645.181448689999;5653.491185793216;5670.177824215548;5673.609965558382;5685.914711279334;5632.493075623213;5731.168980497804;5770.971145144332;5774.769009239667;5786.710664466844;5985.700714964234;6123.142760013601;6144.539159563051;6159.4456498915315;6172.598781079216;6707.832304192434;6719.865602688016;6741.966290168518;6750;6768.221654509696;1956.7847536770944;1977.3771262052408;1979.6436641404398;1982.5725477656065;1990.5421752996601;1418.3971811979868;1472.1387988027125;1476.9187694676896;1497.2438918346384;1497.2663394936403;5940.792072277536;6028.306744724325;6085.300190478063;6102.298235702951;6136.631802091889;6487.156669632917;6520.804375868636;6575.145231120019;6621.50338724588;6652.800415987528;6031.8793624127275;6054.033523573417;6093.908591371133;6100.938990610037;6123.2652081750475;3490.8305717584894;3493.3013397320638;3496.3356962177104;3497.720182385454;3530.841112149908;6890.483213758988;6905.896411553831;6934.791956241321;6985.266547012592;7007.8598428031155;6836.282190370039;6864.5881247125335;6906.205786334636;6983.720651173896;6984.546004509691;6867.862642747217;6971.372576468142;7021.543599666005;7038.366547010896;7046.1192350956135;6663.46692264608;6706.826305396438;6736.259011508632;6743.9325606743305;6754.696038678173;6604.339566043357;6615.834604134935;6653.434458071119;6701.497387695845;6710.261871194067;6931.7227310907;6989.7553585624255;6991.475639326969;7002.7899163025195;7007.7196912122945;5628.718564071862;5631.63394379364;5653.123765816305;5668.752874367632;5674.631148975303;6479.384458476437;6649.60102393858;6736.248575370341;6746.628935408599;6761.532040804972;6248.219257511588;6480.870382592416;6488.188976377953;6538.705758240986;6573.375529324707;6372.458341041063;6480.055983204926;6509.511786615928;6559.836004099936;6585.539012269095;6207.6585787782105;6378.756142552158;6412.006139048455;6452.9677351612945;6458.160439142881;6539.476841852736;6551.868962620816;6557.606543607399;6574.276829548841;6583.847826630416;6355.7457701693265;6412.647304398303;6416.615003099829;6430.64631445275;6438.710258038306 12:29:50 INFO - PID 4889 | |5;yui.html;940.9623615055402;972.8637990681306;977.1401166973064;980.5636491761164;992.4739887859437;20724.274650387222;20988;21003.894980525078;21011.894940525293;21023.05396257168;19512.90243548784;20135;20139.395818125442;20140.19439222433;20178.19287228509;1031.1183937733242;1069.6951368859877;1071.3625392891256;1072.0994364733622;1074.5433190893878;1087.2443651662097;1087.4617064553058;1089.1341383600018;1089.9455027248646;1096.9561217551288;1072.6460268111505;1130.129800053958;1137.5222406589212;1138.0554140063769;1138.226006315705;1131.4569006876704;1132.2131118872387;1132.4960392625285;1134.2854001978742;1136.8408422820812;801.2548330053048;819.3895547816855;821.1131977464324;821.3470291118575;825.6325934958886;21431.464213394633;21761.891190544105;21773.45566360838;21810.236641717463;21886.343409697736;23059;23117.88441057784;23606.763932360627;23757.881210593838;24094.51810963771;18020.819791802045;18078.819211807975;18495.2601895924;18565.16456759449;18752.906235468872;4010.5187377514594;4046;4047.4738284023183;4065.0447144920918;4066.6340029397497;129428;157489;158050;158518.20740896222;161969.99999999884;18736.718949215774;19052;19135;19187.424377268704;19309;33100.1724956878;33117.66882331194;33139.66860331366;33378.8331058348;33925.32149356999;9506.191973682315;9553.18797902186;9615.663451779154;9616.567254473566;9617.374870633386;4450.799714012877;4464.419625448736;4501.234790085693;4502.414686090853;4543.54564543543;6228.781992630236;6287.685615719196;6320.367963203735;6337.366263373718;6380.904286435707;1244.7012716947868;1282.410091357967;1282.9807552886714;1297.9675508112373;1298.4741179822188;722.1478655186825;723.7177500774702;724.6087112959049;729.1323325242944;746.932776050158;13497.595072147851;13627.795583066261;14091.78862317066;14812.851871481364;14880.255987200597;683.1835956032593;689.0904006711094;696.8327601375735;703.3634560722554;707.593133947982;6519.022146677941;6544.411003009752;6561.573497722633;6562.573432726857;6566.409023187841;153139;156728.1848237486;156957;161013.1949340246;161032;120757.39621302013;121559;124629;127443.3627831855;131635;106435;106479;108863.45568272108;110921.99999999838;114130;14264.714705706036;14413;14461.49384771528;14514.129152250898;14669.559913202622;49885;50729.49270507248;50790.74604626953;51893.962120756616;52005.7399712999;68834;69833.65083174552;70106;70116.6494167526;71826.64086679534;20472;20610.89694551518;20746.48133796667;20889.477763056046;21015.159393624694;22622.660660089783;22858.199963001214;23001.769982300633;23078.076876924733;23337.649935251324;21308.254211102536;21480.24819131323;21695.674564881854;21913.452163695398;21919.561608767417;20821.167153314305;20882.58234835325;21185.682214767097;21205.15179392807;21236;19896.70154947706;20115.497112572302;20144.194232230548;20242.08910599056;20351.185952562322;15427.228638568251;15495.380184792482;15620.453284135001;15752.448664297153;15862.206889655241;14408.13551186932;14436.566902992927;14471.224545749194;14518.709625807214;14560.49038283655 12:29:50 INFO - PID 4889 | __end_tp_report 12:29:50 INFO - PID 4889 | __start_cc_report 12:29:50 INFO - PID 4889 | _x_x_mozilla_cycle_collect,255 12:29:50 INFO - PID 4889 | __end_cc_report 12:29:50 INFO - PID 4889 | __startTimestamp1506022190508__endTimestamp 12:29:50 INFO - PID 4889 | 12:29:50 INFO - PID 4889 | ------- Summary: start ------- 12:29:50 INFO - PID 4889 | Number of tests: 6 12:29:50 INFO - PID 4889 | 12:29:50 INFO - PID 4889 | [#0] dojo.html Cycles:165 Average:6187.34 Median:6407.97 stddev:742.92 (11.6%) stddev-sans-first:720.31 12:29:50 INFO - PID 4889 | Values: 3756.3 3795.3 3839.2 3848.8 3850.5 6030.7 6108.6 6173.1 6204.3 6230.9 6164.1 6179.8 6190.2 6212.6 6215.9 5483.4 5529.1 5545.4 5547.1 5647.0 5643.5 5657.4 5689.5 5690.2 5753.1 5499.3 5546.1 5570.4 5625.3 5661.5 5344.7 5487.4 5511.3 5549.8 5604.4 5489.1 5529.1 5551.3 5596.8 5603.6 6018.7 6063.6 6123.8 6182.3 6214.5 6563.2 6625.1 6627.0 6647.4 6659.6 6799.6 6804.9 6823.0 6831.3 6837.0 6128.1 6181.9 6198.4 6312.3 6318.4 6898.3 6932.4 7012.9 7030.1 7033.4 6640.4 6643.3 6654.9 6768.5 6777.2 6686.3 6746.2 6759.4 6768.3 6844.5 6648.7 6661.0 6667.4 6715.9 6778.7 6672.9 6695.6 6725.7 6729.2 6739.0 4851.8 4852.1 4874.1 4933.0 4935.3 4390.3 4450.8 4464.0 4486.2 4498.8 6265.5 6291.9 6293.6 6296.3 6297.1 6615.6 6688.7 6773.8 6792.5 6796.6 6099.2 6176.4 6206.1 6276.8 6296.9 5392.1 5462.7 5466.8 5467.3 5469.6 6998.6 7047.6 7082.2 7088.1 7109.2 6608.3 6682.2 6712.4 6787.3 6790.7 6879.4 6958.8 6969.3 7067.5 7069.1 6607.8 6609.2 6634.5 6637.4 6638.2 6658.5 6734.4 6737.6 6746.4 6815.9 6490.5 6562.3 6567.2 6583.6 6585.8 6470.9 6481.3 6553.4 6558.3 6576.4 6227.6 6333.0 6408.0 6437.1 6540.4 6468.2 6520.5 6534.1 6544.0 6565.8 6272.1 6354.7 6355.4 6356.3 6385.6 12:29:50 INFO - PID 4889 | 12:29:50 INFO - PID 4889 | [#1] ext.html Cycles:180 Average:43312.04 Median:20586.81 stddev:66121.27 (321.2%) stddev-sans-first:66230.29 12:29:50 INFO - PID 4889 | Values: 960.9 963.2 986.4 1011.0 1017.0 42098.6 43188.0 43293.4 43313.8 43317.6 43963.3 44555.3 44610.3 44619.6 44811.3 8411.6 8550.0 8551.6 8557.7 8557.7 7898.1 7904.1 7904.2 7948.3 8014.4 7864.5 7891.3 7928.8 7978.2 8032.0 7797.3 7836.0 7862.4 7863.3 7886.3 7905.4 7964.7 7977.1 7997.5 8012.7 39267.6 39468.6 39515.4 39697.6 39719.2 3748.6 3793.4 3795.3 3796.6 3798.0 32524.3 32861.5 32902.8 32903.2 32905.5 5301.7 5398.0 5474.6 5487.8 5580.2 214381.0 215381.0 216996.0 217416.9 218335.0 50117.2 50763.7 51803.0 52872.7 53111.5 55798.2 55976.4 56049.4 56066.2 56098.4 16203.3 16304.2 16487.3 16490.4 16573.4 13063.8 13163.8 13180.1 13189.9 13198.8 15500.7 15573.5 15752.0 15781.0 15831.5 6224.1 6343.5 6361.2 6417.6 6423.9 1042.1 1074.2 1084.4 1091.3 1092.7 25557.5 25620.5 25659.0 25726.0 25946.2 1177.8 1189.2 1191.9 1202.3 1204.5 13852.0 13889.9 13986.4 14065.9 14144.9 279151.0 279584.0 281598.0 282088.0 283663.0 56250.2 61636.0 62866.4 62876.7 63067.0 1467.2 1514.3 1515.4 1519.4 1534.1 30758.0 31069.5 31224.5 31740.8 32316.7 41457.2 41749.4 41763.0 41765.5 41831.8 258418.7 260408.0 260463.0 261862.0 262087.0 35939.3 37185.8 37265.4 37425.0 37544.4 46112.3 46545.3 46837.0 47175.0 47358.8 34464.3 34627.5 34845.5 34909.3 35043.8 34398.5 34464.5 34506.3 34646.7 35420.5 39109.4 40371.2 40595.6 40716.6 40773.0 33185.7 33486.8 33494.8 33718.0 34228.3 24984.6 25468.4 26260.6 26466.6 26571.6 12:29:50 INFO - PID 4889 | 12:29:50 INFO - PID 4889 | [#2] jquery.html Cycles:180 Average:39776.68 Median:22853.60 stddev:101997.16 (446.3%) stddev-sans-first:102240.87 12:29:50 INFO - PID 4889 | Values: 597.9 603.1 603.9 604.9 623.0 23234.3 24245.6 24389.9 24402.4 24448.3 24525.6 26020.7 26222.9 26224.2 26227.0 6785.1 6834.8 6878.6 6934.0 6935.1 6562.2 6810.9 6812.4 6818.9 6819.6 6737.7 6745.0 6775.8 6778.3 6782.2 6766.5 6772.5 6774.7 6775.4 6798.6 6313.6 6316.1 6316.2 6336.4 6338.7 28975.7 29784.7 30159.2 30318.2 30382.5 2140.5 2149.1 2151.1 2162.1 2162.2 20295.3 20747.9 20765.8 20773.0 20784.2 2458.9 2509.3 2569.0 2579.6 2584.5 134848.0 140074.0 141017.0 144974.3 150662.0 15442.8 15962.2 15990.4 16399.2 16519.1 25804.6 25842.2 25852.9 25872.9 25886.4 10429.9 10437.2 10492.1 10555.5 10562.3 9387.9 9404.6 9508.4 9536.5 9595.7 9511.3 9667.6 9846.7 9979.8 10057.1 2934.5 2941.7 2946.6 2960.2 2962.3 1044.4 1074.2 1081.2 1086.8 1100.5 21111.7 21155.7 21387.3 21398.7 21938.2 1111.7 1115.0 1122.1 1132.2 1132.6 11426.7 11732.6 11761.8 11766.8 11826.6 600428.0 604887.0 612227.0 620125.0 620304.0 128285.0 129387.0 133678.0 133896.0 134188.0 1105.9 1137.9 1159.6 1202.7 1215.4 21644.9 22377.9 22387.0 22414.9 22472.9 63156.4 63355.4 63463.4 65270.0 65555.7 74718.3 74758.3 75159.6 75981.2 78637.0 15483.8 15592.2 15605.8 15780.5 15805.1 23923.0 24014.2 24185.9 24477.0 24814.4 19639.5 19792.7 19907.9 20066.0 20093.4 17394.0 17479.8 17909.4 17948.6 18169.8 20411.8 20561.9 20700.9 20736.7 20762.7 15447.7 15540.9 15554.5 15585.8 15648.1 19238.9 19331.3 19427.4 19935.3 19937.2 12:29:50 INFO - PID 4889 | 12:29:50 INFO - PID 4889 | [#3] mootools.html Cycles:180 Average:10378.39 Median:2661.00 stddev:7644.05 (287.3%) stddev-sans-first:7635.62 12:29:50 INFO - PID 4889 | Values: 1383.7 1463.0 1468.6 1484.4 1497.5 16782.7 17419.7 17459.4 17464.0 17489.8 16987.3 17193.3 17258.9 17303.0 17334.3 2874.0 2876.8 2920.3 2920.4 2927.5 2753.5 2793.5 2796.7 2811.8 2816.2 2768.8 2796.0 2803.6 2806.1 2813.3 2683.2 2687.5 2703.7 2713.1 2716.1 2638.0 2639.3 2658.6 2663.4 2665.2 17035.9 17340.8 17486.7 17579.4 17605.0 8554.1 8892.6 8893.6 8899.3 8940.0 5692.3 5833.1 5873.8 5924.2 5942.8 2061.3 2090.2 2096.5 2101.0 2122.7 30594.5 30686.7 30878.4 30893.7 30919.8 7238.5 7249.1 7250.5 7416.3 7479.4 19444.0 19525.8 19694.1 19748.4 19756.8 7929.2 8083.7 8165.7 8170.0 8176.0 7306.7 7461.1 7508.5 7643.2 7788.3 5525.8 5639.8 5827.3 5860.2 5885.9 1638.4 1673.6 1689.4 1705.2 1712.1 972.7 997.7 1004.9 1029.7 1030.0 8607.8 8641.4 8828.1 8860.9 9014.3 791.1 801.1 814.3 820.0 829.6 5877.2 5992.9 6118.7 6164.2 6252.5 22755.0 23201.9 23666.9 24022.9 24043.9 23211.9 23747.3 23862.8 24005.9 24006.2 11688.8 12234.3 12504.8 12561.7 12563.8 9519.7 9522.6 9565.5 9586.7 9640.7 20884.6 21134.2 21322.7 21822.9 22370.6 22112.6 22123.9 22184.9 22202.4 22207.6 11890.1 12071.0 12124.8 12163.5 12171.3 14975.6 15019.3 15229.6 15234.8 15256.2 12477.4 12800.3 12964.7 12976.4 13002.6 11778.9 11861.6 12044.8 12243.8 12418.8 10409.1 10537.2 10648.8 10650.4 10650.7 8163.3 8209.5 8283.4 8283.9 8359.5 8115.4 8157.5 8172.0 8318.1 8349.4 12:29:50 INFO - PID 4889 | 12:29:50 INFO - PID 4889 | [#4] prototype.html Cycles:180 Average:5718.99 Median:6194.63 stddev:1520.24 (24.5%) stddev-sans-first:1474.11 12:29:50 INFO - PID 4889 | Values: 546.8 576.7 579.9 582.6 593.5 6302.7 6306.0 6308.6 6372.9 6383.8 6108.7 6194.6 6265.9 6278.4 6292.9 5138.8 5148.8 5169.3 5202.2 5213.8 5103.5 5146.6 5160.9 5172.2 5192.2 5167.9 5173.7 5193.0 5196.9 5240.6 5139.6 5163.2 5233.9 5234.7 5240.9 4728.2 4866.4 4869.9 4881.0 4881.2 6086.1 6106.4 6157.2 6178.8 6182.9 6134.1 6139.9 6146.5 6173.5 6194.7 6690.8 6751.5 6757.0 6759.7 6766.0 5438.7 5504.6 5505.2 5508.2 5519.8 6687.2 6766.3 6865.9 6916.2 7020.7 5645.2 5653.5 5670.2 5673.6 5685.9 5632.5 5731.2 5771.0 5774.8 5786.7 5985.7 6123.1 6144.5 6159.4 6172.6 6707.8 6719.9 6742.0 6750.0 6768.2 1956.8 1977.4 1979.6 1982.6 1990.5 1418.4 1472.1 1476.9 1497.2 1497.3 5940.8 6028.3 6085.3 6102.3 6136.6 6487.2 6520.8 6575.1 6621.5 6652.8 6031.9 6054.0 6093.9 6100.9 6123.3 3490.8 3493.3 3496.3 3497.7 3530.8 6890.5 6905.9 6934.8 6985.3 7007.9 6836.3 6864.6 6906.2 6983.7 6984.5 6867.9 6971.4 7021.5 7038.4 7046.1 6663.5 6706.8 6736.3 6743.9 6754.7 6604.3 6615.8 6653.4 6701.5 6710.3 6931.7 6989.8 6991.5 7002.8 7007.7 5628.7 5631.6 5653.1 5668.8 5674.6 6479.4 6649.6 6736.2 6746.6 6761.5 6248.2 6480.9 6488.2 6538.7 6573.4 6372.5 6480.1 6509.5 6559.8 6585.5 6207.7 6378.8 6412.0 6453.0 6458.2 6539.5 6551.9 6557.6 6574.3 6583.8 6355.7 6412.6 6416.6 6430.6 6438.7 12:29:50 INFO - PID 4889 | 12:29:50 INFO - PID 4889 | [#5] yui.html Cycles:180 Average:28581.23 Median:20886.03 stddev:41381.61 (198.1%) stddev-sans-first:41445.66 12:29:50 INFO - PID 4889 | Values: 941.0 972.9 977.1 980.6 992.5 20724.3 20988.0 21003.9 21011.9 21023.1 19512.9 20135.0 20139.4 20140.2 20178.2 1031.1 1069.7 1071.4 1072.1 1074.5 1087.2 1087.5 1089.1 1089.9 1097.0 1072.6 1130.1 1137.5 1138.1 1138.2 1131.5 1132.2 1132.5 1134.3 1136.8 801.3 819.4 821.1 821.3 825.6 21431.5 21761.9 21773.5 21810.2 21886.3 23059.0 23117.9 23606.8 23757.9 24094.5 18020.8 18078.8 18495.3 18565.2 18752.9 4010.5 4046.0 4047.5 4065.0 4066.6 129428.0 157489.0 158050.0 158518.2 161970.0 18736.7 19052.0 19135.0 19187.4 19309.0 33100.2 33117.7 33139.7 33378.8 33925.3 9506.2 9553.2 9615.7 9616.6 9617.4 4450.8 4464.4 4501.2 4502.4 4543.5 6228.8 6287.7 6320.4 6337.4 6380.9 1244.7 1282.4 1283.0 1298.0 1298.5 722.1 723.7 724.6 729.1 746.9 13497.6 13627.8 14091.8 14812.9 14880.3 683.2 689.1 696.8 703.4 707.6 6519.0 6544.4 6561.6 6562.6 6566.4 153139.0 156728.2 156957.0 161013.2 161032.0 120757.4 121559.0 124629.0 127443.4 131635.0 106435.0 106479.0 108863.5 110922.0 114130.0 14264.7 14413.0 14461.5 14514.1 14669.6 49885.0 50729.5 50790.7 51894.0 52005.7 68834.0 69833.7 70106.0 70116.6 71826.6 20472.0 20610.9 20746.5 20889.5 21015.2 22622.7 22858.2 23001.8 23078.1 23337.6 21308.3 21480.2 21695.7 21913.5 21919.6 20821.2 20882.6 21185.7 21205.2 21236.0 19896.7 20115.5 20144.2 20242.1 20351.2 15427.2 15495.4 15620.5 15752.4 15862.2 14408.1 14436.6 14471.2 14518.7 14560.5 12:29:50 INFO - PID 4889 | -------- Summary: end -------- 12:29:50 INFO - PID 4889 | 12:29:51 INFO - TEST-INFO | 4889: exit 0 12:29:51 INFO - TEST-OK | dromaeo_css | took 1107697ms 12:29:51 INFO - TEST-START | kraken 12:29:51 INFO - Initialising browser for kraken test... 12:29:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33127/getInfo.html -profile /tmp/tmpPxVAaY/profile 12:29:51 INFO - TEST-INFO | started process 5267 (/builds/slave/test/build/application/firefox/firefox http://localhost:33127/getInfo.html) 12:29:56 INFO - TEST-INFO | 5267: exit 0 12:29:56 INFO - Browser initialized. 12:29:56 INFO - Running cycle 1/1 for kraken test... 12:29:56 INFO - Using env: {'DISPLAY': ':0', 12:29:56 INFO - 'HOME': '/home/cltbld', 12:29:56 INFO - 'JSGC_DISABLE_POISONING': '1', 12:29:56 INFO - 'LANG': 'en_US.UTF-8', 12:29:56 INFO - 'LANGUAGE': 'en_US:en', 12:29:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:29:56 INFO - 'LOGNAME': 'cltbld', 12:29:56 INFO - 'MAIL': '/var/mail/cltbld', 12:29:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:29:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:29:56 INFO - 'MOZ_CRASHREPORTER': '1', 12:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:29:56 INFO - 'MOZ_NO_REMOTE': '1', 12:29:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:29:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:56 INFO - 'NO_EM_RESTART': '1', 12:29:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:56 INFO - 'PWD': '/builds/slave/test', 12:29:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:29:56 INFO - 'RUST_BACKTRACE': 'full', 12:29:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:29:56 INFO - 'SHELL': '/bin/bash', 12:29:56 INFO - 'SHLVL': '1', 12:29:56 INFO - 'TERM': 'linux', 12:29:56 INFO - 'TMOUT': '86400', 12:29:56 INFO - 'USER': 'cltbld', 12:29:56 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855', 12:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:56 INFO - '_': '/tools/buildbot/bin/python'} 12:29:56 INFO - TEST-INFO | started process 5508 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPxVAaY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:29:57 INFO - PID 5508 | 12:29:57 INFO - PID 5508 | (/builds/slave/test/build/application/firefox/firefox:5557): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:29:57 INFO - PID 5508 | 12:30:07 INFO - PID 5508 | RSS: Main: 148070400 12:30:07 INFO - PID 5508 | 12:30:09 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33127/tests/kraken/driver.html?testName=audio-beat-detection) 12:30:10 INFO - PID 5508 | RSS: Main: 152842240 12:30:10 INFO - PID 5508 | 12:30:13 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33127/tests/kraken/driver.html?testName=audio-dft) 12:30:14 INFO - PID 5508 | RSS: Main: 153739264 12:30:14 INFO - PID 5508 | 12:30:16 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33127/tests/kraken/driver.html?testName=audio-fft) 12:30:17 INFO - PID 5508 | RSS: Main: 150106112 12:30:17 INFO - PID 5508 | 12:30:19 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33127/tests/kraken/driver.html?testName=audio-oscillator) 12:30:20 INFO - PID 5508 | RSS: Main: 151318528 12:30:20 INFO - PID 5508 | 12:30:22 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33127/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:30:22 INFO - PID 5508 | RSS: Main: 151052288 12:30:22 INFO - PID 5508 | 12:30:25 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33127/tests/kraken/driver.html?testName=imaging-darkroom) 12:30:26 INFO - PID 5508 | RSS: Main: 151396352 12:30:26 INFO - PID 5508 | 12:30:30 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33127/tests/kraken/driver.html?testName=imaging-desaturate) 12:30:30 INFO - PID 5508 | RSS: Main: 152272896 12:30:30 INFO - PID 5508 | 12:30:33 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33127/tests/kraken/driver.html?testName=json-parse-financial) 12:30:34 INFO - PID 5508 | RSS: Main: 153772032 12:30:34 INFO - PID 5508 | 12:30:35 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33127/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:30:36 INFO - PID 5508 | RSS: Main: 154583040 12:30:36 INFO - PID 5508 | 12:30:37 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33127/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:30:38 INFO - PID 5508 | RSS: Main: 154644480 12:30:38 INFO - PID 5508 | 12:30:40 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33127/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:30:41 INFO - PID 5508 | RSS: Main: 151711744 12:30:41 INFO - PID 5508 | 12:30:43 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33127/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:30:44 INFO - PID 5508 | RSS: Main: 151564288 12:30:44 INFO - PID 5508 | 12:30:46 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33127/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:30:47 INFO - PID 5508 | RSS: Main: 151629824 12:30:47 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | Cycle 1(1): loaded http://localhost:33127/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33127/tests/kraken/driver.html?testName=ai-astar) 12:30:49 INFO - PID 5508 | RSS: Main: 153288704 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | __start_tp_report 12:30:49 INFO - PID 5508 | _x_x_mozilla_page_load 12:30:49 INFO - PID 5508 | _x_x_mozilla_page_load_details 12:30:49 INFO - PID 5508 | |i|pagename|runs| 12:30:49 INFO - PID 5508 | |0;ai-astar;107;106;105;104;105;109;120;109;106;104 12:30:49 INFO - PID 5508 | |1;audio-beat-detection;113;112;113;111;114;113;139;114;116;113 12:30:49 INFO - PID 5508 | |2;audio-dft;162;163;162;161;162;162;163;162;169;165 12:30:49 INFO - PID 5508 | |3;audio-fft;74;73;74;73;74;73;74;74;73;73 12:30:49 INFO - PID 5508 | |4;audio-oscillator;80;78;77;78;78;78;82;87;79;78 12:30:49 INFO - PID 5508 | |5;imaging-gaussian-blur;114;114;114;114;113;113;113;113;113;114 12:30:49 INFO - PID 5508 | |6;imaging-darkroom;149;152;151;150;149;151;151;150;151;150 12:30:49 INFO - PID 5508 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;94;99 12:30:49 INFO - PID 5508 | |8;json-parse-financial;44;43;46;43;45;45;44;43;43;43 12:30:49 INFO - PID 5508 | |9;json-stringify-tinderbox;37;37;37;37;37;37;37;37;37;37 12:30:49 INFO - PID 5508 | |10;stanford-crypto-aes;73;73;72;71;76;75;75;70;76;70 12:30:49 INFO - PID 5508 | |11;stanford-crypto-ccm;107;102;102;109;111;105;105;104;109;104 12:30:49 INFO - PID 5508 | |12;stanford-crypto-pbkdf2;149;151;150;155;150;152;146;150;152;156 12:30:49 INFO - PID 5508 | |13;stanford-crypto-sha256-iterative;59;60;59;59;62;59;59;59;57;59 12:30:49 INFO - PID 5508 | __end_tp_report 12:30:49 INFO - PID 5508 | __start_cc_report 12:30:49 INFO - PID 5508 | _x_x_mozilla_cycle_collect,683 12:30:49 INFO - PID 5508 | __end_cc_report 12:30:49 INFO - PID 5508 | __startTimestamp1506022249408__endTimestamp 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | ------- Summary: start ------- 12:30:49 INFO - PID 5508 | Number of tests: 14 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#0] ai-astar Cycles:10 Average:107.50 Median:106.50 stddev:4.74 (4.5%) stddev-sans-first:5.03 12:30:49 INFO - PID 5508 | Values: 107.0 106.0 105.0 104.0 105.0 109.0 120.0 109.0 106.0 104.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#1] audio-beat-detection Cycles:10 Average:115.80 Median:113.50 stddev:8.26 (7.3%) stddev-sans-first:8.70 12:30:49 INFO - PID 5508 | Values: 113.0 112.0 113.0 111.0 114.0 113.0 139.0 114.0 116.0 113.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#2] audio-dft Cycles:10 Average:163.10 Median:162.50 stddev:2.33 (1.4%) stddev-sans-first:2.44 12:30:49 INFO - PID 5508 | Values: 162.0 163.0 162.0 161.0 162.0 162.0 163.0 162.0 169.0 165.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#3] audio-fft Cycles:10 Average:73.50 Median:74.00 stddev:0.53 (0.7%) stddev-sans-first:0.53 12:30:49 INFO - PID 5508 | Values: 74.0 73.0 74.0 73.0 74.0 73.0 74.0 74.0 73.0 73.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#4] audio-oscillator Cycles:10 Average:79.50 Median:78.50 stddev:2.99 (3.8%) stddev-sans-first:3.17 12:30:49 INFO - PID 5508 | Values: 80.0 78.0 77.0 78.0 78.0 78.0 82.0 87.0 79.0 78.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 12:30:49 INFO - PID 5508 | Values: 114.0 114.0 114.0 114.0 113.0 113.0 113.0 113.0 113.0 114.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:151.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 12:30:49 INFO - PID 5508 | Values: 149.0 152.0 151.0 150.0 149.0 151.0 151.0 150.0 151.0 150.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:1.58 (1.7%) stddev-sans-first:1.67 12:30:49 INFO - PID 5508 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 99.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#8] json-parse-financial Cycles:10 Average:43.90 Median:44.00 stddev:1.10 (2.5%) stddev-sans-first:1.17 12:30:49 INFO - PID 5508 | Values: 44.0 43.0 46.0 43.0 45.0 45.0 44.0 43.0 43.0 43.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 12:30:49 INFO - PID 5508 | Values: 37.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:74.00 stddev:2.33 (3.1%) stddev-sans-first:2.47 12:30:49 INFO - PID 5508 | Values: 73.0 73.0 72.0 71.0 76.0 75.0 75.0 70.0 76.0 70.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#11] stanford-crypto-ccm Cycles:10 Average:105.80 Median:106.00 stddev:3.08 (2.9%) stddev-sans-first:3.24 12:30:49 INFO - PID 5508 | Values: 107.0 102.0 102.0 109.0 111.0 105.0 105.0 104.0 109.0 104.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.10 Median:151.50 stddev:2.88 (1.9%) stddev-sans-first:2.96 12:30:49 INFO - PID 5508 | Values: 149.0 151.0 150.0 155.0 150.0 152.0 146.0 150.0 152.0 156.0 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.20 Median:59.00 stddev:1.23 (2.1%) stddev-sans-first:1.30 12:30:49 INFO - PID 5508 | Values: 59.0 60.0 59.0 59.0 62.0 59.0 59.0 59.0 57.0 59.0 12:30:49 INFO - PID 5508 | -------- Summary: end -------- 12:30:49 INFO - PID 5508 | 12:30:49 INFO - PID 5508 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:30:49 INFO - TEST-INFO | 5508: exit 0 12:30:50 INFO - TEST-OK | kraken | took 58817ms 12:30:50 INFO - SUITE-END | took 1166s 12:30:50 INFO - Completed test suite (00:19:26) 12:30:50 INFO - javascript benchmark 12:30:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9149.815845426847, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3756.267527832072, 3795.2599243147583, 3839.2321535692868, 3848.80755962202, 3850.5186851643543, 6030.66831324277, 6108.633481991073, 6173.1357609934585, 6204.317525072244, 6230.906536401958, 6164.137020817089, 6179.7837075702355, 6190.195274614294, 6212.565120441571, 6215.875682486348, 5483.424240454755, 5529.142982837647, 5545.445455454453, 5547.11246200608, 5647.01177293974, 5643.548516118701, 5657.405972372903, 5689.544836413098, 5690.174924635925, 5753.137029445575, 5499.312585926759, 5546.057170281062, 5570.415106413829, 5625.296837895263, 5661.5187709044785, 5344.679319240858, 5487.368952570426, 5511.283533140666, 5549.778008879681, 5604.411536788619, 5489.149181876775, 5529.142982837667, 5551.250581171571, 5596.804111856066, 5603.551715862721, 6018.6689732064715, 6063.605865618721, 6123.785667501616, 6182.258129024546, 6214.471769899519, 6563.245226798884, 6625.138731964814, 6626.966865165691, 6647.434968027724, 6659.63372014539, 6799.5920244784975, 6804.897926531106, 6823.044773731682, 6831.28271531487, 6836.965815170893, 6128.142060111588, 6181.876362472725, 6198.442140207403, 6312.274088479838, 6318.43134117927, 6898.2756810535175, 6932.37608615217, 7012.894806577905, 7030.050943122716, 7033.437325013987, 6640.402363787282, 6643.30245324234, 6654.86690266192, 6768.458523318122, 6777.152855893013, 6686.297938716462, 6746.22418421878, 6759.357861003196, 6768.255491895888, 6844.452443804484, 6648.7340506379205, 6661.034150048215, 6667.399934005963, 6715.865682686319, 6778.661066946732, 6672.899906501407, 6695.631740254333, 6725.6973436195485, 6729.158855143107, 6739.022841687928, 4851.781669824866, 4852.102361063144, 4874.073925954058, 4932.950670493249, 4935.309056731989, 4390.3414487827085, 4450.844220452269, 4463.977680111643, 4486.170058539181, 4498.820047198141, 6265.530085243625, 6291.874162516724, 6293.622382657055, 6296.275928268308, 6297.086922396227, 6615.636140012345, 6688.732450702015, 6773.796786096425, 6792.45660347171, 6796.626185559743, 6099.20710307666, 6176.413240742122, 6206.131141640174, 6276.843078923063, 6296.874062518724, 5392.137258038695, 5462.699551524624, 5466.835994920079, 5467.316585426822, 5469.644473109235, 6998.6150761708595, 7047.577145371191, 7082.18554866197, 7088.149422069385, 7109.217986021534, 6608.339166083353, 6682.198136223781, 6712.39588437043, 6787.253402125663, 6790.728370865308, 6879.415249703631, 6958.756443524452, 6969.337912898267, 7067.469939754436, 7069.116360454943, 6607.834804129935, 6609.20689517261, 6634.535582509178, 6637.435817955527, 6638.236602790547, 6658.467322614082, 6734.427573656294, 6737.562058466184, 6746.392824645805, 6815.89776153368, 6490.545661803629, 6562.343765623399, 6567.179102612174, 6583.604983700993, 6585.835354115994, 6470.935290647033, 6481.254655714464, 6553.377429144223, 6558.311377305505, 6576.408123268929, 6227.595206311576, 6332.968335158294, 6407.967960160169, 6437.098806167047, 6540.444062254572, 6468.15913931186, 6520.543561950618, 6534.117894084237, 6543.967280163759, 6565.80302590923, 6272.059191121369, 6354.6822658867795, 6355.428011478989, 6356.3325850785, 6385.584936979081], "value": 6136.424296860605, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [960.8943016268214, 963.2053555816444, 986.442659897158, 1010.9940609093952, 1017.0236572890022, 42098.579014209885, 43188.0, 43293.35059974099, 43313.783431082804, 43317.56682433175, 43963.3405498917, 44555.3316700249, 44610.33084503735, 44619.553804461946, 44811.32783008258, 8411.621477033548, 8550.0, 8551.615177317004, 8557.700480483185, 8557.743267701979, 7898.091719452251, 7904.09102953162, 7904.249096335839, 7948.324392426651, 8014.358851291882, 7864.5281283123195, 7891.289783919446, 7928.80177995549, 7978.242067003625, 8032.036155661329, 7797.298243158115, 7836.0, 7862.410319226053, 7863.3316168125775, 7886.250806173462, 7905.446618736691, 7964.681412743541, 7977.082635496934, 7997.520148791091, 8012.719554815553, 39267.60732392697, 39468.60531394678, 39515.40726889099, 39697.60302396997, 39719.20561588781, 3748.625137486257, 3793.3551296279697, 3795.3358162321483, 3796.6393192646656, 3798.0315019669806, 32524.349513009845, 32861.50707739386, 32902.83548582242, 32903.17742056468, 32905.50641740376, 5301.6818990860675, 5397.973010134924, 5474.589405794542, 5487.807926722546, 5580.190872323531, 214381.0, 215381.0, 216996.0, 217416.9129154344, 218335.0, 50117.24824127641, 50763.746181268856, 51803.0, 52872.73563632157, 53111.46888531143, 55798.16302755462, 55976.440235597125, 56049.43950560524, 56066.159007614915, 56098.439015610136, 16203.270852811651, 16304.184790760652, 16487.34050637961, 16490.422835200712, 16573.41993030238, 13063.804042939364, 13163.802542961863, 13180.143290686075, 13189.868101318863, 13198.802017969738, 15500.68998620044, 15573.532794016197, 15751.976121552063, 15781.0, 15831.525054248392, 6224.128621992924, 6343.492520598341, 6361.2048493938255, 6417.582857114273, 6423.903641445382, 1042.0829669890447, 1074.156786922278, 1084.3981590217504, 1091.307020042263, 1092.7158938675843, 25557.48885022289, 25620.487590248464, 25659.0, 25726.0, 25946.22161335163, 1177.8233265010317, 1189.2448295332354, 1191.904647628205, 1202.2786328203183, 1204.5482943896038, 13852.0, 13889.861101388857, 13986.44054237819, 14065.929670351583, 14144.85855141456, 279151.0, 279584.0, 281598.0, 282088.0, 283663.0, 56250.15624765632, 61636.0, 62866.37133628697, 62876.68561657254, 63067.0, 1467.222372142766, 1514.2504460292225, 1515.4165646226281, 1519.3618680154582, 1534.1255484374024, 30758.0, 31069.53395699021, 31224.531632025082, 31740.841295793372, 32316.676833231366, 41457.1708565833, 41749.37375939303, 41763.0, 41765.494070355875, 41831.7908410456, 258418.70790645925, 260408.0, 260463.0, 261862.0, 262087.0, 35939.28121437609, 37185.81407093055, 37265.441018384205, 37425.0, 37544.43683344807, 46112.30831537463, 46545.301820473396, 46837.0, 47175.0, 47358.76320618375, 34464.31071378508, 34627.48058779171, 34845.47731784076, 34909.301813964084, 35043.82478087593, 34398.484022739176, 34464.483032754026, 34506.309873802886, 34646.65353346434, 35420.46869296911, 39109.41335879907, 40371.1925761489, 40595.59404405918, 40716.592834072464, 40773.0, 33185.66814331826, 33486.832565837016, 33494.83252583721, 33718.0, 34228.315433691685, 24984.625230621918, 25468.363290917874, 26260.60609090827, 26466.603000955387, 26571.601425979014], "value": 18768.947582821063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.9252593425822, 603.1224568253192, 603.897337452633, 604.9485793707535, 622.9937700622993, 23234.302970910856, 24245.63631545526, 24389.87805060972, 24402.389940251458, 24448.266552003446, 24525.63211551828, 26020.739792602068, 26222.868885655545, 26224.213273601774, 26227.0, 6785.050092986986, 6834.829129271783, 6878.621675807829, 6933.965330173367, 6935.098437203182, 6562.245341785708, 6810.931890681104, 6812.352826481501, 6818.897716534256, 6819.556728812636, 6737.663116844163, 6744.966275168617, 6775.796726098225, 6778.322167783232, 6782.186137663487, 6766.492513061491, 6772.525923185379, 6774.661266936658, 6775.424088952445, 6798.592084474898, 6313.589616674947, 6316.08416779569, 6316.1788967434395, 6336.398042185985, 6338.651374174418, 28975.710242897723, 29784.70215297841, 30159.246018849484, 30318.242043948856, 30382.54426183609, 2140.4541841830387, 2149.0973791007814, 2151.128792838903, 2162.1135334512933, 2162.178372218547, 20295.289664861808, 20747.89626051875, 20765.792342076536, 20773.0, 20784.1686332548, 2458.864762438065, 2509.3099397665605, 2569.0237709670205, 2579.587266037444, 2584.508943300767, 134848.0, 140074.0, 141017.0, 144974.27512862367, 150661.9999999989, 15442.845571544365, 15962.201889905458, 15990.441529355247, 16399.244219176544, 16519.09144997013, 25804.61293080605, 25842.224733257655, 25852.8707356462, 25872.8706356467, 25886.35284117912, 10429.9478502607, 10437.165026797838, 10492.108170805413, 10555.525001374957, 10562.260641755003, 9387.90612093884, 9404.576794044284, 9508.381955172892, 9536.523173841419, 9595.712128636153, 9511.334206605472, 9667.564959576695, 9846.704598862045, 9979.750506237257, 10057.145142662956, 2934.471795076907, 2941.7205365490245, 2946.57274695168, 2960.2007457986224, 2962.289050627877, 1044.3785947361307, 1074.2104553153426, 1081.1837063017529, 1086.8478413022183, 1100.5487750022453, 21111.683324749833, 21155.653304008734, 21387.25144619931, 21398.679019814714, 21938.232161874257, 1111.6720567432626, 1114.9554017839357, 1122.119136477861, 1132.2074547816562, 1132.5979277356585, 11426.714332141597, 11732.589359372381, 11761.823572646417, 11766.823497647541, 11826.586069487526, 600428.0, 604887.0, 612227.0, 620125.0, 620304.0, 128285.0, 129387.0, 133678.0, 133896.0, 134188.0, 1105.8949399807007, 1137.9089672826153, 1159.6231224851788, 1202.6692659518528, 1215.392303848076, 21644.861679815862, 22377.888110559343, 22386.992585333373, 22414.887925560266, 22472.887635561718, 63156.36843631689, 63355.36644633495, 63463.36536634759, 65270.0, 65555.67222163858, 74718.2528174733, 74758.25241747513, 75159.62420187864, 75981.24018759742, 78637.0, 15483.84516154824, 15592.220388980733, 15605.765913511534, 15780.526584202493, 15805.13071781063, 23923.0, 24014.15950441726, 24185.879070604533, 24477.020919163035, 24814.37964050913, 19639.509012274808, 19792.703109453083, 19907.900460498186, 20066.0, 20093.39719808408, 17394.0, 17479.82520174833, 17909.373171938918, 17948.55128621795, 18169.818301816813, 20411.79588204099, 20561.89719051455, 20700.896495517427, 20736.688949665466, 20762.688559671315, 15447.691046179238, 15540.922295388451, 15554.455594054154, 15585.844141558438, 15648.061116333056, 19238.903805480884, 19331.323403680803, 19427.4171774847, 19935.302264420676, 19937.20251189994], "value": 12821.59609536686, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.7301726163396, 1462.9780553291696, 1468.5594321703486, 1484.3765618440254, 1497.4833682379578, 16782.66434671306, 17419.73870391945, 17459.388921387737, 17464.0, 17489.82510174898, 16987.320507179727, 17193.312267509285, 17258.913705431485, 17303.0, 17334.306627734877, 2874.0363745228733, 2876.755475784561, 2920.3283244853696, 2920.4451154280723, 2927.458420192271, 2753.504369213541, 2793.469240844233, 2796.706345833689, 2811.8453485058417, 2816.1833068410137, 2768.8477133757633, 2795.9580606290924, 2803.565447355653, 2806.0880213930454, 2813.254487560798, 2683.2486903667004, 2687.4625074984983, 2703.7296270372904, 2713.0639929224385, 2716.0765339784566, 2638.0, 2639.3005853448853, 2658.6410834537282, 2663.3607934095676, 2665.2404064841426, 17035.914820425944, 17340.826591734174, 17486.737698934397, 17579.384721534814, 17605.0, 8554.101819308946, 8892.555372231363, 8893.555322233862, 8899.288056955493, 8939.955300223457, 5692.316921969349, 5833.095870140135, 5873.794417195378, 5924.229850119457, 5942.7920022799, 2061.2785525066256, 2090.1606586204043, 2096.5387614724737, 2100.978990210094, 2122.6603743400983, 30594.541081883788, 30686.693133068606, 30878.382432351227, 30893.691063089533, 30919.845400773076, 7238.457115716343, 7249.093863267091, 7250.528715633467, 7416.25837416254, 7479.439042071757, 19444.0, 19525.80474195268, 19694.113764880614, 19748.40754777359, 19756.802431975782, 7929.207079292025, 8083.676652933935, 8165.673373065129, 8169.9591502043295, 8176.01887773471, 7306.671199796022, 7461.067366579177, 7508.474406791473, 7643.1974642662835, 7788.337991270692, 5525.751341189656, 5639.802606908738, 5827.3298570664065, 5860.150278209721, 5885.852853678607, 1638.3937942961181, 1673.6401673640225, 1689.4340395967488, 1705.1815128738276, 1712.1011468978886, 972.6984634763246, 997.7306127345576, 1004.8743907011624, 1029.7425643589104, 1029.984550231747, 8607.827843443096, 8641.351898607629, 8828.117188281249, 8860.8670869937, 9014.278857691368, 791.133708589094, 801.1267718187204, 814.2834305810967, 820.0364571628313, 829.6432534010434, 5877.23595932526, 5992.940070599326, 6118.694065296806, 6164.198654175018, 6252.531060170505, 22755.0, 23201.88399057994, 23666.881665591907, 24022.87988560046, 24043.879780600986, 23211.88394058019, 23747.287581372242, 23862.7613723864, 24005.879970600035, 24006.15978440746, 11688.824667629993, 12234.265944043207, 12504.81242781359, 12561.748765024831, 12563.811542826865, 9519.714408567754, 9522.571484283086, 9565.521723913917, 9586.712398628053, 9640.662576809778, 20884.582308354053, 21134.15463381509, 21322.680159797306, 21822.890885545472, 22370.552588947805, 22112.557748845255, 22123.889380552995, 22184.889075554518, 22202.44493887666, 22207.555848883254, 11890.108241882066, 12071.0, 12124.818127728267, 12163.45264463119, 12171.33057681836, 14975.550733478012, 15019.32413041394, 15229.619259518158, 15234.771478428054, 15256.160911149993, 12477.438515267017, 12800.295983720986, 12964.675883102998, 12976.416061277076, 13002.609921702364, 11778.882211177779, 11861.5848445304, 12044.819327710267, 12243.75512489763, 12418.813717794059, 10409.115225205942, 10537.157027437634, 10648.787024259627, 10650.414227217578, 10650.73373165677, 8163.346932245525, 8209.466384684976, 8283.37874659391, 8283.917160828556, 8359.540225287668, 8115.391345649218, 8157.469764465291, 8172.019357677117, 8318.126596707258, 8349.373796965374], "value": 7259.074686619939, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.814083211708, 576.7289373994224, 579.94200579942, 582.5756026515172, 593.545937357921, 6302.716377762995, 6305.9684701576425, 6308.558400911938, 6372.904406433907, 6383.840403989902, 6108.664023478706, 6194.566380353377, 6265.874682506358, 6278.434940855322, 6292.874142517158, 5138.845834624948, 5148.794048238066, 5169.250458683488, 5202.219667049935, 5213.791448342062, 5103.515166059237, 5146.614003949719, 5160.92258616121, 5172.172452407615, 5192.247124166994, 5167.9224811627855, 5173.663711858736, 5193.039967218687, 5196.92204616931, 5240.633155679104, 5139.588832893378, 5163.225516172567, 5233.947660523422, 5234.659747116444, 5240.895182096337, 4728.219843725816, 4866.391701037371, 4869.902601947977, 4880.951190488121, 4881.167644032383, 6086.052463211774, 6106.358832322587, 6157.230346206724, 6178.814635560941, 6182.938170618326, 6134.141220229171, 6139.907901381483, 6146.508279337687, 6173.536984726119, 6194.690265486753, 6690.765823196213, 6751.527393082486, 6756.952672335796, 6759.695813688347, 6765.966170169167, 5438.7280635968045, 5504.614676972614, 5505.229267902497, 5508.173773933902, 5519.834404967857, 6687.157957867936, 6766.3233676632435, 6865.862682746367, 6916.23921368649, 7020.66607344608, 5645.181448689999, 5653.491185793216, 5670.177824215548, 5673.609965558382, 5685.914711279334, 5632.493075623213, 5731.168980497804, 5770.971145144332, 5774.769009239667, 5786.710664466844, 5985.700714964234, 6123.142760013601, 6144.539159563051, 6159.4456498915315, 6172.598781079216, 6707.832304192434, 6719.865602688016, 6741.966290168518, 6750.0, 6768.221654509696, 1956.7847536770944, 1977.3771262052408, 1979.6436641404398, 1982.5725477656065, 1990.5421752996601, 1418.3971811979868, 1472.1387988027125, 1476.9187694676896, 1497.2438918346384, 1497.2663394936403, 5940.792072277536, 6028.306744724325, 6085.300190478063, 6102.298235702951, 6136.631802091889, 6487.156669632917, 6520.804375868636, 6575.145231120019, 6621.50338724588, 6652.800415987528, 6031.8793624127275, 6054.033523573417, 6093.908591371133, 6100.938990610037, 6123.2652081750475, 3490.8305717584894, 3493.3013397320638, 3496.3356962177104, 3497.720182385454, 3530.841112149908, 6890.483213758988, 6905.896411553831, 6934.791956241321, 6985.266547012592, 7007.8598428031155, 6836.282190370039, 6864.5881247125335, 6906.205786334636, 6983.720651173896, 6984.546004509691, 6867.862642747217, 6971.372576468142, 7021.543599666005, 7038.366547010896, 7046.1192350956135, 6663.46692264608, 6706.826305396438, 6736.259011508632, 6743.9325606743305, 6754.696038678173, 6604.339566043357, 6615.834604134935, 6653.434458071119, 6701.497387695845, 6710.261871194067, 6931.7227310907, 6989.7553585624255, 6991.475639326969, 7002.7899163025195, 7007.7196912122945, 5628.718564071862, 5631.63394379364, 5653.123765816305, 5668.752874367632, 5674.631148975303, 6479.384458476437, 6649.60102393858, 6736.248575370341, 6746.628935408599, 6761.532040804972, 6248.219257511588, 6480.870382592416, 6488.188976377953, 6538.705758240986, 6573.375529324707, 6372.458341041063, 6480.055983204926, 6509.511786615928, 6559.836004099936, 6585.539012269095, 6207.6585787782105, 6378.756142552158, 6412.006139048455, 6452.9677351612945, 6458.160439142881, 6539.476841852736, 6551.868962620816, 6557.606543607399, 6574.276829548841, 6583.847826630416, 6355.7457701693265, 6412.647304398303, 6416.615003099829, 6430.64631445275, 6438.710258038306], "value": 5292.6474536280775, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [940.9623615055402, 972.8637990681306, 977.1401166973064, 980.5636491761164, 992.4739887859437, 20724.274650387222, 20988.0, 21003.894980525078, 21011.894940525293, 21023.05396257168, 19512.90243548784, 20135.0, 20139.395818125442, 20140.19439222433, 20178.19287228509, 1031.1183937733242, 1069.6951368859877, 1071.3625392891256, 1072.0994364733622, 1074.5433190893878, 1087.2443651662097, 1087.4617064553058, 1089.1341383600018, 1089.9455027248646, 1096.9561217551288, 1072.6460268111505, 1130.129800053958, 1137.5222406589212, 1138.0554140063769, 1138.226006315705, 1131.4569006876704, 1132.2131118872387, 1132.4960392625285, 1134.2854001978742, 1136.8408422820812, 801.2548330053048, 819.3895547816855, 821.1131977464324, 821.3470291118575, 825.6325934958886, 21431.464213394633, 21761.891190544105, 21773.45566360838, 21810.236641717463, 21886.343409697736, 23059.0, 23117.88441057784, 23606.763932360627, 23757.881210593838, 24094.51810963771, 18020.819791802045, 18078.819211807975, 18495.2601895924, 18565.16456759449, 18752.906235468872, 4010.5187377514594, 4046.0, 4047.4738284023183, 4065.0447144920918, 4066.6340029397497, 129428.0, 157489.0, 158050.0, 158518.20740896222, 161969.99999999884, 18736.718949215774, 19052.0, 19135.0, 19187.424377268704, 19309.0, 33100.1724956878, 33117.66882331194, 33139.66860331366, 33378.8331058348, 33925.32149356999, 9506.191973682315, 9553.18797902186, 9615.663451779154, 9616.567254473566, 9617.374870633386, 4450.799714012877, 4464.419625448736, 4501.234790085693, 4502.414686090853, 4543.54564543543, 6228.781992630236, 6287.685615719196, 6320.367963203735, 6337.366263373718, 6380.904286435707, 1244.7012716947868, 1282.410091357967, 1282.9807552886714, 1297.9675508112373, 1298.4741179822188, 722.1478655186825, 723.7177500774702, 724.6087112959049, 729.1323325242944, 746.932776050158, 13497.595072147851, 13627.795583066261, 14091.78862317066, 14812.851871481364, 14880.255987200597, 683.1835956032593, 689.0904006711094, 696.8327601375735, 703.3634560722554, 707.593133947982, 6519.022146677941, 6544.411003009752, 6561.573497722633, 6562.573432726857, 6566.409023187841, 153139.0, 156728.1848237486, 156957.0, 161013.1949340246, 161032.0, 120757.39621302013, 121559.0, 124629.0, 127443.3627831855, 131635.0, 106435.0, 106479.0, 108863.45568272108, 110921.99999999838, 114130.0, 14264.714705706036, 14413.0, 14461.49384771528, 14514.129152250898, 14669.559913202622, 49885.0, 50729.49270507248, 50790.74604626953, 51893.962120756616, 52005.7399712999, 68834.0, 69833.65083174552, 70106.0, 70116.6494167526, 71826.64086679534, 20472.0, 20610.89694551518, 20746.48133796667, 20889.477763056046, 21015.159393624694, 22622.660660089783, 22858.199963001214, 23001.769982300633, 23078.076876924733, 23337.649935251324, 21308.254211102536, 21480.24819131323, 21695.674564881854, 21913.452163695398, 21919.561608767417, 20821.167153314305, 20882.58234835325, 21185.682214767097, 21205.15179392807, 21236.0, 19896.70154947706, 20115.497112572302, 20144.194232230548, 20242.08910599056, 20351.185952562322, 15427.228638568251, 15495.380184792482, 15620.453284135001, 15752.448664297153, 15862.206889655241, 14408.13551186932, 14436.566902992927, 14471.224545749194, 14518.709625807214, 14560.49038283655], "value": 10341.787917244023, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1367.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 106.0, 105.0, 104.0, 105.0, 109.0, 120.0, 109.0, 106.0, 104.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 111.0, 114.0, 113.0, 139.0, 114.0, 116.0, 113.0], "value": 115.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 161.0, 162.0, 162.0, 163.0, 162.0, 169.0, 165.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 73.0, 74.0, 73.0, 74.0, 74.0, 73.0, 73.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 77.0, 78.0, 78.0, 78.0, 82.0, 87.0, 79.0, 78.0], "value": 79.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 151.0, 150.0, 149.0, 151.0, 151.0, 150.0, 151.0, 150.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 99.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 43.0, 46.0, 43.0, 45.0, 45.0, 44.0, 43.0, 43.0, 43.0], "value": 43.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 71.0, 76.0, 75.0, 75.0, 70.0, 76.0, 70.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 102.0, 102.0, 109.0, 111.0, 105.0, 105.0, 104.0, 109.0, 104.0], "value": 105.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 150.0, 155.0, 150.0, 152.0, 146.0, 150.0, 152.0, 156.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 62.0, 59.0, 59.0, 59.0, 57.0, 59.0], "value": 59.2, "unit": "score"}]}]} 12:30:50 INFO - Return code: 0 12:30:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:30:50 INFO - # TBPL SUCCESS # 12:30:50 INFO - Running post-action listener: _package_coverage_data 12:30:50 INFO - Running post-action listener: _resource_record_post_action 12:30:50 INFO - [mozharness: 2017-09-21 19:30:50.199816Z] Finished run-tests step (success) 12:30:50 INFO - Running post-run listener: _resource_record_post_run 12:30:50 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 316551168; Read time: 12; Write time: 235108 12:30:50 INFO - TinderboxPrint: CPU usage
13.1% 12:30:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:30:50 INFO - TinderboxPrint: I/O write bytes / time
316,551,168 / 235,108 12:30:50 INFO - TinderboxPrint: CPU idle
8,206.0 (86.9%) 12:30:50 INFO - TinderboxPrint: CPU user
1,186.6 (12.6%) 12:30:50 INFO - TinderboxPrint: Swap in / out
0 / 0 12:30:50 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9396224; Read time: 0; Write time: 820 12:30:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:30:50 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 4096; Write bytes: 306135040; Read time: 12; Write time: 234268 12:30:50 INFO - Running post-run listener: _upload_blobber_files 12:30:50 INFO - Blob upload gear active. 12:30:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:30:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:30:51 INFO - (blobuploader) - INFO - Open directory for files ... 12:30:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:30:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:30:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:52 INFO - (blobuploader) - INFO - Done attempting. 12:30:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:30:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:30:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:55 INFO - (blobuploader) - INFO - Done attempting. 12:30:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:30:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:30:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:56 INFO - (blobuploader) - INFO - Done attempting. 12:30:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:30:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:30:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:57 INFO - (blobuploader) - INFO - Done attempting. 12:30:57 INFO - (blobuploader) - INFO - Iteration through files over. 12:30:57 INFO - Return code: 0 12:30:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:30:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:30:57 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bce1a3088467976e92a2a4d6c74a4f395d77dd1a22f0d949022f16e1f98081ebc5b699f504d3134775a4c20a15a286d6ba1e1431fb0b897c26c2ffab315fd68", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e581c716221f2cd2f3aab3db88521a5d39b4bc40459fb109e5f1b8a298a604403a9d475213298d0dccd479590ee3cbe7e8030981aefd515245ddd6cab3da64ee", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cc128150f5b87b6564fbc12dc96cfd9deef751963dfeb7a55b8f02075557a3aae108b7d6c7c0169a19bc4735b583a785cfee758add22c6ba3f08c25e4e8df0f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6884f584f2cac8cfb7c3847825c135f14af1da52206e796a87ad89b419df82bb9d3203aad1734bd0e40e4b4bbe1c1fdd205f2e3adbd3aea4bf60687b666fe9"} 12:30:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:30:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:30:57 INFO - Contents: 12:30:57 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bce1a3088467976e92a2a4d6c74a4f395d77dd1a22f0d949022f16e1f98081ebc5b699f504d3134775a4c20a15a286d6ba1e1431fb0b897c26c2ffab315fd68", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e581c716221f2cd2f3aab3db88521a5d39b4bc40459fb109e5f1b8a298a604403a9d475213298d0dccd479590ee3cbe7e8030981aefd515245ddd6cab3da64ee", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cc128150f5b87b6564fbc12dc96cfd9deef751963dfeb7a55b8f02075557a3aae108b7d6c7c0169a19bc4735b583a785cfee758add22c6ba3f08c25e4e8df0f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6884f584f2cac8cfb7c3847825c135f14af1da52206e796a87ad89b419df82bb9d3203aad1734bd0e40e4b4bbe1c1fdd205f2e3adbd3aea4bf60687b666fe9"} 12:30:57 INFO - Running post-run listener: copy_logs_to_upload_dir 12:30:57 INFO - Copying logs to upload dir... 12:30:57 INFO - mkdir: /builds/slave/test/build/upload/logs 12:30:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.701118 ========= master_lag: -0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-09-21 12:30:57.607139) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 12:30:57.614624) ========= 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=20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bce1a3088467976e92a2a4d6c74a4f395d77dd1a22f0d949022f16e1f98081ebc5b699f504d3134775a4c20a15a286d6ba1e1431fb0b897c26c2ffab315fd68", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e581c716221f2cd2f3aab3db88521a5d39b4bc40459fb109e5f1b8a298a604403a9d475213298d0dccd479590ee3cbe7e8030981aefd515245ddd6cab3da64ee", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cc128150f5b87b6564fbc12dc96cfd9deef751963dfeb7a55b8f02075557a3aae108b7d6c7c0169a19bc4735b583a785cfee758add22c6ba3f08c25e4e8df0f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6884f584f2cac8cfb7c3847825c135f14af1da52206e796a87ad89b419df82bb9d3203aad1734bd0e40e4b4bbe1c1fdd205f2e3adbd3aea4bf60687b666fe9"} build_url:https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/TlqS4ZRNTFKzKKSYPo9p3Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bce1a3088467976e92a2a4d6c74a4f395d77dd1a22f0d949022f16e1f98081ebc5b699f504d3134775a4c20a15a286d6ba1e1431fb0b897c26c2ffab315fd68", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e581c716221f2cd2f3aab3db88521a5d39b4bc40459fb109e5f1b8a298a604403a9d475213298d0dccd479590ee3cbe7e8030981aefd515245ddd6cab3da64ee", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cc128150f5b87b6564fbc12dc96cfd9deef751963dfeb7a55b8f02075557a3aae108b7d6c7c0169a19bc4735b583a785cfee758add22c6ba3f08c25e4e8df0f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6884f584f2cac8cfb7c3847825c135f14af1da52206e796a87ad89b419df82bb9d3203aad1734bd0e40e4b4bbe1c1fdd205f2e3adbd3aea4bf60687b666fe9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 12:30:57.646267) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 12:30:57.646596) ========= 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=20e666a3d4cfbb71505a330e000001b4-1506019052.374031-1007802855 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 12:30:57.727100) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 12:30:57.730466) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 12:30:57.730821) ========= ========= Total master_lag: 0.17 =========