builder: mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s slave: talos-linux64-ix-035 starttime: 1506018831.56 results: success (0) revision: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:51.564180) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:51.564591) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:51.564856) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:51.611791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:51.612091) ========= 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=ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 11:33:51-- 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% 222M=0s 2017-09-21 11:33:51 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.175144 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:51.813939) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:51.814336) ========= 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=ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015599 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:51.888576) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-21 11:33:51.888880) ========= 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=ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 11:33:51,924 truncating revision to first 12 chars 2017-09-21 11:33:51,924 Setting DEBUG logging. 2017-09-21 11:33:51,924 attempt 1/10 2017-09-21 11:33:51,924 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 11:33:52,630 unpacking tar archive at: mozilla-central-ca7d18dbacbf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.030884 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-21 11:33:52.955673) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:52.955974) ========= script_repo_revision: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:52.959366) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:52.959631) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 11:33:52.973030) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-21 11:33:52.973304) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:33:53 INFO - MultiFileLogger online at 20170921 11:33:53 in /builds/slave/test 11:33:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:33:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:33:53 INFO - {'append_to_log': False, 11:33:53 INFO - 'base_work_dir': '/builds/slave/test', 11:33:53 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:33:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:33:53 INFO - 'branch': 'Firefox-Non-PGO', 11:33:53 INFO - 'buildbot_json_path': 'buildprops.json', 11:33:53 INFO - 'code_coverage': False, 11:33:53 INFO - 'config_files': ('talos/linux_config.py',), 11:33:53 INFO - 'default_actions': ('clobber', 11:33:53 INFO - 'read-buildbot-config', 11:33:53 INFO - 'download-and-extract', 11:33:53 INFO - 'populate-webroot', 11:33:53 INFO - 'create-virtualenv', 11:33:53 INFO - 'install', 11:33:53 INFO - 'setup-mitmproxy', 11:33:53 INFO - 'run-tests'), 11:33:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:33:53 INFO - 'disable_ccov_upload': False, 11:33:53 INFO - 'disable_stylo': False, 11:33:53 INFO - 'download_minidump_stackwalk': True, 11:33:53 INFO - 'download_symbols': 'ondemand', 11:33:53 INFO - 'enable_stylo': False, 11:33:53 INFO - 'enable_webrender': False, 11:33:53 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:33:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:33:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:33:53 INFO - 'gecko_profile': False, 11:33:53 INFO - 'gecko_profile_interval': 0, 11:33:53 INFO - 'installer_path': 'installer.exe', 11:33:53 INFO - 'log_level': 'info', 11:33:53 INFO - 'log_name': 'talos', 11:33:53 INFO - 'log_to_console': True, 11:33:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:33:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:33:53 INFO - 'opt_config_files': (), 11:33:53 INFO - 'pip_index': False, 11:33:53 INFO - 'suite': 'dromaeojs-stylo-disabled-e10s', 11:33:53 INFO - 'system_bits': '32', 11:33:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:33:53 INFO - 'title': 'talos-linux64-ix-035', 11:33:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:33:53 INFO - 'use_talos_json': True, 11:33:53 INFO - 'verify': 'False', 11:33:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:33:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:33:53 INFO - 'work_dir': 'build'} 11:33:53 INFO - [mozharness: 2017-09-21 18:33:53.141285Z] Running clobber step. 11:33:53 INFO - Running pre-action listener: _resource_record_pre_action 11:33:53 INFO - Running main action method: clobber 11:33:53 INFO - rmtree: /builds/slave/test/build 11:33:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:33:54 INFO - Running post-action listener: _resource_record_post_action 11:33:54 INFO - [mozharness: 2017-09-21 18:33:54.654467Z] Finished clobber step (success) 11:33:54 INFO - [mozharness: 2017-09-21 18:33:54.654576Z] Running read-buildbot-config step. 11:33:54 INFO - Running pre-action listener: _resource_record_pre_action 11:33:54 INFO - Running main action method: read_buildbot_config 11:33:54 INFO - Using buildbot properties: 11:33:54 INFO - { 11:33:54 INFO - "project": "", 11:33:54 INFO - "product": "firefox", 11:33:54 INFO - "who": "ffxbld@noreply.mozilla.org", 11:33:54 INFO - "installer_path": "public/build/target.tar.bz2", 11:33:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s", 11:33:54 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:33:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-stylo-disabled-e10s", 11:33:54 INFO - "stage_platform": "linux64", 11:33:54 INFO - "basedir": "/builds/slave/test", 11:33:54 INFO - "buildnumber": 56, 11:33:54 INFO - "platform": "ubuntu64_hw", 11:33:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:33:54 INFO - "slavebuilddir": "test", 11:33:54 INFO - "taskId": "Y9DsIocnQsK8vCInT1ptZQ", 11:33:54 INFO - "branch": "mozilla-central", 11:33:54 INFO - "script_repo_revision": "production", 11:33:54 INFO - "revision": "ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2", 11:33:54 INFO - "slavename": "talos-linux64-ix-035", 11:33:54 INFO - "repo_path": "mozilla-central" 11:33:54 INFO - } 11:33:54 INFO - Finding installer, test and symbols from parent task. 11:33:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y9DsIocnQsK8vCInT1ptZQ'}, attempt #1 11:33:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y9DsIocnQsK8vCInT1ptZQ'}, attempt #1 11:33:56 INFO - Task dependencies: TWoC3Z28QNeZ_g1kUharhQ 11:33:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ'}, attempt #1 11:33:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 11:33:56 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.test_packages.json 11:33:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.crashreporter-symbols.zip 11:33:56 INFO - Running post-action listener: _resource_record_post_action 11:33:56 INFO - [mozharness: 2017-09-21 18:33:56.579561Z] Finished read-buildbot-config step (success) 11:33:56 INFO - [mozharness: 2017-09-21 18:33:56.579867Z] Running download-and-extract step. 11:33:56 INFO - Running pre-action listener: _resource_record_pre_action 11:33:56 INFO - Running main action method: download_and_extract 11:33:56 INFO - mkdir: /builds/slave/test/build/tests 11:33: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')]} 11:33:56 INFO - trying https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.test_packages.json 11:33:56 INFO - Downloading https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:33:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:33:57 INFO - Downloaded 989 bytes. 11:33:57 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:33:57 INFO - Using the following test package requirements: 11:33:57 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:33:57 INFO - u'common': [u'target.common.tests.zip'], 11:33:57 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:33:57 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:33:57 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:33:57 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:33:57 INFO - u'mozbase': [u'target.common.tests.zip'], 11:33:57 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:33:57 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:33:57 INFO - u'web-platform': [u'target.common.tests.zip', 11:33:57 INFO - u'target.web-platform.tests.tar.gz'], 11:33:57 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:33:57 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:33:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.common.tests.zip 11:33:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:33:57 INFO - Fetch https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.common.tests.zip into memory 11:33:59 INFO - Content-Length response header: 54741039 11:33:59 INFO - Bytes received: 54741039 11:34:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.talos.tests.zip 11:34:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:34:09 INFO - Fetch https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.talos.tests.zip into memory 11:34:10 INFO - Content-Length response header: 14509184 11:34:10 INFO - Bytes received: 14509184 11:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:11 INFO - trying https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 11:34:11 INFO - Downloading https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:34:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:34:13 INFO - Downloaded 61492348 bytes. 11:34:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 11:34:13 INFO - mkdir: /builds/slave/test/properties 11:34:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:34:13 INFO - Writing to file /builds/slave/test/properties/build_url 11:34:13 INFO - Contents: 11:34:13 INFO - build_url:https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 11:34:13 INFO - Running post-action listener: _resource_record_post_action 11:34:13 INFO - Running post-action listener: find_tests_for_verification 11:34:13 INFO - Running post-action listener: set_extra_try_arguments 11:34:13 INFO - [mozharness: 2017-09-21 18:34:13.307758Z] Finished download-and-extract step (success) 11:34:13 INFO - [mozharness: 2017-09-21 18:34:13.307869Z] Running populate-webroot step. 11:34:13 INFO - Running pre-action listener: _resource_record_pre_action 11:34:13 INFO - Running main action method: populate_webroot 11:34:13 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:34:13 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['tresize', 11:34:13 INFO - 'tcanvasmark']}, 11:34:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:34:13 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['dromaeo_css', 11:34:13 INFO - 'kraken']}, 11:34:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:34:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:34:13 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:13 INFO - 'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:34:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:34:13 INFO - 'tests': ['damp', 'tps']}, 11:34:13 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:13 INFO - 'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['damp', 'tps']}, 11:34:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:34:13 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['dromaeo_dom']}, 11:34:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:34:13 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['basic_compositor_video', 11:34:13 INFO - 'glvideo']}, 11:34:13 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:34:13 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:34:13 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:13 INFO - 'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['ts_paint_webext', 11:34:13 INFO - 'tp5o_webext']}, 11:34:13 INFO - 'other-e10s': {'tests': ['a11yr', 11:34:13 INFO - 'ts_paint', 11:34:13 INFO - 'tpaint', 11:34:13 INFO - 'sessionrestore', 11:34:13 INFO - 'sessionrestore_many_windows', 11:34:13 INFO - 'sessionrestore_no_auto_restore', 11:34:13 INFO - 'tabpaint']}, 11:34:13 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['a11yr', 11:34:13 INFO - 'ts_paint', 11:34:13 INFO - 'tpaint', 11:34:13 INFO - 'sessionrestore', 11:34:13 INFO - 'sessionrestore_many_windows', 11:34:13 INFO - 'sessionrestore_no_auto_restore', 11:34:13 INFO - 'tabpaint']}, 11:34:13 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:34:13 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:34:13 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['perf_reftest_singletons']}, 11:34:13 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['bloom_basic']}, 11:34:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:34:13 INFO - 'tsvgr_opacity', 11:34:13 INFO - 'tart', 11:34:13 INFO - 'tscrollx', 11:34:13 INFO - 'tsvg_static']}, 11:34:13 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['tsvgx', 11:34:13 INFO - 'tsvgr_opacity', 11:34:13 INFO - 'tart', 11:34:13 INFO - 'tscrollx', 11:34:13 INFO - 'tsvg_static']}, 11:34:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:34:13 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:13 INFO - 'talos_options': ['--disable-stylo'], 11:34:13 INFO - 'tests': ['tp5o']}, 11:34:13 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:34:13 INFO - 'talos_options': ['--mitmproxy', 11:34:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:34:13 INFO - '--firstNonBlankPaint'], 11:34:13 INFO - 'tests': ['tp6_google', 11:34:13 INFO - 'tp6_youtube', 11:34:13 INFO - 'tp6_amazon', 11:34:13 INFO - 'tp6_facebook']}, 11:34:13 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:34:13 INFO - 'talos_options': ['--disable-stylo', 11:34:13 INFO - '--mitmproxy', 11:34:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:34:13 INFO - '--firstNonBlankPaint'], 11:34:13 INFO - 'tests': ['tp6_google', 11:34:13 INFO - 'tp6_youtube', 11:34:13 INFO - 'tp6_amazon', 11:34:13 INFO - 'tp6_facebook']}, 11:34:13 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:34:13 INFO - 'talos_options': ['--stylo-threads=1', 11:34:13 INFO - '--mitmproxy', 11:34:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:34:13 INFO - '--firstNonBlankPaint'], 11:34:13 INFO - 'tests': ['tp6_google', 11:34:13 INFO - 'tp6_youtube', 11:34:13 INFO - 'tp6_amazon', 11:34:13 INFO - 'tp6_facebook']}, 11:34:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:34:13 INFO - 'talos_options': ['--xperf_path', 11:34:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:34:13 INFO - 'tests': ['tp5n']}, 11:34:13 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:34:13 INFO - 'talos_options': ['--disable-stylo', 11:34:13 INFO - '--xperf_path', 11:34:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:34:13 INFO - 'tests': ['tp5n']}}} 11:34:13 INFO - Running post-action listener: _resource_record_post_action 11:34:13 INFO - [mozharness: 2017-09-21 18:34:13.332556Z] Finished populate-webroot step (success) 11:34:13 INFO - [mozharness: 2017-09-21 18:34:13.332646Z] Running create-virtualenv step. 11:34:13 INFO - Running pre-action listener: _resource_record_pre_action 11:34:13 INFO - Running main action method: create_virtualenv 11:34:13 INFO - Creating virtualenv /builds/slave/test/build/venv 11:34:13 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:34:13 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:34:13 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:34:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:34:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:34:13 INFO - Installing distribute.............................................................................................................................................................................................done. 11:34:14 INFO - Installing pip...............done. 11:34:14 INFO - Return code: 0 11:34:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:34:14 INFO - Reading from file tmpfile_stdout 11:34:14 INFO - Output received: 11:34:14 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:34:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:34:14 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')]} 11:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:14 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')]} 11:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:14 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:14 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 11:34:14 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 11:34:14 INFO - Using env: {'DISPLAY': ':0', 11:34:14 INFO - 'HOME': '/home/cltbld', 11:34:14 INFO - 'LANG': 'en_US.UTF-8', 11:34:14 INFO - 'LANGUAGE': 'en_US:en', 11:34:14 INFO - 'LOGNAME': 'cltbld', 11:34:14 INFO - 'MAIL': '/var/mail/cltbld', 11:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:14 INFO - 'MOZ_NO_REMOTE': '1', 11:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:14 INFO - 'NO_EM_RESTART': '1', 11:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:14 INFO - 'PWD': '/builds/slave/test', 11:34:14 INFO - 'SHELL': '/bin/bash', 11:34:14 INFO - 'SHLVL': '1', 11:34:14 INFO - 'TERM': 'linux', 11:34:14 INFO - 'TMOUT': '86400', 11:34:14 INFO - 'USER': 'cltbld', 11:34:14 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:14 INFO - '_': '/tools/buildbot/bin/python'} 11:34:15 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:34:15 INFO - Downloading/unpacking pip>=1.5 11:34:15 INFO - Running setup.py egg_info for package pip 11:34:15 INFO - warning: no files found matching 'pip/cacert.pem' 11:34:15 INFO - warning: no files found matching '*.html' under directory 'docs' 11:34:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:34:15 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:34:15 INFO - Installing collected packages: pip 11:34:15 INFO - Found existing installation: pip 1.1 11:34:15 INFO - Uninstalling pip: 11:34:15 INFO - Successfully uninstalled pip 11:34:15 INFO - Running setup.py install for pip 11:34:15 INFO - warning: no files found matching 'pip/cacert.pem' 11:34:15 INFO - warning: no files found matching '*.html' under directory 'docs' 11:34:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:34:15 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:34:15 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:34:15 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:34:15 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:34:15 INFO - Successfully installed pip 11:34:15 INFO - Cleaning up... 11:34:15 INFO - Return code: 0 11:34:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:34:15 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')]} 11:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:15 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')]} 11:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:15 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:15 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 11:34:15 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 11:34:15 INFO - Using env: {'DISPLAY': ':0', 11:34:15 INFO - 'HOME': '/home/cltbld', 11:34:15 INFO - 'LANG': 'en_US.UTF-8', 11:34:15 INFO - 'LANGUAGE': 'en_US:en', 11:34:15 INFO - 'LOGNAME': 'cltbld', 11:34:15 INFO - 'MAIL': '/var/mail/cltbld', 11:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:15 INFO - 'MOZ_NO_REMOTE': '1', 11:34:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:15 INFO - 'NO_EM_RESTART': '1', 11:34:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:15 INFO - 'PWD': '/builds/slave/test', 11:34:15 INFO - 'SHELL': '/bin/bash', 11:34:15 INFO - 'SHLVL': '1', 11:34:15 INFO - 'TERM': 'linux', 11:34:15 INFO - 'TMOUT': '86400', 11:34:15 INFO - 'USER': 'cltbld', 11:34:15 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:15 INFO - '_': '/tools/buildbot/bin/python'} 11:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:15 INFO - Downloading/unpacking psutil>=3.1.1 11:34:15 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 11:34:15 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 11:34:15 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 11:34:15 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 11:34:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:34:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:34:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:34:16 INFO - Installing collected packages: psutil 11:34:16 INFO - Running setup.py install for psutil 11:34:17 INFO - building 'psutil._psutil_linux' extension 11:34:17 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 11:34:17 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 11:34:17 INFO - building 'psutil._psutil_posix' extension 11:34:17 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 11:34:17 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 11:34:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:34:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:34:17 INFO - Successfully installed psutil 11:34:17 INFO - Cleaning up... 11:34:17 INFO - Return code: 0 11:34:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:34:17 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')]} 11:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:17 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')]} 11:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:17 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:17 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 11:34:17 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 11:34:17 INFO - Using env: {'DISPLAY': ':0', 11:34:17 INFO - 'HOME': '/home/cltbld', 11:34:17 INFO - 'LANG': 'en_US.UTF-8', 11:34:17 INFO - 'LANGUAGE': 'en_US:en', 11:34:17 INFO - 'LOGNAME': 'cltbld', 11:34:17 INFO - 'MAIL': '/var/mail/cltbld', 11:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:17 INFO - 'MOZ_NO_REMOTE': '1', 11:34:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:17 INFO - 'NO_EM_RESTART': '1', 11:34:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:17 INFO - 'PWD': '/builds/slave/test', 11:34:17 INFO - 'SHELL': '/bin/bash', 11:34:17 INFO - 'SHLVL': '1', 11:34:17 INFO - 'TERM': 'linux', 11:34:17 INFO - 'TMOUT': '86400', 11:34:17 INFO - 'USER': 'cltbld', 11:34:17 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:17 INFO - '_': '/tools/buildbot/bin/python'} 11:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:34:17 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 11:34:17 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 11:34:17 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 11:34:17 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 11:34:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:34:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:34:18 INFO - Installing collected packages: mozsystemmonitor 11:34:18 INFO - Running setup.py install for mozsystemmonitor 11:34:19 INFO - Successfully installed mozsystemmonitor 11:34:19 INFO - Cleaning up... 11:34:19 INFO - Return code: 0 11:34:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:34:19 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')]} 11:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:19 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')]} 11:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:19 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:19 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 11:34:19 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 11:34:19 INFO - Using env: {'DISPLAY': ':0', 11:34:19 INFO - 'HOME': '/home/cltbld', 11:34:19 INFO - 'LANG': 'en_US.UTF-8', 11:34:19 INFO - 'LANGUAGE': 'en_US:en', 11:34:19 INFO - 'LOGNAME': 'cltbld', 11:34:19 INFO - 'MAIL': '/var/mail/cltbld', 11:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:19 INFO - 'MOZ_NO_REMOTE': '1', 11:34:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:19 INFO - 'NO_EM_RESTART': '1', 11:34:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:19 INFO - 'PWD': '/builds/slave/test', 11:34:19 INFO - 'SHELL': '/bin/bash', 11:34:19 INFO - 'SHLVL': '1', 11:34:19 INFO - 'TERM': 'linux', 11:34:19 INFO - 'TMOUT': '86400', 11:34:19 INFO - 'USER': 'cltbld', 11:34:19 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:19 INFO - '_': '/tools/buildbot/bin/python'} 11:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:19 INFO - Downloading/unpacking jsonschema==2.5.1 11:34:19 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 11:34:19 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 11:34:19 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 11:34:19 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 11:34:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:34:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:34:21 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 11:34:21 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 11:34:21 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 11:34:21 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 11:34:21 INFO - Downloading functools32-3.2.3-2.tar.gz 11:34:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:34:21 INFO - warning: no files found matching '*.txt' 11:34:21 INFO - no previously-included directories found matching 'build' 11:34:21 INFO - no previously-included directories found matching 'dist' 11:34:21 INFO - no previously-included directories found matching '.git*' 11:34:21 INFO - Installing collected packages: jsonschema, functools32 11:34:21 INFO - Running setup.py install for functools32 11:34:21 INFO - warning: no files found matching '*.txt' 11:34:21 INFO - no previously-included directories found matching 'build' 11:34:21 INFO - no previously-included directories found matching 'dist' 11:34:21 INFO - no previously-included directories found matching '.git*' 11:34:21 INFO - Successfully installed jsonschema functools32 11:34:21 INFO - Cleaning up... 11:34:21 INFO - Return code: 0 11:34:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:34:21 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')]} 11:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:21 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')]} 11:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:21 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:21 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 11:34:21 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 11:34:21 INFO - Using env: {'DISPLAY': ':0', 11:34:21 INFO - 'HOME': '/home/cltbld', 11:34:21 INFO - 'LANG': 'en_US.UTF-8', 11:34:21 INFO - 'LANGUAGE': 'en_US:en', 11:34:21 INFO - 'LOGNAME': 'cltbld', 11:34:21 INFO - 'MAIL': '/var/mail/cltbld', 11:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:21 INFO - 'MOZ_NO_REMOTE': '1', 11:34:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:21 INFO - 'NO_EM_RESTART': '1', 11:34:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:21 INFO - 'PWD': '/builds/slave/test', 11:34:21 INFO - 'SHELL': '/bin/bash', 11:34:21 INFO - 'SHLVL': '1', 11:34:21 INFO - 'TERM': 'linux', 11:34:21 INFO - 'TMOUT': '86400', 11:34:21 INFO - 'USER': 'cltbld', 11:34:21 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:21 INFO - '_': '/tools/buildbot/bin/python'} 11:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:34:21 INFO - Cleaning up... 11:34:21 INFO - Return code: 0 11:34:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:34:21 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')]} 11:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:21 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')]} 11:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:21 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:21 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 11:34:21 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 11:34:21 INFO - Using env: {'DISPLAY': ':0', 11:34:21 INFO - 'HOME': '/home/cltbld', 11:34:21 INFO - 'LANG': 'en_US.UTF-8', 11:34:21 INFO - 'LANGUAGE': 'en_US:en', 11:34:21 INFO - 'LOGNAME': 'cltbld', 11:34:21 INFO - 'MAIL': '/var/mail/cltbld', 11:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:21 INFO - 'MOZ_NO_REMOTE': '1', 11:34:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:21 INFO - 'NO_EM_RESTART': '1', 11:34:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:21 INFO - 'PWD': '/builds/slave/test', 11:34:21 INFO - 'SHELL': '/bin/bash', 11:34:21 INFO - 'SHLVL': '1', 11:34:21 INFO - 'TERM': 'linux', 11:34:21 INFO - 'TMOUT': '86400', 11:34:21 INFO - 'USER': 'cltbld', 11:34:21 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:21 INFO - '_': '/tools/buildbot/bin/python'} 11:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:21 INFO - Downloading/unpacking blobuploader==1.2.4 11:34:21 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 11:34:21 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 11:34:21 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 11:34:21 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 11:34:23 INFO - Downloading blobuploader-1.2.4.tar.gz 11:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:34:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:34:23 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 11:34:23 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 11:34:23 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 11:34:23 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 11:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:34:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:34:23 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 11:34:23 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 11:34:23 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 11:34:23 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 11:34:23 INFO - Downloading docopt-0.6.1.tar.gz 11:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:34:23 INFO - Installing collected packages: blobuploader, requests, docopt 11:34:23 INFO - Running setup.py install for blobuploader 11:34:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:34:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:34:23 INFO - Running setup.py install for requests 11:34:24 INFO - Running setup.py install for docopt 11:34:24 INFO - Successfully installed blobuploader requests docopt 11:34:24 INFO - Cleaning up... 11:34:24 INFO - Return code: 0 11:34:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:34:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:24 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:24 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 11:34:24 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 11:34:24 INFO - Using env: {'DISPLAY': ':0', 11:34:24 INFO - 'HOME': '/home/cltbld', 11:34:24 INFO - 'LANG': 'en_US.UTF-8', 11:34:24 INFO - 'LANGUAGE': 'en_US:en', 11:34:24 INFO - 'LOGNAME': 'cltbld', 11:34:24 INFO - 'MAIL': '/var/mail/cltbld', 11:34:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:24 INFO - 'MOZ_NO_REMOTE': '1', 11:34:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:24 INFO - 'NO_EM_RESTART': '1', 11:34:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:24 INFO - 'PWD': '/builds/slave/test', 11:34:24 INFO - 'SHELL': '/bin/bash', 11:34:24 INFO - 'SHLVL': '1', 11:34:24 INFO - 'TERM': 'linux', 11:34:24 INFO - 'TMOUT': '86400', 11:34:24 INFO - 'USER': 'cltbld', 11:34:24 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:24 INFO - '_': '/tools/buildbot/bin/python'} 11:34:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:34:24 INFO - Running setup.py (path:/tmp/pip-TBDYuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:34:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:34:24 INFO - Running setup.py (path:/tmp/pip-Xj4oeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:34:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:34:24 INFO - Running setup.py (path:/tmp/pip-yD2g99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:34:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:34:24 INFO - Running setup.py (path:/tmp/pip-w8m1cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:34:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:34:24 INFO - Running setup.py (path:/tmp/pip-BBjri2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:34:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:34:24 INFO - Running setup.py (path:/tmp/pip-s2wmQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:34:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:34:24 INFO - Running setup.py (path:/tmp/pip-Dz3226-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:34:25 INFO - Running setup.py (path:/tmp/pip-nsmUTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:34:25 INFO - Running setup.py (path:/tmp/pip-ad6s3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:34:25 INFO - Running setup.py (path:/tmp/pip-U7PiM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:34:25 INFO - Running setup.py (path:/tmp/pip-YDUo2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:34:25 INFO - Running setup.py (path:/tmp/pip-OwMTzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:34:25 INFO - Running setup.py (path:/tmp/pip-o6i4Kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:34:25 INFO - Running setup.py (path:/tmp/pip-0w2kag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:34:25 INFO - Running setup.py (path:/tmp/pip-mJl6aK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:34:25 INFO - Running setup.py (path:/tmp/pip-3qqYmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:34:25 INFO - Running setup.py (path:/tmp/pip-rwEg7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:34:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:34:25 INFO - Running setup.py install for manifestparser 11:34:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:34:25 INFO - Running setup.py install for mozcrash 11:34:25 INFO - Running setup.py install for mozdebug 11:34:26 INFO - Running setup.py install for mozdevice 11:34:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:34:26 INFO - Running setup.py install for mozfile 11:34:26 INFO - Running setup.py install for mozhttpd 11:34:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:34:26 INFO - Running setup.py install for mozinfo 11:34:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:34:26 INFO - Running setup.py install for mozInstall 11:34:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:34:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:34:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:34:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:34:26 INFO - Running setup.py install for mozleak 11:34:26 INFO - Running setup.py install for mozlog 11:34:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:34:26 INFO - Running setup.py install for moznetwork 11:34:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:34:26 INFO - Running setup.py install for mozprocess 11:34:26 INFO - Running setup.py install for mozprofile 11:34:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:34:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:34:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:34:27 INFO - Running setup.py install for mozrunner 11:34:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:34:27 INFO - Running setup.py install for mozscreenshot 11:34:27 INFO - Running setup.py install for moztest 11:34:27 INFO - Running setup.py install for mozversion 11:34:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:34:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:34:27 INFO - Cleaning up... 11:34:27 INFO - Return code: 0 11:34:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:34:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:27 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:27 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 11:34:27 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 11:34:27 INFO - Using env: {'DISPLAY': ':0', 11:34:27 INFO - 'HOME': '/home/cltbld', 11:34:27 INFO - 'LANG': 'en_US.UTF-8', 11:34:27 INFO - 'LANGUAGE': 'en_US:en', 11:34:27 INFO - 'LOGNAME': 'cltbld', 11:34:27 INFO - 'MAIL': '/var/mail/cltbld', 11:34:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:27 INFO - 'MOZ_NO_REMOTE': '1', 11:34:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:27 INFO - 'NO_EM_RESTART': '1', 11:34:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:27 INFO - 'PWD': '/builds/slave/test', 11:34:27 INFO - 'SHELL': '/bin/bash', 11:34:27 INFO - 'SHLVL': '1', 11:34:27 INFO - 'TERM': 'linux', 11:34:27 INFO - 'TMOUT': '86400', 11:34:27 INFO - 'USER': 'cltbld', 11:34:27 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:27 INFO - '_': '/tools/buildbot/bin/python'} 11:34:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:34:27 INFO - Running setup.py (path:/tmp/pip-7e3zTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:34:27 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)) 11:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:34:27 INFO - Running setup.py (path:/tmp/pip-N1nF4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:34:27 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)) 11:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:34:27 INFO - Running setup.py (path:/tmp/pip-U6Hx6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:34:27 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)) 11:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:34:27 INFO - Running setup.py (path:/tmp/pip-CXnbgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:34:27 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)) 11:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:34:27 INFO - Running setup.py (path:/tmp/pip-D8WIRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:34:28 INFO - Running setup.py (path:/tmp/pip-Uig4P8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:34:28 INFO - Running setup.py (path:/tmp/pip-3UBS0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:34:28 INFO - Running setup.py (path:/tmp/pip-lTTAbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:34:28 INFO - Running setup.py (path:/tmp/pip-Lzgojv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:34:28 INFO - Running setup.py (path:/tmp/pip-pBQajo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:34:28 INFO - Running setup.py (path:/tmp/pip-Qcx5sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:34:28 INFO - Running setup.py (path:/tmp/pip-XwG85E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:34:28 INFO - Running setup.py (path:/tmp/pip-3A4rg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:34:28 INFO - Running setup.py (path:/tmp/pip-GQ_H_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:34:28 INFO - Running setup.py (path:/tmp/pip-gCuRrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:34:28 INFO - Running setup.py (path:/tmp/pip-4QKFIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:34:28 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)) 11:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:34:28 INFO - Running setup.py (path:/tmp/pip-RAV9_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:34:28 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)) 11:34:28 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)) 11:34:28 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)) 11:34:28 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)) 11:34:28 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)) 11:34:28 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)) 11:34:28 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)) 11:34:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:34:28 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 11:34:28 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 11:34:28 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 11:34:28 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 11:34:30 INFO - Downloading blessings-1.6.tar.gz 11:34:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:34:30 INFO - Installing collected packages: blessings 11:34:30 INFO - Running setup.py install for blessings 11:34:30 INFO - Successfully installed blessings 11:34:30 INFO - Cleaning up... 11:34:30 INFO - Return code: 0 11:34:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:34:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:34:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:34:30 INFO - Reading from file tmpfile_stdout 11:34:30 INFO - Current package versions: 11:34:30 INFO - argparse == 1.2.1 11:34:30 INFO - blessings == 1.6 11:34:30 INFO - blobuploader == 1.2.4 11:34:30 INFO - docopt == 0.6.1 11:34:30 INFO - functools32 == 3.2.3-2 11:34:30 INFO - jsonschema == 2.5.1 11:34:30 INFO - manifestparser == 1.1 11:34:30 INFO - mozInstall == 1.13 11:34:30 INFO - mozcrash == 1.0 11:34:30 INFO - mozdebug == 0.1 11:34:30 INFO - mozdevice == 0.51 11:34:30 INFO - mozfile == 1.2 11:34:30 INFO - mozhttpd == 0.7 11:34:30 INFO - mozinfo == 0.10 11:34:30 INFO - mozleak == 0.1 11:34:30 INFO - mozlog == 3.5 11:34:30 INFO - moznetwork == 0.27 11:34:30 INFO - mozprocess == 0.25 11:34:30 INFO - mozprofile == 0.28 11:34:30 INFO - mozrunner == 6.13 11:34:30 INFO - mozscreenshot == 0.1 11:34:30 INFO - mozsystemmonitor == 0.3 11:34:30 INFO - moztest == 0.8 11:34:30 INFO - mozversion == 1.4 11:34:30 INFO - psutil == 3.1.1 11:34:30 INFO - requests == 1.2.3 11:34:30 INFO - wsgiref == 0.1.2 11:34:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:34:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:30 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 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:30 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 11:34:30 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 11:34:30 INFO - Using env: {'DISPLAY': ':0', 11:34:30 INFO - 'HOME': '/home/cltbld', 11:34:30 INFO - 'LANG': 'en_US.UTF-8', 11:34:30 INFO - 'LANGUAGE': 'en_US:en', 11:34:30 INFO - 'LOGNAME': 'cltbld', 11:34:30 INFO - 'MAIL': '/var/mail/cltbld', 11:34:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:30 INFO - 'MOZ_NO_REMOTE': '1', 11:34:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:30 INFO - 'NO_EM_RESTART': '1', 11:34:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:30 INFO - 'PWD': '/builds/slave/test', 11:34:30 INFO - 'SHELL': '/bin/bash', 11:34:30 INFO - 'SHLVL': '1', 11:34:30 INFO - 'TERM': 'linux', 11:34:30 INFO - 'TMOUT': '86400', 11:34:30 INFO - 'USER': 'cltbld', 11:34:30 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:30 INFO - '_': '/tools/buildbot/bin/python'} 11:34:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 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)) 11:34:30 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:34:30 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 11:34:30 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 11:34:30 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 11:34:30 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 11:34:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:34:31 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)) 11:34:31 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)) 11:34:31 INFO - Installing collected packages: simplejson 11:34:31 INFO - Running setup.py install for simplejson 11:34:31 INFO - building 'simplejson._speedups' extension 11:34:31 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 11:34:32 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 11:34:32 INFO - Successfully installed simplejson 11:34:32 INFO - Cleaning up... 11:34:32 INFO - Return code: 0 11:34:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:34:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:34:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c051f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, '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': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', '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 11:34:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:34:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:34:32 INFO - Using env: {'DISPLAY': ':0', 11:34:32 INFO - 'HOME': '/home/cltbld', 11:34:32 INFO - 'LANG': 'en_US.UTF-8', 11:34:32 INFO - 'LANGUAGE': 'en_US:en', 11:34:32 INFO - 'LOGNAME': 'cltbld', 11:34:32 INFO - 'MAIL': '/var/mail/cltbld', 11:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:32 INFO - 'MOZ_NO_REMOTE': '1', 11:34:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:32 INFO - 'NO_EM_RESTART': '1', 11:34:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:32 INFO - 'PWD': '/builds/slave/test', 11:34:32 INFO - 'SHELL': '/bin/bash', 11:34:32 INFO - 'SHLVL': '1', 11:34:32 INFO - 'TERM': 'linux', 11:34:32 INFO - 'TMOUT': '86400', 11:34:32 INFO - 'USER': 'cltbld', 11:34:32 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:32 INFO - '_': '/tools/buildbot/bin/python'} 11:34:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:34:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:34:33 INFO - Cleaning up... 11:34:33 INFO - Return code: 0 11:34:33 INFO - Running post-action listener: _resource_record_post_action 11:34:33 INFO - Running post-action listener: _start_resource_monitoring 11:34:33 INFO - Starting resource monitoring. 11:34:33 INFO - [mozharness: 2017-09-21 18:34:33.211065Z] Finished create-virtualenv step (success) 11:34:33 INFO - [mozharness: 2017-09-21 18:34:33.211568Z] Running install step. 11:34:33 INFO - Running pre-action listener: _resource_record_pre_action 11:34:33 INFO - Running main action method: install 11:34:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:34:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:34:33 INFO - Reading from file tmpfile_stdout 11:34:33 INFO - Detecting whether we're running mozinstall >=1.0... 11:34:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:34:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:34:33 INFO - Reading from file tmpfile_stdout 11:34:33 INFO - Output received: 11:34:33 INFO - Usage: mozinstall [options] installer 11:34:33 INFO - Options: 11:34:33 INFO - -h, --help show this help message and exit 11:34:33 INFO - -d DEST, --destination=DEST 11:34:33 INFO - Directory to install application into. [default: 11:34:33 INFO - "/builds/slave/test"] 11:34:33 INFO - --app=APP Application being installed. [default: firefox] 11:34:33 INFO - mkdir: /builds/slave/test/build/application 11:34:33 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'] 11:34:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:34:51 INFO - Reading from file tmpfile_stdout 11:34:51 INFO - Output received: 11:34:51 INFO - /builds/slave/test/build/application/firefox/firefox 11:34:51 INFO - Running post-action listener: _resource_record_post_action 11:34:51 INFO - [mozharness: 2017-09-21 18:34:51.344932Z] Finished install step (success) 11:34:51 INFO - [mozharness: 2017-09-21 18:34:51.345076Z] Running setup-mitmproxy step. 11:34:51 INFO - Running pre-action listener: _resource_record_pre_action 11:34:51 INFO - Running main action method: setup_mitmproxy 11:34:51 INFO - Skipping: mitmproxy is not required 11:34:51 INFO - Running post-action listener: _resource_record_post_action 11:34:51 INFO - [mozharness: 2017-09-21 18:34:51.345611Z] Finished setup-mitmproxy step (success) 11:34:51 INFO - [mozharness: 2017-09-21 18:34:51.345715Z] Running run-tests step. 11:34:51 INFO - Running pre-action listener: _resource_record_pre_action 11:34:51 INFO - Running pre-action listener: _set_gcov_prefix 11:34:51 INFO - Running main action method: run_tests 11:34:51 WARNING - Try message not found. 11:34:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:34:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:34:51 INFO - Python 2.7.3 11:34:51 INFO - Return code: 0 11:34:51 INFO - grabbing minidump binary from tooltool 11:34: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')]} 11:34:51 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 0x1f75090>, '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 0x1f75d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6ff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:34:51 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 11:34:51 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 11:34:51 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 11:34:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:34:51 INFO - Return code: 0 11:34:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:34:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:34:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:34:51 INFO - ENV: RUST_BACKTRACE is now full 11:34:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:34:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:34:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build 11:34:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log 11:34:51 INFO - Using env: {'DISPLAY': ':0', 11:34:51 INFO - 'HOME': '/home/cltbld', 11:34:51 INFO - 'LANG': 'en_US.UTF-8', 11:34:51 INFO - 'LANGUAGE': 'en_US:en', 11:34:51 INFO - 'LOGNAME': 'cltbld', 11:34:51 INFO - 'MAIL': '/var/mail/cltbld', 11:34:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:34:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:51 INFO - 'MOZ_NO_REMOTE': '1', 11:34:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:34:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:51 INFO - 'NO_EM_RESTART': '1', 11:34:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:51 INFO - 'PWD': '/builds/slave/test', 11:34:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:34:51 INFO - 'RUST_BACKTRACE': 'full', 11:34:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:34:51 INFO - 'SHELL': '/bin/bash', 11:34:51 INFO - 'SHLVL': '1', 11:34:51 INFO - 'TERM': 'linux', 11:34:51 INFO - 'TMOUT': '86400', 11:34:51 INFO - 'USER': 'cltbld', 11:34:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:51 INFO - '_': '/tools/buildbot/bin/python'} 11:34:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] with output_timeout 3600 11:34:51 INFO - mozversion application_buildid: 20170921174618 11:34:51 INFO - mozversion application_changeset: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 11:34:51 INFO - mozversion application_display_name: Nightly 11:34:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:34:51 INFO - mozversion application_name: Firefox 11:34:51 INFO - mozversion application_remotingname: firefox 11:34:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:34:51 INFO - mozversion application_vendor: Mozilla 11:34:51 INFO - mozversion application_version: 58.0a1 11:34:51 INFO - mozversion platform_buildid: 20170921174618 11:34:51 INFO - mozversion platform_changeset: ca7d18dbacbf103d74a3213d8d08a7c3e4def9a2 11:34:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:34:51 INFO - mozversion platform_version: 58.0a1 11:34:51 INFO - using testdate: 1506018891 11:34:51 INFO - actual date: 1506018891 11:34:51 INFO - starting webserver on 'localhost:58667' 11:34:51 INFO - SUITE-START | Running 2 tests 11:34:51 INFO - TEST-START | dromaeo_css 11:34:51 INFO - Initialising browser for dromaeo_css test... 11:34:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58667/getInfo.html -profile /tmp/tmp4eqHsM/profile 11:34:51 INFO - TEST-INFO | started process 14516 (/builds/slave/test/build/application/firefox/firefox http://localhost:58667/getInfo.html) 11:34:57 INFO - TEST-INFO | 14516: exit 0 11:34:57 INFO - Browser initialized. 11:34:57 INFO - Running cycle 1/1 for dromaeo_css test... 11:34:57 INFO - Using env: {'DISPLAY': ':0', 11:34:57 INFO - 'HOME': '/home/cltbld', 11:34:57 INFO - 'JSGC_DISABLE_POISONING': '1', 11:34:57 INFO - 'LANG': 'en_US.UTF-8', 11:34:57 INFO - 'LANGUAGE': 'en_US:en', 11:34:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:34:57 INFO - 'LOGNAME': 'cltbld', 11:34:57 INFO - 'MAIL': '/var/mail/cltbld', 11:34:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:34:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:34:57 INFO - 'MOZ_CRASHREPORTER': '1', 11:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:34:57 INFO - 'MOZ_NO_REMOTE': '1', 11:34:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:34:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:57 INFO - 'NO_EM_RESTART': '1', 11:34:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:57 INFO - 'PWD': '/builds/slave/test', 11:34:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:34:57 INFO - 'RUST_BACKTRACE': 'full', 11:34:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:34:57 INFO - 'SHELL': '/bin/bash', 11:34:57 INFO - 'SHLVL': '1', 11:34:57 INFO - 'STYLO_FORCE_DISABLED': '1', 11:34:57 INFO - 'TERM': 'linux', 11:34:57 INFO - 'TMOUT': '86400', 11:34:57 INFO - 'USER': 'cltbld', 11:34:57 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:57 INFO - '_': '/tools/buildbot/bin/python'} 11:34:57 INFO - TEST-INFO | started process 14752 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4eqHsM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:34:58 INFO - PID 14752 | 11:34:58 INFO - PID 14752 | (/builds/slave/test/build/application/firefox/firefox:14801): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:34:58 INFO - PID 14752 | 11:35:08 INFO - PID 14752 | RSS: Main: 155258880 11:35:08 INFO - PID 14752 | 11:38:05 INFO - PID 14752 | Cycle 1(1): loaded http://localhost:58667/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58667/tests/dromaeo/cssquery-ext.html) 11:38:07 INFO - PID 14752 | RSS: Main: 155238400 11:38:07 INFO - PID 14752 | 11:41:08 INFO - PID 14752 | Cycle 1(1): loaded http://localhost:58667/tests/dromaeo/cssquery-ext.html (next: http://localhost:58667/tests/dromaeo/cssquery-jquery.html) 11:41:09 INFO - PID 14752 | RSS: Main: 155471872 11:41:09 INFO - PID 14752 | 11:44:11 INFO - PID 14752 | Cycle 1(1): loaded http://localhost:58667/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58667/tests/dromaeo/cssquery-mootools.html) 11:44:11 INFO - PID 14752 | RSS: Main: 158023680 11:44:11 INFO - PID 14752 | 11:47:13 INFO - PID 14752 | Cycle 1(1): loaded http://localhost:58667/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58667/tests/dromaeo/cssquery-prototype.html) 11:47:13 INFO - PID 14752 | RSS: Main: 155738112 11:47:13 INFO - PID 14752 | 11:50:17 INFO - PID 14752 | Cycle 1(1): loaded http://localhost:58667/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58667/tests/dromaeo/cssquery-yui.html) 11:50:17 INFO - PID 14752 | RSS: Main: 155684864 11:50:17 INFO - PID 14752 | 11:53:19 INFO - PID 14752 | Cycle 1(1): loaded http://localhost:58667/tests/dromaeo/cssquery-yui.html (next: http://localhost:58667/tests/dromaeo/cssquery-dojo.html) 11:53:19 INFO - PID 14752 | RSS: Main: 159727616 11:53:19 INFO - PID 14752 | 11:53:19 INFO - PID 14752 | __start_tp_report 11:53:19 INFO - PID 14752 | _x_x_mozilla_page_load 11:53:19 INFO - PID 14752 | _x_x_mozilla_page_load_details 11:53:19 INFO - PID 14752 | |i|pagename|runs| 11:53:19 INFO - PID 14752 | |0;dojo.html;3146.0404576604133;3155.2585142491516;3204.006757905051;3204.3270913108245;3209.8876539321113;6209.565330426872;6358;6378.904316435257;6438.259600145984;6460.353964603537;6332.398422149889;6364.427201551859;6453.741850325993;6513.934860651392;6553.77061802837;5790.7104644767805;5898.056310990253;5971.492423144016;5985.072313791369;6014.789482368118;5899.646021238739;5951.732172052268;6002.909956350658;6016.879662406749;6035.396460353973;5725;5789.681567513785;5882.058870580708;5924.97037514814;5929.762809487615;5811.128330750421;5859.941400585983;5891.970540147272;5912.113183022538;5927.347991720907;5806.506446952014;5849.38581448946;5882.205902203234;5900.881982360372;5929.822105336847;6333.176687030703;6348.904766428507;6401.903971440385;6414.166158399378;6418.19772528434;6818.079559259536;6826.590404575742;6915.170179578483;6931;6941.340572645641;6927.757528486479;6949.791506254821;7017.964910175468;7025.262347453495;7030.261822508665;6256.405641464099;6361.9045714314325;6396.648184349859;6471.935280647227;6509.316521765194;7016.36852683261;7068.434525238072;7069.116360454943;7072;7080.716771329089;6716.261211266757;6722.865542689216;6753.257141714407;6816.48876334267;6869.7595584154315;6781.880989636655;6891.138607674041;6903.0680858083515;6933.514653974275;6963.955406689037;6679.499037572202;6778.966105169443;6828.078209441761;6876.277990810993;6900.689468973807;6797.5581587196675;6829.897551536731;6941.271166527442;6949.583025018515;6965.268646792014;5404.216388623628;5415.323084614423;5473.945260547343;5484.095124304525;5493.478119578634;4727.7163370197895;4760.262159365304;4764;4780.856574302777;4786.210275304606;5664.150377443334;5766.798162064308;5772.163036359789;5796.246487956623;5867.882642347129;6792.558483698544;6809.727610895508;6812.761553345708;6821.249662537117;6822.147231596051;5846.619969701956;5928.170056192136;5933.110033495011;5938.317093534212;5943.643381397043;5877.9118313225335;5907.822765317047;5910.645361278337;5969.492593129633;5983.850403739942;6496.090547323379;6623.138991931114;6660.533762636569;6723;6732.966335168293;6842.55523390968;6849.931500685128;6850.0409942607075;6854.794356169323;6856.760013399507;6527.51043671736;6575.671216439063;6707.228668703162;6712.463002959849;6728.125343705287;6797.93202067973;6878.415334696407;6888.035675005403;6893.414059804973;6894.24163342022;6916.273791251846;6948.305169483011;7009.0537777399395;7027;7032.8593428130125;6452.806415807533;6663.866722665616;6667.099941508029;6679.365460281266;6777.356151165632;6617.7683781067435;6644.169478815148;6732.764353247613;6733.158355205599;6734;6627.204735431779;6658.83352916181;6719.630420326929;6731.730730770714;6734.56225345351;6768.763093291711;6776.186857577122;6842.7605033823575;6864.622445765531;6879.380855723009;6485.221773387224;6488.221413430419;6578.309277525982;6601.570897891814;6603.9339606605245 11:53:19 INFO - PID 14752 | |1;ext.html;879.7932485865821;903.3495882964268;903.7876099116706;904.6336233825301;913.0777914306549;42384.57615423845;43009.35485967709;43023.35464968028;43062.56937430625;43068.784656076714;39003;39304.41043384351;39342;39366;39383.21233575327;7287.27127287271;7967.083785364699;8147.918520814776;8177.959110204471;8195.262426381625;7972.760817175494;8089.312408445289;8089.878651820198;8097.825815256784;8105.513669179839;8050.033995920498;8108.148644392342;8110.756677299691;8122.837543249131;8124.918750812475;8102.675892964304;8134.145914678992;8138.3896207784355;8148.022237331558;8150.3479721622425;7953.562554059525;7971.362291016705;8002.719904803362;8035.075966263896;8035.718749843727;38663;38821.22357552862;38945.80527097346;39122;39136.21727565461;3512.121969507623;3524.4184709522897;3558.5551806024246;3566.892993210208;3567.714582833367;32220.516692249636;32441.837790810896;32473.6752632473;32512.187195320068;32770;5048.545630893201;5211.322528071364;5274.630775845692;5293.04725149473;5303.337082864642;186142;196234;196962;197552;197627;43052.354214686806;50196.74901625468;51429.48570514322;51490.74254628703;51579;54092.729536352854;54183.45816541863;54251.18623220576;54386.45613543893;54392;14651.413943442143;14666.85333146655;14726.116433014053;14747;14862.479813206486;11953.70115747096;12005.219660722025;12015.759684806255;12016.218945768496;12057.457414416373;14649.487267945571;14738.705225895423;14832.480863169738;14850.108993460426;14966.850331496546;5707.344869987657;5764.913526297109;5789.565782566325;5798.420157984167;5806.129080637854;1014.2849291249652;1019.1897441533998;1024.1192574386023;1024.7284469615408;1025.6256466389714;22715;22729.772702272767;22867.656985145237;22897.771022289562;23008.309750707507;1100.290312748289;1106.9723256918642;1120.2774210634095;1120.3054106453887;1123.662901129658;12223.44994475233;12250.754984900252;12335.444904979298;12380.62858114258;12407.875921240853;219314;219686;220211;220577;221405;52220.47779522232;52663.473365266625;55105.448945510834;55682.77268909198;56361.71819140878;1437.6549628089183;1445.2340259662606;1451.4266864588428;1452.8692417682248;1457.176695167227;27256.863715681295;27467.86266068657;27551.448971020065;27723.861380693776;28073;38121.237575248895;38221;38348.61651383562;38368.80815595904;38450.42324365081;195333;195546;195828;195933;196896;30821.8458907713;31542.211444713146;33971.66028339685;33986.66013339934;34565.13587160341;43514.564854351054;43518.12963740771;43642.78178609087;43789;44116;32322.35355292928;32324.030279091665;32334.83832580822;32378.352432950738;32743.672563275017;31330.68669313278;31773.364532708754;31990.52014219835;32162;32184.19539511531;35757.64242357543;35860.64139358573;36010;36054.27891442209;36146.63853361433;31065;31146.532802007536;31150.68849311478;31163.220919477193;31481;23165.536689265784;23367;23487;23898.761012389652;24044.27867163988 11:53:19 INFO - PID 14752 | |2;jquery.html;598.2790737161721;599.6312267955207;600.6966481926625;602.9065494848295;613.3866133866134;22110.336689899308;22193.334199973986;22837.08651653934;22868.88565557174;22877.08491660336;23170.189043383485;23499.882500587515;23643.763562364373;23740.287791366285;23740.762592374114;6757.898631520518;6890;6894.9655251723925;6895.965520172392;6897.206821215549;6696.631685257309;6995.160580730294;7209.423246140296;7328.8167795805;7349.595772232525;7288.817779555527;7291.963540182292;7294.379977701902;7299.233580474053;7299.598522081284;7300.634968251619;7305.598192099405;7305.744298949538;7306.780796576111;7308.378787803043;7162.677679504435;7171.605561694105;7175.533590316612;7177.3899218566485;7177.569345839266;30025.399492010038;30923;31246.843765781254;31500.36999260025;31517.212069698213;2114.4185349028985;2138.5936672032262;2140.486283291999;2141.2826703054498;2141.325482473016;20829.79170208309;21058.47353816167;21179.258725944674;21230.681539776917;21330.680039799416;2453.9509209815706;2517.332906779705;2537.9111731089324;2553.961690574643;2556.75710807473;137694;140995;141082;141509.2924535381;142478;20166.495837604176;20529.281475148597;20862.791372086387;21130.894345528483;22367.328980130296;43092.13815723668;43202.56797432048;43255;43427.13145737131;43465;10661.68014959537;10851.348919064882;10878;10901.1279097672;11015.83476247857;7288.744893928687;7577.673907066242;8856.335774816915;8889.288856891433;9021.684241051531;10903.781924361469;11278.661640150809;11348.978591926767;11537.134714896249;11923.151911453739;3911.843526258918;4073.4113695658716;4138.34466213517;4214.536400995888;4238.554951730086;969.4280374578891;970.3207754571828;983.9016098390103;1000.46475135802;1004.2819384140374;19977.600447990957;19995.500112497302;20124.39626811198;20443.38669839907;20676.759394436383;1023.0894503891542;1049.8687664041995;1052.4211683574003;1059.9576016959236;1068.1294744782977;10822.350658960488;11240.718982025352;11399.772004559862;11429.59996400122;11432.94283528577;469950;474197;475767;476901;489810;107476.46261768641;109886;110206.44896775465;110308.4484577572;111742;1153.5270539078938;1171.988280117205;1175.13040350142;1178.7171078940992;1183.4851839449866;17468.21393037291;17652;18368.816311836712;18411.17149728292;18462.07689615487;56016.43983560112;56697.71651141718;56976.1453578205;57524.71237643785;58312.708436459245;67878.66060669665;68751.31248687449;70163.64918175376;71158.28841711517;72094;17695.64608707793;20075;20913.163473460892;21095.472613184793;21178;23040.769592303863;23186.420339491648;23392.64911026302;23554.29337119889;23572.6464103042;18375;18436.17037233301;18699.439016829518;18925.810741892405;18946.810531895055;16439.424620138238;16575.17124143812;16786.160691965597;17091.914540427217;17224.913875430542;18213.81786182121;18259.178336975132;18833.6233275331;19212.423627291202;19303.131359089155;20122.19511219535;20273;20279.4930126748;20315.187392504726;20409.79590204079;17738.11309434497;18126.456206313833;18244.635107298047;18247.45257642273;18321.450356489328 11:53:19 INFO - PID 14752 | |3;mootools.html;1313.783225767748;1363.4205462678365;1381.7581923163448;1389.0693235532192;1394.693167503149;12776.488940442383;13380.799288010687;13426.530071447502;15279.465218717347;15967.600809979756;15515.689686206297;15835;15840.99999999997;15848.524544263662;15853.365865365371;2831.730985556374;2889.739923406893;2911.7816163787693;2912.8252304861776;2913.8397388143644;2508.4606809535944;2591.06721580231;2900.709929007093;2907.1569244918946;2917.8541072946373;2809.3678922242534;2849.0598102626186;2874.655041395025;2874.726900944407;2875.309925617847;2819.6898341182455;2828.406034732698;2828.7029861864517;2836.064098847396;2840.204742672055;2640.762331390165;2647.1529110684587;2666.653335066429;2668.21287720123;2682.8926842926257;14935;15279.770803437958;15298.847011529853;15305.387784488606;15310.540683779505;7935.285824275843;8210.835783284301;8231.259186673227;8232.135625759329;8259.132791056913;5321.3348331458565;5473.31583552056;5511.944880551223;5540;5637.915431268534;1956.2761778142037;1977.169588772719;2004.0781240629328;2044.6524090904581;2051.6307064728344;26860;27477.862610687018;27553.86223068872;27575;27596.724032759616;7234.819129521698;7455.776326710207;7529.134149572759;7553.8866916996285;7877.842443151105;20845.1661933524;20906.163753449997;20910.163593456396;20914.790852091286;20930.89534552348;7941.324987376138;8108.675652973933;8127.187281271944;8135;8151.714689985822;6246.46905013079;7012.824679383056;7176.892346614805;7299.744508942162;7320.341169294789;5426.620136590401;5982.850428739229;6110.450059494667;6111.724972376165;6126.417990290915;1771.973420398695;1836.3756322850295;1838.512794109562;1838.558745901001;1856.4709057918428;908.0919080919081;913.6802119258207;918.4213945214472;925.5696101312922;927.1701826864918;8420.789480263042;8446.197611226924;8451.788705282417;8469.491830490191;8505.192006759347;985.5318723606373;994.2344394815951;1000.4497526360618;1004.2769206171545;1007.01815729663;4618.214903466419;4656.41794775653;4657.417822772153;4680.859574212779;4722.409698787652;21319.57360852805;21797;22169.77830221677;22216.444588885406;22263.666045009337;20699.8965005174;20907.26824561133;21047.78952210489;21179.8941005294;21181.78818211798;11581.478833452515;11588.130890183102;11684.941575292069;11697.356645384416;11700.414979250832;8044;8148.5518296494265;8186.713465028934;8193.50838949665;8360.74917752444;18330.358437454626;18577.907110464363;18932.242710291433;19156.71264931055;19226.134823932676;19254;19393.224271029;19438.222471101;19439.416817495498;19467.221311147394;12252.081093917814;12384.318862462651;12394.628161154818;12397.93801030989;12440.75118497643;12332.07509436778;12451.875481245072;13973.161610303414;14226.928865355607;14261.144331340152;10990.615328463466;11254.268472549258;11578.189526733047;11713;11790.764184716087;11306.152038597302;11469.139814514112;11526.07791376671;11534.480948357177;11555.075593952632;9287.349885507949;9406.05939406054;9421.293402994666;9441.33910626277;9454.43273403598;6950.721971121098;6979.720811167496;6980.825479363057;6997.965010174917;7018.824529386806;7472.178060413242;7586.544807311579;7678.692852285846;7691.769246922601;7705.691772329044 11:53:19 INFO - PID 14752 | |4;prototype.html;507.084712094669;522.3966318901668;537.0547835808976;542.224618795123;546.3443867359167;6378.585391949526;6429.549931504796;6442.904706199952;6475.158229430168;6478.740850365992;6287.497000239981;6325.873482530358;6341;6363.172787537614;6398.648074355909;5286.365636123671;5288.286081379022;5298.708571028592;5316.229146773716;5351.464853514656;4906.828261010865;5117.462666420028;5181.896362072775;5307.73461326934;5331.493508116742;5027.773750181233;5075.5178258065425;5086.262491938685;5098.796048158069;5099.413567439755;5092.159793634046;5104.821331253425;5107.616928730342;5111.667741596784;5130.461301563338;4461.5315391883705;4569.817207311704;4584.266517357207;4596.241620132674;4619.284010978304;5992.400759924017;6048.69756512177;6067.393260673942;6158.107074474176;6206.937930620681;5755.913661295084;5796.739271684047;5826.446487583673;5834.6790926499025;5920.5559583031445;6560.376764207393;6587.736490540373;6589.759481776219;6627.436667883236;6629;5025.899482010376;5042.798288068473;5047.369078865142;5062.367204099488;5065.924011139836;6741.966290168567;6827.931720682829;6843.247242803337;7024.754133605299;7068.823279418004;4891.804327826847;5077.746112694351;5107;5153.433122356538;5173.224016397495;5170.1986192140275;5174.042802081627;5189.756402248595;5215.739213039333;5218.556422704112;5788.88422231553;5807.88384232313;5853.707314634336;5875.441833025856;5901.291844978544;6690.966545167243;6734.292899245606;6749.797506074826;6776.661166941633;6778;1784.8750587458753;1801.3965321617143;1822.489702883195;1823.2706917232947;1826.0869565217392;1154.2019301128007;1264.4689230523018;1267.6879429790124;1268.0236218112002;1269.149669721289;5723.971380143156;5730.770769169187;5757.769689212385;5770.653760774283;5801.477866991991;6821.4542836572955;6860.725570977205;6860.862782744317;6917.100776899071;6981;5754;5793.536517078624;5795.304563452412;5803.520102373867;5836.270466191726;3451.4650229214008;3517.683408493248;3519.63043880394;3609.620989796086;3616.7449295633614;6464.838379040561;6469.773557925544;6537.515744017243;6539.215294164731;6584.539082264196;6785.389314961677;6913.930860691429;6922.131024623323;6936.34104760047;6938.889777635554;6501.512386571026;6523.739050437929;6546.18172728409;6595.670216489157;6596.868062638816;6793.354631310018;6859.965700171467;6873.247321932875;6907.585544867324;6910.550814197061;6842.897356539556;6872.278410766798;6875.48433867472;6939.514234003571;6976.825579360354;6590.736370545124;6668.466522678271;6678.4235163638905;6694.598324100375;6701.195856497252;5064.696118232918;5115.820946266863;5129.589632829439;5143.794248230179;5174.844754657366;6603.966980165068;6781.11845460087;6867.553609015398;6912.516123871482;6974.58152510851;6320;6600.735970561123;6683.365080317362;6690.464762818866;6700.765473208415;6593.3736295051895;6613.206415230203;6668.333166683293;6670.899936501053;6691.899621505779;6512.088307637029;6532.379423954717;6544.560196756706;6582.76960306387;6598.967005164944;6641.900371494335;6650.268470468148;6658.7336506539195;6695.966520167368;6755.864882702417;6480.189976252968;6509.349065093453;6516.804495865132;6518.217813862367;6528.836779080561 11:53:19 INFO - PID 14752 | |5;yui.html;923.0723123261122;925.2043242811184;946.8011717539316;950.9667161649344;951.9476428796413;19385.903070484645;20091.095900684468;20273.290434834784;20296.69554956675;20322.796772032274;18457.077146142674;18503.35238266661;18593.7210941836;18654.53363665909;18768;897.7396554999059;972.6741541583579;978.9510524473783;981.9312648114635;982.8230918434679;982.2338575910801;995.1839491616877;996.3424140067557;996.6113215845826;996.6362277768599;1008.4100801031406;1032.989670103297;1038.5897570459663;1049.6536143072806;1052.9262951593391;1023.4013102335143;1029;1031.9742006449785;1032.927695061346;1034.0900007992993;750.7034721285062;753.2618034326337;753.6194221917951;755.5089192025173;755.5466719967975;20258.088386022664;20558.38324850257;20859.374218773308;21170.682439763415;21185.576288474298;22193.44516387103;22377.552448951094;22473;22728.772712272832;22729.77270227293;17847.107644617718;17983.370582029565;17985.910070449696;18143.364982225557;18167;3846.673032792216;3871.4386413970096;3876.7092468065007;3876.8061596920325;3877.6316249956208;104722.28749706205;143256.28371858178;144134.2793286027;144506;145664.27167864091;17202.1398930053;17623.295068197127;17660.293588256583;17738.201780919888;17827.10864456772;31925;32698;32736.345273094405;32740.526676299625;32784.50823237653;9256.213221876216;9439.480828554359;9517.857232141523;9533.90466095344;9562.378445401027;4368.5194628590825;4429.512753597101;4450.421445212167;4459.955400446019;4467.419235499429;5561.054620714488;5611.018071837412;5674.319081710221;5687.260656114761;5689.886202275931;1132.5243397772956;1149.9080073594093;1153.7461758413135;1156.1386766858575;1159.4956194055444;599.4694695194697;614.966176860277;619.916311297978;632.7627139822566;634.1407392982535;12098.213616115101;12154.453049612788;12205.145639805129;12443;12729.108962372546;635.9682015899186;636.0522820996682;647.0229952771349;649.9805005849832;652.1912828093131;5471.534919531805;5491.36849262332;5493.7802487900835;5530.2810634618045;5564.415736347706;123096.38451807684;123550.38224808818;127865;129109.35445322713;131240;109471;109782.45108774565;110981.44509277403;116658.41670791592;116662.41668791772;94691;95434;98773.50613246887;99882.5005874966;101826.49086754519;10029.247806414402;10259.640912568024;10289.74275643115;10292.845607315747;10311.175105991353;41988.790056049525;42887.78556107199;43703.125937480436;44028.55971440244;44588.33117503175;56232;57629.71185144047;57705;57705.422945770006;57832.42167578439;15979.920100399424;16375.918120409322;16422.589435264214;16430.424935127692;16474.093924834247;17599.29602815873;17671.558211044827;17704.82295177032;17841;17845.10774461298;15105;15326;15405;15421.768673469682;15435.382584696936;16552.33790648409;16702.916485417496;16789.580260493585;16925.492235232963;16931.746023809406;15313.310901009705;15412.537623871302;15472;15555.222238888236;15579.143147127017;11081.335119892832;11134.443277836237;11263.887361126284;11379.260348077349;11502.712432189262;9766.85349719769;10180.287379883337;10216.846747298945;10292.125169360388;10360.481975901326 11:53:19 INFO - PID 14752 | __end_tp_report 11:53:19 INFO - PID 14752 | __start_cc_report 11:53:19 INFO - PID 14752 | _x_x_mozilla_cycle_collect,266 11:53:19 INFO - PID 14752 | __end_cc_report 11:53:19 INFO - PID 14752 | __startTimestamp1506019999596__endTimestamp 11:53:19 INFO - PID 14752 | 11:53:19 INFO - PID 14752 | ------- Summary: start ------- 11:53:19 INFO - PID 14752 | Number of tests: 6 11:53:19 INFO - PID 14752 | 11:53:19 INFO - PID 14752 | [#0] dojo.html Cycles:165 Average:6323.75 Median:6601.57 stddev:766.19 (11.6%) stddev-sans-first:726.86 11:53:19 INFO - PID 14752 | Values: 3146.0 3155.3 3204.0 3204.3 3209.9 6209.6 6358.0 6378.9 6438.3 6460.4 6332.4 6364.4 6453.7 6513.9 6553.8 5790.7 5898.1 5971.5 5985.1 6014.8 5899.6 5951.7 6002.9 6016.9 6035.4 5725.0 5789.7 5882.1 5925.0 5929.8 5811.1 5859.9 5892.0 5912.1 5927.3 5806.5 5849.4 5882.2 5900.9 5929.8 6333.2 6348.9 6401.9 6414.2 6418.2 6818.1 6826.6 6915.2 6931.0 6941.3 6927.8 6949.8 7018.0 7025.3 7030.3 6256.4 6361.9 6396.6 6471.9 6509.3 7016.4 7068.4 7069.1 7072.0 7080.7 6716.3 6722.9 6753.3 6816.5 6869.8 6781.9 6891.1 6903.1 6933.5 6964.0 6679.5 6779.0 6828.1 6876.3 6900.7 6797.6 6829.9 6941.3 6949.6 6965.3 5404.2 5415.3 5473.9 5484.1 5493.5 4727.7 4760.3 4764.0 4780.9 4786.2 5664.2 5766.8 5772.2 5796.2 5867.9 6792.6 6809.7 6812.8 6821.2 6822.1 5846.6 5928.2 5933.1 5938.3 5943.6 5877.9 5907.8 5910.6 5969.5 5983.9 6496.1 6623.1 6660.5 6723.0 6733.0 6842.6 6849.9 6850.0 6854.8 6856.8 6527.5 6575.7 6707.2 6712.5 6728.1 6797.9 6878.4 6888.0 6893.4 6894.2 6916.3 6948.3 7009.1 7027.0 7032.9 6452.8 6663.9 6667.1 6679.4 6777.4 6617.8 6644.2 6732.8 6733.2 6734.0 6627.2 6658.8 6719.6 6731.7 6734.6 6768.8 6776.2 6842.8 6864.6 6879.4 6485.2 6488.2 6578.3 6601.6 6603.9 11:53:19 INFO - PID 14752 | 11:53:19 INFO - PID 14752 | [#1] ext.html Cycles:180 Average:37682.29 Median:3518.27 stddev:52820.47 (1501.3%) stddev-sans-first:52896.35 11:53:19 INFO - PID 14752 | Values: 879.8 903.3 903.8 904.6 913.1 42384.6 43009.4 43023.4 43062.6 43068.8 39003.0 39304.4 39342.0 39366.0 39383.2 7287.3 7967.1 8147.9 8178.0 8195.3 7972.8 8089.3 8089.9 8097.8 8105.5 8050.0 8108.1 8110.8 8122.8 8124.9 8102.7 8134.1 8138.4 8148.0 8150.3 7953.6 7971.4 8002.7 8035.1 8035.7 38663.0 38821.2 38945.8 39122.0 39136.2 3512.1 3524.4 3558.6 3566.9 3567.7 32220.5 32441.8 32473.7 32512.2 32770.0 5048.5 5211.3 5274.6 5293.0 5303.3 186142.0 196234.0 196962.0 197552.0 197627.0 43052.4 50196.7 51429.5 51490.7 51579.0 54092.7 54183.5 54251.2 54386.5 54392.0 14651.4 14666.9 14726.1 14747.0 14862.5 11953.7 12005.2 12015.8 12016.2 12057.5 14649.5 14738.7 14832.5 14850.1 14966.9 5707.3 5764.9 5789.6 5798.4 5806.1 1014.3 1019.2 1024.1 1024.7 1025.6 22715.0 22729.8 22867.7 22897.8 23008.3 1100.3 1107.0 1120.3 1120.3 1123.7 12223.4 12250.8 12335.4 12380.6 12407.9 219314.0 219686.0 220211.0 220577.0 221405.0 52220.5 52663.5 55105.4 55682.8 56361.7 1437.7 1445.2 1451.4 1452.9 1457.2 27256.9 27467.9 27551.4 27723.9 28073.0 38121.2 38221.0 38348.6 38368.8 38450.4 195333.0 195546.0 195828.0 195933.0 196896.0 30821.8 31542.2 33971.7 33986.7 34565.1 43514.6 43518.1 43642.8 43789.0 44116.0 32322.4 32324.0 32334.8 32378.4 32743.7 31330.7 31773.4 31990.5 32162.0 32184.2 35757.6 35860.6 36010.0 36054.3 36146.6 31065.0 31146.5 31150.7 31163.2 31481.0 23165.5 23367.0 23487.0 23898.8 24044.3 11:53:19 INFO - PID 14752 | 11:53:19 INFO - PID 14752 | [#2] jquery.html Cycles:180 Average:35623.64 Median:12125.83 stddev:80362.59 (662.7%) stddev-sans-first:80545.00 11:53:19 INFO - PID 14752 | Values: 598.3 599.6 600.7 602.9 613.4 22110.3 22193.3 22837.1 22868.9 22877.1 23170.2 23499.9 23643.8 23740.3 23740.8 6757.9 6890.0 6895.0 6896.0 6897.2 6696.6 6995.2 7209.4 7328.8 7349.6 7288.8 7292.0 7294.4 7299.2 7299.6 7300.6 7305.6 7305.7 7306.8 7308.4 7162.7 7171.6 7175.5 7177.4 7177.6 30025.4 30923.0 31246.8 31500.4 31517.2 2114.4 2138.6 2140.5 2141.3 2141.3 20829.8 21058.5 21179.3 21230.7 21330.7 2454.0 2517.3 2537.9 2554.0 2556.8 137694.0 140995.0 141082.0 141509.3 142478.0 20166.5 20529.3 20862.8 21130.9 22367.3 43092.1 43202.6 43255.0 43427.1 43465.0 10661.7 10851.3 10878.0 10901.1 11015.8 7288.7 7577.7 8856.3 8889.3 9021.7 10903.8 11278.7 11349.0 11537.1 11923.2 3911.8 4073.4 4138.3 4214.5 4238.6 969.4 970.3 983.9 1000.5 1004.3 19977.6 19995.5 20124.4 20443.4 20676.8 1023.1 1049.9 1052.4 1060.0 1068.1 10822.4 11240.7 11399.8 11429.6 11432.9 469950.0 474197.0 475767.0 476901.0 489810.0 107476.5 109886.0 110206.4 110308.4 111742.0 1153.5 1172.0 1175.1 1178.7 1183.5 17468.2 17652.0 18368.8 18411.2 18462.1 56016.4 56697.7 56976.1 57524.7 58312.7 67878.7 68751.3 70163.6 71158.3 72094.0 17695.6 20075.0 20913.2 21095.5 21178.0 23040.8 23186.4 23392.6 23554.3 23572.6 18375.0 18436.2 18699.4 18925.8 18946.8 16439.4 16575.2 16786.2 17091.9 17224.9 18213.8 18259.2 18833.6 19212.4 19303.1 20122.2 20273.0 20279.5 20315.2 20409.8 17738.1 18126.5 18244.6 18247.5 18321.5 11:53:19 INFO - PID 14752 | 11:53:19 INFO - PID 14752 | [#3] mootools.html Cycles:180 Average:9550.31 Median:27168.93 stddev:6864.10 (25.3%) stddev-sans-first:6855.46 11:53:19 INFO - PID 14752 | Values: 1313.8 1363.4 1381.8 1389.1 1394.7 12776.5 13380.8 13426.5 15279.5 15967.6 15515.7 15835.0 15841.0 15848.5 15853.4 2831.7 2889.7 2911.8 2912.8 2913.8 2508.5 2591.1 2900.7 2907.2 2917.9 2809.4 2849.1 2874.7 2874.7 2875.3 2819.7 2828.4 2828.7 2836.1 2840.2 2640.8 2647.2 2666.7 2668.2 2682.9 14935.0 15279.8 15298.8 15305.4 15310.5 7935.3 8210.8 8231.3 8232.1 8259.1 5321.3 5473.3 5511.9 5540.0 5637.9 1956.3 1977.2 2004.1 2044.7 2051.6 26860.0 27477.9 27553.9 27575.0 27596.7 7234.8 7455.8 7529.1 7553.9 7877.8 20845.2 20906.2 20910.2 20914.8 20930.9 7941.3 8108.7 8127.2 8135.0 8151.7 6246.5 7012.8 7176.9 7299.7 7320.3 5426.6 5982.9 6110.5 6111.7 6126.4 1772.0 1836.4 1838.5 1838.6 1856.5 908.1 913.7 918.4 925.6 927.2 8420.8 8446.2 8451.8 8469.5 8505.2 985.5 994.2 1000.4 1004.3 1007.0 4618.2 4656.4 4657.4 4680.9 4722.4 21319.6 21797.0 22169.8 22216.4 22263.7 20699.9 20907.3 21047.8 21179.9 21181.8 11581.5 11588.1 11684.9 11697.4 11700.4 8044.0 8148.6 8186.7 8193.5 8360.7 18330.4 18577.9 18932.2 19156.7 19226.1 19254.0 19393.2 19438.2 19439.4 19467.2 12252.1 12384.3 12394.6 12397.9 12440.8 12332.1 12451.9 13973.2 14226.9 14261.1 10990.6 11254.3 11578.2 11713.0 11790.8 11306.2 11469.1 11526.1 11534.5 11555.1 9287.3 9406.1 9421.3 9441.3 9454.4 6950.7 6979.7 6980.8 6998.0 7018.8 7472.2 7586.5 7678.7 7691.8 7705.7 11:53:19 INFO - PID 14752 | 11:53:19 INFO - PID 14752 | [#4] prototype.html Cycles:180 Average:5618.81 Median:6303.75 stddev:1567.14 (24.9%) stddev-sans-first:1523.85 11:53:19 INFO - PID 14752 | Values: 507.1 522.4 537.1 542.2 546.3 6378.6 6429.5 6442.9 6475.2 6478.7 6287.5 6325.9 6341.0 6363.2 6398.6 5286.4 5288.3 5298.7 5316.2 5351.5 4906.8 5117.5 5181.9 5307.7 5331.5 5027.8 5075.5 5086.3 5098.8 5099.4 5092.2 5104.8 5107.6 5111.7 5130.5 4461.5 4569.8 4584.3 4596.2 4619.3 5992.4 6048.7 6067.4 6158.1 6206.9 5755.9 5796.7 5826.4 5834.7 5920.6 6560.4 6587.7 6589.8 6627.4 6629.0 5025.9 5042.8 5047.4 5062.4 5065.9 6742.0 6827.9 6843.2 7024.8 7068.8 4891.8 5077.7 5107.0 5153.4 5173.2 5170.2 5174.0 5189.8 5215.7 5218.6 5788.9 5807.9 5853.7 5875.4 5901.3 6691.0 6734.3 6749.8 6776.7 6778.0 1784.9 1801.4 1822.5 1823.3 1826.1 1154.2 1264.5 1267.7 1268.0 1269.1 5724.0 5730.8 5757.8 5770.7 5801.5 6821.5 6860.7 6860.9 6917.1 6981.0 5754.0 5793.5 5795.3 5803.5 5836.3 3451.5 3517.7 3519.6 3609.6 3616.7 6464.8 6469.8 6537.5 6539.2 6584.5 6785.4 6913.9 6922.1 6936.3 6938.9 6501.5 6523.7 6546.2 6595.7 6596.9 6793.4 6860.0 6873.2 6907.6 6910.6 6842.9 6872.3 6875.5 6939.5 6976.8 6590.7 6668.5 6678.4 6694.6 6701.2 5064.7 5115.8 5129.6 5143.8 5174.8 6604.0 6781.1 6867.6 6912.5 6974.6 6320.0 6600.7 6683.4 6690.5 6700.8 6593.4 6613.2 6668.3 6670.9 6691.9 6512.1 6532.4 6544.6 6582.8 6599.0 6641.9 6650.3 6658.7 6696.0 6755.9 6480.2 6509.3 6516.8 6518.2 6528.8 11:53:19 INFO - PID 14752 | 11:53:19 INFO - PID 14752 | [#5] yui.html Cycles:180 Average:24667.12 Median:19738.50 stddev:35860.63 (181.7%) stddev-sans-first:35916.91 11:53:19 INFO - PID 14752 | Values: 923.1 925.2 946.8 951.0 951.9 19385.9 20091.1 20273.3 20296.7 20322.8 18457.1 18503.4 18593.7 18654.5 18768.0 897.7 972.7 979.0 981.9 982.8 982.2 995.2 996.3 996.6 996.6 1008.4 1033.0 1038.6 1049.7 1052.9 1023.4 1029.0 1032.0 1032.9 1034.1 750.7 753.3 753.6 755.5 755.5 20258.1 20558.4 20859.4 21170.7 21185.6 22193.4 22377.6 22473.0 22728.8 22729.8 17847.1 17983.4 17985.9 18143.4 18167.0 3846.7 3871.4 3876.7 3876.8 3877.6 104722.3 143256.3 144134.3 144506.0 145664.3 17202.1 17623.3 17660.3 17738.2 17827.1 31925.0 32698.0 32736.3 32740.5 32784.5 9256.2 9439.5 9517.9 9533.9 9562.4 4368.5 4429.5 4450.4 4460.0 4467.4 5561.1 5611.0 5674.3 5687.3 5689.9 1132.5 1149.9 1153.7 1156.1 1159.5 599.5 615.0 619.9 632.8 634.1 12098.2 12154.5 12205.1 12443.0 12729.1 636.0 636.1 647.0 650.0 652.2 5471.5 5491.4 5493.8 5530.3 5564.4 123096.4 123550.4 127865.0 129109.4 131240.0 109471.0 109782.5 110981.4 116658.4 116662.4 94691.0 95434.0 98773.5 99882.5 101826.5 10029.2 10259.6 10289.7 10292.8 10311.2 41988.8 42887.8 43703.1 44028.6 44588.3 56232.0 57629.7 57705.0 57705.4 57832.4 15979.9 16375.9 16422.6 16430.4 16474.1 17599.3 17671.6 17704.8 17841.0 17845.1 15105.0 15326.0 15405.0 15421.8 15435.4 16552.3 16702.9 16789.6 16925.5 16931.7 15313.3 15412.5 15472.0 15555.2 15579.1 11081.3 11134.4 11263.9 11379.3 11502.7 9766.9 10180.3 10216.8 10292.1 10360.5 11:53:19 INFO - PID 14752 | -------- Summary: end -------- 11:53:19 INFO - PID 14752 | 11:53:20 INFO - TEST-INFO | 14752: exit 0 11:53:20 INFO - TEST-OK | dromaeo_css | took 1108593ms 11:53:20 INFO - TEST-START | kraken 11:53:20 INFO - Initialising browser for kraken test... 11:53:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58667/getInfo.html -profile /tmp/tmpSrE4dN/profile 11:53:20 INFO - TEST-INFO | started process 15119 (/builds/slave/test/build/application/firefox/firefox http://localhost:58667/getInfo.html) 11:53:24 INFO - TEST-INFO | 15119: exit 0 11:53:25 INFO - Browser initialized. 11:53:25 INFO - Running cycle 1/1 for kraken test... 11:53:25 INFO - Using env: {'DISPLAY': ':0', 11:53:25 INFO - 'HOME': '/home/cltbld', 11:53:25 INFO - 'JSGC_DISABLE_POISONING': '1', 11:53:25 INFO - 'LANG': 'en_US.UTF-8', 11:53:25 INFO - 'LANGUAGE': 'en_US:en', 11:53:25 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:53:25 INFO - 'LOGNAME': 'cltbld', 11:53:25 INFO - 'MAIL': '/var/mail/cltbld', 11:53:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:53:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:53:25 INFO - 'MOZ_CRASHREPORTER': '1', 11:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:25 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:53:25 INFO - 'MOZ_NO_REMOTE': '1', 11:53:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:53:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:25 INFO - 'NO_EM_RESTART': '1', 11:53:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:25 INFO - 'PWD': '/builds/slave/test', 11:53:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:53:25 INFO - 'RUST_BACKTRACE': 'full', 11:53:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:53:25 INFO - 'SHELL': '/bin/bash', 11:53:25 INFO - 'SHLVL': '1', 11:53:25 INFO - 'STYLO_FORCE_DISABLED': '1', 11:53:25 INFO - 'TERM': 'linux', 11:53:25 INFO - 'TMOUT': '86400', 11:53:25 INFO - 'USER': 'cltbld', 11:53:25 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598', 11:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:25 INFO - '_': '/tools/buildbot/bin/python'} 11:53:25 INFO - TEST-INFO | started process 15350 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSrE4dN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:53:25 INFO - PID 15350 | 11:53:25 INFO - PID 15350 | (/builds/slave/test/build/application/firefox/firefox:15400): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:53:25 INFO - PID 15350 | 11:53:36 INFO - PID 15350 | RSS: Main: 155713536 11:53:36 INFO - PID 15350 | 11:53:38 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58667/tests/kraken/driver.html?testName=audio-beat-detection) 11:53:39 INFO - PID 15350 | RSS: Main: 161411072 11:53:39 INFO - PID 15350 | 11:53:42 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58667/tests/kraken/driver.html?testName=audio-dft) 11:53:43 INFO - PID 15350 | RSS: Main: 161402880 11:53:43 INFO - PID 15350 | 11:53:46 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58667/tests/kraken/driver.html?testName=audio-fft) 11:53:46 INFO - PID 15350 | RSS: Main: 158363648 11:53:46 INFO - PID 15350 | 11:53:48 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58667/tests/kraken/driver.html?testName=audio-oscillator) 11:53:49 INFO - PID 15350 | RSS: Main: 158932992 11:53:49 INFO - PID 15350 | 11:53:51 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58667/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:53:51 INFO - PID 15350 | RSS: Main: 159088640 11:53:51 INFO - PID 15350 | 11:53:55 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58667/tests/kraken/driver.html?testName=imaging-darkroom) 11:53:55 INFO - PID 15350 | RSS: Main: 162238464 11:53:55 INFO - PID 15350 | 11:53:59 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58667/tests/kraken/driver.html?testName=imaging-desaturate) 11:54:00 INFO - PID 15350 | RSS: Main: 161587200 11:54:00 INFO - PID 15350 | 11:54:03 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58667/tests/kraken/driver.html?testName=json-parse-financial) 11:54:03 INFO - PID 15350 | RSS: Main: 162295808 11:54:03 INFO - PID 15350 | 11:54:05 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58667/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:54:05 INFO - PID 15350 | RSS: Main: 162246656 11:54:05 INFO - PID 15350 | 11:54:07 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58667/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:54:08 INFO - PID 15350 | RSS: Main: 162295808 11:54:08 INFO - PID 15350 | 11:54:10 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58667/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:54:10 INFO - PID 15350 | RSS: Main: 159739904 11:54:10 INFO - PID 15350 | 11:54:13 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:54:13 INFO - PID 15350 | RSS: Main: 159485952 11:54:13 INFO - PID 15350 | 11:54:16 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:54:17 INFO - PID 15350 | RSS: Main: 158887936 11:54:17 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | Cycle 1(1): loaded http://localhost:58667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58667/tests/kraken/driver.html?testName=ai-astar) 11:54:19 INFO - PID 15350 | RSS: Main: 161034240 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | __start_tp_report 11:54:19 INFO - PID 15350 | _x_x_mozilla_page_load 11:54:19 INFO - PID 15350 | _x_x_mozilla_page_load_details 11:54:19 INFO - PID 15350 | |i|pagename|runs| 11:54:19 INFO - PID 15350 | |0;ai-astar;106;107;109;108;117;106;110;106;108;107 11:54:19 INFO - PID 15350 | |1;audio-beat-detection;118;116;115;117;143;118;116;120;117;117 11:54:19 INFO - PID 15350 | |2;audio-dft;162;161;163;162;163;162;163;165;165;163 11:54:19 INFO - PID 15350 | |3;audio-fft;75;77;73;72;76;72;75;75;75;75 11:54:19 INFO - PID 15350 | |4;audio-oscillator;79;80;79;78;78;79;80;128;80;82 11:54:19 INFO - PID 15350 | |5;imaging-gaussian-blur;113;114;113;114;118;110;113;114;113;113 11:54:19 INFO - PID 15350 | |6;imaging-darkroom;156;147;147;148;146;147;147;149;149;148 11:54:19 INFO - PID 15350 | |7;imaging-desaturate;99;99;94;93;94;93;94;95;94;95 11:54:19 INFO - PID 15350 | |8;json-parse-financial;52;51;54;53;52;53;53;53;52;51 11:54:19 INFO - PID 15350 | |9;json-stringify-tinderbox;40;38;38;38;39;45;38;39;39;38 11:54:19 INFO - PID 15350 | |10;stanford-crypto-aes;78;72;80;72;72;74;72;72;73;73 11:54:19 INFO - PID 15350 | |11;stanford-crypto-ccm;111;118;112;108;116;112;118;117;114;120 11:54:19 INFO - PID 15350 | |12;stanford-crypto-pbkdf2;171;170;171;164;163;165;164;171;177;168 11:54:19 INFO - PID 15350 | |13;stanford-crypto-sha256-iterative;68;64;65;68;68;67;66;66;69;67 11:54:19 INFO - PID 15350 | __end_tp_report 11:54:19 INFO - PID 15350 | __start_cc_report 11:54:19 INFO - PID 15350 | _x_x_mozilla_cycle_collect,727 11:54:19 INFO - PID 15350 | __end_cc_report 11:54:19 INFO - PID 15350 | __startTimestamp1506020059510__endTimestamp 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | ------- Summary: start ------- 11:54:19 INFO - PID 15350 | Number of tests: 14 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#0] ai-astar Cycles:10 Average:108.40 Median:108.00 stddev:3.31 (3.1%) stddev-sans-first:3.39 11:54:19 INFO - PID 15350 | Values: 106.0 107.0 109.0 108.0 117.0 106.0 110.0 106.0 108.0 107.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#1] audio-beat-detection Cycles:10 Average:119.70 Median:117.50 stddev:8.30 (7.1%) stddev-sans-first:8.78 11:54:19 INFO - PID 15350 | Values: 118.0 116.0 115.0 117.0 143.0 118.0 116.0 120.0 117.0 117.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:1.29 (0.8%) stddev-sans-first:1.32 11:54:19 INFO - PID 15350 | Values: 162.0 161.0 163.0 162.0 163.0 162.0 163.0 165.0 165.0 163.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#3] audio-fft Cycles:10 Average:74.50 Median:75.00 stddev:1.65 (2.2%) stddev-sans-first:1.74 11:54:19 INFO - PID 15350 | Values: 75.0 77.0 73.0 72.0 76.0 72.0 75.0 75.0 75.0 75.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:79.50 stddev:15.40 (19.4%) stddev-sans-first:16.21 11:54:19 INFO - PID 15350 | Values: 79.0 80.0 79.0 78.0 78.0 79.0 80.0 128.0 80.0 82.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:113.50 stddev:1.96 (1.7%) stddev-sans-first:2.07 11:54:19 INFO - PID 15350 | Values: 113.0 114.0 113.0 114.0 118.0 110.0 113.0 114.0 113.0 113.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.00 stddev:2.84 (1.9%) stddev-sans-first:1.01 11:54:19 INFO - PID 15350 | Values: 156.0 147.0 147.0 148.0 146.0 147.0 147.0 149.0 149.0 148.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:94.50 stddev:2.21 (2.3%) stddev-sans-first:1.81 11:54:19 INFO - PID 15350 | Values: 99.0 99.0 94.0 93.0 94.0 93.0 94.0 95.0 94.0 95.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:53.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 11:54:19 INFO - PID 15350 | Values: 52.0 51.0 54.0 53.0 52.0 53.0 53.0 53.0 52.0 51.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#9] json-stringify-tinderbox Cycles:10 Average:39.20 Median:39.00 stddev:2.15 (5.5%) stddev-sans-first:2.26 11:54:19 INFO - PID 15350 | Values: 40.0 38.0 38.0 38.0 39.0 45.0 38.0 39.0 39.0 38.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:73.00 stddev:2.86 (3.9%) stddev-sans-first:2.60 11:54:19 INFO - PID 15350 | Values: 78.0 72.0 80.0 72.0 72.0 74.0 72.0 72.0 73.0 73.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#11] stanford-crypto-ccm Cycles:10 Average:114.60 Median:116.50 stddev:3.81 (3.3%) stddev-sans-first:3.81 11:54:19 INFO - PID 15350 | Values: 111.0 118.0 112.0 108.0 116.0 112.0 118.0 117.0 114.0 120.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:170.50 stddev:4.43 (2.6%) stddev-sans-first:4.59 11:54:19 INFO - PID 15350 | Values: 171.0 170.0 171.0 164.0 163.0 165.0 164.0 171.0 177.0 168.0 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:1.55 (2.3%) stddev-sans-first:1.58 11:54:19 INFO - PID 15350 | Values: 68.0 64.0 65.0 68.0 68.0 67.0 66.0 66.0 69.0 67.0 11:54:19 INFO - PID 15350 | -------- Summary: end -------- 11:54:19 INFO - PID 15350 | 11:54:19 INFO - PID 15350 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:54:20 INFO - TEST-INFO | 15350: exit 0 11:54:20 INFO - TEST-OK | kraken | took 59840ms 11:54:20 INFO - SUITE-END | took 1168s 11:54:20 INFO - Completed test suite (00:19:28) 11:54:20 INFO - javascript benchmark 11:54:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8757.34234456276, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.0404576604133, 3155.2585142491516, 3204.006757905051, 3204.3270913108245, 3209.8876539321113, 6209.565330426872, 6358.0, 6378.904316435257, 6438.259600145984, 6460.353964603537, 6332.398422149889, 6364.427201551859, 6453.741850325993, 6513.934860651392, 6553.77061802837, 5790.7104644767805, 5898.056310990253, 5971.492423144016, 5985.072313791369, 6014.789482368118, 5899.646021238739, 5951.732172052268, 6002.909956350658, 6016.879662406749, 6035.396460353973, 5725.0, 5789.681567513785, 5882.058870580708, 5924.97037514814, 5929.762809487615, 5811.128330750421, 5859.941400585983, 5891.970540147272, 5912.113183022538, 5927.347991720907, 5806.506446952014, 5849.38581448946, 5882.205902203234, 5900.881982360372, 5929.822105336847, 6333.176687030703, 6348.904766428507, 6401.903971440385, 6414.166158399378, 6418.19772528434, 6818.079559259536, 6826.590404575742, 6915.170179578483, 6931.0, 6941.340572645641, 6927.757528486479, 6949.791506254821, 7017.964910175468, 7025.262347453495, 7030.261822508665, 6256.405641464099, 6361.9045714314325, 6396.648184349859, 6471.935280647227, 6509.316521765194, 7016.36852683261, 7068.434525238072, 7069.116360454943, 7072.0, 7080.716771329089, 6716.261211266757, 6722.865542689216, 6753.257141714407, 6816.48876334267, 6869.7595584154315, 6781.880989636655, 6891.138607674041, 6903.0680858083515, 6933.514653974275, 6963.955406689037, 6679.499037572202, 6778.966105169443, 6828.078209441761, 6876.277990810993, 6900.689468973807, 6797.5581587196675, 6829.897551536731, 6941.271166527442, 6949.583025018515, 6965.268646792014, 5404.216388623628, 5415.323084614423, 5473.945260547343, 5484.095124304525, 5493.478119578634, 4727.7163370197895, 4760.262159365304, 4764.0, 4780.856574302777, 4786.210275304606, 5664.150377443334, 5766.798162064308, 5772.163036359789, 5796.246487956623, 5867.882642347129, 6792.558483698544, 6809.727610895508, 6812.761553345708, 6821.249662537117, 6822.147231596051, 5846.619969701956, 5928.170056192136, 5933.110033495011, 5938.317093534212, 5943.643381397043, 5877.9118313225335, 5907.822765317047, 5910.645361278337, 5969.492593129633, 5983.850403739942, 6496.090547323379, 6623.138991931114, 6660.533762636569, 6723.0, 6732.966335168293, 6842.55523390968, 6849.931500685128, 6850.0409942607075, 6854.794356169323, 6856.760013399507, 6527.51043671736, 6575.671216439063, 6707.228668703162, 6712.463002959849, 6728.125343705287, 6797.93202067973, 6878.415334696407, 6888.035675005403, 6893.414059804973, 6894.24163342022, 6916.273791251846, 6948.305169483011, 7009.0537777399395, 7027.0, 7032.8593428130125, 6452.806415807533, 6663.866722665616, 6667.099941508029, 6679.365460281266, 6777.356151165632, 6617.7683781067435, 6644.169478815148, 6732.764353247613, 6733.158355205599, 6734.0, 6627.204735431779, 6658.83352916181, 6719.630420326929, 6731.730730770714, 6734.56225345351, 6768.763093291711, 6776.186857577122, 6842.7605033823575, 6864.622445765531, 6879.380855723009, 6485.221773387224, 6488.221413430419, 6578.309277525982, 6601.570897891814, 6603.9339606605245], "value": 6264.503200302135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [879.7932485865821, 903.3495882964268, 903.7876099116706, 904.6336233825301, 913.0777914306549, 42384.57615423845, 43009.35485967709, 43023.35464968028, 43062.56937430625, 43068.784656076714, 39003.0, 39304.41043384351, 39342.0, 39366.0, 39383.21233575327, 7287.27127287271, 7967.083785364699, 8147.918520814776, 8177.959110204471, 8195.262426381625, 7972.760817175494, 8089.312408445289, 8089.878651820198, 8097.825815256784, 8105.513669179839, 8050.033995920498, 8108.148644392342, 8110.756677299691, 8122.837543249131, 8124.918750812475, 8102.675892964304, 8134.145914678992, 8138.3896207784355, 8148.022237331558, 8150.3479721622425, 7953.562554059525, 7971.362291016705, 8002.719904803362, 8035.075966263896, 8035.718749843727, 38663.0, 38821.22357552862, 38945.80527097346, 39122.0, 39136.21727565461, 3512.121969507623, 3524.4184709522897, 3558.5551806024246, 3566.892993210208, 3567.714582833367, 32220.516692249636, 32441.837790810896, 32473.6752632473, 32512.187195320068, 32770.0, 5048.545630893201, 5211.322528071364, 5274.630775845692, 5293.04725149473, 5303.337082864642, 186142.0, 196234.0, 196962.0, 197552.0, 197627.0, 43052.354214686806, 50196.74901625468, 51429.48570514322, 51490.74254628703, 51579.0, 54092.729536352854, 54183.45816541863, 54251.18623220576, 54386.45613543893, 54392.0, 14651.413943442143, 14666.85333146655, 14726.116433014053, 14747.0, 14862.479813206486, 11953.70115747096, 12005.219660722025, 12015.759684806255, 12016.218945768496, 12057.457414416373, 14649.487267945571, 14738.705225895423, 14832.480863169738, 14850.108993460426, 14966.850331496546, 5707.344869987657, 5764.913526297109, 5789.565782566325, 5798.420157984167, 5806.129080637854, 1014.2849291249652, 1019.1897441533998, 1024.1192574386023, 1024.7284469615408, 1025.6256466389714, 22715.0, 22729.772702272767, 22867.656985145237, 22897.771022289562, 23008.309750707507, 1100.290312748289, 1106.9723256918642, 1120.2774210634095, 1120.3054106453887, 1123.662901129658, 12223.44994475233, 12250.754984900252, 12335.444904979298, 12380.62858114258, 12407.875921240853, 219314.0, 219686.0, 220211.0, 220577.0, 221405.0, 52220.47779522232, 52663.473365266625, 55105.448945510834, 55682.77268909198, 56361.71819140878, 1437.6549628089183, 1445.2340259662606, 1451.4266864588428, 1452.8692417682248, 1457.176695167227, 27256.863715681295, 27467.86266068657, 27551.448971020065, 27723.861380693776, 28073.0, 38121.237575248895, 38221.0, 38348.61651383562, 38368.80815595904, 38450.42324365081, 195333.0, 195546.0, 195828.0, 195933.0, 196896.0, 30821.8458907713, 31542.211444713146, 33971.66028339685, 33986.66013339934, 34565.13587160341, 43514.564854351054, 43518.12963740771, 43642.78178609087, 43789.0, 44116.0, 32322.35355292928, 32324.030279091665, 32334.83832580822, 32378.352432950738, 32743.672563275017, 31330.68669313278, 31773.364532708754, 31990.52014219835, 32162.0, 32184.19539511531, 35757.64242357543, 35860.64139358573, 36010.0, 36054.27891442209, 36146.63853361433, 31065.0, 31146.532802007536, 31150.68849311478, 31163.220919477193, 31481.0, 23165.536689265784, 23367.0, 23487.0, 23898.761012389652, 24044.27867163988], "value": 17377.344852195776, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.2790737161721, 599.6312267955207, 600.6966481926625, 602.9065494848295, 613.3866133866134, 22110.336689899308, 22193.334199973986, 22837.08651653934, 22868.88565557174, 22877.08491660336, 23170.189043383485, 23499.882500587515, 23643.763562364373, 23740.287791366285, 23740.762592374114, 6757.898631520518, 6890.0, 6894.9655251723925, 6895.965520172392, 6897.206821215549, 6696.631685257309, 6995.160580730294, 7209.423246140296, 7328.8167795805, 7349.595772232525, 7288.817779555527, 7291.963540182292, 7294.379977701902, 7299.233580474053, 7299.598522081284, 7300.634968251619, 7305.598192099405, 7305.744298949538, 7306.780796576111, 7308.378787803043, 7162.677679504435, 7171.605561694105, 7175.533590316612, 7177.3899218566485, 7177.569345839266, 30025.399492010038, 30923.0, 31246.843765781254, 31500.36999260025, 31517.212069698213, 2114.4185349028985, 2138.5936672032262, 2140.486283291999, 2141.2826703054498, 2141.325482473016, 20829.79170208309, 21058.47353816167, 21179.258725944674, 21230.681539776917, 21330.680039799416, 2453.9509209815706, 2517.332906779705, 2537.9111731089324, 2553.961690574643, 2556.75710807473, 137694.0, 140995.0, 141082.0, 141509.2924535381, 142478.0, 20166.495837604176, 20529.281475148597, 20862.791372086387, 21130.894345528483, 22367.328980130296, 43092.13815723668, 43202.56797432048, 43255.0, 43427.13145737131, 43465.0, 10661.68014959537, 10851.348919064882, 10878.0, 10901.1279097672, 11015.83476247857, 7288.744893928687, 7577.673907066242, 8856.335774816915, 8889.288856891433, 9021.684241051531, 10903.781924361469, 11278.661640150809, 11348.978591926767, 11537.134714896249, 11923.151911453739, 3911.843526258918, 4073.4113695658716, 4138.34466213517, 4214.536400995888, 4238.554951730086, 969.4280374578891, 970.3207754571828, 983.9016098390103, 1000.46475135802, 1004.2819384140374, 19977.600447990957, 19995.500112497302, 20124.39626811198, 20443.38669839907, 20676.759394436383, 1023.0894503891542, 1049.8687664041995, 1052.4211683574003, 1059.9576016959236, 1068.1294744782977, 10822.350658960488, 11240.718982025352, 11399.772004559862, 11429.59996400122, 11432.94283528577, 469950.0, 474197.0, 475767.0, 476901.0, 489810.0, 107476.46261768641, 109886.0, 110206.44896775465, 110308.4484577572, 111742.0, 1153.5270539078938, 1171.988280117205, 1175.13040350142, 1178.7171078940992, 1183.4851839449866, 17468.21393037291, 17652.0, 18368.816311836712, 18411.17149728292, 18462.07689615487, 56016.43983560112, 56697.71651141718, 56976.1453578205, 57524.71237643785, 58312.708436459245, 67878.66060669665, 68751.31248687449, 70163.64918175376, 71158.28841711517, 72094.0, 17695.64608707793, 20075.0, 20913.163473460892, 21095.472613184793, 21178.0, 23040.769592303863, 23186.420339491648, 23392.64911026302, 23554.29337119889, 23572.6464103042, 18375.0, 18436.17037233301, 18699.439016829518, 18925.810741892405, 18946.810531895055, 16439.424620138238, 16575.17124143812, 16786.160691965597, 17091.914540427217, 17224.913875430542, 18213.81786182121, 18259.178336975132, 18833.6233275331, 19212.423627291202, 19303.131359089155, 20122.19511219535, 20273.0, 20279.4930126748, 20315.187392504726, 20409.79590204079, 17738.11309434497, 18126.456206313833, 18244.635107298047, 18247.45257642273, 18321.450356489328], "value": 12992.91455156967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.783225767748, 1363.4205462678365, 1381.7581923163448, 1389.0693235532192, 1394.693167503149, 12776.488940442383, 13380.799288010687, 13426.530071447502, 15279.465218717347, 15967.600809979756, 15515.689686206297, 15835.0, 15840.99999999997, 15848.524544263662, 15853.365865365371, 2831.730985556374, 2889.739923406893, 2911.7816163787693, 2912.8252304861776, 2913.8397388143644, 2508.4606809535944, 2591.06721580231, 2900.709929007093, 2907.1569244918946, 2917.8541072946373, 2809.3678922242534, 2849.0598102626186, 2874.655041395025, 2874.726900944407, 2875.309925617847, 2819.6898341182455, 2828.406034732698, 2828.7029861864517, 2836.064098847396, 2840.204742672055, 2640.762331390165, 2647.1529110684587, 2666.653335066429, 2668.21287720123, 2682.8926842926257, 14935.0, 15279.770803437958, 15298.847011529853, 15305.387784488606, 15310.540683779505, 7935.285824275843, 8210.835783284301, 8231.259186673227, 8232.135625759329, 8259.132791056913, 5321.3348331458565, 5473.31583552056, 5511.944880551223, 5540.0, 5637.915431268534, 1956.2761778142037, 1977.169588772719, 2004.0781240629328, 2044.6524090904581, 2051.6307064728344, 26860.0, 27477.862610687018, 27553.86223068872, 27575.0, 27596.724032759616, 7234.819129521698, 7455.776326710207, 7529.134149572759, 7553.8866916996285, 7877.842443151105, 20845.1661933524, 20906.163753449997, 20910.163593456396, 20914.790852091286, 20930.89534552348, 7941.324987376138, 8108.675652973933, 8127.187281271944, 8135.0, 8151.714689985822, 6246.46905013079, 7012.824679383056, 7176.892346614805, 7299.744508942162, 7320.341169294789, 5426.620136590401, 5982.850428739229, 6110.450059494667, 6111.724972376165, 6126.417990290915, 1771.973420398695, 1836.3756322850295, 1838.512794109562, 1838.558745901001, 1856.4709057918428, 908.0919080919081, 913.6802119258207, 918.4213945214472, 925.5696101312922, 927.1701826864918, 8420.789480263042, 8446.197611226924, 8451.788705282417, 8469.491830490191, 8505.192006759347, 985.5318723606373, 994.2344394815951, 1000.4497526360618, 1004.2769206171545, 1007.01815729663, 4618.214903466419, 4656.41794775653, 4657.417822772153, 4680.859574212779, 4722.409698787652, 21319.57360852805, 21797.0, 22169.77830221677, 22216.444588885406, 22263.666045009337, 20699.8965005174, 20907.26824561133, 21047.78952210489, 21179.8941005294, 21181.78818211798, 11581.478833452515, 11588.130890183102, 11684.941575292069, 11697.356645384416, 11700.414979250832, 8044.0, 8148.5518296494265, 8186.713465028934, 8193.50838949665, 8360.74917752444, 18330.358437454626, 18577.907110464363, 18932.242710291433, 19156.71264931055, 19226.134823932676, 19254.0, 19393.224271029, 19438.222471101, 19439.416817495498, 19467.221311147394, 12252.081093917814, 12384.318862462651, 12394.628161154818, 12397.93801030989, 12440.75118497643, 12332.07509436778, 12451.875481245072, 13973.161610303414, 14226.928865355607, 14261.144331340152, 10990.615328463466, 11254.268472549258, 11578.189526733047, 11713.0, 11790.764184716087, 11306.152038597302, 11469.139814514112, 11526.07791376671, 11534.480948357177, 11555.075593952632, 9287.349885507949, 9406.05939406054, 9421.293402994666, 9441.33910626277, 9454.43273403598, 6950.721971121098, 6979.720811167496, 6980.825479363057, 6997.965010174917, 7018.824529386806, 7472.178060413242, 7586.544807311579, 7678.692852285846, 7691.769246922601, 7705.691772329044], "value": 6852.647976339712, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.084712094669, 522.3966318901668, 537.0547835808976, 542.224618795123, 546.3443867359167, 6378.585391949526, 6429.549931504796, 6442.904706199952, 6475.158229430168, 6478.740850365992, 6287.497000239981, 6325.873482530358, 6341.0, 6363.172787537614, 6398.648074355909, 5286.365636123671, 5288.286081379022, 5298.708571028592, 5316.229146773716, 5351.464853514656, 4906.828261010865, 5117.462666420028, 5181.896362072775, 5307.73461326934, 5331.493508116742, 5027.773750181233, 5075.5178258065425, 5086.262491938685, 5098.796048158069, 5099.413567439755, 5092.159793634046, 5104.821331253425, 5107.616928730342, 5111.667741596784, 5130.461301563338, 4461.5315391883705, 4569.817207311704, 4584.266517357207, 4596.241620132674, 4619.284010978304, 5992.400759924017, 6048.69756512177, 6067.393260673942, 6158.107074474176, 6206.937930620681, 5755.913661295084, 5796.739271684047, 5826.446487583673, 5834.6790926499025, 5920.5559583031445, 6560.376764207393, 6587.736490540373, 6589.759481776219, 6627.436667883236, 6629.0, 5025.899482010376, 5042.798288068473, 5047.369078865142, 5062.367204099488, 5065.924011139836, 6741.966290168567, 6827.931720682829, 6843.247242803337, 7024.754133605299, 7068.823279418004, 4891.804327826847, 5077.746112694351, 5107.0, 5153.433122356538, 5173.224016397495, 5170.1986192140275, 5174.042802081627, 5189.756402248595, 5215.739213039333, 5218.556422704112, 5788.88422231553, 5807.88384232313, 5853.707314634336, 5875.441833025856, 5901.291844978544, 6690.966545167243, 6734.292899245606, 6749.797506074826, 6776.661166941633, 6778.0, 1784.8750587458753, 1801.3965321617143, 1822.489702883195, 1823.2706917232947, 1826.0869565217392, 1154.2019301128007, 1264.4689230523018, 1267.6879429790124, 1268.0236218112002, 1269.149669721289, 5723.971380143156, 5730.770769169187, 5757.769689212385, 5770.653760774283, 5801.477866991991, 6821.4542836572955, 6860.725570977205, 6860.862782744317, 6917.100776899071, 6981.0, 5754.0, 5793.536517078624, 5795.304563452412, 5803.520102373867, 5836.270466191726, 3451.4650229214008, 3517.683408493248, 3519.63043880394, 3609.620989796086, 3616.7449295633614, 6464.838379040561, 6469.773557925544, 6537.515744017243, 6539.215294164731, 6584.539082264196, 6785.389314961677, 6913.930860691429, 6922.131024623323, 6936.34104760047, 6938.889777635554, 6501.512386571026, 6523.739050437929, 6546.18172728409, 6595.670216489157, 6596.868062638816, 6793.354631310018, 6859.965700171467, 6873.247321932875, 6907.585544867324, 6910.550814197061, 6842.897356539556, 6872.278410766798, 6875.48433867472, 6939.514234003571, 6976.825579360354, 6590.736370545124, 6668.466522678271, 6678.4235163638905, 6694.598324100375, 6701.195856497252, 5064.696118232918, 5115.820946266863, 5129.589632829439, 5143.794248230179, 5174.844754657366, 6603.966980165068, 6781.11845460087, 6867.553609015398, 6912.516123871482, 6974.58152510851, 6320.0, 6600.735970561123, 6683.365080317362, 6690.464762818866, 6700.765473208415, 6593.3736295051895, 6613.206415230203, 6668.333166683293, 6670.899936501053, 6691.899621505779, 6512.088307637029, 6532.379423954717, 6544.560196756706, 6582.76960306387, 6598.967005164944, 6641.900371494335, 6650.268470468148, 6658.7336506539195, 6695.966520167368, 6755.864882702417, 6480.189976252968, 6509.349065093453, 6516.804495865132, 6518.217813862367, 6528.836779080561], "value": 5159.095348837067, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [923.0723123261122, 925.2043242811184, 946.8011717539316, 950.9667161649344, 951.9476428796413, 19385.903070484645, 20091.095900684468, 20273.290434834784, 20296.69554956675, 20322.796772032274, 18457.077146142674, 18503.35238266661, 18593.7210941836, 18654.53363665909, 18768.0, 897.7396554999059, 972.6741541583579, 978.9510524473783, 981.9312648114635, 982.8230918434679, 982.2338575910801, 995.1839491616877, 996.3424140067557, 996.6113215845826, 996.6362277768599, 1008.4100801031406, 1032.989670103297, 1038.5897570459663, 1049.6536143072806, 1052.9262951593391, 1023.4013102335143, 1029.0, 1031.9742006449785, 1032.927695061346, 1034.0900007992993, 750.7034721285062, 753.2618034326337, 753.6194221917951, 755.5089192025173, 755.5466719967975, 20258.088386022664, 20558.38324850257, 20859.374218773308, 21170.682439763415, 21185.576288474298, 22193.44516387103, 22377.552448951094, 22473.0, 22728.772712272832, 22729.77270227293, 17847.107644617718, 17983.370582029565, 17985.910070449696, 18143.364982225557, 18167.0, 3846.673032792216, 3871.4386413970096, 3876.7092468065007, 3876.8061596920325, 3877.6316249956208, 104722.28749706205, 143256.28371858178, 144134.2793286027, 144506.0, 145664.27167864091, 17202.1398930053, 17623.295068197127, 17660.293588256583, 17738.201780919888, 17827.10864456772, 31925.0, 32698.0, 32736.345273094405, 32740.526676299625, 32784.50823237653, 9256.213221876216, 9439.480828554359, 9517.857232141523, 9533.90466095344, 9562.378445401027, 4368.5194628590825, 4429.512753597101, 4450.421445212167, 4459.955400446019, 4467.419235499429, 5561.054620714488, 5611.018071837412, 5674.319081710221, 5687.260656114761, 5689.886202275931, 1132.5243397772956, 1149.9080073594093, 1153.7461758413135, 1156.1386766858575, 1159.4956194055444, 599.4694695194697, 614.966176860277, 619.916311297978, 632.7627139822566, 634.1407392982535, 12098.213616115101, 12154.453049612788, 12205.145639805129, 12443.0, 12729.108962372546, 635.9682015899186, 636.0522820996682, 647.0229952771349, 649.9805005849832, 652.1912828093131, 5471.534919531805, 5491.36849262332, 5493.7802487900835, 5530.2810634618045, 5564.415736347706, 123096.38451807684, 123550.38224808818, 127865.0, 129109.35445322713, 131240.0, 109471.0, 109782.45108774565, 110981.44509277403, 116658.41670791592, 116662.41668791772, 94691.0, 95434.0, 98773.50613246887, 99882.5005874966, 101826.49086754519, 10029.247806414402, 10259.640912568024, 10289.74275643115, 10292.845607315747, 10311.175105991353, 41988.790056049525, 42887.78556107199, 43703.125937480436, 44028.55971440244, 44588.33117503175, 56232.0, 57629.71185144047, 57705.0, 57705.422945770006, 57832.42167578439, 15979.920100399424, 16375.918120409322, 16422.589435264214, 16430.424935127692, 16474.093924834247, 17599.29602815873, 17671.558211044827, 17704.82295177032, 17841.0, 17845.10774461298, 15105.0, 15326.0, 15405.0, 15421.768673469682, 15435.382584696936, 16552.33790648409, 16702.916485417496, 16789.580260493585, 16925.492235232963, 16931.746023809406, 15313.310901009705, 15412.537623871302, 15472.0, 15555.222238888236, 15579.143147127017, 11081.335119892832, 11134.443277836237, 11263.887361126284, 11379.260348077349, 11502.712432189262, 9766.85349719769, 10180.287379883337, 10216.846747298945, 10292.125169360388, 10360.481975901326], "value": 9019.853015728628, "unit": "score"}]}, {"extraOptions": ["e10s", "stylo_disabled"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1421.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 109.0, 108.0, 117.0, 106.0, 110.0, 106.0, 108.0, 107.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 115.0, 117.0, 143.0, 118.0, 116.0, 120.0, 117.0, 117.0], "value": 119.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 162.0, 163.0, 162.0, 163.0, 165.0, 165.0, 163.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 73.0, 72.0, 76.0, 72.0, 75.0, 75.0, 75.0, 75.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 78.0, 78.0, 79.0, 80.0, 128.0, 80.0, 82.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 118.0, 110.0, 113.0, 114.0, 113.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 147.0, 147.0, 148.0, 146.0, 147.0, 147.0, 149.0, 149.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 94.0, 93.0, 94.0, 93.0, 94.0, 95.0, 94.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 54.0, 53.0, 52.0, 53.0, 53.0, 53.0, 52.0, 51.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 38.0, 38.0, 38.0, 39.0, 45.0, 38.0, 39.0, 39.0, 38.0], "value": 39.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 80.0, 72.0, 72.0, 74.0, 72.0, 72.0, 73.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 118.0, 112.0, 108.0, 116.0, 112.0, 118.0, 117.0, 114.0, 120.0], "value": 114.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 171.0, 164.0, 163.0, 165.0, 164.0, 171.0, 177.0, 168.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 65.0, 68.0, 68.0, 67.0, 66.0, 66.0, 69.0, 67.0], "value": 66.8, "unit": "score"}]}]} 11:54:20 INFO - Return code: 0 11:54:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:54:20 INFO - # TBPL SUCCESS # 11:54:20 INFO - Running post-action listener: _package_coverage_data 11:54:20 INFO - Running post-action listener: _resource_record_post_action 11:54:20 INFO - [mozharness: 2017-09-21 18:54:20.308923Z] Finished run-tests step (success) 11:54:20 INFO - Running post-run listener: _resource_record_post_run 11:54:20 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 16384; Write bytes: 347009024; Read time: 176; Write time: 190160 11:54:20 INFO - TinderboxPrint: CPU usage
13.1% 11:54:20 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 176 11:54:20 INFO - TinderboxPrint: I/O write bytes / time
347,009,024 / 190,160 11:54:20 INFO - TinderboxPrint: CPU idle
8,224.5 (86.9%) 11:54:20 INFO - TinderboxPrint: CPU user
1,190.8 (12.6%) 11:54:20 INFO - TinderboxPrint: Swap in / out
0 / 0 11:54:20 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13164544; Read time: 0; Write time: 2296 11:54:20 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:54:20 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 12288; Write bytes: 333844480; Read time: 168; Write time: 187864 11:54:20 INFO - Running post-run listener: _upload_blobber_files 11:54:20 INFO - Blob upload gear active. 11:54:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:54:20 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 11:54:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:54:21 INFO - (blobuploader) - INFO - Open directory for files ... 11:54:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log ... 11:54:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_errorsummary.log: uploaded 11:54:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:22 INFO - (blobuploader) - INFO - Done attempting. 11:54:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:54:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:54:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:25 INFO - (blobuploader) - INFO - Done attempting. 11:54:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log ... 11:54:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_raw.log: uploaded 11:54:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:26 INFO - (blobuploader) - INFO - Done attempting. 11:54:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:54:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:54:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:27 INFO - (blobuploader) - INFO - Done attempting. 11:54:27 INFO - (blobuploader) - INFO - Iteration through files over. 11:54:27 INFO - Return code: 0 11:54:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:54:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:54:27 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7ea9b94c9b6966ce22d1549f5ecfb765cc816a41469651469ea62cf3334676f19736cefa9d230c691eace54a2639aac59921b694aae36db388532fe2e825778", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac50e10986f6077a6f5be89153ebc4099259fe9330bc3af1b304c7e5cd511d21a7c8638197e8a8beb18d9928e1e0e220a9b01feb99a032055d25c0c9eae989d0", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d1129b000f6b576993dbf6849b0360e89ac4e4f64daa04d7e416c8dda55b6b49f4b709bb77db87857a26dce64b80ab4b9a1e558a00dc8e8d6eb22fa40f206fc", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3f22dfe2ae239e8f35cb5aed486d62067b3c94a631d0a39affc488389c66302f1d99f91c8b69aa8801443c688c6a360b8a9368149461e6d2b898701dca09710f"} 11:54:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:54:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:54:27 INFO - Contents: 11:54:27 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7ea9b94c9b6966ce22d1549f5ecfb765cc816a41469651469ea62cf3334676f19736cefa9d230c691eace54a2639aac59921b694aae36db388532fe2e825778", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac50e10986f6077a6f5be89153ebc4099259fe9330bc3af1b304c7e5cd511d21a7c8638197e8a8beb18d9928e1e0e220a9b01feb99a032055d25c0c9eae989d0", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d1129b000f6b576993dbf6849b0360e89ac4e4f64daa04d7e416c8dda55b6b49f4b709bb77db87857a26dce64b80ab4b9a1e558a00dc8e8d6eb22fa40f206fc", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3f22dfe2ae239e8f35cb5aed486d62067b3c94a631d0a39affc488389c66302f1d99f91c8b69aa8801443c688c6a360b8a9368149461e6d2b898701dca09710f"} 11:54:27 INFO - Running post-run listener: copy_logs_to_upload_dir 11:54:27 INFO - Copying logs to upload dir... 11:54:27 INFO - mkdir: /builds/slave/test/build/upload/logs 11:54:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.702900 ========= master_lag: -0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-21 11:54:27.581521) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 11:54:27.588668) ========= 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=ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7ea9b94c9b6966ce22d1549f5ecfb765cc816a41469651469ea62cf3334676f19736cefa9d230c691eace54a2639aac59921b694aae36db388532fe2e825778", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac50e10986f6077a6f5be89153ebc4099259fe9330bc3af1b304c7e5cd511d21a7c8638197e8a8beb18d9928e1e0e220a9b01feb99a032055d25c0c9eae989d0", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d1129b000f6b576993dbf6849b0360e89ac4e4f64daa04d7e416c8dda55b6b49f4b709bb77db87857a26dce64b80ab4b9a1e558a00dc8e8d6eb22fa40f206fc", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3f22dfe2ae239e8f35cb5aed486d62067b3c94a631d0a39affc488389c66302f1d99f91c8b69aa8801443c688c6a360b8a9368149461e6d2b898701dca09710f"} build_url:https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011251 build_url: 'https://queue.taskcluster.net/v1/task/TWoC3Z28QNeZ_g1kUharhQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7ea9b94c9b6966ce22d1549f5ecfb765cc816a41469651469ea62cf3334676f19736cefa9d230c691eace54a2639aac59921b694aae36db388532fe2e825778", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac50e10986f6077a6f5be89153ebc4099259fe9330bc3af1b304c7e5cd511d21a7c8638197e8a8beb18d9928e1e0e220a9b01feb99a032055d25c0c9eae989d0", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4d1129b000f6b576993dbf6849b0360e89ac4e4f64daa04d7e416c8dda55b6b49f4b709bb77db87857a26dce64b80ab4b9a1e558a00dc8e8d6eb22fa40f206fc", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3f22dfe2ae239e8f35cb5aed486d62067b3c94a631d0a39affc488389c66302f1d99f91c8b69aa8801443c688c6a360b8a9368149461e6d2b898701dca09710f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 11:54:27.616279) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:54:27.616586) ========= 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=ed14279e38b05397cf4aa391000001a4-1506018769.391345-1667189598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004512 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 11:54:27.702061) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 11:54:27.704385) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 11:54:27.704690) ========= ========= Total master_lag: 0.12 =========