builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-076 starttime: 1506817525.71 results: success (0) revision: 179dccc17c7f261347436b4b1c33029c8272960b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:25.714804) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:25.715199) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:25.715469) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:25.759681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:25.759965) ========= 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=9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-30 17:25:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.9M=0s 2017-09-30 17:25:26 (32.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.367122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:26.145335) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:26.145663) ========= 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=9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019139 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:26.198036) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:26.198387) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 179dccc17c7f261347436b4b1c33029c8272960b --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 179dccc17c7f261347436b4b1c33029c8272960b --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=9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-30 17:25:26,263 truncating revision to first 12 chars 2017-09-30 17:25:26,263 Setting DEBUG logging. 2017-09-30 17:25:26,264 attempt 1/10 2017-09-30 17:25:26,264 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/179dccc17c7f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-30 17:25:26,612 unpacking tar archive at: mozilla-central-179dccc17c7f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.648931 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:26.861590) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:26.864902) ========= script_repo_revision: 179dccc17c7f261347436b4b1c33029c8272960b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:26.865304) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:26.865581) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 17:25:26.878531) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-30 17:25:26.878837) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:25:27 INFO - MultiFileLogger online at 20170930 17:25:27 in /builds/slave/test 17:25:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:25:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:25:27 INFO - {'append_to_log': False, 17:25:27 INFO - 'base_work_dir': '/builds/slave/test', 17:25:27 INFO - 'blob_upload_branch': 'Firefox', 17:25:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:25:27 INFO - 'branch': 'Firefox', 17:25:27 INFO - 'buildbot_json_path': 'buildprops.json', 17:25:27 INFO - 'code_coverage': False, 17:25:27 INFO - 'config_files': ('talos/linux_config.py',), 17:25:27 INFO - 'default_actions': ('clobber', 17:25:27 INFO - 'read-buildbot-config', 17:25:27 INFO - 'download-and-extract', 17:25:27 INFO - 'populate-webroot', 17:25:27 INFO - 'create-virtualenv', 17:25:27 INFO - 'install', 17:25:27 INFO - 'setup-mitmproxy', 17:25:27 INFO - 'run-tests'), 17:25:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:25:27 INFO - 'disable_ccov_upload': False, 17:25:27 INFO - 'disable_stylo': False, 17:25:27 INFO - 'download_minidump_stackwalk': True, 17:25:27 INFO - 'download_symbols': 'ondemand', 17:25:27 INFO - 'enable_stylo': False, 17:25:27 INFO - 'enable_webrender': False, 17:25:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:25:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:25:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:25:27 INFO - 'gecko_profile': False, 17:25:27 INFO - 'gecko_profile_interval': 0, 17:25:27 INFO - 'installer_path': 'installer.exe', 17:25:27 INFO - 'log_level': 'info', 17:25:27 INFO - 'log_name': 'talos', 17:25:27 INFO - 'log_to_console': True, 17:25:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:25:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:25:27 INFO - 'opt_config_files': (), 17:25:27 INFO - 'pip_index': False, 17:25:27 INFO - 'suite': 'dromaeojs-e10s', 17:25:27 INFO - 'system_bits': '32', 17:25:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:25:27 INFO - 'title': 'talos-linux64-ix-076', 17:25:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:25:27 INFO - 'use_talos_json': True, 17:25:27 INFO - 'verify': 'False', 17:25:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:25:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:25:27 INFO - 'work_dir': 'build'} 17:25:27 INFO - [mozharness: 2017-10-01 00:25:27.090473Z] Running clobber step. 17:25:27 INFO - Running pre-action listener: _resource_record_pre_action 17:25:27 INFO - Running main action method: clobber 17:25:27 INFO - rmtree: /builds/slave/test/build 17:25:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:25:28 INFO - Running post-action listener: _resource_record_post_action 17:25:28 INFO - [mozharness: 2017-10-01 00:25:28.581735Z] Finished clobber step (success) 17:25:28 INFO - [mozharness: 2017-10-01 00:25:28.581833Z] Running read-buildbot-config step. 17:25:28 INFO - Running pre-action listener: _resource_record_pre_action 17:25:28 INFO - Running main action method: read_buildbot_config 17:25:28 INFO - Using buildbot properties: 17:25:28 INFO - { 17:25:28 INFO - "project": "", 17:25:28 INFO - "product": "firefox", 17:25:28 INFO - "who": "archaeopteryx@coole-files.de", 17:25:28 INFO - "installer_path": "public/build/target.tar.bz2", 17:25:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:25:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:25:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 17:25:28 INFO - "stage_platform": "linux64-pgo", 17:25:28 INFO - "basedir": "/builds/slave/test", 17:25:28 INFO - "buildnumber": 224, 17:25:28 INFO - "platform": "ubuntu64_hw", 17:25:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:25:28 INFO - "slavebuilddir": "test", 17:25:28 INFO - "taskId": "WVZZSKN0QuOHrnd6GQZTgg", 17:25:28 INFO - "branch": "mozilla-central", 17:25:28 INFO - "script_repo_revision": "production", 17:25:28 INFO - "revision": "179dccc17c7f261347436b4b1c33029c8272960b", 17:25:28 INFO - "slavename": "talos-linux64-ix-076", 17:25:28 INFO - "repo_path": "mozilla-central" 17:25:28 INFO - } 17:25:28 INFO - Finding installer, test and symbols from parent task. 17:25:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WVZZSKN0QuOHrnd6GQZTgg'}, attempt #1 17:25:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WVZZSKN0QuOHrnd6GQZTgg'}, attempt #1 17:25:29 INFO - Task dependencies: N6FqeYAdQrOnZuU68arTCg 17:25:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg'}, attempt #1 17:25:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.tar.bz2 17:25:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.test_packages.json 17:25:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.crashreporter-symbols.zip 17:25:29 INFO - Running post-action listener: _resource_record_post_action 17:25:29 INFO - [mozharness: 2017-10-01 00:25:29.985016Z] Finished read-buildbot-config step (success) 17:25:29 INFO - [mozharness: 2017-10-01 00:25:29.985234Z] Running download-and-extract step. 17:25:29 INFO - Running pre-action listener: _resource_record_pre_action 17:25:29 INFO - Running main action method: download_and_extract 17:25:29 INFO - mkdir: /builds/slave/test/build/tests 17:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:29 INFO - trying https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.test_packages.json 17:25:29 INFO - Downloading https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:25:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:25:30 INFO - Downloaded 989 bytes. 17:25:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:25:30 INFO - Using the following test package requirements: 17:25:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:25:30 INFO - u'common': [u'target.common.tests.zip'], 17:25:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:25:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:25:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:25:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:25:30 INFO - u'mozbase': [u'target.common.tests.zip'], 17:25:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:25:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:25:30 INFO - u'web-platform': [u'target.common.tests.zip', 17:25:30 INFO - u'target.web-platform.tests.tar.gz'], 17:25:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:25:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:25:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.common.tests.zip 17:25:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:25:30 INFO - Fetch https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.common.tests.zip into memory 17:25:32 INFO - Content-Length response header: 55917009 17:25:32 INFO - Bytes received: 55917009 17:25:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.talos.tests.zip 17:25:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:25:43 INFO - Fetch https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.talos.tests.zip into memory 17:25:44 INFO - Content-Length response header: 14522385 17:25:44 INFO - Bytes received: 14522385 17:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:45 INFO - trying https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.tar.bz2 17:25:45 INFO - Downloading https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:25:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:25:48 INFO - Downloaded 62377863 bytes. 17:25:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.tar.bz2 17:25:48 INFO - mkdir: /builds/slave/test/properties 17:25:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:25:48 INFO - Writing to file /builds/slave/test/properties/build_url 17:25:48 INFO - Contents: 17:25:48 INFO - build_url:https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.tar.bz2 17:25:48 INFO - Running post-action listener: _resource_record_post_action 17:25:48 INFO - Running post-action listener: find_tests_for_verification 17:25:48 INFO - Running post-action listener: set_extra_try_arguments 17:25:48 INFO - [mozharness: 2017-10-01 00:25:48.798598Z] Finished download-and-extract step (success) 17:25:48 INFO - [mozharness: 2017-10-01 00:25:48.798888Z] Running populate-webroot step. 17:25:48 INFO - Running pre-action listener: _resource_record_pre_action 17:25:48 INFO - Running main action method: populate_webroot 17:25:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:25:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['tresize', 17:25:48 INFO - 'tcanvasmark']}, 17:25:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:25:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['dromaeo_css', 17:25:48 INFO - 'kraken']}, 17:25:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:25:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:25:48 INFO - 'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:25:48 INFO - 'tests': ['damp', 'tps']}, 17:25:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:25:48 INFO - 'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['damp', 'tps']}, 17:25:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:25:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['dromaeo_dom']}, 17:25:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:25:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['basic_compositor_video', 17:25:48 INFO - 'glvideo']}, 17:25:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:25:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:25:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:25:48 INFO - 'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['ts_paint_webext', 17:25:48 INFO - 'tp5o_webext']}, 17:25:48 INFO - 'other-e10s': {'tests': ['a11yr', 17:25:48 INFO - 'ts_paint', 17:25:48 INFO - 'tpaint', 17:25:48 INFO - 'sessionrestore', 17:25:48 INFO - 'sessionrestore_many_windows', 17:25:48 INFO - 'sessionrestore_no_auto_restore', 17:25:48 INFO - 'tabpaint', 17:25:48 INFO - 'cpstartup']}, 17:25:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['a11yr', 17:25:48 INFO - 'ts_paint', 17:25:48 INFO - 'tpaint', 17:25:48 INFO - 'sessionrestore', 17:25:48 INFO - 'sessionrestore_many_windows', 17:25:48 INFO - 'sessionrestore_no_auto_restore', 17:25:48 INFO - 'tabpaint', 17:25:48 INFO - 'cpstartup']}, 17:25:48 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 17:25:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 17:25:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['perf_reftest_singletons']}, 17:25:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['perf_reftest']}, 17:25:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:25:48 INFO - 'tsvgr_opacity', 17:25:48 INFO - 'tart', 17:25:48 INFO - 'tscrollx', 17:25:48 INFO - 'tsvg_static']}, 17:25:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['tsvgx', 17:25:48 INFO - 'tsvgr_opacity', 17:25:48 INFO - 'tart', 17:25:48 INFO - 'tscrollx', 17:25:48 INFO - 'tsvg_static']}, 17:25:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:25:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:25:48 INFO - 'talos_options': ['--disable-stylo'], 17:25:48 INFO - 'tests': ['tp5o']}, 17:25:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:25:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:25:48 INFO - 'talos_options': ['--mitmproxy', 17:25:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:25:48 INFO - '--firstNonBlankPaint'], 17:25:48 INFO - 'tests': ['tp6_google', 17:25:48 INFO - 'tp6_youtube', 17:25:48 INFO - 'tp6_amazon', 17:25:48 INFO - 'tp6_facebook']}, 17:25:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:25:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:25:48 INFO - 'talos_options': ['--disable-stylo', 17:25:48 INFO - '--mitmproxy', 17:25:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:25:48 INFO - '--firstNonBlankPaint'], 17:25:48 INFO - 'tests': ['tp6_google', 17:25:48 INFO - 'tp6_youtube', 17:25:48 INFO - 'tp6_amazon', 17:25:48 INFO - 'tp6_facebook']}, 17:25:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:25:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:25:48 INFO - 'talos_options': ['--stylo-threads=1', 17:25:48 INFO - '--mitmproxy', 17:25:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:25:48 INFO - '--firstNonBlankPaint'], 17:25:48 INFO - 'tests': ['tp6_google', 17:25:48 INFO - 'tp6_youtube', 17:25:48 INFO - 'tp6_amazon', 17:25:48 INFO - 'tp6_facebook']}, 17:25:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:25:48 INFO - 'talos_options': ['--xperf_path', 17:25:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:48 INFO - 'tests': ['tp5n']}, 17:25:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:25:48 INFO - 'talos_options': ['--disable-stylo', 17:25:48 INFO - '--xperf_path', 17:25:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:48 INFO - 'tests': ['tp5n']}}} 17:25:48 INFO - Running post-action listener: _resource_record_post_action 17:25:48 INFO - [mozharness: 2017-10-01 00:25:48.817361Z] Finished populate-webroot step (success) 17:25:48 INFO - [mozharness: 2017-10-01 00:25:48.817451Z] Running create-virtualenv step. 17:25:48 INFO - Running pre-action listener: _resource_record_pre_action 17:25:48 INFO - Running main action method: create_virtualenv 17:25:48 INFO - Creating virtualenv /builds/slave/test/build/venv 17:25:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:25:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:25:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:25:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:25:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:25:49 INFO - Installing distribute.............................................................................................................................................................................................done. 17:25:49 INFO - Installing pip...............done. 17:25:49 INFO - Return code: 0 17:25:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:25:49 INFO - Reading from file tmpfile_stdout 17:25:49 INFO - Output received: 17:25:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:25:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:25:49 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')]} 17:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:49 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')]} 17:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:49 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 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:25:49 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 17:25:49 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 17:25:49 INFO - Using env: {'DISPLAY': ':0', 17:25:49 INFO - 'HOME': '/home/cltbld', 17:25:49 INFO - 'LANG': 'en_US.UTF-8', 17:25:49 INFO - 'LANGUAGE': 'en_US:en', 17:25:49 INFO - 'LOGNAME': 'cltbld', 17:25:49 INFO - 'MAIL': '/var/mail/cltbld', 17:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:49 INFO - 'MOZ_NO_REMOTE': '1', 17:25:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:25:49 INFO - 'NO_EM_RESTART': '1', 17:25:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:49 INFO - 'PWD': '/builds/slave/test', 17:25:49 INFO - 'SHELL': '/bin/bash', 17:25:49 INFO - 'SHLVL': '1', 17:25:49 INFO - 'TERM': 'linux', 17:25:49 INFO - 'TMOUT': '86400', 17:25:49 INFO - 'USER': 'cltbld', 17:25:49 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:49 INFO - '_': '/tools/buildbot/bin/python'} 17:25:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:25:51 INFO - Downloading/unpacking pip>=1.5 17:25:51 INFO - Running setup.py egg_info for package pip 17:25:51 INFO - warning: no files found matching 'pip/cacert.pem' 17:25:51 INFO - warning: no files found matching '*.html' under directory 'docs' 17:25:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:25:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:25:51 INFO - Installing collected packages: pip 17:25:51 INFO - Found existing installation: pip 1.1 17:25:51 INFO - Uninstalling pip: 17:25:51 INFO - Successfully uninstalled pip 17:25:51 INFO - Running setup.py install for pip 17:25:51 INFO - warning: no files found matching 'pip/cacert.pem' 17:25:51 INFO - warning: no files found matching '*.html' under directory 'docs' 17:25:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:25:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:25:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:25:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:25:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:25:51 INFO - Successfully installed pip 17:25:51 INFO - Cleaning up... 17:25:51 INFO - Return code: 0 17:25:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25: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')]} 17:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:25:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:25:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:25:51 INFO - Using env: {'DISPLAY': ':0', 17:25:51 INFO - 'HOME': '/home/cltbld', 17:25:51 INFO - 'LANG': 'en_US.UTF-8', 17:25:51 INFO - 'LANGUAGE': 'en_US:en', 17:25:51 INFO - 'LOGNAME': 'cltbld', 17:25:51 INFO - 'MAIL': '/var/mail/cltbld', 17:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:51 INFO - 'MOZ_NO_REMOTE': '1', 17:25:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:25:51 INFO - 'NO_EM_RESTART': '1', 17:25:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:51 INFO - 'PWD': '/builds/slave/test', 17:25:51 INFO - 'SHELL': '/bin/bash', 17:25:51 INFO - 'SHLVL': '1', 17:25:51 INFO - 'TERM': 'linux', 17:25:51 INFO - 'TMOUT': '86400', 17:25:51 INFO - 'USER': 'cltbld', 17:25:51 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:51 INFO - '_': '/tools/buildbot/bin/python'} 17:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:51 INFO - Downloading/unpacking psutil>=3.1.1 17:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:25:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:52 INFO - Installing collected packages: psutil 17:25:52 INFO - Running setup.py install for psutil 17:25:52 INFO - building 'psutil._psutil_linux' extension 17:25:52 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 17:25:52 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 17:25:52 INFO - building 'psutil._psutil_posix' extension 17:25:52 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 17:25:52 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 17:25:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:52 INFO - Successfully installed psutil 17:25:52 INFO - Cleaning up... 17:25:53 INFO - Return code: 0 17:25:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:25:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:25:53 INFO - Using env: {'DISPLAY': ':0', 17:25:53 INFO - 'HOME': '/home/cltbld', 17:25:53 INFO - 'LANG': 'en_US.UTF-8', 17:25:53 INFO - 'LANGUAGE': 'en_US:en', 17:25:53 INFO - 'LOGNAME': 'cltbld', 17:25:53 INFO - 'MAIL': '/var/mail/cltbld', 17:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:53 INFO - 'MOZ_NO_REMOTE': '1', 17:25:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:25:53 INFO - 'NO_EM_RESTART': '1', 17:25:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:53 INFO - 'PWD': '/builds/slave/test', 17:25:53 INFO - 'SHELL': '/bin/bash', 17:25:53 INFO - 'SHLVL': '1', 17:25:53 INFO - 'TERM': 'linux', 17:25:53 INFO - 'TMOUT': '86400', 17:25:53 INFO - 'USER': 'cltbld', 17:25:53 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:53 INFO - '_': '/tools/buildbot/bin/python'} 17:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:25:54 INFO - Installing collected packages: mozsystemmonitor 17:25:54 INFO - Running setup.py install for mozsystemmonitor 17:25:54 INFO - Successfully installed mozsystemmonitor 17:25:54 INFO - Cleaning up... 17:25:55 INFO - Return code: 0 17:25:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:25:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:25:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:25:55 INFO - Using env: {'DISPLAY': ':0', 17:25:55 INFO - 'HOME': '/home/cltbld', 17:25:55 INFO - 'LANG': 'en_US.UTF-8', 17:25:55 INFO - 'LANGUAGE': 'en_US:en', 17:25:55 INFO - 'LOGNAME': 'cltbld', 17:25:55 INFO - 'MAIL': '/var/mail/cltbld', 17:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:55 INFO - 'MOZ_NO_REMOTE': '1', 17:25:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:25:55 INFO - 'NO_EM_RESTART': '1', 17:25:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:55 INFO - 'PWD': '/builds/slave/test', 17:25:55 INFO - 'SHELL': '/bin/bash', 17:25:55 INFO - 'SHLVL': '1', 17:25:55 INFO - 'TERM': 'linux', 17:25:55 INFO - 'TMOUT': '86400', 17:25:55 INFO - 'USER': 'cltbld', 17:25:55 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:55 INFO - '_': '/tools/buildbot/bin/python'} 17:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:55 INFO - Downloading/unpacking jsonschema==2.5.1 17:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:25:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:57 INFO - Downloading functools32-3.2.3-2.tar.gz 17:25:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:25:57 INFO - warning: no files found matching '*.txt' 17:25:57 INFO - no previously-included directories found matching 'build' 17:25:57 INFO - no previously-included directories found matching 'dist' 17:25:57 INFO - no previously-included directories found matching '.git*' 17:25:57 INFO - Installing collected packages: jsonschema, functools32 17:25:57 INFO - Running setup.py install for functools32 17:25:57 INFO - warning: no files found matching '*.txt' 17:25:57 INFO - no previously-included directories found matching 'build' 17:25:57 INFO - no previously-included directories found matching 'dist' 17:25:57 INFO - no previously-included directories found matching '.git*' 17:25:57 INFO - Successfully installed jsonschema functools32 17:25:57 INFO - Cleaning up... 17:25:58 INFO - Return code: 0 17:25:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:25:58 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')]} 17:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:58 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')]} 17:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:58 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 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:25:58 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 17:25:58 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 17:25:58 INFO - Using env: {'DISPLAY': ':0', 17:25:58 INFO - 'HOME': '/home/cltbld', 17:25:58 INFO - 'LANG': 'en_US.UTF-8', 17:25:58 INFO - 'LANGUAGE': 'en_US:en', 17:25:58 INFO - 'LOGNAME': 'cltbld', 17:25:58 INFO - 'MAIL': '/var/mail/cltbld', 17:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:58 INFO - 'MOZ_NO_REMOTE': '1', 17:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:25:58 INFO - 'NO_EM_RESTART': '1', 17:25:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:58 INFO - 'PWD': '/builds/slave/test', 17:25:58 INFO - 'SHELL': '/bin/bash', 17:25:58 INFO - 'SHLVL': '1', 17:25:58 INFO - 'TERM': 'linux', 17:25:58 INFO - 'TMOUT': '86400', 17:25:58 INFO - 'USER': 'cltbld', 17:25:58 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:58 INFO - '_': '/tools/buildbot/bin/python'} 17:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:25:58 INFO - Cleaning up... 17:25:58 INFO - Return code: 0 17:25:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:25:58 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')]} 17:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:58 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')]} 17:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:58 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 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:25:58 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 17:25:58 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 17:25:58 INFO - Using env: {'DISPLAY': ':0', 17:25:58 INFO - 'HOME': '/home/cltbld', 17:25:58 INFO - 'LANG': 'en_US.UTF-8', 17:25:58 INFO - 'LANGUAGE': 'en_US:en', 17:25:58 INFO - 'LOGNAME': 'cltbld', 17:25:58 INFO - 'MAIL': '/var/mail/cltbld', 17:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:58 INFO - 'MOZ_NO_REMOTE': '1', 17:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:25:58 INFO - 'NO_EM_RESTART': '1', 17:25:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:58 INFO - 'PWD': '/builds/slave/test', 17:25:58 INFO - 'SHELL': '/bin/bash', 17:25:58 INFO - 'SHLVL': '1', 17:25:58 INFO - 'TERM': 'linux', 17:25:58 INFO - 'TMOUT': '86400', 17:25:58 INFO - 'USER': 'cltbld', 17:25:58 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:58 INFO - '_': '/tools/buildbot/bin/python'} 17:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:58 INFO - Downloading/unpacking blobuploader==1.2.4 17:25:58 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 17:25:58 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 17:25:58 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 17:25:58 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 17:25:59 INFO - Downloading blobuploader-1.2.4.tar.gz 17:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:25:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:25:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:26:00 INFO - Downloading docopt-0.6.1.tar.gz 17:26:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:26:00 INFO - Installing collected packages: blobuploader, requests, docopt 17:26:00 INFO - Running setup.py install for blobuploader 17:26:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:26:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:26:00 INFO - Running setup.py install for requests 17:26:00 INFO - Running setup.py install for docopt 17:26:00 INFO - Successfully installed blobuploader requests docopt 17:26:00 INFO - Cleaning up... 17:26:00 INFO - Return code: 0 17:26:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:00 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 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:26:00 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 17:26:00 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 17:26:00 INFO - Using env: {'DISPLAY': ':0', 17:26:00 INFO - 'HOME': '/home/cltbld', 17:26:00 INFO - 'LANG': 'en_US.UTF-8', 17:26:00 INFO - 'LANGUAGE': 'en_US:en', 17:26:00 INFO - 'LOGNAME': 'cltbld', 17:26:00 INFO - 'MAIL': '/var/mail/cltbld', 17:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:00 INFO - 'MOZ_NO_REMOTE': '1', 17:26:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:26:00 INFO - 'NO_EM_RESTART': '1', 17:26:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:00 INFO - 'PWD': '/builds/slave/test', 17:26:00 INFO - 'SHELL': '/bin/bash', 17:26:00 INFO - 'SHLVL': '1', 17:26:00 INFO - 'TERM': 'linux', 17:26:00 INFO - 'TMOUT': '86400', 17:26:00 INFO - 'USER': 'cltbld', 17:26:00 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:00 INFO - '_': '/tools/buildbot/bin/python'} 17:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:26:00 INFO - Running setup.py (path:/tmp/pip-zWKNKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:26:01 INFO - Running setup.py (path:/tmp/pip-4_44wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:26:01 INFO - Running setup.py (path:/tmp/pip-YRqkEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:26:01 INFO - Running setup.py (path:/tmp/pip-bZNPAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:26:01 INFO - Running setup.py (path:/tmp/pip-YaNImZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:26:01 INFO - Running setup.py (path:/tmp/pip-0jIswE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:26:01 INFO - Running setup.py (path:/tmp/pip-Ky97jX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:26:01 INFO - Running setup.py (path:/tmp/pip-l1vEtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:26:01 INFO - Running setup.py (path:/tmp/pip-HwOzTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:26:01 INFO - Running setup.py (path:/tmp/pip-QlWV9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:26:01 INFO - Running setup.py (path:/tmp/pip-ojEaQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:26:01 INFO - Running setup.py (path:/tmp/pip-lsgDn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:26:01 INFO - Running setup.py (path:/tmp/pip-BZcHXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:26:01 INFO - Running setup.py (path:/tmp/pip-bL9ZtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:01 INFO - Running setup.py (path:/tmp/pip-UNG0hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:26:02 INFO - Running setup.py (path:/tmp/pip-Ajioc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:26:02 INFO - Running setup.py (path:/tmp/pip-w81uP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:26:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:26:02 INFO - Running setup.py install for manifestparser 17:26:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:26:02 INFO - Running setup.py install for mozcrash 17:26:02 INFO - Running setup.py install for mozdebug 17:26:02 INFO - Running setup.py install for mozdevice 17:26:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:26:02 INFO - Running setup.py install for mozfile 17:26:02 INFO - Running setup.py install for mozhttpd 17:26:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:26:02 INFO - Running setup.py install for mozinfo 17:26:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:26:02 INFO - Running setup.py install for mozInstall 17:26:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:26:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:26:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:26:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:26:02 INFO - Running setup.py install for mozleak 17:26:03 INFO - Running setup.py install for mozlog 17:26:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:26:03 INFO - Running setup.py install for moznetwork 17:26:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:26:03 INFO - Running setup.py install for mozprocess 17:26:03 INFO - Running setup.py install for mozprofile 17:26:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:26:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:26:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:26:03 INFO - Running setup.py install for mozrunner 17:26:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:26:03 INFO - Running setup.py install for mozscreenshot 17:26:03 INFO - Running setup.py install for moztest 17:26:03 INFO - Running setup.py install for mozversion 17:26:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:26:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:26:03 INFO - Cleaning up... 17:26:03 INFO - Return code: 0 17:26:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:26:03 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')]} 17:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:03 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')]} 17:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:03 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 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:26:03 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 17:26:03 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 17:26:03 INFO - Using env: {'DISPLAY': ':0', 17:26:03 INFO - 'HOME': '/home/cltbld', 17:26:03 INFO - 'LANG': 'en_US.UTF-8', 17:26:03 INFO - 'LANGUAGE': 'en_US:en', 17:26:03 INFO - 'LOGNAME': 'cltbld', 17:26:03 INFO - 'MAIL': '/var/mail/cltbld', 17:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:03 INFO - 'MOZ_NO_REMOTE': '1', 17:26:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:26:03 INFO - 'NO_EM_RESTART': '1', 17:26:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:03 INFO - 'PWD': '/builds/slave/test', 17:26:03 INFO - 'SHELL': '/bin/bash', 17:26:03 INFO - 'SHLVL': '1', 17:26:03 INFO - 'TERM': 'linux', 17:26:03 INFO - 'TMOUT': '86400', 17:26:03 INFO - 'USER': 'cltbld', 17:26:03 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:03 INFO - '_': '/tools/buildbot/bin/python'} 17:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:26:04 INFO - Running setup.py (path:/tmp/pip-UJroNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:26:04 INFO - Running setup.py (path:/tmp/pip-rXw4ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:26:04 INFO - Running setup.py (path:/tmp/pip-HyB3e9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:26:04 INFO - Running setup.py (path:/tmp/pip-rE9Ppo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:26:04 INFO - Running setup.py (path:/tmp/pip-YF2JZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:26:04 INFO - Running setup.py (path:/tmp/pip-6J_itp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:26:04 INFO - Running setup.py (path:/tmp/pip-KKKbtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:26:04 INFO - Running setup.py (path:/tmp/pip-3wqwwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:26:04 INFO - Running setup.py (path:/tmp/pip-e6lFA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:26:04 INFO - Running setup.py (path:/tmp/pip-j916Aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:26:04 INFO - Running setup.py (path:/tmp/pip-j4jgyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:26:04 INFO - Running setup.py (path:/tmp/pip-srK9K0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:26:04 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)) 17:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:26:04 INFO - Running setup.py (path:/tmp/pip-O7Byjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:26:05 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)) 17:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:26:05 INFO - Running setup.py (path:/tmp/pip-KGqoNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:26:05 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)) 17:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:05 INFO - Running setup.py (path:/tmp/pip-cLSDG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:05 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)) 17:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:26:05 INFO - Running setup.py (path:/tmp/pip-g8X0MR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:26:05 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)) 17:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:26:05 INFO - Running setup.py (path:/tmp/pip-PJcG61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:26:05 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)) 17:26:05 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)) 17:26:05 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)) 17:26:05 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)) 17:26:05 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)) 17:26:05 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)) 17:26:05 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)) 17:26:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:26:05 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 17:26:05 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 17:26:05 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 17:26:05 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 17:26:06 INFO - Downloading blessings-1.6.tar.gz 17:26:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:26:06 INFO - Installing collected packages: blessings 17:26:06 INFO - Running setup.py install for blessings 17:26:06 INFO - Successfully installed blessings 17:26:06 INFO - Cleaning up... 17:26:06 INFO - Return code: 0 17:26:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:26:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:26:06 INFO - Reading from file tmpfile_stdout 17:26:06 INFO - Current package versions: 17:26:06 INFO - argparse == 1.2.1 17:26:06 INFO - blessings == 1.6 17:26:06 INFO - blobuploader == 1.2.4 17:26:06 INFO - docopt == 0.6.1 17:26:06 INFO - functools32 == 3.2.3-2 17:26:06 INFO - jsonschema == 2.5.1 17:26:06 INFO - manifestparser == 1.1 17:26:06 INFO - mozInstall == 1.13 17:26:06 INFO - mozcrash == 1.0 17:26:06 INFO - mozdebug == 0.1 17:26:06 INFO - mozdevice == 0.51 17:26:06 INFO - mozfile == 1.2 17:26:06 INFO - mozhttpd == 0.7 17:26:06 INFO - mozinfo == 0.10 17:26:06 INFO - mozleak == 0.1 17:26:06 INFO - mozlog == 3.5 17:26:06 INFO - moznetwork == 0.27 17:26:06 INFO - mozprocess == 0.25 17:26:06 INFO - mozprofile == 0.28 17:26:06 INFO - mozrunner == 6.13 17:26:06 INFO - mozscreenshot == 0.1 17:26:06 INFO - mozsystemmonitor == 0.3 17:26:06 INFO - moztest == 0.8 17:26:06 INFO - mozversion == 1.4 17:26:06 INFO - psutil == 3.1.1 17:26:06 INFO - requests == 1.2.3 17:26:06 INFO - wsgiref == 0.1.2 17:26:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:26:06 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')]} 17:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:06 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')]} 17:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:06 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 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:26:06 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 17:26:06 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 17:26:06 INFO - Using env: {'DISPLAY': ':0', 17:26:06 INFO - 'HOME': '/home/cltbld', 17:26:06 INFO - 'LANG': 'en_US.UTF-8', 17:26:06 INFO - 'LANGUAGE': 'en_US:en', 17:26:06 INFO - 'LOGNAME': 'cltbld', 17:26:06 INFO - 'MAIL': '/var/mail/cltbld', 17:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:06 INFO - 'MOZ_NO_REMOTE': '1', 17:26:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:26:06 INFO - 'NO_EM_RESTART': '1', 17:26:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:06 INFO - 'PWD': '/builds/slave/test', 17:26:06 INFO - 'SHELL': '/bin/bash', 17:26:06 INFO - 'SHLVL': '1', 17:26:06 INFO - 'TERM': 'linux', 17:26:06 INFO - 'TMOUT': '86400', 17:26:06 INFO - 'USER': 'cltbld', 17:26:06 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:06 INFO - '_': '/tools/buildbot/bin/python'} 17:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:07 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)) 17:26:07 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)) 17:26:07 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)) 17:26:07 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)) 17:26:07 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)) 17:26:07 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)) 17:26:07 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)) 17:26:07 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)) 17:26:07 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)) 17:26:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:26:07 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 17:26:07 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 17:26:07 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 17:26:07 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 17:26:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:26:08 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)) 17:26:08 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)) 17:26:08 INFO - Installing collected packages: simplejson 17:26:08 INFO - Running setup.py install for simplejson 17:26:08 INFO - building 'simplejson._speedups' extension 17:26:08 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 17:26:09 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 17:26:09 INFO - Successfully installed simplejson 17:26:09 INFO - Cleaning up... 17:26:09 INFO - Return code: 0 17:26:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:26:09 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')]} 17:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:09 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')]} 17:26:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:09 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 0xa1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, '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': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', '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 17:26:09 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 17:26:09 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 17:26:09 INFO - Using env: {'DISPLAY': ':0', 17:26:09 INFO - 'HOME': '/home/cltbld', 17:26:09 INFO - 'LANG': 'en_US.UTF-8', 17:26:09 INFO - 'LANGUAGE': 'en_US:en', 17:26:09 INFO - 'LOGNAME': 'cltbld', 17:26:09 INFO - 'MAIL': '/var/mail/cltbld', 17:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:09 INFO - 'MOZ_NO_REMOTE': '1', 17:26:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:26:09 INFO - 'NO_EM_RESTART': '1', 17:26:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:09 INFO - 'PWD': '/builds/slave/test', 17:26:09 INFO - 'SHELL': '/bin/bash', 17:26:09 INFO - 'SHLVL': '1', 17:26:09 INFO - 'TERM': 'linux', 17:26:09 INFO - 'TMOUT': '86400', 17:26:09 INFO - 'USER': 'cltbld', 17:26:09 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:09 INFO - '_': '/tools/buildbot/bin/python'} 17:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:26:09 INFO - Cleaning up... 17:26:09 INFO - Return code: 0 17:26:09 INFO - Running post-action listener: _resource_record_post_action 17:26:09 INFO - Running post-action listener: _start_resource_monitoring 17:26:09 INFO - Starting resource monitoring. 17:26:09 INFO - [mozharness: 2017-10-01 00:26:09.636174Z] Finished create-virtualenv step (success) 17:26:09 INFO - [mozharness: 2017-10-01 00:26:09.636648Z] Running install step. 17:26:09 INFO - Running pre-action listener: _resource_record_pre_action 17:26:09 INFO - Running main action method: install 17:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:26:09 INFO - Reading from file tmpfile_stdout 17:26:09 INFO - Detecting whether we're running mozinstall >=1.0... 17:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:26:10 INFO - Reading from file tmpfile_stdout 17:26:10 INFO - Output received: 17:26:10 INFO - Usage: mozinstall [options] installer 17:26:10 INFO - Options: 17:26:10 INFO - -h, --help show this help message and exit 17:26:10 INFO - -d DEST, --destination=DEST 17:26:10 INFO - Directory to install application into. [default: 17:26:10 INFO - "/builds/slave/test"] 17:26:10 INFO - --app=APP Application being installed. [default: firefox] 17:26:10 INFO - mkdir: /builds/slave/test/build/application 17:26:10 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'] 17:26:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:26:27 INFO - Reading from file tmpfile_stdout 17:26:27 INFO - Output received: 17:26:27 INFO - /builds/slave/test/build/application/firefox/firefox 17:26:27 INFO - Running post-action listener: _resource_record_post_action 17:26:27 INFO - [mozharness: 2017-10-01 00:26:27.602325Z] Finished install step (success) 17:26:27 INFO - [mozharness: 2017-10-01 00:26:27.602578Z] Running setup-mitmproxy step. 17:26:27 INFO - Running pre-action listener: _resource_record_pre_action 17:26:27 INFO - Running main action method: setup_mitmproxy 17:26:27 INFO - Skipping: mitmproxy is not required 17:26:27 INFO - Running post-action listener: _resource_record_post_action 17:26:27 INFO - [mozharness: 2017-10-01 00:26:27.603765Z] Finished setup-mitmproxy step (success) 17:26:27 INFO - [mozharness: 2017-10-01 00:26:27.603987Z] Running run-tests step. 17:26:27 INFO - Running pre-action listener: _resource_record_pre_action 17:26:27 INFO - Running pre-action listener: _set_gcov_prefix 17:26:27 INFO - Running main action method: run_tests 17:26:27 WARNING - Try message not found. 17:26:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:26:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:26:27 INFO - Python 2.7.3 17:26:27 INFO - Return code: 0 17:26:27 INFO - grabbing minidump binary from tooltool 17:26: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')]} 17:26:27 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 0xd89d60>, '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 0xd8aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8aef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:26:27 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 17:26:27 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 17:26:27 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 17:26:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:26:27 INFO - Return code: 0 17:26:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:26:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:26:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:26:27 INFO - ENV: RUST_BACKTRACE is now full 17:26:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:26:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:26:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:26:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-076 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:26:27 INFO - Using env: {'DISPLAY': ':0', 17:26:27 INFO - 'HOME': '/home/cltbld', 17:26:27 INFO - 'LANG': 'en_US.UTF-8', 17:26:27 INFO - 'LANGUAGE': 'en_US:en', 17:26:27 INFO - 'LOGNAME': 'cltbld', 17:26:27 INFO - 'MAIL': '/var/mail/cltbld', 17:26:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:26:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:26:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:27 INFO - 'MOZ_NO_REMOTE': '1', 17:26:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:26:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:26:27 INFO - 'NO_EM_RESTART': '1', 17:26:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:26:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:27 INFO - 'PWD': '/builds/slave/test', 17:26:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:26:27 INFO - 'RUST_BACKTRACE': 'full', 17:26:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:26:27 INFO - 'SHELL': '/bin/bash', 17:26:27 INFO - 'SHLVL': '1', 17:26:27 INFO - 'TERM': 'linux', 17:26:27 INFO - 'TMOUT': '86400', 17:26:27 INFO - 'USER': 'cltbld', 17:26:27 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:26:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:27 INFO - '_': '/tools/buildbot/bin/python'} 17:26:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:26:27 INFO - mozversion application_buildid: 20170930230214 17:26:27 INFO - mozversion application_changeset: 179dccc17c7f261347436b4b1c33029c8272960b 17:26:27 INFO - mozversion application_display_name: FirefoxNightly 17:26:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:26:27 INFO - mozversion application_name: Firefox 17:26:27 INFO - mozversion application_remotingname: firefox 17:26:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:26:27 INFO - mozversion application_vendor: Mozilla 17:26:27 INFO - mozversion application_version: 58.0a1 17:26:27 INFO - mozversion platform_buildid: 20170930230214 17:26:27 INFO - mozversion platform_changeset: 179dccc17c7f261347436b4b1c33029c8272960b 17:26:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:26:27 INFO - mozversion platform_version: 58.0a1 17:26:27 INFO - using testdate: 1506817587 17:26:27 INFO - actual date: 1506817587 17:26:27 INFO - starting webserver on 'localhost:37206' 17:26:27 INFO - SUITE-START | Running 2 tests 17:26:27 INFO - TEST-START | dromaeo_css 17:26:27 INFO - Initialising browser for dromaeo_css test... 17:26:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37206/getInfo.html -profile /tmp/tmptxutEh/profile 17:26:27 INFO - TEST-INFO | started process 6051 (/builds/slave/test/build/application/firefox/firefox http://localhost:37206/getInfo.html) 17:26:34 INFO - TEST-INFO | 6051: exit 0 17:26:34 INFO - Browser initialized. 17:26:34 INFO - Running cycle 1/1 for dromaeo_css test... 17:26:34 INFO - Using env: {'DISPLAY': ':0', 17:26:34 INFO - 'HOME': '/home/cltbld', 17:26:34 INFO - 'JSGC_DISABLE_POISONING': '1', 17:26:34 INFO - 'LANG': 'en_US.UTF-8', 17:26:34 INFO - 'LANGUAGE': 'en_US:en', 17:26:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:26:34 INFO - 'LOGNAME': 'cltbld', 17:26:34 INFO - 'MAIL': '/var/mail/cltbld', 17:26:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:26:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:26:34 INFO - 'MOZ_CRASHREPORTER': '1', 17:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:26:34 INFO - 'MOZ_NO_REMOTE': '1', 17:26:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:26:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:26:34 INFO - 'NO_EM_RESTART': '1', 17:26:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:34 INFO - 'PWD': '/builds/slave/test', 17:26:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:26:34 INFO - 'RUST_BACKTRACE': 'full', 17:26:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:26:34 INFO - 'SHELL': '/bin/bash', 17:26:34 INFO - 'SHLVL': '1', 17:26:34 INFO - 'TERM': 'linux', 17:26:34 INFO - 'TMOUT': '86400', 17:26:34 INFO - 'USER': 'cltbld', 17:26:34 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:34 INFO - '_': '/tools/buildbot/bin/python'} 17:26:34 INFO - TEST-INFO | started process 6291 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptxutEh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:34 INFO - PID 6291 | 17:26:34 INFO - PID 6291 | (/builds/slave/test/build/application/firefox/firefox:6341): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:26:34 INFO - PID 6291 | 17:26:45 INFO - PID 6291 | RSS: Main: 144785408 17:26:45 INFO - PID 6291 | 17:29:42 INFO - PID 6291 | Cycle 1(1): loaded http://localhost:37206/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37206/tests/dromaeo/cssquery-ext.html) 17:29:43 INFO - PID 6291 | RSS: Main: 143155200 17:29:43 INFO - PID 6291 | 17:32:45 INFO - PID 6291 | Cycle 1(1): loaded http://localhost:37206/tests/dromaeo/cssquery-ext.html (next: http://localhost:37206/tests/dromaeo/cssquery-jquery.html) 17:32:45 INFO - PID 6291 | RSS: Main: 143654912 17:32:45 INFO - PID 6291 | 17:35:47 INFO - PID 6291 | Cycle 1(1): loaded http://localhost:37206/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37206/tests/dromaeo/cssquery-mootools.html) 17:35:48 INFO - PID 6291 | RSS: Main: 145395712 17:35:48 INFO - PID 6291 | 17:38:49 INFO - PID 6291 | Cycle 1(1): loaded http://localhost:37206/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37206/tests/dromaeo/cssquery-prototype.html) 17:38:50 INFO - PID 6291 | RSS: Main: 145186816 17:38:50 INFO - PID 6291 | 17:41:53 INFO - PID 6291 | Cycle 1(1): loaded http://localhost:37206/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37206/tests/dromaeo/cssquery-yui.html) 17:41:53 INFO - PID 6291 | RSS: Main: 147886080 17:41:53 INFO - PID 6291 | 17:44:55 INFO - PID 6291 | Cycle 1(1): loaded http://localhost:37206/tests/dromaeo/cssquery-yui.html (next: http://localhost:37206/tests/dromaeo/cssquery-dojo.html) 17:44:55 INFO - PID 6291 | RSS: Main: 149204992 17:44:55 INFO - PID 6291 | 17:44:55 INFO - PID 6291 | __start_tp_report 17:44:55 INFO - PID 6291 | _x_x_mozilla_page_load 17:44:55 INFO - PID 6291 | _x_x_mozilla_page_load_details 17:44:55 INFO - PID 6291 | |i|pagename|runs| 17:44:55 INFO - PID 6291 | |0;dojo.html;3898.70759693023;3905.140869008817;3911.9804400978;3913.041304880305;3918.5101862267215;6166.753329866811;6202.813915582528;6277.497800175986;6277.937220627792;6357.427831495164;6288.496920246369;6290.779822706206;6305.873882522347;6317.273513545943;6334.144890439789;5492.148716948879;5618.634788738719;5648.926703926251;5654.180143879135;5680.460356266148;5627.521660658828;5695.886082278352;5700.8859822803515;5742.770289188427;5746.626469279484;643.8206457954182;5649.887002259932;5708.20085188074;5806.680632565207;5810.215620891189;5259.211118332242;5639;5648.068068768648;5651.0675738503505;5687.26065611472;5573.721313934328;5599.916001259984;5678.68767217807;5681.091025435951;5684.687342196177;6223.626582405071;6227.190465239535;6235.189425374672;6254.186955695776;6283.842903927392;6797;6808.659567021678;6874.450043996469;6886.931130688729;6893.0694356261765;6750.62871542065;6803.7618683345845;6832.624205668686;6890.138732658418;6897.827554311132;6275.215598050244;6327.651979141191;6344.49244060474;6344.873102537924;6367.108604795332;6859.9314006860295;6863.970404439374;6881.277465866012;6889.069975553336;6894.620795856175;6520.739170433224;6529.347065293433;6610.173728283965;6610.206775186912;6611.669416529155;6661.3338666132995;6680.432163266177;6712.060311556386;6734.326567343226;6741.932580674131;6736.696848641823;6742.157230346207;6762.222344430355;6780.288069752703;6800.31996800316;6734.46124310054;6744.932550674431;6756.4932630052945;6781.152355955505;6821.317868213139;5012.649114561946;5038.874028149326;5040.092783299041;5060.038592667382;5070.974645126825;4481.977590112028;4553.863384098482;4559.3844830948065;4609.7695115244105;4669.042846216517;6084.847878803065;6184.969075154595;6241.937580624226;6282.528810339243;6335.968320158369;6734.730610775612;6749.662516874137;6758.560693554903;6772.864542709118;6774.491913106537;6134.079888016833;6218.378162183745;6238.563300568917;6267.1539342189135;6283.905741413882;5516.172574113915;5536.861578460571;5574.916376254359;5576.442355764391;5594.104943209067;7068.5758854468895;7068.681909314093;7159.606221657858;7162.606056666829;7165.247648996885;6671.166104237068;6748.595084294958;6753.257141714309;6754.966225168842;6763.425108865703;6963.09479767627;6981.79054628362;7008.859822803413;7071.71713131469;7076.929230707627;6760.357766012221;6835.384815366641;6844.726210951506;6850.9314906850295;6856.931430685829;6851;6855.211650660039;6864.039034535269;6944.548604340702;6946.340097690711;6475.481961443167;6531.640759758259;6538.313477084837;6575.671216439063;6685.665716714242;6490.578112422678;6637.336266373324;6646.5347425681675;6653.5009874260595;6669.533132680665;6493.123428337114;6556.082148499118;6574.737010519717;6582.078509008838;6583.210014798255;6640.037194606852;6657.866842663023;6742.988551717085;6744.5616034957575;6758.391744742997;6367.363263673596;6431.48548116159;6444.065610486359;6454.580452270588;6483.059956306403 17:44:55 INFO - PID 6291 | |1;ext.html;920.1488623023702;937.0442149008012;941.7033634405162;950.4677380666827;952.7475219066953;42504.57495425045;44070.77964610181;44101.11797764043;44134.55865441345;44142.558574414245;42175.57824421755;42406.787966060125;42439.575604244026;42460.15079698404;42491;7382.077240344944;7481.289277518655;7496.812579685524;7516.210797866227;7518.774436766906;7356.852862942738;7447.553146811181;7466;7468;7482.102147742278;8400.369972252074;8642.30861531079;8657.567121643893;8657.653693852239;8662.740117796477;8559.871601925945;8583.95708021462;8600.22597966186;8619.698310559132;8622.267107295887;8012.278894899487;8491.74524764258;8491.787705307355;8494.915050849537;8497.872531911964;40943;41300.38049429261;41354.37968430476;41426.792866035474;41536.79231603823;3755.061234691327;3796.6013568575177;3805.086779173006;3806.276807406584;3806.371948628501;34061.829690851635;34073.659263407295;34144.48783268253;34179.65820341815;34184.48723269153;5497.890042199174;5514.228008078872;5540.168974653834;5546.889062218733;5621.690807005613;215079.99999999843;218632;218904;219284;219596;47594.762026189645;47804.7609761949;52842.47157528452;53724;53885.46114538883;56605.71697141488;56721.43278567244;56749.14876276859;56763.71618141883;56848.715756420956;16900.492985210465;16914.407995720092;16942.152892355334;17006.404775832787;17063.232154553076;13891.722165556632;13936.721265574632;14027.158370497802;14042.648933776738;14100;14988.550343489713;15129.39482420713;15186.088834669734;15223.238838058052;15363.231838408035;6060.212172417646;6226.782062627786;6255.874882502325;6315.242170939425;6321.936780632135;1128.9613555528933;1138.0610995928391;1144.0104309772153;1155.3472288157163;1156.7397335599592;25613.3596660085;25984.610230846552;26231.868840655676;26254.737452625228;26275.868620656773;1223.8347823043775;1232.2175418609247;1235.6663700800734;1243.0242259825993;1249.5564074753513;13684.31578421075;13787.172769633855;13894.722105558034;13910.095843770123;14059.929700351433;301312;304250;305696;306409;307232;56156;56249;56262.71868640713;56496;56677.43322566804;1519.4226194046191;1549.2486144220045;1563.2730780187267;1577.83432739563;1592.840715928398;25459.581339772692;25743.227703168934;25751.356216094748;25831.741682582935;26066;40937;40953.79523102365;41138.38292425556;41154;41172;237853;241140;241374;241441;243142;33395.165120872174;34102;36322.273554528234;36354.27291454209;37532;46302.768486158704;47212.7639361801;47879.281810773566;48392.5160748388;48415.515844842506;35156.47265290972;35455.64544354523;35541.28917421689;35542.28915421729;35839.28321433609;34572.481412778325;34798.30403391969;34989.825050874584;35209.295814084086;35669;38548;39800.80099599483;39859.60140398675;39894;39908.2018359637;33500.49749253812;33600;33650.3269934595;33911.15222119368;34384;25257.242282731546;25521.723913804606;25742.227733168034;26007.60988585135;26378.208653740418 17:44:55 INFO - PID 6291 | |2;jquery.html;625.7465726380817;637.3579324303284;640.0015586034913;641.8541586394252;649.2371463530351;23003.769962300372;23100.768992310073;23285.301440956755;23720.406989825264;23911.402214944614;25667.22998310049;26553.336166595844;26604.467910641775;26604.733952660466;26629.334266643342;6322.462590679797;6508.316626754194;6537.77117800877;6561.704723287439;6800.251972283045;6793.864122717543;6841.41847942923;6846.041554182419;6848.657567121649;6849.041134241185;6711.362420570038;6798.9660051699675;6803.455723542104;6827.112475378194;6827.249002609734;6761.661916904135;6788.932110678904;6832.897506537406;6837.418819400357;6841.144856892888;6329.810105696791;6365.236171659384;6372.426481616677;6374.490040796725;6389;29013.85493072542;29925.70074299251;30015.249618759488;30035.549466758017;30149.849250753607;2273.079402841852;2287.656851472276;2292.621717416624;2294.128231272123;2294.8852557372234;19938.401847944584;20330.390088297376;20800.375988720363;20914;20917.267895623732;2593.07945679283;2599.584066549343;2652.336915771057;2660.627512148301;2664.3072801071667;137205;142693.28653356666;156969.21515392466;160793;161712.99999999884;15448.845511544965;16017.519474415787;16610.252538635792;17185.226664800113;17204.1397930103;26730.465390692076;26860.731392686215;26890.462190756076;26904.327391814968;27051.323716907234;10442.321249118782;10500.264981451224;10579.682609521727;10604.719985203219;10737.731556711145;9389.10803473669;9419.24646028316;9456;9537.2370210383;9657.179139773058;9768.853467197998;9838.409695418297;10132.189424845994;10283.200439922983;10310.484475776182;2939.500284951521;3000.369922316338;3042.680518545521;3052.4658184817567;3090.8145511269395;1045.7647029418472;1055.3562326980536;1062.9627963021258;1067.354250678344;1068.930519516229;21533;21639;21690.132394704036;21949.341519754435;22242;1114.8216285394299;1135.7614900870744;1136.1251836086333;1145.3242586873619;1146.5356530605118;11540.711482212844;11822.172447928735;11901.34542600148;11952.701182470508;12096.153269271243;639056;659076;660582;661661;666751;129481;130784.3460782709;132840;132873;133765;1177.9116566257565;1201.2517578086156;1235.2958813476412;1236.3014896583402;1236.4436003798328;18645.533861653566;19003.144858481126;19194.13626386844;19198.328058517884;19246.615067698847;63028;63209.367906322186;64224.67887660532;64932;66029;81565;84772;85164.57417712871;85578;86301;15701.450449234222;15870.52388428349;15932.56606905384;16010.9199454002;16036.957597756575;24192.637110443004;24228.273151805475;24322.878385607957;24468.632970505812;24652.506949860544;19175.52061198481;19287.324943626907;19567.217311307388;19749.308774192836;19749.506262343555;17023.91488042552;17579.82420175782;17828.46514604564;17830.286788528832;18130.45608631743;19846;20255;20263.78417294967;20349.79650203479;20379.286724964553;15739.291731872323;15756.133412662413;15760.290786914387;15874.444394446138;15911.761323579924;17701.203445845225;17775.377861774778;17785.28858845632;18577.907110464363;19153.712694309874 17:44:55 INFO - PID 6291 | |3;mootools.html;1384.1141669331626;1467.2590341877358;1479.4673917389732;1484.2504535209723;1486.5168820133456;15488.612784680388;16371.427000055;17109.82890171098;17204.569885752848;17260.30958761648;17473.301067957265;17782.466526004242;17818;17856.64286714265;17861.7320740189;2650.774684151849;2739.493193759151;2746.615473833665;2749.628800111979;2786.7770578353684;2682.2757855379036;2683.7852971762213;2715.660542432196;2720.1567514070603;2720.741529554699;2718.7281271872853;2823.2800635837934;2868.4263147370507;2889.855507224641;2890.219640697021;2722.9319267018286;2776.5973933779487;2787.317107308702;2787.5539913613825;2791.6929137794828;2683.932901677454;2696.3933115049153;2697.9055733049445;2699.325168707823;2703.3782230086995;16610.75083873743;16613.750793738105;16721.080340581262;16731.163441827983;16807.747883781754;8175.918240817575;8417.368697347662;8439.493630382196;8444.408891377607;8464.365172612079;5407.621466497347;5670.5747068969995;5787.102999035156;5811;5836.153757705151;1928.5853541488507;1954.377866370923;1973.398113575359;1997.4407165993546;2018.4348382452936;30271;30343.24141896448;30571.2357191072;30583.2354191147;30623.38753224923;7411.481196316314;7503.5873026982945;7603.581803000888;7720;7739.690412383442;20092.799072009384;20118;20130.396088117086;20165.39503814888;20215.49461263451;7538.54768713868;8715.172058654545;8892.73321800347;8917.598708058153;8923.15230053144;7085.716571337089;7756.457048006585;7856.25365590268;7882.684692612346;7895.486793358414;5798.681072540966;5819.214406055213;5922.644641321534;5963.135345374983;5983.222181116427;1706.1384001079375;1715.794104707443;1718.089412611318;1741.6690828742499;1748.7027205375116;977.5405559387077;982.1553464020936;993.5727637115965;1012.6506355307407;1014.7108074198818;8575.313974881994;8620;8629.964404271528;8636.309095272363;8655.783605409915;756.6897571995455;784.0591290451481;788.050399268891;793.1354823242693;798.1738900238225;6050.939490605126;6054.636721796706;6059.454649081604;6123.785667501616;6205.193324867739;24088.15691450791;24558.508829823302;24565.38586535351;24577;24881;23187.7681223189;24257.75742242555;24547.63178552323;24644.753552464244;24690;12490;12589.18170318908;12695.809562856564;12755.808662870064;12767.680807979688;9704.175145112744;9827.31208815376;9840.065193806577;9881.752956176153;10033.648822291185;20656.17375304971;20744.27395041166;20782;21065.262715804874;21082.57834843264;21603.35189944304;21711.240106596193;21783.12867485283;21962.341129766133;21978.45053873602;11682.415879205835;12213.755724885275;12706;12716.809247861105;12911.35443227816;15855.841441585437;16012.599685007968;16067.11630860314;16071.67856642884;16100.67798644044;11811.173217874666;11848.170628056298;11986.46060927243;12026.819597705866;12101.818472723093;11618.128640351839;11747.47136378848;11816.40917954116;11852.58515951972;11962.461689223832;10644.414557199429;10666.946665266623;10671.199660025684;10718;10734.946325268324;8567.100454452193;8612.311015118901;8639.352048596505;8693.26107280863;8794.560271986504;8309.127541608286;8386.916130838614;8423.452475589067;8428.578571071299;8508.659653614033 17:44:55 INFO - PID 6291 | |4;prototype.html;551.9089350257208;558.6648011193283;578.7560722152333;584.8684046089629;593.525179856115;6196.349383314755;6236.812895613133;6257.155284036654;6308.558400911938;6311.842203944903;6168.568200225986;6197.380261973812;6197.5041996640275;6216.627002379849;6233.002719564871;4878.121938051149;5080.898382032357;5102.821401250957;5109.0803655342115;5131.589472842183;4989.376327959005;5015.297858299823;5022.698638081727;5038.420581633104;5045.167547354682;4946.826861059863;4950.009998000396;4965.106280869442;4974.701517908937;4977.153883839738;4962.60299176065;4995.300657907859;5008.624353173526;5013.197888337867;5038.596912247048;4567.885802854922;4582.931256031162;4592.288195329696;4593.655475839292;4620.3993480847275;5937.851553711149;5981.970090149565;6022.187004754346;6111.419415155598;6116.877662446771;5829.096490044008;5844.766209351621;5884.764609415618;5908.734106965197;5910.113482977545;6441.25925518562;6491.974975515163;6494.350564943468;6500.902486462707;6541.313162117956;4844.370231869834;5046.167382381903;5072.746362681851;5085;5115.61632877532;6524.87941419036;6637.502187335969;6770.932290677079;6821.4542836572955;6828.965855170742;5554.750036248379;5576.6375185613615;5580.637258578179;5620.803271885465;5661.235733176051;5593.692346920959;5600.971995139998;5701.572382071278;5720.342160651495;5722.855428914234;6062.09044151091;6073.453389194994;6077.17958075663;6084.5436592255755;6111.938880611226;6558.344165583403;6605.471562275006;6631.568948018364;6645.501587380966;6667.399934005963;1937.2928880958348;1973.3586584360141;1978.3867001229664;1994.650936086208;2007.3175120459114;1436.8060311857973;1502.7595584706394;1509.5094094419358;1517.383986054848;1519.8100237470317;5887.114504427058;5963.880722385615;6011.1884895539415;6028.960596400422;6050.122732203806;6504.869902601921;6614.900776488356;6639.634820084942;6662.40038396537;6686.4985126115735;5984.401559844068;5991.400859913973;6043.8489037773525;6071.878562428727;6080.847978800565;3478.4608385700258;3481.1819222482695;3523.71810255179;3523.823808809549;3534.293141371736;6788;6845.863082738417;6882.552634078769;6896.655167241618;6899.93100069003;6598.637074960923;6676.79969600903;6732.461403087741;6748.29142939994;6788.796336109925;6669.4330981866115;6766.18805743314;6784.016317633916;6840.3501667341525;6872.450203983769;6561.868762624817;6615.933840661532;6662.666866656745;6680.265170831108;6735.49483788708;6557.180352455943;6569.540132190701;6612.90887003639;6623.1389919312105;6641.26946035926;6919;6943.270956549692;6963.860722785617;6966.616836074065;6973.095066762629;5646.604737668488;5675.404082571271;5679.6876171810945;5685.573581981285;5704.629199102045;6516.57642253252;6696.7656132035145;6708.698108585223;6711.228208756055;6722.9663851680425;6293.622382657055;6390.712417941111;6459.321771214143;6475.190601174853;6565.638889861103;6361.586496877688;6397.8483872903325;6409.8718025640155;6507.778649568494;6522.21733391996;6121.969390153199;6233.566279755591;6268.749250029948;6385.648789316632;6460.8707825842275;6437.903431448625;6452.193475815523;6461.967690161519;6537.411632952867;6591.571547849375;6321.775894294035;6327.86098502274;6343.619382837044;6383.585066970632;6438.710258038306 17:44:55 INFO - PID 6291 | |5;yui.html;961.1685891703678;970.791279874827;994.1698681600863;1000.2448151645507;1004.7990401919618;18140.274389024424;18492.0753962302;18548.350807721734;18548.81451185488;18583.62832743346;16807.83192168078;17101.40145094922;17119.229634666437;17119.486415407526;17125.400610978617;1031.1029404418148;1059.0680201422738;1059.9841002384933;1061.7823346214045;1062.8033813744444;1065.9520321585508;1066.3282132256668;1066.5200659703125;1069.395791377869;1070.9303895246785;1074.8119079161154;1112.7496313329477;1115.5036008976008;1127.6560649002051;1128.6839684888243;1117.2235296468916;1118.2563595209176;1118.664400679797;1119.1270808769173;1120.0703416164604;796.044746304437;818.4639061414783;825.5170725125809;826.6486743134204;831.0484495252902;19642.116104775178;19824.405267841987;19860.304889328803;19902.502437439034;19905.303314384073;20509.4872628184;20650.48373790652;21128.683069753966;21429.24997625091;21499;17133.82866171335;17354.913225433917;17529.649407011915;17767.289308427647;17943.461696149006;3894.941575876364;3922.8430862765454;3924.646781789624;3928.410738389236;3928.6464218220212;121575.39212303971;154380;154452;157786.21106894393;157806;17576.384826531266;17687.20407581662;17850.91074544645;17975.370862020027;18092.638147236983;34803.477947830805;34950.47574286388;35084.29831403409;35416.46875296873;35536.822315888254;9662.75843103914;9730.221582273402;9769.51152442375;9817.55820988043;9830.852537211947;4433.556644335541;4451.154280686659;4484.775761211927;4498.955010449919;4503.2569626012;6099.146119543268;6164.784232551929;6233.376662333731;6233.719482623293;6256.843578910472;1125.3360517294673;1161.4367031989477;1173.049829637996;1177.952881884732;1179.2924245452832;724.6738967464662;727.6798208788116;732.9267073292629;736.2085757810376;756.5422919133955;12244.084682440847;12364.752704945851;12427.31649759272;13191.208527488381;13326.93336533311;659.5745743995096;660.4023358860239;668.5620918298522;670.4200866250658;674.8886433738477;5731.684757338387;5774.393688662714;5829.329627092854;5861.736221870026;5921.73352199152;149806;151868;152501.23749381403;153373;160224.1988790072;120189;129547;132066.33966830105;137521.3123934374;137892.931606026;112433.43783281195;112800;113836.43081784538;115924.42037789756;116919.8308016926;13260.801087983495;13368.264745438706;13616.455341786217;13620.863791362355;13665.726685466036;53958.730206348715;54101;54186.187207191135;54322.72838635781;55848.162277566684;70571;72449.63775181091;72640.9103863453;73416.63291683508;73505;18363.449096527125;18881.244750209837;18883.05584720731;18914.52713682169;18942.715859262396;19726.605467890848;19808.207671693548;19836.305729299405;19867.503312417306;19884.90057549703;18926.621467570298;19056.142473588443;19180.71228931595;19249.845009299486;19396.2241510338;18377.54056148607;18998.715019274998;19103.140358683613;19210.711839322143;19262;17810.91094544519;17886.910565447088;17891.910540447214;17937.37219197322;18066.819331806513;13488.393022314216;13535;13643;13796.793048104086;13850.30748462593;12977.545785897448;12982.935085324512;12994.870051299366;13068.607941761762;13122.278274694601 17:44:55 INFO - PID 6291 | __end_tp_report 17:44:55 INFO - PID 6291 | __start_cc_report 17:44:55 INFO - PID 6291 | _x_x_mozilla_cycle_collect,269 17:44:55 INFO - PID 6291 | __end_cc_report 17:44:55 INFO - PID 6291 | __startTimestamp1506818695604__endTimestamp 17:44:55 INFO - PID 6291 | 17:44:55 INFO - PID 6291 | ------- Summary: start ------- 17:44:55 INFO - PID 6291 | Number of tests: 6 17:44:55 INFO - PID 6291 | 17:44:55 INFO - PID 6291 | [#0] dojo.html Cycles:165 Average:6229.57 Median:6493.12 stddev:845.28 (13.0%) stddev-sans-first:827.85 17:44:55 INFO - PID 6291 | Values: 3898.7 3905.1 3912.0 3913.0 3918.5 6166.8 6202.8 6277.5 6277.9 6357.4 6288.5 6290.8 6305.9 6317.3 6334.1 5492.1 5618.6 5648.9 5654.2 5680.5 5627.5 5695.9 5700.9 5742.8 5746.6 643.8 5649.9 5708.2 5806.7 5810.2 5259.2 5639.0 5648.1 5651.1 5687.3 5573.7 5599.9 5678.7 5681.1 5684.7 6223.6 6227.2 6235.2 6254.2 6283.8 6797.0 6808.7 6874.5 6886.9 6893.1 6750.6 6803.8 6832.6 6890.1 6897.8 6275.2 6327.7 6344.5 6344.9 6367.1 6859.9 6864.0 6881.3 6889.1 6894.6 6520.7 6529.3 6610.2 6610.2 6611.7 6661.3 6680.4 6712.1 6734.3 6741.9 6736.7 6742.2 6762.2 6780.3 6800.3 6734.5 6744.9 6756.5 6781.2 6821.3 5012.6 5038.9 5040.1 5060.0 5071.0 4482.0 4553.9 4559.4 4609.8 4669.0 6084.8 6185.0 6241.9 6282.5 6336.0 6734.7 6749.7 6758.6 6772.9 6774.5 6134.1 6218.4 6238.6 6267.2 6283.9 5516.2 5536.9 5574.9 5576.4 5594.1 7068.6 7068.7 7159.6 7162.6 7165.2 6671.2 6748.6 6753.3 6755.0 6763.4 6963.1 6981.8 7008.9 7071.7 7076.9 6760.4 6835.4 6844.7 6850.9 6856.9 6851.0 6855.2 6864.0 6944.5 6946.3 6475.5 6531.6 6538.3 6575.7 6685.7 6490.6 6637.3 6646.5 6653.5 6669.5 6493.1 6556.1 6574.7 6582.1 6583.2 6640.0 6657.9 6743.0 6744.6 6758.4 6367.4 6431.5 6444.1 6454.6 6483.1 17:44:55 INFO - PID 6291 | 17:44:55 INFO - PID 6291 | [#1] ext.html Cycles:180 Average:43250.10 Median:3775.83 stddev:67015.79 (1774.9%) stddev-sans-first:67128.42 17:44:55 INFO - PID 6291 | Values: 920.1 937.0 941.7 950.5 952.7 42504.6 44070.8 44101.1 44134.6 44142.6 42175.6 42406.8 42439.6 42460.2 42491.0 7382.1 7481.3 7496.8 7516.2 7518.8 7356.9 7447.6 7466.0 7468.0 7482.1 8400.4 8642.3 8657.6 8657.7 8662.7 8559.9 8584.0 8600.2 8619.7 8622.3 8012.3 8491.7 8491.8 8494.9 8497.9 40943.0 41300.4 41354.4 41426.8 41536.8 3755.1 3796.6 3805.1 3806.3 3806.4 34061.8 34073.7 34144.5 34179.7 34184.5 5497.9 5514.2 5540.2 5546.9 5621.7 215080.0 218632.0 218904.0 219284.0 219596.0 47594.8 47804.8 52842.5 53724.0 53885.5 56605.7 56721.4 56749.1 56763.7 56848.7 16900.5 16914.4 16942.2 17006.4 17063.2 13891.7 13936.7 14027.2 14042.6 14100.0 14988.6 15129.4 15186.1 15223.2 15363.2 6060.2 6226.8 6255.9 6315.2 6321.9 1129.0 1138.1 1144.0 1155.3 1156.7 25613.4 25984.6 26231.9 26254.7 26275.9 1223.8 1232.2 1235.7 1243.0 1249.6 13684.3 13787.2 13894.7 13910.1 14059.9 301312.0 304250.0 305696.0 306409.0 307232.0 56156.0 56249.0 56262.7 56496.0 56677.4 1519.4 1549.2 1563.3 1577.8 1592.8 25459.6 25743.2 25751.4 25831.7 26066.0 40937.0 40953.8 41138.4 41154.0 41172.0 237853.0 241140.0 241374.0 241441.0 243142.0 33395.2 34102.0 36322.3 36354.3 37532.0 46302.8 47212.8 47879.3 48392.5 48415.5 35156.5 35455.6 35541.3 35542.3 35839.3 34572.5 34798.3 34989.8 35209.3 35669.0 38548.0 39800.8 39859.6 39894.0 39908.2 33500.5 33600.0 33650.3 33911.2 34384.0 25257.2 25521.7 25742.2 26007.6 26378.2 17:44:55 INFO - PID 6291 | 17:44:55 INFO - PID 6291 | [#2] jquery.html Cycles:180 Average:41492.33 Median:2293.37 stddev:109572.96 (4777.8%) stddev-sans-first:109837.38 17:44:55 INFO - PID 6291 | Values: 625.7 637.4 640.0 641.9 649.2 23003.8 23100.8 23285.3 23720.4 23911.4 25667.2 26553.3 26604.5 26604.7 26629.3 6322.5 6508.3 6537.8 6561.7 6800.3 6793.9 6841.4 6846.0 6848.7 6849.0 6711.4 6799.0 6803.5 6827.1 6827.2 6761.7 6788.9 6832.9 6837.4 6841.1 6329.8 6365.2 6372.4 6374.5 6389.0 29013.9 29925.7 30015.2 30035.5 30149.8 2273.1 2287.7 2292.6 2294.1 2294.9 19938.4 20330.4 20800.4 20914.0 20917.3 2593.1 2599.6 2652.3 2660.6 2664.3 137205.0 142693.3 156969.2 160793.0 161713.0 15448.8 16017.5 16610.3 17185.2 17204.1 26730.5 26860.7 26890.5 26904.3 27051.3 10442.3 10500.3 10579.7 10604.7 10737.7 9389.1 9419.2 9456.0 9537.2 9657.2 9768.9 9838.4 10132.2 10283.2 10310.5 2939.5 3000.4 3042.7 3052.5 3090.8 1045.8 1055.4 1063.0 1067.4 1068.9 21533.0 21639.0 21690.1 21949.3 22242.0 1114.8 1135.8 1136.1 1145.3 1146.5 11540.7 11822.2 11901.3 11952.7 12096.2 639056.0 659076.0 660582.0 661661.0 666751.0 129481.0 130784.3 132840.0 132873.0 133765.0 1177.9 1201.3 1235.3 1236.3 1236.4 18645.5 19003.1 19194.1 19198.3 19246.6 63028.0 63209.4 64224.7 64932.0 66029.0 81565.0 84772.0 85164.6 85578.0 86301.0 15701.5 15870.5 15932.6 16010.9 16037.0 24192.6 24228.3 24322.9 24468.6 24652.5 19175.5 19287.3 19567.2 19749.3 19749.5 17023.9 17579.8 17828.5 17830.3 18130.5 19846.0 20255.0 20263.8 20349.8 20379.3 15739.3 15756.1 15760.3 15874.4 15911.8 17701.2 17775.4 17785.3 18577.9 19153.7 17:44:55 INFO - PID 6291 | 17:44:55 INFO - PID 6291 | [#3] mootools.html Cycles:180 Average:10417.33 Median:2683.86 stddev:7665.47 (285.6%) stddev-sans-first:7656.93 17:44:55 INFO - PID 6291 | Values: 1384.1 1467.3 1479.5 1484.3 1486.5 15488.6 16371.4 17109.8 17204.6 17260.3 17473.3 17782.5 17818.0 17856.6 17861.7 2650.8 2739.5 2746.6 2749.6 2786.8 2682.3 2683.8 2715.7 2720.2 2720.7 2718.7 2823.3 2868.4 2889.9 2890.2 2722.9 2776.6 2787.3 2787.6 2791.7 2683.9 2696.4 2697.9 2699.3 2703.4 16610.8 16613.8 16721.1 16731.2 16807.7 8175.9 8417.4 8439.5 8444.4 8464.4 5407.6 5670.6 5787.1 5811.0 5836.2 1928.6 1954.4 1973.4 1997.4 2018.4 30271.0 30343.2 30571.2 30583.2 30623.4 7411.5 7503.6 7603.6 7720.0 7739.7 20092.8 20118.0 20130.4 20165.4 20215.5 7538.5 8715.2 8892.7 8917.6 8923.2 7085.7 7756.5 7856.3 7882.7 7895.5 5798.7 5819.2 5922.6 5963.1 5983.2 1706.1 1715.8 1718.1 1741.7 1748.7 977.5 982.2 993.6 1012.7 1014.7 8575.3 8620.0 8630.0 8636.3 8655.8 756.7 784.1 788.1 793.1 798.2 6050.9 6054.6 6059.5 6123.8 6205.2 24088.2 24558.5 24565.4 24577.0 24881.0 23187.8 24257.8 24547.6 24644.8 24690.0 12490.0 12589.2 12695.8 12755.8 12767.7 9704.2 9827.3 9840.1 9881.8 10033.6 20656.2 20744.3 20782.0 21065.3 21082.6 21603.4 21711.2 21783.1 21962.3 21978.5 11682.4 12213.8 12706.0 12716.8 12911.4 15855.8 16012.6 16067.1 16071.7 16100.7 11811.2 11848.2 11986.5 12026.8 12101.8 11618.1 11747.5 11816.4 11852.6 11962.5 10644.4 10666.9 10671.2 10718.0 10734.9 8567.1 8612.3 8639.4 8693.3 8794.6 8309.1 8386.9 8423.5 8428.6 8508.7 17:44:55 INFO - PID 6291 | 17:44:55 INFO - PID 6291 | [#4] prototype.html Cycles:180 Average:5621.48 Median:6145.27 stddev:1502.20 (24.4%) stddev-sans-first:1457.42 17:44:55 INFO - PID 6291 | Values: 551.9 558.7 578.8 584.9 593.5 6196.3 6236.8 6257.2 6308.6 6311.8 6168.6 6197.4 6197.5 6216.6 6233.0 4878.1 5080.9 5102.8 5109.1 5131.6 4989.4 5015.3 5022.7 5038.4 5045.2 4946.8 4950.0 4965.1 4974.7 4977.2 4962.6 4995.3 5008.6 5013.2 5038.6 4567.9 4582.9 4592.3 4593.7 4620.4 5937.9 5982.0 6022.2 6111.4 6116.9 5829.1 5844.8 5884.8 5908.7 5910.1 6441.3 6492.0 6494.4 6500.9 6541.3 4844.4 5046.2 5072.7 5085.0 5115.6 6524.9 6637.5 6770.9 6821.5 6829.0 5554.8 5576.6 5580.6 5620.8 5661.2 5593.7 5601.0 5701.6 5720.3 5722.9 6062.1 6073.5 6077.2 6084.5 6111.9 6558.3 6605.5 6631.6 6645.5 6667.4 1937.3 1973.4 1978.4 1994.7 2007.3 1436.8 1502.8 1509.5 1517.4 1519.8 5887.1 5963.9 6011.2 6029.0 6050.1 6504.9 6614.9 6639.6 6662.4 6686.5 5984.4 5991.4 6043.8 6071.9 6080.8 3478.5 3481.2 3523.7 3523.8 3534.3 6788.0 6845.9 6882.6 6896.7 6899.9 6598.6 6676.8 6732.5 6748.3 6788.8 6669.4 6766.2 6784.0 6840.4 6872.5 6561.9 6615.9 6662.7 6680.3 6735.5 6557.2 6569.5 6612.9 6623.1 6641.3 6919.0 6943.3 6963.9 6966.6 6973.1 5646.6 5675.4 5679.7 5685.6 5704.6 6516.6 6696.8 6708.7 6711.2 6723.0 6293.6 6390.7 6459.3 6475.2 6565.6 6361.6 6397.8 6409.9 6507.8 6522.2 6122.0 6233.6 6268.7 6385.6 6460.9 6437.9 6452.2 6462.0 6537.4 6591.6 6321.8 6327.9 6343.6 6383.6 6438.7 17:44:55 INFO - PID 6291 | 17:44:55 INFO - PID 6291 | [#5] yui.html Cycles:180 Average:28028.15 Median:18520.21 stddev:41761.76 (225.5%) stddev-sans-first:41829.46 17:44:55 INFO - PID 6291 | Values: 961.2 970.8 994.2 1000.2 1004.8 18140.3 18492.1 18548.4 18548.8 18583.6 16807.8 17101.4 17119.2 17119.5 17125.4 1031.1 1059.1 1060.0 1061.8 1062.8 1066.0 1066.3 1066.5 1069.4 1070.9 1074.8 1112.7 1115.5 1127.7 1128.7 1117.2 1118.3 1118.7 1119.1 1120.1 796.0 818.5 825.5 826.6 831.0 19642.1 19824.4 19860.3 19902.5 19905.3 20509.5 20650.5 21128.7 21429.2 21499.0 17133.8 17354.9 17529.6 17767.3 17943.5 3894.9 3922.8 3924.6 3928.4 3928.6 121575.4 154380.0 154452.0 157786.2 157806.0 17576.4 17687.2 17850.9 17975.4 18092.6 34803.5 34950.5 35084.3 35416.5 35536.8 9662.8 9730.2 9769.5 9817.6 9830.9 4433.6 4451.2 4484.8 4499.0 4503.3 6099.1 6164.8 6233.4 6233.7 6256.8 1125.3 1161.4 1173.0 1178.0 1179.3 724.7 727.7 732.9 736.2 756.5 12244.1 12364.8 12427.3 13191.2 13326.9 659.6 660.4 668.6 670.4 674.9 5731.7 5774.4 5829.3 5861.7 5921.7 149806.0 151868.0 152501.2 153373.0 160224.2 120189.0 129547.0 132066.3 137521.3 137892.9 112433.4 112800.0 113836.4 115924.4 116919.8 13260.8 13368.3 13616.5 13620.9 13665.7 53958.7 54101.0 54186.2 54322.7 55848.2 70571.0 72449.6 72640.9 73416.6 73505.0 18363.4 18881.2 18883.1 18914.5 18942.7 19726.6 19808.2 19836.3 19867.5 19884.9 18926.6 19056.1 19180.7 19249.8 19396.2 18377.5 18998.7 19103.1 19210.7 19262.0 17810.9 17886.9 17891.9 17937.4 18066.8 13488.4 13535.0 13643.0 13796.8 13850.3 12977.5 12982.9 12994.9 13068.6 13122.3 17:44:55 INFO - PID 6291 | -------- Summary: end -------- 17:44:55 INFO - PID 6291 | 17:44:56 INFO - TEST-INFO | 6291: exit 0 17:44:56 INFO - TEST-OK | dromaeo_css | took 1108142ms 17:44:56 INFO - TEST-START | kraken 17:44:56 INFO - Initialising browser for kraken test... 17:44:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37206/getInfo.html -profile /tmp/tmpXIWnak/profile 17:44:56 INFO - TEST-INFO | started process 6662 (/builds/slave/test/build/application/firefox/firefox http://localhost:37206/getInfo.html) 17:45:01 INFO - TEST-INFO | 6662: exit 0 17:45:01 INFO - Browser initialized. 17:45:01 INFO - Running cycle 1/1 for kraken test... 17:45:01 INFO - Using env: {'DISPLAY': ':0', 17:45:01 INFO - 'HOME': '/home/cltbld', 17:45:01 INFO - 'JSGC_DISABLE_POISONING': '1', 17:45:01 INFO - 'LANG': 'en_US.UTF-8', 17:45:01 INFO - 'LANGUAGE': 'en_US:en', 17:45:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:45:01 INFO - 'LOGNAME': 'cltbld', 17:45:01 INFO - 'MAIL': '/var/mail/cltbld', 17:45:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:45:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:45:01 INFO - 'MOZ_CRASHREPORTER': '1', 17:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:45:01 INFO - 'MOZ_NO_REMOTE': '1', 17:45:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:45:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:45:01 INFO - 'NO_EM_RESTART': '1', 17:45:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:01 INFO - 'PWD': '/builds/slave/test', 17:45:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:45:01 INFO - 'RUST_BACKTRACE': 'full', 17:45:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:45:01 INFO - 'SHELL': '/bin/bash', 17:45:01 INFO - 'SHLVL': '1', 17:45:01 INFO - 'TERM': 'linux', 17:45:01 INFO - 'TMOUT': '86400', 17:45:01 INFO - 'USER': 'cltbld', 17:45:01 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954', 17:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:01 INFO - '_': '/tools/buildbot/bin/python'} 17:45:01 INFO - TEST-INFO | started process 6900 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXIWnak/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:45:02 INFO - PID 6900 | 17:45:02 INFO - PID 6900 | (/builds/slave/test/build/application/firefox/firefox:6949): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:45:02 INFO - PID 6900 | 17:45:12 INFO - PID 6900 | RSS: Main: 143917056 17:45:12 INFO - PID 6900 | 17:45:15 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37206/tests/kraken/driver.html?testName=audio-beat-detection) 17:45:16 INFO - PID 6900 | RSS: Main: 150466560 17:45:16 INFO - PID 6900 | 17:45:18 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37206/tests/kraken/driver.html?testName=audio-dft) 17:45:19 INFO - PID 6900 | RSS: Main: 150839296 17:45:19 INFO - PID 6900 | 17:45:22 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37206/tests/kraken/driver.html?testName=audio-fft) 17:45:22 INFO - PID 6900 | RSS: Main: 147243008 17:45:22 INFO - PID 6900 | 17:45:24 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37206/tests/kraken/driver.html?testName=audio-oscillator) 17:45:25 INFO - PID 6900 | RSS: Main: 148566016 17:45:25 INFO - PID 6900 | 17:45:27 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37206/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:45:28 INFO - PID 6900 | RSS: Main: 148381696 17:45:28 INFO - PID 6900 | 17:45:31 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37206/tests/kraken/driver.html?testName=imaging-darkroom) 17:45:31 INFO - PID 6900 | RSS: Main: 148381696 17:45:31 INFO - PID 6900 | 17:45:35 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37206/tests/kraken/driver.html?testName=imaging-desaturate) 17:45:36 INFO - PID 6900 | RSS: Main: 149716992 17:45:36 INFO - PID 6900 | 17:45:38 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37206/tests/kraken/driver.html?testName=json-parse-financial) 17:45:39 INFO - PID 6900 | RSS: Main: 151355392 17:45:39 INFO - PID 6900 | 17:45:41 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37206/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:45:41 INFO - PID 6900 | RSS: Main: 151281664 17:45:41 INFO - PID 6900 | 17:45:43 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37206/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:45:43 INFO - PID 6900 | RSS: Main: 151097344 17:45:43 INFO - PID 6900 | 17:45:45 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37206/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:45:46 INFO - PID 6900 | RSS: Main: 148123648 17:45:46 INFO - PID 6900 | 17:45:48 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37206/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:45:49 INFO - PID 6900 | RSS: Main: 148086784 17:45:49 INFO - PID 6900 | 17:45:52 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37206/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:45:52 INFO - PID 6900 | RSS: Main: 148951040 17:45:52 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | Cycle 1(1): loaded http://localhost:37206/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37206/tests/kraken/driver.html?testName=ai-astar) 17:45:54 INFO - PID 6900 | RSS: Main: 150376448 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | __start_tp_report 17:45:54 INFO - PID 6900 | _x_x_mozilla_page_load 17:45:54 INFO - PID 6900 | _x_x_mozilla_page_load_details 17:45:54 INFO - PID 6900 | |i|pagename|runs| 17:45:54 INFO - PID 6900 | |0;ai-astar;105;105;105;105;105;106;108;106;105;106 17:45:54 INFO - PID 6900 | |1;audio-beat-detection;111;111;115;110;116;110;111;144;110;142 17:45:54 INFO - PID 6900 | |2;audio-dft;164;164;163;162;162;163;162;164;161;164 17:45:54 INFO - PID 6900 | |3;audio-fft;75;73;73;72;73;73;73;73;74;73 17:45:54 INFO - PID 6900 | |4;audio-oscillator;78;77;77;79;79;78;78;78;77;82 17:45:54 INFO - PID 6900 | |5;imaging-gaussian-blur;111;111;117;111;111;111;112;116;112;117 17:45:54 INFO - PID 6900 | |6;imaging-darkroom;149;149;148;151;150;149;150;149;151;153 17:45:54 INFO - PID 6900 | |7;imaging-desaturate;94;96;95;94;94;94;94;94;97;95 17:45:54 INFO - PID 6900 | |8;json-parse-financial;44;42;43;43;44;44;43;42;44;43 17:45:54 INFO - PID 6900 | |9;json-stringify-tinderbox;36;36;36;36;38;36;35;37;36;40 17:45:54 INFO - PID 6900 | |10;stanford-crypto-aes;76;73;75;69;72;71;72;73;70;70 17:45:54 INFO - PID 6900 | |11;stanford-crypto-ccm;115;105;102;112;103;106;104;107;107;104 17:45:54 INFO - PID 6900 | |12;stanford-crypto-pbkdf2;150;156;146;148;151;147;147;149;148;149 17:45:54 INFO - PID 6900 | |13;stanford-crypto-sha256-iterative;59;57;58;58;58;59;57;60;68;64 17:45:54 INFO - PID 6900 | __end_tp_report 17:45:54 INFO - PID 6900 | __start_cc_report 17:45:54 INFO - PID 6900 | _x_x_mozilla_cycle_collect,752 17:45:54 INFO - PID 6900 | __end_cc_report 17:45:54 INFO - PID 6900 | __startTimestamp1506818754691__endTimestamp 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | ------- Summary: start ------- 17:45:54 INFO - PID 6900 | Number of tests: 14 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#0] ai-astar Cycles:10 Average:105.60 Median:105.50 stddev:0.97 (0.9%) stddev-sans-first:1.00 17:45:54 INFO - PID 6900 | Values: 105.0 105.0 105.0 105.0 105.0 106.0 108.0 106.0 105.0 106.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#1] audio-beat-detection Cycles:10 Average:118.00 Median:113.00 stddev:13.35 (11.8%) stddev-sans-first:13.92 17:45:54 INFO - PID 6900 | Values: 111.0 111.0 115.0 110.0 116.0 110.0 111.0 144.0 110.0 142.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.50 stddev:1.10 (0.7%) stddev-sans-first:1.09 17:45:54 INFO - PID 6900 | Values: 164.0 164.0 163.0 162.0 162.0 163.0 162.0 164.0 161.0 164.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#3] audio-fft Cycles:10 Average:73.20 Median:73.00 stddev:0.79 (1.1%) stddev-sans-first:0.50 17:45:54 INFO - PID 6900 | Values: 75.0 73.0 73.0 72.0 73.0 73.0 73.0 73.0 74.0 73.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:78.00 stddev:1.49 (1.9%) stddev-sans-first:1.58 17:45:54 INFO - PID 6900 | Values: 78.0 77.0 77.0 79.0 79.0 78.0 78.0 78.0 77.0 82.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:112.00 stddev:2.64 (2.4%) stddev-sans-first:2.71 17:45:54 INFO - PID 6900 | Values: 111.0 111.0 117.0 111.0 111.0 111.0 112.0 116.0 112.0 117.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:150.00 stddev:1.45 (1.0%) stddev-sans-first:1.50 17:45:54 INFO - PID 6900 | Values: 149.0 149.0 148.0 151.0 150.0 149.0 150.0 149.0 151.0 153.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.50 stddev:1.06 (1.1%) stddev-sans-first:1.09 17:45:54 INFO - PID 6900 | Values: 94.0 96.0 95.0 94.0 94.0 94.0 94.0 94.0 97.0 95.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#8] json-parse-financial Cycles:10 Average:43.20 Median:43.50 stddev:0.79 (1.8%) stddev-sans-first:0.78 17:45:54 INFO - PID 6900 | Values: 44.0 42.0 43.0 43.0 44.0 44.0 43.0 42.0 44.0 43.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#9] json-stringify-tinderbox Cycles:10 Average:36.60 Median:36.00 stddev:1.43 (4.0%) stddev-sans-first:1.50 17:45:54 INFO - PID 6900 | Values: 36.0 36.0 36.0 36.0 38.0 36.0 35.0 37.0 36.0 40.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#10] stanford-crypto-aes Cycles:10 Average:72.10 Median:72.50 stddev:2.23 (3.1%) stddev-sans-first:1.87 17:45:54 INFO - PID 6900 | Values: 76.0 73.0 75.0 69.0 72.0 71.0 72.0 73.0 70.0 70.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#11] stanford-crypto-ccm Cycles:10 Average:106.50 Median:106.50 stddev:4.09 (3.8%) stddev-sans-first:2.96 17:45:54 INFO - PID 6900 | Values: 115.0 105.0 102.0 112.0 103.0 106.0 104.0 107.0 107.0 104.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.10 Median:149.00 stddev:2.85 (1.9%) stddev-sans-first:3.00 17:45:54 INFO - PID 6900 | Values: 150.0 156.0 146.0 148.0 151.0 147.0 147.0 149.0 148.0 149.0 17:45:54 INFO - PID 6900 | 17:45:54 INFO - PID 6900 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:59.00 stddev:3.52 (6.0%) stddev-sans-first:3.72 17:45:54 INFO - PID 6900 | Values: 59.0 57.0 58.0 58.0 58.0 59.0 57.0 60.0 68.0 64.0 17:45:54 INFO - PID 6900 | -------- Summary: end -------- 17:45:54 INFO - PID 6900 | 17:45:55 INFO - PID 6900 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:45:55 INFO - TEST-INFO | 6900: exit 0 17:45:55 INFO - TEST-OK | kraken | took 59277ms 17:45:55 INFO - SUITE-END | took 1167s 17:45:55 INFO - Completed test suite (00:19:27) 17:45:55 INFO - javascript benchmark 17:45:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9073.594718202157, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3898.70759693023, 3905.140869008817, 3911.9804400978, 3913.041304880305, 3918.5101862267215, 6166.753329866811, 6202.813915582528, 6277.497800175986, 6277.937220627792, 6357.427831495164, 6288.496920246369, 6290.779822706206, 6305.873882522347, 6317.273513545943, 6334.144890439789, 5492.148716948879, 5618.634788738719, 5648.926703926251, 5654.180143879135, 5680.460356266148, 5627.521660658828, 5695.886082278352, 5700.8859822803515, 5742.770289188427, 5746.626469279484, 643.8206457954182, 5649.887002259932, 5708.20085188074, 5806.680632565207, 5810.215620891189, 5259.211118332242, 5639.0, 5648.068068768648, 5651.0675738503505, 5687.26065611472, 5573.721313934328, 5599.916001259984, 5678.68767217807, 5681.091025435951, 5684.687342196177, 6223.626582405071, 6227.190465239535, 6235.189425374672, 6254.186955695776, 6283.842903927392, 6797.0, 6808.659567021678, 6874.450043996469, 6886.931130688729, 6893.0694356261765, 6750.62871542065, 6803.7618683345845, 6832.624205668686, 6890.138732658418, 6897.827554311132, 6275.215598050244, 6327.651979141191, 6344.49244060474, 6344.873102537924, 6367.108604795332, 6859.9314006860295, 6863.970404439374, 6881.277465866012, 6889.069975553336, 6894.620795856175, 6520.739170433224, 6529.347065293433, 6610.173728283965, 6610.206775186912, 6611.669416529155, 6661.3338666132995, 6680.432163266177, 6712.060311556386, 6734.326567343226, 6741.932580674131, 6736.696848641823, 6742.157230346207, 6762.222344430355, 6780.288069752703, 6800.31996800316, 6734.46124310054, 6744.932550674431, 6756.4932630052945, 6781.152355955505, 6821.317868213139, 5012.649114561946, 5038.874028149326, 5040.092783299041, 5060.038592667382, 5070.974645126825, 4481.977590112028, 4553.863384098482, 4559.3844830948065, 4609.7695115244105, 4669.042846216517, 6084.847878803065, 6184.969075154595, 6241.937580624226, 6282.528810339243, 6335.968320158369, 6734.730610775612, 6749.662516874137, 6758.560693554903, 6772.864542709118, 6774.491913106537, 6134.079888016833, 6218.378162183745, 6238.563300568917, 6267.1539342189135, 6283.905741413882, 5516.172574113915, 5536.861578460571, 5574.916376254359, 5576.442355764391, 5594.104943209067, 7068.5758854468895, 7068.681909314093, 7159.606221657858, 7162.606056666829, 7165.247648996885, 6671.166104237068, 6748.595084294958, 6753.257141714309, 6754.966225168842, 6763.425108865703, 6963.09479767627, 6981.79054628362, 7008.859822803413, 7071.71713131469, 7076.929230707627, 6760.357766012221, 6835.384815366641, 6844.726210951506, 6850.9314906850295, 6856.931430685829, 6851.0, 6855.211650660039, 6864.039034535269, 6944.548604340702, 6946.340097690711, 6475.481961443167, 6531.640759758259, 6538.313477084837, 6575.671216439063, 6685.665716714242, 6490.578112422678, 6637.336266373324, 6646.5347425681675, 6653.5009874260595, 6669.533132680665, 6493.123428337114, 6556.082148499118, 6574.737010519717, 6582.078509008838, 6583.210014798255, 6640.037194606852, 6657.866842663023, 6742.988551717085, 6744.5616034957575, 6758.391744742997, 6367.363263673596, 6431.48548116159, 6444.065610486359, 6454.580452270588, 6483.059956306403], "value": 6176.035990907956, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [920.1488623023702, 937.0442149008012, 941.7033634405162, 950.4677380666827, 952.7475219066953, 42504.57495425045, 44070.77964610181, 44101.11797764043, 44134.55865441345, 44142.558574414245, 42175.57824421755, 42406.787966060125, 42439.575604244026, 42460.15079698404, 42491.0, 7382.077240344944, 7481.289277518655, 7496.812579685524, 7516.210797866227, 7518.774436766906, 7356.852862942738, 7447.553146811181, 7466.0, 7468.0, 7482.102147742278, 8400.369972252074, 8642.30861531079, 8657.567121643893, 8657.653693852239, 8662.740117796477, 8559.871601925945, 8583.95708021462, 8600.22597966186, 8619.698310559132, 8622.267107295887, 8012.278894899487, 8491.74524764258, 8491.787705307355, 8494.915050849537, 8497.872531911964, 40943.0, 41300.38049429261, 41354.37968430476, 41426.792866035474, 41536.79231603823, 3755.061234691327, 3796.6013568575177, 3805.086779173006, 3806.276807406584, 3806.371948628501, 34061.829690851635, 34073.659263407295, 34144.48783268253, 34179.65820341815, 34184.48723269153, 5497.890042199174, 5514.228008078872, 5540.168974653834, 5546.889062218733, 5621.690807005613, 215079.99999999843, 218632.0, 218904.0, 219284.0, 219596.0, 47594.762026189645, 47804.7609761949, 52842.47157528452, 53724.0, 53885.46114538883, 56605.71697141488, 56721.43278567244, 56749.14876276859, 56763.71618141883, 56848.715756420956, 16900.492985210465, 16914.407995720092, 16942.152892355334, 17006.404775832787, 17063.232154553076, 13891.722165556632, 13936.721265574632, 14027.158370497802, 14042.648933776738, 14100.0, 14988.550343489713, 15129.39482420713, 15186.088834669734, 15223.238838058052, 15363.231838408035, 6060.212172417646, 6226.782062627786, 6255.874882502325, 6315.242170939425, 6321.936780632135, 1128.9613555528933, 1138.0610995928391, 1144.0104309772153, 1155.3472288157163, 1156.7397335599592, 25613.3596660085, 25984.610230846552, 26231.868840655676, 26254.737452625228, 26275.868620656773, 1223.8347823043775, 1232.2175418609247, 1235.6663700800734, 1243.0242259825993, 1249.5564074753513, 13684.31578421075, 13787.172769633855, 13894.722105558034, 13910.095843770123, 14059.929700351433, 301312.0, 304250.0, 305696.0, 306409.0, 307232.0, 56156.0, 56249.0, 56262.71868640713, 56496.0, 56677.43322566804, 1519.4226194046191, 1549.2486144220045, 1563.2730780187267, 1577.83432739563, 1592.840715928398, 25459.581339772692, 25743.227703168934, 25751.356216094748, 25831.741682582935, 26066.0, 40937.0, 40953.79523102365, 41138.38292425556, 41154.0, 41172.0, 237853.0, 241140.0, 241374.0, 241441.0, 243142.0, 33395.165120872174, 34102.0, 36322.273554528234, 36354.27291454209, 37532.0, 46302.768486158704, 47212.7639361801, 47879.281810773566, 48392.5160748388, 48415.515844842506, 35156.47265290972, 35455.64544354523, 35541.28917421689, 35542.28915421729, 35839.28321433609, 34572.481412778325, 34798.30403391969, 34989.825050874584, 35209.295814084086, 35669.0, 38548.0, 39800.80099599483, 39859.60140398675, 39894.0, 39908.2018359637, 33500.49749253812, 33600.0, 33650.3269934595, 33911.15222119368, 34384.0, 25257.242282731546, 25521.723913804606, 25742.227733168034, 26007.60988585135, 26378.208653740418], "value": 18733.613278980014, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.7465726380817, 637.3579324303284, 640.0015586034913, 641.8541586394252, 649.2371463530351, 23003.769962300372, 23100.768992310073, 23285.301440956755, 23720.406989825264, 23911.402214944614, 25667.22998310049, 26553.336166595844, 26604.467910641775, 26604.733952660466, 26629.334266643342, 6322.462590679797, 6508.316626754194, 6537.77117800877, 6561.704723287439, 6800.251972283045, 6793.864122717543, 6841.41847942923, 6846.041554182419, 6848.657567121649, 6849.041134241185, 6711.362420570038, 6798.9660051699675, 6803.455723542104, 6827.112475378194, 6827.249002609734, 6761.661916904135, 6788.932110678904, 6832.897506537406, 6837.418819400357, 6841.144856892888, 6329.810105696791, 6365.236171659384, 6372.426481616677, 6374.490040796725, 6389.0, 29013.85493072542, 29925.70074299251, 30015.249618759488, 30035.549466758017, 30149.849250753607, 2273.079402841852, 2287.656851472276, 2292.621717416624, 2294.128231272123, 2294.8852557372234, 19938.401847944584, 20330.390088297376, 20800.375988720363, 20914.0, 20917.267895623732, 2593.07945679283, 2599.584066549343, 2652.336915771057, 2660.627512148301, 2664.3072801071667, 137205.0, 142693.28653356666, 156969.21515392466, 160793.0, 161712.99999999884, 15448.845511544965, 16017.519474415787, 16610.252538635792, 17185.226664800113, 17204.1397930103, 26730.465390692076, 26860.731392686215, 26890.462190756076, 26904.327391814968, 27051.323716907234, 10442.321249118782, 10500.264981451224, 10579.682609521727, 10604.719985203219, 10737.731556711145, 9389.10803473669, 9419.24646028316, 9456.0, 9537.2370210383, 9657.179139773058, 9768.853467197998, 9838.409695418297, 10132.189424845994, 10283.200439922983, 10310.484475776182, 2939.500284951521, 3000.369922316338, 3042.680518545521, 3052.4658184817567, 3090.8145511269395, 1045.7647029418472, 1055.3562326980536, 1062.9627963021258, 1067.354250678344, 1068.930519516229, 21533.0, 21639.0, 21690.132394704036, 21949.341519754435, 22242.0, 1114.8216285394299, 1135.7614900870744, 1136.1251836086333, 1145.3242586873619, 1146.5356530605118, 11540.711482212844, 11822.172447928735, 11901.34542600148, 11952.701182470508, 12096.153269271243, 639056.0, 659076.0, 660582.0, 661661.0, 666751.0, 129481.0, 130784.3460782709, 132840.0, 132873.0, 133765.0, 1177.9116566257565, 1201.2517578086156, 1235.2958813476412, 1236.3014896583402, 1236.4436003798328, 18645.533861653566, 19003.144858481126, 19194.13626386844, 19198.328058517884, 19246.615067698847, 63028.0, 63209.367906322186, 64224.67887660532, 64932.0, 66029.0, 81565.0, 84772.0, 85164.57417712871, 85578.0, 86301.0, 15701.450449234222, 15870.52388428349, 15932.56606905384, 16010.9199454002, 16036.957597756575, 24192.637110443004, 24228.273151805475, 24322.878385607957, 24468.632970505812, 24652.506949860544, 19175.52061198481, 19287.324943626907, 19567.217311307388, 19749.308774192836, 19749.506262343555, 17023.91488042552, 17579.82420175782, 17828.46514604564, 17830.286788528832, 18130.45608631743, 19846.0, 20255.0, 20263.78417294967, 20349.79650203479, 20379.286724964553, 15739.291731872323, 15756.133412662413, 15760.290786914387, 15874.444394446138, 15911.761323579924, 17701.203445845225, 17775.377861774778, 17785.28858845632, 18577.907110464363, 19153.712694309874], "value": 12935.51322382668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.1141669331626, 1467.2590341877358, 1479.4673917389732, 1484.2504535209723, 1486.5168820133456, 15488.612784680388, 16371.427000055, 17109.82890171098, 17204.569885752848, 17260.30958761648, 17473.301067957265, 17782.466526004242, 17818.0, 17856.64286714265, 17861.7320740189, 2650.774684151849, 2739.493193759151, 2746.615473833665, 2749.628800111979, 2786.7770578353684, 2682.2757855379036, 2683.7852971762213, 2715.660542432196, 2720.1567514070603, 2720.741529554699, 2718.7281271872853, 2823.2800635837934, 2868.4263147370507, 2889.855507224641, 2890.219640697021, 2722.9319267018286, 2776.5973933779487, 2787.317107308702, 2787.5539913613825, 2791.6929137794828, 2683.932901677454, 2696.3933115049153, 2697.9055733049445, 2699.325168707823, 2703.3782230086995, 16610.75083873743, 16613.750793738105, 16721.080340581262, 16731.163441827983, 16807.747883781754, 8175.918240817575, 8417.368697347662, 8439.493630382196, 8444.408891377607, 8464.365172612079, 5407.621466497347, 5670.5747068969995, 5787.102999035156, 5811.0, 5836.153757705151, 1928.5853541488507, 1954.377866370923, 1973.398113575359, 1997.4407165993546, 2018.4348382452936, 30271.0, 30343.24141896448, 30571.2357191072, 30583.2354191147, 30623.38753224923, 7411.481196316314, 7503.5873026982945, 7603.581803000888, 7720.0, 7739.690412383442, 20092.799072009384, 20118.0, 20130.396088117086, 20165.39503814888, 20215.49461263451, 7538.54768713868, 8715.172058654545, 8892.73321800347, 8917.598708058153, 8923.15230053144, 7085.716571337089, 7756.457048006585, 7856.25365590268, 7882.684692612346, 7895.486793358414, 5798.681072540966, 5819.214406055213, 5922.644641321534, 5963.135345374983, 5983.222181116427, 1706.1384001079375, 1715.794104707443, 1718.089412611318, 1741.6690828742499, 1748.7027205375116, 977.5405559387077, 982.1553464020936, 993.5727637115965, 1012.6506355307407, 1014.7108074198818, 8575.313974881994, 8620.0, 8629.964404271528, 8636.309095272363, 8655.783605409915, 756.6897571995455, 784.0591290451481, 788.050399268891, 793.1354823242693, 798.1738900238225, 6050.939490605126, 6054.636721796706, 6059.454649081604, 6123.785667501616, 6205.193324867739, 24088.15691450791, 24558.508829823302, 24565.38586535351, 24577.0, 24881.0, 23187.7681223189, 24257.75742242555, 24547.63178552323, 24644.753552464244, 24690.0, 12490.0, 12589.18170318908, 12695.809562856564, 12755.808662870064, 12767.680807979688, 9704.175145112744, 9827.31208815376, 9840.065193806577, 9881.752956176153, 10033.648822291185, 20656.17375304971, 20744.27395041166, 20782.0, 21065.262715804874, 21082.57834843264, 21603.35189944304, 21711.240106596193, 21783.12867485283, 21962.341129766133, 21978.45053873602, 11682.415879205835, 12213.755724885275, 12706.0, 12716.809247861105, 12911.35443227816, 15855.841441585437, 16012.599685007968, 16067.11630860314, 16071.67856642884, 16100.67798644044, 11811.173217874666, 11848.170628056298, 11986.46060927243, 12026.819597705866, 12101.818472723093, 11618.128640351839, 11747.47136378848, 11816.40917954116, 11852.58515951972, 11962.461689223832, 10644.414557199429, 10666.946665266623, 10671.199660025684, 10718.0, 10734.946325268324, 8567.100454452193, 8612.311015118901, 8639.352048596505, 8693.26107280863, 8794.560271986504, 8309.127541608286, 8386.916130838614, 8423.452475589067, 8428.578571071299, 8508.659653614033], "value": 7268.159774336029, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.9089350257208, 558.6648011193283, 578.7560722152333, 584.8684046089629, 593.525179856115, 6196.349383314755, 6236.812895613133, 6257.155284036654, 6308.558400911938, 6311.842203944903, 6168.568200225986, 6197.380261973812, 6197.5041996640275, 6216.627002379849, 6233.002719564871, 4878.121938051149, 5080.898382032357, 5102.821401250957, 5109.0803655342115, 5131.589472842183, 4989.376327959005, 5015.297858299823, 5022.698638081727, 5038.420581633104, 5045.167547354682, 4946.826861059863, 4950.009998000396, 4965.106280869442, 4974.701517908937, 4977.153883839738, 4962.60299176065, 4995.300657907859, 5008.624353173526, 5013.197888337867, 5038.596912247048, 4567.885802854922, 4582.931256031162, 4592.288195329696, 4593.655475839292, 4620.3993480847275, 5937.851553711149, 5981.970090149565, 6022.187004754346, 6111.419415155598, 6116.877662446771, 5829.096490044008, 5844.766209351621, 5884.764609415618, 5908.734106965197, 5910.113482977545, 6441.25925518562, 6491.974975515163, 6494.350564943468, 6500.902486462707, 6541.313162117956, 4844.370231869834, 5046.167382381903, 5072.746362681851, 5085.0, 5115.61632877532, 6524.87941419036, 6637.502187335969, 6770.932290677079, 6821.4542836572955, 6828.965855170742, 5554.750036248379, 5576.6375185613615, 5580.637258578179, 5620.803271885465, 5661.235733176051, 5593.692346920959, 5600.971995139998, 5701.572382071278, 5720.342160651495, 5722.855428914234, 6062.09044151091, 6073.453389194994, 6077.17958075663, 6084.5436592255755, 6111.938880611226, 6558.344165583403, 6605.471562275006, 6631.568948018364, 6645.501587380966, 6667.399934005963, 1937.2928880958348, 1973.3586584360141, 1978.3867001229664, 1994.650936086208, 2007.3175120459114, 1436.8060311857973, 1502.7595584706394, 1509.5094094419358, 1517.383986054848, 1519.8100237470317, 5887.114504427058, 5963.880722385615, 6011.1884895539415, 6028.960596400422, 6050.122732203806, 6504.869902601921, 6614.900776488356, 6639.634820084942, 6662.40038396537, 6686.4985126115735, 5984.401559844068, 5991.400859913973, 6043.8489037773525, 6071.878562428727, 6080.847978800565, 3478.4608385700258, 3481.1819222482695, 3523.71810255179, 3523.823808809549, 3534.293141371736, 6788.0, 6845.863082738417, 6882.552634078769, 6896.655167241618, 6899.93100069003, 6598.637074960923, 6676.79969600903, 6732.461403087741, 6748.29142939994, 6788.796336109925, 6669.4330981866115, 6766.18805743314, 6784.016317633916, 6840.3501667341525, 6872.450203983769, 6561.868762624817, 6615.933840661532, 6662.666866656745, 6680.265170831108, 6735.49483788708, 6557.180352455943, 6569.540132190701, 6612.90887003639, 6623.1389919312105, 6641.26946035926, 6919.0, 6943.270956549692, 6963.860722785617, 6966.616836074065, 6973.095066762629, 5646.604737668488, 5675.404082571271, 5679.6876171810945, 5685.573581981285, 5704.629199102045, 6516.57642253252, 6696.7656132035145, 6708.698108585223, 6711.228208756055, 6722.9663851680425, 6293.622382657055, 6390.712417941111, 6459.321771214143, 6475.190601174853, 6565.638889861103, 6361.586496877688, 6397.8483872903325, 6409.8718025640155, 6507.778649568494, 6522.21733391996, 6121.969390153199, 6233.566279755591, 6268.749250029948, 6385.648789316632, 6460.8707825842275, 6437.903431448625, 6452.193475815523, 6461.967690161519, 6537.411632952867, 6591.571547849375, 6321.775894294035, 6327.86098502274, 6343.619382837044, 6383.585066970632, 6438.710258038306], "value": 5206.1347521670095, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [961.1685891703678, 970.791279874827, 994.1698681600863, 1000.2448151645507, 1004.7990401919618, 18140.274389024424, 18492.0753962302, 18548.350807721734, 18548.81451185488, 18583.62832743346, 16807.83192168078, 17101.40145094922, 17119.229634666437, 17119.486415407526, 17125.400610978617, 1031.1029404418148, 1059.0680201422738, 1059.9841002384933, 1061.7823346214045, 1062.8033813744444, 1065.9520321585508, 1066.3282132256668, 1066.5200659703125, 1069.395791377869, 1070.9303895246785, 1074.8119079161154, 1112.7496313329477, 1115.5036008976008, 1127.6560649002051, 1128.6839684888243, 1117.2235296468916, 1118.2563595209176, 1118.664400679797, 1119.1270808769173, 1120.0703416164604, 796.044746304437, 818.4639061414783, 825.5170725125809, 826.6486743134204, 831.0484495252902, 19642.116104775178, 19824.405267841987, 19860.304889328803, 19902.502437439034, 19905.303314384073, 20509.4872628184, 20650.48373790652, 21128.683069753966, 21429.24997625091, 21499.0, 17133.82866171335, 17354.913225433917, 17529.649407011915, 17767.289308427647, 17943.461696149006, 3894.941575876364, 3922.8430862765454, 3924.646781789624, 3928.410738389236, 3928.6464218220212, 121575.39212303971, 154380.0, 154452.0, 157786.21106894393, 157806.0, 17576.384826531266, 17687.20407581662, 17850.91074544645, 17975.370862020027, 18092.638147236983, 34803.477947830805, 34950.47574286388, 35084.29831403409, 35416.46875296873, 35536.822315888254, 9662.75843103914, 9730.221582273402, 9769.51152442375, 9817.55820988043, 9830.852537211947, 4433.556644335541, 4451.154280686659, 4484.775761211927, 4498.955010449919, 4503.2569626012, 6099.146119543268, 6164.784232551929, 6233.376662333731, 6233.719482623293, 6256.843578910472, 1125.3360517294673, 1161.4367031989477, 1173.049829637996, 1177.952881884732, 1179.2924245452832, 724.6738967464662, 727.6798208788116, 732.9267073292629, 736.2085757810376, 756.5422919133955, 12244.084682440847, 12364.752704945851, 12427.31649759272, 13191.208527488381, 13326.93336533311, 659.5745743995096, 660.4023358860239, 668.5620918298522, 670.4200866250658, 674.8886433738477, 5731.684757338387, 5774.393688662714, 5829.329627092854, 5861.736221870026, 5921.73352199152, 149806.0, 151868.0, 152501.23749381403, 153373.0, 160224.1988790072, 120189.0, 129547.0, 132066.33966830105, 137521.3123934374, 137892.931606026, 112433.43783281195, 112800.0, 113836.43081784538, 115924.42037789756, 116919.8308016926, 13260.801087983495, 13368.264745438706, 13616.455341786217, 13620.863791362355, 13665.726685466036, 53958.730206348715, 54101.0, 54186.187207191135, 54322.72838635781, 55848.162277566684, 70571.0, 72449.63775181091, 72640.9103863453, 73416.63291683508, 73505.0, 18363.449096527125, 18881.244750209837, 18883.05584720731, 18914.52713682169, 18942.715859262396, 19726.605467890848, 19808.207671693548, 19836.305729299405, 19867.503312417306, 19884.90057549703, 18926.621467570298, 19056.142473588443, 19180.71228931595, 19249.845009299486, 19396.2241510338, 18377.54056148607, 18998.715019274998, 19103.140358683613, 19210.711839322143, 19262.0, 17810.91094544519, 17886.910565447088, 17891.910540447214, 17937.37219197322, 18066.819331806513, 13488.393022314216, 13535.0, 13643.0, 13796.793048104086, 13850.30748462593, 12977.545785897448, 12982.935085324512, 12994.870051299366, 13068.607941761762, 13122.278274694601], "value": 9853.583997383217, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1362.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 105.0, 105.0, 105.0, 106.0, 108.0, 106.0, 105.0, 106.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 115.0, 110.0, 116.0, 110.0, 111.0, 144.0, 110.0, 142.0], "value": 118.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 162.0, 162.0, 163.0, 162.0, 164.0, 161.0, 164.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 73.0, 72.0, 73.0, 73.0, 73.0, 73.0, 74.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 79.0, 79.0, 78.0, 78.0, 78.0, 77.0, 82.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 117.0, 111.0, 111.0, 111.0, 112.0, 116.0, 112.0, 117.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 148.0, 151.0, 150.0, 149.0, 150.0, 149.0, 151.0, 153.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 97.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 42.0, 43.0, 43.0, 44.0, 44.0, 43.0, 42.0, 44.0, 43.0], "value": 43.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 36.0, 36.0, 36.0, 38.0, 36.0, 35.0, 37.0, 36.0, 40.0], "value": 36.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 75.0, 69.0, 72.0, 71.0, 72.0, 73.0, 70.0, 70.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 105.0, 102.0, 112.0, 103.0, 106.0, 104.0, 107.0, 107.0, 104.0], "value": 106.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 156.0, 146.0, 148.0, 151.0, 147.0, 147.0, 149.0, 148.0, 149.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 58.0, 58.0, 58.0, 59.0, 57.0, 60.0, 68.0, 64.0], "value": 59.8, "unit": "score"}]}]} 17:45:55 INFO - Return code: 0 17:45:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:45:55 INFO - # TBPL SUCCESS # 17:45:55 INFO - Running post-action listener: _package_coverage_data 17:45:55 INFO - Running post-action listener: _resource_record_post_action 17:45:55 INFO - [mozharness: 2017-10-01 00:45:55.964999Z] Finished run-tests step (success) 17:45:55 INFO - Running post-run listener: _resource_record_post_run 17:45:56 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 329863168; Read time: 0; Write time: 279128 17:45:56 INFO - TinderboxPrint: CPU usage
13.1% 17:45:56 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 17:45:56 INFO - TinderboxPrint: I/O write bytes / time
329,863,168 / 279,128 17:45:56 INFO - TinderboxPrint: CPU idle
8,220.1 (86.9%) 17:45:56 INFO - TinderboxPrint: CPU user
1,187.1 (12.6%) 17:45:56 INFO - TinderboxPrint: Swap in / out
0 / 0 17:45:56 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12578816; Read time: 0; Write time: 2464 17:45:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:45:56 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 0; Write bytes: 316669952; Read time: 0; Write time: 276664 17:45:56 INFO - Running post-run listener: _upload_blobber_files 17:45:56 INFO - Blob upload gear active. 17:45:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:45:56 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 17:45:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:45:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:45:57 INFO - (blobuploader) - INFO - Open directory for files ... 17:45:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:45:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:45:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:58 INFO - (blobuploader) - INFO - Done attempting. 17:45:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:45:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:46:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:04 INFO - (blobuploader) - INFO - Done attempting. 17:46:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:46:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:46:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:05 INFO - (blobuploader) - INFO - Done attempting. 17:46:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:46:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:46:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:06 INFO - (blobuploader) - INFO - Done attempting. 17:46:06 INFO - (blobuploader) - INFO - Iteration through files over. 17:46:06 INFO - Return code: 0 17:46:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:46:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:46:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d64e76c56c6b4fd0b62ba16b4e49b39ea537a8bb4aae85e8f8954dba875c07fa07a07108926783ce879513dc94fc1b67fb32fa2148935cc5ed89e63c4c220ef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/118eaa159c0c1bc41172a8318589d0878edb76af38dc1cba80ea3a71648c16922e4706bf18a6ee5121155eb328864d2f3251926899315ba33551371049a7f268", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c15ecf0b997590a19719c269dce53e7426d87993b36acbfbbd8dbf153da2fbbf34d65aebe6fd71ff17e4586750f2efbada7c742f161913db0ecc2dfbee0613e8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/861e738ccf6d0b9cad8f9243d740305c1179692f6b82d0b7dcfe07b67ffbdc72d9ef6b3b1fb3b1b13929e27e9f08fdd7c14cf2a56fc8be9e19c8e43008844e15"} 17:46:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:46:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:46:06 INFO - Contents: 17:46:06 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d64e76c56c6b4fd0b62ba16b4e49b39ea537a8bb4aae85e8f8954dba875c07fa07a07108926783ce879513dc94fc1b67fb32fa2148935cc5ed89e63c4c220ef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/118eaa159c0c1bc41172a8318589d0878edb76af38dc1cba80ea3a71648c16922e4706bf18a6ee5121155eb328864d2f3251926899315ba33551371049a7f268", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c15ecf0b997590a19719c269dce53e7426d87993b36acbfbbd8dbf153da2fbbf34d65aebe6fd71ff17e4586750f2efbada7c742f161913db0ecc2dfbee0613e8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/861e738ccf6d0b9cad8f9243d740305c1179692f6b82d0b7dcfe07b67ffbdc72d9ef6b3b1fb3b1b13929e27e9f08fdd7c14cf2a56fc8be9e19c8e43008844e15"} 17:46:06 INFO - Running post-run listener: copy_logs_to_upload_dir 17:46:06 INFO - Copying logs to upload dir... 17:46:06 INFO - mkdir: /builds/slave/test/build/upload/logs 17:46:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.803274 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-30 17:46:06.729153) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 17:46:06.735256) ========= 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=9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d64e76c56c6b4fd0b62ba16b4e49b39ea537a8bb4aae85e8f8954dba875c07fa07a07108926783ce879513dc94fc1b67fb32fa2148935cc5ed89e63c4c220ef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/118eaa159c0c1bc41172a8318589d0878edb76af38dc1cba80ea3a71648c16922e4706bf18a6ee5121155eb328864d2f3251926899315ba33551371049a7f268", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c15ecf0b997590a19719c269dce53e7426d87993b36acbfbbd8dbf153da2fbbf34d65aebe6fd71ff17e4586750f2efbada7c742f161913db0ecc2dfbee0613e8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/861e738ccf6d0b9cad8f9243d740305c1179692f6b82d0b7dcfe07b67ffbdc72d9ef6b3b1fb3b1b13929e27e9f08fdd7c14cf2a56fc8be9e19c8e43008844e15"} build_url:https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011295 build_url: 'https://queue.taskcluster.net/v1/task/N6FqeYAdQrOnZuU68arTCg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d64e76c56c6b4fd0b62ba16b4e49b39ea537a8bb4aae85e8f8954dba875c07fa07a07108926783ce879513dc94fc1b67fb32fa2148935cc5ed89e63c4c220ef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/118eaa159c0c1bc41172a8318589d0878edb76af38dc1cba80ea3a71648c16922e4706bf18a6ee5121155eb328864d2f3251926899315ba33551371049a7f268", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c15ecf0b997590a19719c269dce53e7426d87993b36acbfbbd8dbf153da2fbbf34d65aebe6fd71ff17e4586750f2efbada7c742f161913db0ecc2dfbee0613e8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/861e738ccf6d0b9cad8f9243d740305c1179692f6b82d0b7dcfe07b67ffbdc72d9ef6b3b1fb3b1b13929e27e9f08fdd7c14cf2a56fc8be9e19c8e43008844e15"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 17:46:06.766399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:46:06.766691) ========= 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=9bfb7e13875ed353a705960e000001c0-1506817045.654616-1351809954 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:46:06.848622) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 17:46:06.848934) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 17:46:06.849198) ========= ========= Total master_lag: 0.21 =========