builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-042 starttime: 1506510565.75 results: success (0) revision: 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:25.754726) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:25.755181) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:25.755448) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:25.856382) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:25.856648) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 04:09: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% 974K=0.01s 2017-09-27 04:09:26 (974 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122928 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:26.007591) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:26.010988) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021743 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:26.074668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 04:09:26.075073) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 --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 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 --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=74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 04:09:26,145 truncating revision to first 12 chars 2017-09-27 04:09:26,145 Setting DEBUG logging. 2017-09-27 04:09:26,145 attempt 1/10 2017-09-27 04:09:26,145 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5563e7da39b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 04:09:26,404 unpacking tar archive at: mozilla-central-5563e7da39b2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.556732 ========= master_lag: 0.72 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 04:09:27.353584) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:27.357912) ========= script_repo_revision: 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 04:09:27.360511) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-09-27 04:09:27.360817) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-09-27 04:09:28.837341) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-27 04:09:28.837716) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:09:29 INFO - MultiFileLogger online at 20170927 04:09:29 in /builds/slave/test 04:09:29 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 04:09:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:09:29 INFO - {'append_to_log': False, 04:09:29 INFO - 'base_work_dir': '/builds/slave/test', 04:09:29 INFO - 'blob_upload_branch': 'Firefox', 04:09:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:09:29 INFO - 'branch': 'Firefox', 04:09:29 INFO - 'buildbot_json_path': 'buildprops.json', 04:09:29 INFO - 'code_coverage': False, 04:09:29 INFO - 'config_files': ('talos/linux_config.py',), 04:09:29 INFO - 'default_actions': ('clobber', 04:09:29 INFO - 'read-buildbot-config', 04:09:29 INFO - 'download-and-extract', 04:09:29 INFO - 'populate-webroot', 04:09:29 INFO - 'create-virtualenv', 04:09:29 INFO - 'install', 04:09:29 INFO - 'setup-mitmproxy', 04:09:29 INFO - 'run-tests'), 04:09:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:09:29 INFO - 'disable_ccov_upload': False, 04:09:29 INFO - 'disable_stylo': False, 04:09:29 INFO - 'download_minidump_stackwalk': True, 04:09:29 INFO - 'download_symbols': 'ondemand', 04:09:29 INFO - 'enable_stylo': False, 04:09:29 INFO - 'enable_webrender': False, 04:09:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:09:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:09:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:09:29 INFO - 'gecko_profile': False, 04:09:29 INFO - 'gecko_profile_interval': 0, 04:09:29 INFO - 'installer_path': 'installer.exe', 04:09:29 INFO - 'log_level': 'info', 04:09:29 INFO - 'log_name': 'talos', 04:09:29 INFO - 'log_to_console': True, 04:09:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:09:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:09:29 INFO - 'opt_config_files': (), 04:09:29 INFO - 'pip_index': False, 04:09:29 INFO - 'suite': 'dromaeojs-e10s', 04:09:29 INFO - 'system_bits': '32', 04:09:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:09:29 INFO - 'title': 'talos-linux64-ix-042', 04:09:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:09:29 INFO - 'use_talos_json': True, 04:09:29 INFO - 'verify': 'False', 04:09:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:09:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:09:29 INFO - 'work_dir': 'build'} 04:09:29 INFO - [mozharness: 2017-09-27 11:09:29.143492Z] Running clobber step. 04:09:29 INFO - Running pre-action listener: _resource_record_pre_action 04:09:29 INFO - Running main action method: clobber 04:09:29 INFO - rmtree: /builds/slave/test/build 04:09:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:09:30 INFO - Running post-action listener: _resource_record_post_action 04:09:30 INFO - [mozharness: 2017-09-27 11:09:30.661702Z] Finished clobber step (success) 04:09:30 INFO - [mozharness: 2017-09-27 11:09:30.661802Z] Running read-buildbot-config step. 04:09:30 INFO - Running pre-action listener: _resource_record_pre_action 04:09:30 INFO - Running main action method: read_buildbot_config 04:09:30 INFO - Using buildbot properties: 04:09:30 INFO - { 04:09:30 INFO - "project": "", 04:09:30 INFO - "product": "firefox", 04:09:30 INFO - "who": "archaeopteryx@coole-files.de", 04:09:30 INFO - "installer_path": "public/build/target.tar.bz2", 04:09:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:09:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:09:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:09:30 INFO - "stage_platform": "linux64-pgo", 04:09:30 INFO - "basedir": "/builds/slave/test", 04:09:30 INFO - "buildnumber": 212, 04:09:30 INFO - "platform": "ubuntu64_hw", 04:09:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:09:30 INFO - "slavebuilddir": "test", 04:09:30 INFO - "taskId": "SF2nI1DwSJ6NK58rBCG46w", 04:09:30 INFO - "branch": "mozilla-central", 04:09:30 INFO - "script_repo_revision": "production", 04:09:30 INFO - "revision": "5563e7da39b265ed1ba7796ec058bdbcf6f792f6", 04:09:30 INFO - "slavename": "talos-linux64-ix-042", 04:09:30 INFO - "repo_path": "mozilla-central" 04:09:30 INFO - } 04:09:30 INFO - Finding installer, test and symbols from parent task. 04:09:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SF2nI1DwSJ6NK58rBCG46w'}, attempt #1 04:09:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SF2nI1DwSJ6NK58rBCG46w'}, attempt #1 04:09:31 INFO - Task dependencies: Ar8OP6SQTLyEO3BLSuuQ1Q 04:09:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q'}, attempt #1 04:09:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.tar.bz2 04:09:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.test_packages.json 04:09:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.crashreporter-symbols.zip 04:09:32 INFO - Running post-action listener: _resource_record_post_action 04:09:32 INFO - [mozharness: 2017-09-27 11:09:32.205409Z] Finished read-buildbot-config step (success) 04:09:32 INFO - [mozharness: 2017-09-27 11:09:32.205631Z] Running download-and-extract step. 04:09:32 INFO - Running pre-action listener: _resource_record_pre_action 04:09:32 INFO - Running main action method: download_and_extract 04:09:32 INFO - mkdir: /builds/slave/test/build/tests 04:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:32 INFO - trying https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.test_packages.json 04:09:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:09:32 INFO - Downloaded 989 bytes. 04:09:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:09:32 INFO - Using the following test package requirements: 04:09:32 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:09:32 INFO - u'common': [u'target.common.tests.zip'], 04:09:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:09:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:09:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:09:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:09:32 INFO - u'mozbase': [u'target.common.tests.zip'], 04:09:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:09:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:09:32 INFO - u'web-platform': [u'target.common.tests.zip', 04:09:32 INFO - u'target.web-platform.tests.tar.gz'], 04:09:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:09:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:09:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.common.tests.zip 04:09:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:09:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.common.tests.zip into memory 04:09:33 INFO - Content-Length response header: 55348607 04:09:33 INFO - Bytes received: 55348607 04:09:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.talos.tests.zip 04:09:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:09:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.talos.tests.zip into memory 04:09:45 INFO - Content-Length response header: 14513823 04:09:45 INFO - Bytes received: 14513823 04:09: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')]} 04:09:45 INFO - trying https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.tar.bz2 04:09:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:09:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:09:47 INFO - Downloaded 62405265 bytes. 04:09:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.tar.bz2 04:09:47 INFO - mkdir: /builds/slave/test/properties 04:09:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:09:47 INFO - Writing to file /builds/slave/test/properties/build_url 04:09:47 INFO - Contents: 04:09:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.tar.bz2 04:09:47 INFO - Running post-action listener: _resource_record_post_action 04:09:47 INFO - Running post-action listener: find_tests_for_verification 04:09:47 INFO - Running post-action listener: set_extra_try_arguments 04:09:47 INFO - [mozharness: 2017-09-27 11:09:47.495311Z] Finished download-and-extract step (success) 04:09:47 INFO - [mozharness: 2017-09-27 11:09:47.495608Z] Running populate-webroot step. 04:09:47 INFO - Running pre-action listener: _resource_record_pre_action 04:09:47 INFO - Running main action method: populate_webroot 04:09:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:09:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['tresize', 04:09:47 INFO - 'tcanvasmark']}, 04:09:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:09:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['dromaeo_css', 04:09:47 INFO - 'kraken']}, 04:09:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:09:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:47 INFO - 'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:09:47 INFO - 'tests': ['damp', 'tps']}, 04:09:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:47 INFO - 'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['damp', 'tps']}, 04:09:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:09:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['dromaeo_dom']}, 04:09:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:09:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['basic_compositor_video', 04:09:47 INFO - 'glvideo']}, 04:09:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:09:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:09:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:47 INFO - 'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['ts_paint_webext', 04:09:47 INFO - 'tp5o_webext']}, 04:09:47 INFO - 'other-e10s': {'tests': ['a11yr', 04:09:47 INFO - 'ts_paint', 04:09:47 INFO - 'tpaint', 04:09:47 INFO - 'sessionrestore', 04:09:47 INFO - 'sessionrestore_many_windows', 04:09:47 INFO - 'sessionrestore_no_auto_restore', 04:09:47 INFO - 'tabpaint']}, 04:09:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['a11yr', 04:09:47 INFO - 'ts_paint', 04:09:47 INFO - 'tpaint', 04:09:47 INFO - 'sessionrestore', 04:09:47 INFO - 'sessionrestore_many_windows', 04:09:47 INFO - 'sessionrestore_no_auto_restore', 04:09:47 INFO - 'tabpaint']}, 04:09:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:09:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:09:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['perf_reftest_singletons']}, 04:09:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['perf_reftest']}, 04:09:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:09:47 INFO - 'tsvgr_opacity', 04:09:47 INFO - 'tart', 04:09:47 INFO - 'tscrollx', 04:09:47 INFO - 'tsvg_static']}, 04:09:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['tsvgx', 04:09:47 INFO - 'tsvgr_opacity', 04:09:47 INFO - 'tart', 04:09:47 INFO - 'tscrollx', 04:09:47 INFO - 'tsvg_static']}, 04:09:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:09:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:47 INFO - 'talos_options': ['--disable-stylo'], 04:09:47 INFO - 'tests': ['tp5o']}, 04:09:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:09:47 INFO - 'talos_options': ['--mitmproxy', 04:09:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:47 INFO - '--firstNonBlankPaint'], 04:09:47 INFO - 'tests': ['tp6_google', 04:09:47 INFO - 'tp6_youtube', 04:09:47 INFO - 'tp6_amazon', 04:09:47 INFO - 'tp6_facebook']}, 04:09:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:09:47 INFO - 'talos_options': ['--disable-stylo', 04:09:47 INFO - '--mitmproxy', 04:09:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:47 INFO - '--firstNonBlankPaint'], 04:09:47 INFO - 'tests': ['tp6_google', 04:09:47 INFO - 'tp6_youtube', 04:09:47 INFO - 'tp6_amazon', 04:09:47 INFO - 'tp6_facebook']}, 04:09:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:09:47 INFO - 'talos_options': ['--stylo-threads=1', 04:09:47 INFO - '--mitmproxy', 04:09:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:47 INFO - '--firstNonBlankPaint'], 04:09:47 INFO - 'tests': ['tp6_google', 04:09:47 INFO - 'tp6_youtube', 04:09:47 INFO - 'tp6_amazon', 04:09:47 INFO - 'tp6_facebook']}, 04:09:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:09:47 INFO - 'talos_options': ['--xperf_path', 04:09:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:47 INFO - 'tests': ['tp5n']}, 04:09:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:47 INFO - 'talos_options': ['--disable-stylo', 04:09:47 INFO - '--xperf_path', 04:09:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:47 INFO - 'tests': ['tp5n']}}} 04:09:47 INFO - Running post-action listener: _resource_record_post_action 04:09:47 INFO - [mozharness: 2017-09-27 11:09:47.517536Z] Finished populate-webroot step (success) 04:09:47 INFO - [mozharness: 2017-09-27 11:09:47.517626Z] Running create-virtualenv step. 04:09:47 INFO - Running pre-action listener: _resource_record_pre_action 04:09:47 INFO - Running main action method: create_virtualenv 04:09:47 INFO - Creating virtualenv /builds/slave/test/build/venv 04:09:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:09:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:09:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:09:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:09:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:09:48 INFO - Installing distribute.............................................................................................................................................................................................done. 04:09:48 INFO - Installing pip...............done. 04:09:48 INFO - Return code: 0 04:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:09:48 INFO - Reading from file tmpfile_stdout 04:09:48 INFO - Output received: 04:09:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:09:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:09:48 INFO - Using env: {'DISPLAY': ':0', 04:09:48 INFO - 'HOME': '/home/cltbld', 04:09:48 INFO - 'LANG': 'en_US.UTF-8', 04:09:48 INFO - 'LANGUAGE': 'en_US:en', 04:09:48 INFO - 'LOGNAME': 'cltbld', 04:09:48 INFO - 'MAIL': '/var/mail/cltbld', 04:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:48 INFO - 'MOZ_NO_REMOTE': '1', 04:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:48 INFO - 'NO_EM_RESTART': '1', 04:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:48 INFO - 'PWD': '/builds/slave/test', 04:09:48 INFO - 'SHELL': '/bin/bash', 04:09:48 INFO - 'SHLVL': '1', 04:09:48 INFO - 'TERM': 'linux', 04:09:48 INFO - 'TMOUT': '86400', 04:09:48 INFO - 'USER': 'cltbld', 04:09:48 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:48 INFO - '_': '/tools/buildbot/bin/python'} 04:09:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:09:49 INFO - Downloading/unpacking pip>=1.5 04:09:49 INFO - Running setup.py egg_info for package pip 04:09:49 INFO - warning: no files found matching 'pip/cacert.pem' 04:09:49 INFO - warning: no files found matching '*.html' under directory 'docs' 04:09:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:09:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:09:49 INFO - Installing collected packages: pip 04:09:49 INFO - Found existing installation: pip 1.1 04:09:49 INFO - Uninstalling pip: 04:09:49 INFO - Successfully uninstalled pip 04:09:49 INFO - Running setup.py install for pip 04:09:49 INFO - warning: no files found matching 'pip/cacert.pem' 04:09:49 INFO - warning: no files found matching '*.html' under directory 'docs' 04:09:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:09:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:09:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:09:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:09:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:09:49 INFO - Successfully installed pip 04:09:49 INFO - Cleaning up... 04:09:49 INFO - Return code: 0 04:09:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:09: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')]} 04:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09: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')]} 04:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09: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', '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 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:09: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:09: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 psutil>=3.1.1 04:09:49 INFO - Using env: {'DISPLAY': ':0', 04:09:49 INFO - 'HOME': '/home/cltbld', 04:09:49 INFO - 'LANG': 'en_US.UTF-8', 04:09:49 INFO - 'LANGUAGE': 'en_US:en', 04:09:49 INFO - 'LOGNAME': 'cltbld', 04:09:49 INFO - 'MAIL': '/var/mail/cltbld', 04:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:49 INFO - 'MOZ_NO_REMOTE': '1', 04:09:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:49 INFO - 'NO_EM_RESTART': '1', 04:09:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:49 INFO - 'PWD': '/builds/slave/test', 04:09:49 INFO - 'SHELL': '/bin/bash', 04:09:49 INFO - 'SHLVL': '1', 04:09:49 INFO - 'TERM': 'linux', 04:09:49 INFO - 'TMOUT': '86400', 04:09:49 INFO - 'USER': 'cltbld', 04:09:49 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:49 INFO - '_': '/tools/buildbot/bin/python'} 04:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:49 INFO - Downloading/unpacking psutil>=3.1.1 04:09:49 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 04:09:49 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 04:09:49 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 04:09:49 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 04:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:09:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:51 INFO - Installing collected packages: psutil 04:09:51 INFO - Running setup.py install for psutil 04:09:51 INFO - building 'psutil._psutil_linux' extension 04:09:51 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 04:09:51 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 04:09:51 INFO - building 'psutil._psutil_posix' extension 04:09:51 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 04:09:51 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 04:09:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:51 INFO - Successfully installed psutil 04:09:51 INFO - Cleaning up... 04:09:51 INFO - Return code: 0 04:09:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:09: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')]} 04:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09: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')]} 04:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:09: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:09: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 mozsystemmonitor==0.3 04:09:51 INFO - Using env: {'DISPLAY': ':0', 04:09:51 INFO - 'HOME': '/home/cltbld', 04:09:51 INFO - 'LANG': 'en_US.UTF-8', 04:09:51 INFO - 'LANGUAGE': 'en_US:en', 04:09:51 INFO - 'LOGNAME': 'cltbld', 04:09:51 INFO - 'MAIL': '/var/mail/cltbld', 04:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:51 INFO - 'MOZ_NO_REMOTE': '1', 04:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:51 INFO - 'NO_EM_RESTART': '1', 04:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:51 INFO - 'PWD': '/builds/slave/test', 04:09:51 INFO - 'SHELL': '/bin/bash', 04:09:51 INFO - 'SHLVL': '1', 04:09:51 INFO - 'TERM': 'linux', 04:09:51 INFO - 'TMOUT': '86400', 04:09:51 INFO - 'USER': 'cltbld', 04:09:51 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:51 INFO - '_': '/tools/buildbot/bin/python'} 04:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:09: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 04:09: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 04:09: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 04:09: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 04:09:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:09:53 INFO - Installing collected packages: mozsystemmonitor 04:09:53 INFO - Running setup.py install for mozsystemmonitor 04:09:53 INFO - Successfully installed mozsystemmonitor 04:09:53 INFO - Cleaning up... 04:09:54 INFO - Return code: 0 04:09:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:09:54 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')]} 04:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:54 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')]} 04:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:54 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 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:09:54 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 04:09:54 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 04:09:54 INFO - Using env: {'DISPLAY': ':0', 04:09:54 INFO - 'HOME': '/home/cltbld', 04:09:54 INFO - 'LANG': 'en_US.UTF-8', 04:09:54 INFO - 'LANGUAGE': 'en_US:en', 04:09:54 INFO - 'LOGNAME': 'cltbld', 04:09:54 INFO - 'MAIL': '/var/mail/cltbld', 04:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:54 INFO - 'MOZ_NO_REMOTE': '1', 04:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:54 INFO - 'NO_EM_RESTART': '1', 04:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:54 INFO - 'PWD': '/builds/slave/test', 04:09:54 INFO - 'SHELL': '/bin/bash', 04:09:54 INFO - 'SHLVL': '1', 04:09:54 INFO - 'TERM': 'linux', 04:09:54 INFO - 'TMOUT': '86400', 04:09:54 INFO - 'USER': 'cltbld', 04:09:54 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:54 INFO - '_': '/tools/buildbot/bin/python'} 04:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:54 INFO - Downloading/unpacking jsonschema==2.5.1 04:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:09:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:09: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 04:09: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 04:09: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 04:09: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 04:09:55 INFO - Downloading functools32-3.2.3-2.tar.gz 04:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:09:55 INFO - warning: no files found matching '*.txt' 04:09:55 INFO - no previously-included directories found matching 'build' 04:09:55 INFO - no previously-included directories found matching 'dist' 04:09:55 INFO - no previously-included directories found matching '.git*' 04:09:55 INFO - Installing collected packages: jsonschema, functools32 04:09:56 INFO - Running setup.py install for functools32 04:09:56 INFO - warning: no files found matching '*.txt' 04:09:56 INFO - no previously-included directories found matching 'build' 04:09:56 INFO - no previously-included directories found matching 'dist' 04:09:56 INFO - no previously-included directories found matching '.git*' 04:09:56 INFO - Successfully installed jsonschema functools32 04:09:56 INFO - Cleaning up... 04:09:56 INFO - Return code: 0 04:09:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:56 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 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:09:56 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 04:09:56 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 04:09:56 INFO - Using env: {'DISPLAY': ':0', 04:09:56 INFO - 'HOME': '/home/cltbld', 04:09:56 INFO - 'LANG': 'en_US.UTF-8', 04:09:56 INFO - 'LANGUAGE': 'en_US:en', 04:09:56 INFO - 'LOGNAME': 'cltbld', 04:09:56 INFO - 'MAIL': '/var/mail/cltbld', 04:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:56 INFO - 'MOZ_NO_REMOTE': '1', 04:09:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:56 INFO - 'NO_EM_RESTART': '1', 04:09:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:56 INFO - 'PWD': '/builds/slave/test', 04:09:56 INFO - 'SHELL': '/bin/bash', 04:09:56 INFO - 'SHLVL': '1', 04:09:56 INFO - 'TERM': 'linux', 04:09:56 INFO - 'TMOUT': '86400', 04:09:56 INFO - 'USER': 'cltbld', 04:09:56 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:56 INFO - '_': '/tools/buildbot/bin/python'} 04:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:09:56 INFO - Cleaning up... 04:09:56 INFO - Return code: 0 04:09:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:56 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 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:09:56 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 04:09:56 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 04:09:56 INFO - Using env: {'DISPLAY': ':0', 04:09:56 INFO - 'HOME': '/home/cltbld', 04:09:56 INFO - 'LANG': 'en_US.UTF-8', 04:09:56 INFO - 'LANGUAGE': 'en_US:en', 04:09:56 INFO - 'LOGNAME': 'cltbld', 04:09:56 INFO - 'MAIL': '/var/mail/cltbld', 04:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:56 INFO - 'MOZ_NO_REMOTE': '1', 04:09:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:56 INFO - 'NO_EM_RESTART': '1', 04:09:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:56 INFO - 'PWD': '/builds/slave/test', 04:09:56 INFO - 'SHELL': '/bin/bash', 04:09:56 INFO - 'SHLVL': '1', 04:09:56 INFO - 'TERM': 'linux', 04:09:56 INFO - 'TMOUT': '86400', 04:09:56 INFO - 'USER': 'cltbld', 04:09:56 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:56 INFO - '_': '/tools/buildbot/bin/python'} 04:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:57 INFO - Downloading/unpacking blobuploader==1.2.4 04:09: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 04:09: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 04:09: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 04:09: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 04:09:58 INFO - Downloading blobuploader-1.2.4.tar.gz 04:09:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:09:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:09: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 04:09: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 04:09: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 04:09: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 04:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:10:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:00 INFO - Downloading docopt-0.6.1.tar.gz 04:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:10:00 INFO - Installing collected packages: blobuploader, requests, docopt 04:10:00 INFO - Running setup.py install for blobuploader 04:10:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:10:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:10:00 INFO - Running setup.py install for requests 04:10:00 INFO - Running setup.py install for docopt 04:10:00 INFO - Successfully installed blobuploader requests docopt 04:10:00 INFO - Cleaning up... 04:10:00 INFO - Return code: 0 04:10:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10: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')]} 04:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10: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')]} 04:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10: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 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:10: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 04:10: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 04:10:00 INFO - Using env: {'DISPLAY': ':0', 04:10:00 INFO - 'HOME': '/home/cltbld', 04:10:00 INFO - 'LANG': 'en_US.UTF-8', 04:10:00 INFO - 'LANGUAGE': 'en_US:en', 04:10:00 INFO - 'LOGNAME': 'cltbld', 04:10:00 INFO - 'MAIL': '/var/mail/cltbld', 04:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:00 INFO - 'MOZ_NO_REMOTE': '1', 04:10:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:00 INFO - 'NO_EM_RESTART': '1', 04:10:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:00 INFO - 'PWD': '/builds/slave/test', 04:10:00 INFO - 'SHELL': '/bin/bash', 04:10:00 INFO - 'SHLVL': '1', 04:10:00 INFO - 'TERM': 'linux', 04:10:00 INFO - 'TMOUT': '86400', 04:10:00 INFO - 'USER': 'cltbld', 04:10:00 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:00 INFO - '_': '/tools/buildbot/bin/python'} 04:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:10:01 INFO - Running setup.py (path:/tmp/pip-kwG4od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:10:01 INFO - Running setup.py (path:/tmp/pip-ZOsnas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:10:01 INFO - Running setup.py (path:/tmp/pip-fcQtYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:10:01 INFO - Running setup.py (path:/tmp/pip-rfWVfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:10:01 INFO - Running setup.py (path:/tmp/pip-_0ijEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:10:01 INFO - Running setup.py (path:/tmp/pip-lpXACb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:10:01 INFO - Running setup.py (path:/tmp/pip-h2AoEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:10:01 INFO - Running setup.py (path:/tmp/pip-428NV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:10:01 INFO - Running setup.py (path:/tmp/pip-RLafOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:10:01 INFO - Running setup.py (path:/tmp/pip-3plPPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:10:01 INFO - Running setup.py (path:/tmp/pip-3LJBnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:10:01 INFO - Running setup.py (path:/tmp/pip-dQv6wX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:10:02 INFO - Running setup.py (path:/tmp/pip-yGVSW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:10:02 INFO - Running setup.py (path:/tmp/pip-fKkYmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:02 INFO - Running setup.py (path:/tmp/pip-RldTDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:10:02 INFO - Running setup.py (path:/tmp/pip-2KjrUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:10:02 INFO - Running setup.py (path:/tmp/pip-NMWlD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:10:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:10:02 INFO - Running setup.py install for manifestparser 04:10:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:10:02 INFO - Running setup.py install for mozcrash 04:10:02 INFO - Running setup.py install for mozdebug 04:10:02 INFO - Running setup.py install for mozdevice 04:10:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:10:02 INFO - Running setup.py install for mozfile 04:10:02 INFO - Running setup.py install for mozhttpd 04:10:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:10:02 INFO - Running setup.py install for mozinfo 04:10:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Running setup.py install for mozInstall 04:10:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Running setup.py install for mozleak 04:10:03 INFO - Running setup.py install for mozlog 04:10:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Running setup.py install for moznetwork 04:10:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Running setup.py install for mozprocess 04:10:03 INFO - Running setup.py install for mozprofile 04:10:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Running setup.py install for mozrunner 04:10:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:10:03 INFO - Running setup.py install for mozscreenshot 04:10:03 INFO - Running setup.py install for moztest 04:10:04 INFO - Running setup.py install for mozversion 04:10:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:10:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:10:04 INFO - Cleaning up... 04:10:04 INFO - Return code: 0 04:10:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:04 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 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:10:04 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 04:10:04 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 04:10:04 INFO - Using env: {'DISPLAY': ':0', 04:10:04 INFO - 'HOME': '/home/cltbld', 04:10:04 INFO - 'LANG': 'en_US.UTF-8', 04:10:04 INFO - 'LANGUAGE': 'en_US:en', 04:10:04 INFO - 'LOGNAME': 'cltbld', 04:10:04 INFO - 'MAIL': '/var/mail/cltbld', 04:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:04 INFO - 'MOZ_NO_REMOTE': '1', 04:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:04 INFO - 'NO_EM_RESTART': '1', 04:10:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:04 INFO - 'PWD': '/builds/slave/test', 04:10:04 INFO - 'SHELL': '/bin/bash', 04:10:04 INFO - 'SHLVL': '1', 04:10:04 INFO - 'TERM': 'linux', 04:10:04 INFO - 'TMOUT': '86400', 04:10:04 INFO - 'USER': 'cltbld', 04:10:04 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:04 INFO - '_': '/tools/buildbot/bin/python'} 04:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:10:04 INFO - Running setup.py (path:/tmp/pip-gXwuhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:10: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)) 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:10:04 INFO - Running setup.py (path:/tmp/pip-Mlqfwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:10: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)) 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:10:04 INFO - Running setup.py (path:/tmp/pip-OYTgJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:10: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)) 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:10:04 INFO - Running setup.py (path:/tmp/pip-6EVhFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:10: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)) 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:10:04 INFO - Running setup.py (path:/tmp/pip-1ylspZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:10: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)) 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:10:04 INFO - Running setup.py (path:/tmp/pip-tN2eYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:10: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)) 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:10:04 INFO - Running setup.py (path:/tmp/pip-jJ3uQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:10: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)) 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:10:04 INFO - Running setup.py (path:/tmp/pip-K6NsJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:10: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)) 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:10:04 INFO - Running setup.py (path:/tmp/pip-sgAtfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:10: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)) 04:10:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:10:05 INFO - Running setup.py (path:/tmp/pip-PFFFO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:10:05 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)) 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:10:05 INFO - Running setup.py (path:/tmp/pip-10Hdw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:10:05 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)) 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:10:05 INFO - Running setup.py (path:/tmp/pip-JTW_PF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:10:05 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)) 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:10:05 INFO - Running setup.py (path:/tmp/pip-ZpUbn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:10: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)) 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:10:05 INFO - Running setup.py (path:/tmp/pip-wqolnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:10: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)) 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:05 INFO - Running setup.py (path:/tmp/pip-Cpl7S0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:10: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)) 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:10:05 INFO - Running setup.py (path:/tmp/pip-lZNbU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:10: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)) 04:10:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:10:05 INFO - Running setup.py (path:/tmp/pip-OQvlx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:10: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 04:10: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 04:10: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 04:10: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 04:10:06 INFO - Downloading blessings-1.6.tar.gz 04:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:10:06 INFO - Installing collected packages: blessings 04:10:06 INFO - Running setup.py install for blessings 04:10:06 INFO - Successfully installed blessings 04:10:06 INFO - Cleaning up... 04:10:06 INFO - Return code: 0 04:10:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:10:07 INFO - Reading from file tmpfile_stdout 04:10:07 INFO - Current package versions: 04:10:07 INFO - argparse == 1.2.1 04:10:07 INFO - blessings == 1.6 04:10:07 INFO - blobuploader == 1.2.4 04:10:07 INFO - docopt == 0.6.1 04:10:07 INFO - functools32 == 3.2.3-2 04:10:07 INFO - jsonschema == 2.5.1 04:10:07 INFO - manifestparser == 1.1 04:10:07 INFO - mozInstall == 1.13 04:10:07 INFO - mozcrash == 1.0 04:10:07 INFO - mozdebug == 0.1 04:10:07 INFO - mozdevice == 0.51 04:10:07 INFO - mozfile == 1.2 04:10:07 INFO - mozhttpd == 0.7 04:10:07 INFO - mozinfo == 0.10 04:10:07 INFO - mozleak == 0.1 04:10:07 INFO - mozlog == 3.5 04:10:07 INFO - moznetwork == 0.27 04:10:07 INFO - mozprocess == 0.25 04:10:07 INFO - mozprofile == 0.28 04:10:07 INFO - mozrunner == 6.13 04:10:07 INFO - mozscreenshot == 0.1 04:10:07 INFO - mozsystemmonitor == 0.3 04:10:07 INFO - moztest == 0.8 04:10:07 INFO - mozversion == 1.4 04:10:07 INFO - psutil == 3.1.1 04:10:07 INFO - requests == 1.2.3 04:10:07 INFO - wsgiref == 0.1.2 04:10:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10:07 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')]} 04:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:07 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')]} 04:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:07 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 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:10:07 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 04:10:07 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 04:10:07 INFO - Using env: {'DISPLAY': ':0', 04:10:07 INFO - 'HOME': '/home/cltbld', 04:10:07 INFO - 'LANG': 'en_US.UTF-8', 04:10:07 INFO - 'LANGUAGE': 'en_US:en', 04:10:07 INFO - 'LOGNAME': 'cltbld', 04:10:07 INFO - 'MAIL': '/var/mail/cltbld', 04:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:07 INFO - 'MOZ_NO_REMOTE': '1', 04:10:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:07 INFO - 'NO_EM_RESTART': '1', 04:10:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:07 INFO - 'PWD': '/builds/slave/test', 04:10:07 INFO - 'SHELL': '/bin/bash', 04:10:07 INFO - 'SHLVL': '1', 04:10:07 INFO - 'TERM': 'linux', 04:10:07 INFO - 'TMOUT': '86400', 04:10:07 INFO - 'USER': 'cltbld', 04:10:07 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:07 INFO - '_': '/tools/buildbot/bin/python'} 04:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10: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)) 04:10:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:10: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 04:10: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 04:10: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 04:10: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 04:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:10: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)) 04:10: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)) 04:10:08 INFO - Installing collected packages: simplejson 04:10:08 INFO - Running setup.py install for simplejson 04:10:08 INFO - building 'simplejson._speedups' extension 04:10: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 04:10: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 04:10:09 INFO - Successfully installed simplejson 04:10:09 INFO - Cleaning up... 04:10:09 INFO - Return code: 0 04:10:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:10: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')]} 04:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10: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')]} 04:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10: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 0x22191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25869c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', '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 04:10: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 04:10: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 04:10:09 INFO - Using env: {'DISPLAY': ':0', 04:10:09 INFO - 'HOME': '/home/cltbld', 04:10:09 INFO - 'LANG': 'en_US.UTF-8', 04:10:09 INFO - 'LANGUAGE': 'en_US:en', 04:10:09 INFO - 'LOGNAME': 'cltbld', 04:10:09 INFO - 'MAIL': '/var/mail/cltbld', 04:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:09 INFO - 'MOZ_NO_REMOTE': '1', 04:10:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:09 INFO - 'NO_EM_RESTART': '1', 04:10:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:09 INFO - 'PWD': '/builds/slave/test', 04:10:09 INFO - 'SHELL': '/bin/bash', 04:10:09 INFO - 'SHLVL': '1', 04:10:09 INFO - 'TERM': 'linux', 04:10:09 INFO - 'TMOUT': '86400', 04:10:09 INFO - 'USER': 'cltbld', 04:10:09 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:09 INFO - '_': '/tools/buildbot/bin/python'} 04:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:10:09 INFO - Cleaning up... 04:10:09 INFO - Return code: 0 04:10:09 INFO - Running post-action listener: _resource_record_post_action 04:10:09 INFO - Running post-action listener: _start_resource_monitoring 04:10:09 INFO - Starting resource monitoring. 04:10:09 INFO - [mozharness: 2017-09-27 11:10:09.867247Z] Finished create-virtualenv step (success) 04:10:09 INFO - [mozharness: 2017-09-27 11:10:09.867717Z] Running install step. 04:10:09 INFO - Running pre-action listener: _resource_record_pre_action 04:10:09 INFO - Running main action method: install 04:10:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:10:10 INFO - Reading from file tmpfile_stdout 04:10:10 INFO - Detecting whether we're running mozinstall >=1.0... 04:10:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:10:10 INFO - Reading from file tmpfile_stdout 04:10:10 INFO - Output received: 04:10:10 INFO - Usage: mozinstall [options] installer 04:10:10 INFO - Options: 04:10:10 INFO - -h, --help show this help message and exit 04:10:10 INFO - -d DEST, --destination=DEST 04:10:10 INFO - Directory to install application into. [default: 04:10:10 INFO - "/builds/slave/test"] 04:10:10 INFO - --app=APP Application being installed. [default: firefox] 04:10:10 INFO - mkdir: /builds/slave/test/build/application 04:10: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'] 04:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:10:27 INFO - Reading from file tmpfile_stdout 04:10:27 INFO - Output received: 04:10:27 INFO - /builds/slave/test/build/application/firefox/firefox 04:10:27 INFO - Running post-action listener: _resource_record_post_action 04:10:27 INFO - [mozharness: 2017-09-27 11:10:27.953829Z] Finished install step (success) 04:10:27 INFO - [mozharness: 2017-09-27 11:10:27.954065Z] Running setup-mitmproxy step. 04:10:27 INFO - Running pre-action listener: _resource_record_pre_action 04:10:27 INFO - Running main action method: setup_mitmproxy 04:10:27 INFO - Skipping: mitmproxy is not required 04:10:27 INFO - Running post-action listener: _resource_record_post_action 04:10:27 INFO - [mozharness: 2017-09-27 11:10:27.955193Z] Finished setup-mitmproxy step (success) 04:10:27 INFO - [mozharness: 2017-09-27 11:10:27.955415Z] Running run-tests step. 04:10:27 INFO - Running pre-action listener: _resource_record_pre_action 04:10:27 INFO - Running pre-action listener: _set_gcov_prefix 04:10:27 INFO - Running main action method: run_tests 04:10:27 WARNING - Try message not found. 04:10:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:10:27 INFO - Python 2.7.3 04:10:27 INFO - Return code: 0 04:10:27 INFO - grabbing minidump binary from tooltool 04:10: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')]} 04:10: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 0x2583d60>, '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 0x2584a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2584ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:10: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 04:10: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 04:10: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 04:10:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:10:28 INFO - Return code: 0 04:10:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:10:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:10:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:10:28 INFO - ENV: RUST_BACKTRACE is now full 04:10:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:10:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:10:28 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/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 04:10:28 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/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 04:10:28 INFO - Using env: {'DISPLAY': ':0', 04:10:28 INFO - 'HOME': '/home/cltbld', 04:10:28 INFO - 'LANG': 'en_US.UTF-8', 04:10:28 INFO - 'LANGUAGE': 'en_US:en', 04:10:28 INFO - 'LOGNAME': 'cltbld', 04:10:28 INFO - 'MAIL': '/var/mail/cltbld', 04:10:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:10:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:28 INFO - 'MOZ_NO_REMOTE': '1', 04:10:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:10:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:28 INFO - 'NO_EM_RESTART': '1', 04:10:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:28 INFO - 'PWD': '/builds/slave/test', 04:10:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:10:28 INFO - 'RUST_BACKTRACE': 'full', 04:10:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:10:28 INFO - 'SHELL': '/bin/bash', 04:10:28 INFO - 'SHLVL': '1', 04:10:28 INFO - 'TERM': 'linux', 04:10:28 INFO - 'TMOUT': '86400', 04:10:28 INFO - 'USER': 'cltbld', 04:10:28 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:28 INFO - '_': '/tools/buildbot/bin/python'} 04:10:28 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/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 04:10:28 INFO - mozversion application_buildid: 20170927094612 04:10:28 INFO - mozversion application_changeset: 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 04:10:28 INFO - mozversion application_display_name: Nightly 04:10:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:10:28 INFO - mozversion application_name: Firefox 04:10:28 INFO - mozversion application_remotingname: firefox 04:10:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:10:28 INFO - mozversion application_vendor: Mozilla 04:10:28 INFO - mozversion application_version: 58.0a1 04:10:28 INFO - mozversion platform_buildid: 20170927094612 04:10:28 INFO - mozversion platform_changeset: 5563e7da39b265ed1ba7796ec058bdbcf6f792f6 04:10:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:10:28 INFO - mozversion platform_version: 58.0a1 04:10:28 INFO - using testdate: 1506510628 04:10:28 INFO - actual date: 1506510628 04:10:28 INFO - starting webserver on 'localhost:47704' 04:10:28 INFO - SUITE-START | Running 2 tests 04:10:28 INFO - TEST-START | dromaeo_css 04:10:28 INFO - Initialising browser for dromaeo_css test... 04:10:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47704/getInfo.html -profile /tmp/tmpafF8qL/profile 04:10:28 INFO - TEST-INFO | started process 25021 (/builds/slave/test/build/application/firefox/firefox http://localhost:47704/getInfo.html) 04:10:34 INFO - TEST-INFO | 25021: exit 0 04:10:34 INFO - Browser initialized. 04:10:34 INFO - Running cycle 1/1 for dromaeo_css test... 04:10:34 INFO - Using env: {'DISPLAY': ':0', 04:10:34 INFO - 'HOME': '/home/cltbld', 04:10:34 INFO - 'JSGC_DISABLE_POISONING': '1', 04:10:34 INFO - 'LANG': 'en_US.UTF-8', 04:10:34 INFO - 'LANGUAGE': 'en_US:en', 04:10:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:10:34 INFO - 'LOGNAME': 'cltbld', 04:10:34 INFO - 'MAIL': '/var/mail/cltbld', 04:10:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:10:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:10:34 INFO - 'MOZ_CRASHREPORTER': '1', 04:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:10:34 INFO - 'MOZ_NO_REMOTE': '1', 04:10:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:10:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:34 INFO - 'NO_EM_RESTART': '1', 04:10:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:34 INFO - 'PWD': '/builds/slave/test', 04:10:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:10:34 INFO - 'RUST_BACKTRACE': 'full', 04:10:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:10:34 INFO - 'SHELL': '/bin/bash', 04:10:34 INFO - 'SHLVL': '1', 04:10:34 INFO - 'TERM': 'linux', 04:10:34 INFO - 'TMOUT': '86400', 04:10:34 INFO - 'USER': 'cltbld', 04:10:34 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:34 INFO - '_': '/tools/buildbot/bin/python'} 04:10:34 INFO - TEST-INFO | started process 25255 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpafF8qL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:10:35 INFO - PID 25255 | 04:10:35 INFO - PID 25255 | (/builds/slave/test/build/application/firefox/firefox:25305): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:10:35 INFO - PID 25255 | 04:10:45 INFO - PID 25255 | RSS: Main: 141094912 04:10:45 INFO - PID 25255 | 04:13:43 INFO - PID 25255 | Cycle 1(1): loaded http://localhost:47704/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47704/tests/dromaeo/cssquery-ext.html) 04:13:44 INFO - PID 25255 | RSS: Main: 145801216 04:13:44 INFO - PID 25255 | 04:16:46 INFO - PID 25255 | Cycle 1(1): loaded http://localhost:47704/tests/dromaeo/cssquery-ext.html (next: http://localhost:47704/tests/dromaeo/cssquery-jquery.html) 04:16:46 INFO - PID 25255 | RSS: Main: 146165760 04:16:46 INFO - PID 25255 | 04:19:48 INFO - PID 25255 | Cycle 1(1): loaded http://localhost:47704/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47704/tests/dromaeo/cssquery-mootools.html) 04:19:48 INFO - PID 25255 | RSS: Main: 148291584 04:19:48 INFO - PID 25255 | 04:22:50 INFO - PID 25255 | Cycle 1(1): loaded http://localhost:47704/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47704/tests/dromaeo/cssquery-prototype.html) 04:22:51 INFO - PID 25255 | RSS: Main: 145039360 04:22:51 INFO - PID 25255 | 04:25:54 INFO - PID 25255 | Cycle 1(1): loaded http://localhost:47704/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47704/tests/dromaeo/cssquery-yui.html) 04:25:54 INFO - PID 25255 | RSS: Main: 147628032 04:25:54 INFO - PID 25255 | 04:28:56 INFO - PID 25255 | Cycle 1(1): loaded http://localhost:47704/tests/dromaeo/cssquery-yui.html (next: http://localhost:47704/tests/dromaeo/cssquery-dojo.html) 04:28:56 INFO - PID 25255 | RSS: Main: 149684224 04:28:56 INFO - PID 25255 | 04:28:56 INFO - PID 25255 | __start_tp_report 04:28:56 INFO - PID 25255 | _x_x_mozilla_page_load 04:28:56 INFO - PID 25255 | _x_x_mozilla_page_load_details 04:28:56 INFO - PID 25255 | |i|pagename|runs| 04:28:56 INFO - PID 25255 | |0;dojo.html;3855.2482265958133;3883.184531248438;3896.649301562859;3901.7073719471045;3929.90175245619;6179.814605561829;6236.220472440945;6290;6298.433141017314;6430.710618022188;6286.3713628637115;6310.211223597051;6385.936140638604;6398.232212134539;6407.967960160193;5544.584156188282;5624.465675760796;5647.661140331593;5706.28671416073;5735.0250457422335;5559.221708960728;5595.440455954413;5693.943060569403;5709.914351284713;5721.942780572203;5526.309211348582;5581.832545023656;5618.606697531174;5647.29408823897;5664.980303545361;5664.745086471119;5674.120511320752;5685.090385538294;5686.943130568683;5689.630174038715;5590.692511911843;5601.747921343549;5612.747426365823;5639.984802735506;5643.040683083886;6202.224721909762;6203.100550420164;6328.873422531524;6366.427021568081;6399.784041032189;6772.2211945626395;6779.898301525481;6836.692348844264;6855.314468553154;6914.686209620207;6804.489663275224;6912.55068420551;6921.653917304115;6925.411340036103;6963.9651801740665;6335.904961425582;6343.555951083426;6369.490440764727;6426.485881129498;6437.613743175378;7073.221945585981;7076.150861896605;7157.964210178916;7158.498905076594;7160.427165826722;6805.863882722318;6809.523333366716;6843.62360070191;6867;6890.2076261229595;6779.084823548757;6854.862902742017;6867.656617169122;6937.549059311129;6939.965300173467;6878.415334696507;6894.379505844498;6914.308569143045;6915.585064896122;6924;6730.226024007204;6847.657617119125;6920.9653951730925;6921.2386637470845;6946.5137440378685;5015.899682006412;5022;5032.7735251913755;5094.363204599425;5105.897882042413;4542.750148741851;4559.338895860082;4611.930821037688;4632.305154226893;4634.23535116702;6302.212223472066;6317.6209427434505;6355.650439225795;6362.872742545123;6384.585001974857;6664.100346453167;6743.831404214836;6792.7962161134255;6863.519553631198;6911.343422374866;6209.223847019122;6316.368363163647;6324.430801227912;6326.177596912372;6337.081123237197;5524.917126243109;5563.332400112013;5571.275734154534;5571.749271282802;5573.442655734475;7054.823629409306;7064.434845212473;7162.104736907887;7163.89254161188;7189.928100718926;6750.628715420601;6779.525433219627;6844.93155068443;6871.759488418082;6879.518433709793;7071.575705457689;7104.538205016657;7148.821279468055;7159;7169.641517923978;6770.898436523357;6780.118584583973;6791.93208067913;6886.208086070166;6891.862162756817;6791.286914873867;6800.591964482147;6907.102076729993;6916.585004899521;6931.757388491378;6598;6665.166854143232;6670.966000269966;6684.799456016327;6689.632070235989;6534.673266336569;6569.178852643419;6575.769848055295;6673.666316684244;6694.096296999843;6499.38255865692;6579.111819904251;6581.769638062645;6585.967070164618;6598.60408375499;6566.409023187936;6659.433948114464;6670.699818508082;6672.699728512326;6683.7660681875905;6446.129772480843;6478.287388387368;6533.281339052795;6546.476281897341;6547.803565893031 04:28:56 INFO - PID 25255 | |1;ext.html;954.1603389017663;965.1024547171132;989.2036910287218;1008.6873069348502;1017.4404077757234;41154.58845411545;41754.58245417545;41765.79117104414;41807.16385672289;41885.162296754046;39345.606543934555;39437.40843887337;39515.40726889099;39777.80111099448;39825;7414.332710056107;7550;7555.659995300197;7557.0931488221495;7561.206073362273;7376.151742549622;7423.925760742378;7479.7382091626805;7482.289182527678;7491.288327608869;8221.54781487018;8388.07731149573;8394.160583941619;8399.412041157122;8406.495610263373;8279.254867061964;8307.916920830805;8354.248117669438;8359.791005224857;8365.038020627644;8057.234562716591;8169.183081691784;8191.836163276761;8230;8238.217369349963;40318.1856816444;40342.79828600868;40554.39168412476;40800;41012;3600.6039335673054;3636.654517820803;3659.743817932746;3660.084978755311;3662.816859157058;33173.17067073318;33273.500897486556;33518.49722254168;33553;33566.66433335684;5433.347998240197;5441.918371224435;5443.564514838803;5458.044842152607;5465;228037;230262.84868575717;230354;230784;231313;53147;53339.46660533422;53783.73108134434;53809.730951344995;54018.729906350214;57277.14084288739;57392.71303643455;57424;57490;57802;17014.404495842835;17240;17349.7397539037;17395;17396.39112631052;13986.930065349812;14065.578032659037;14080.507182248572;14081.507147249797;14107.647308817363;15369.615759606098;15526.223688815515;15546.222688865737;15600.843991560167;15717;6096.433586351945;6097.3292937776805;6120.877582448326;6241.906371404432;6246.250449946036;1077.7305673581604;1084.7613525024483;1089.4607169451172;1092.3664274720643;1101.7686285879895;24233.273001809623;24560.508789824104;24583.13959011426;24688.766611192223;24727.752722472906;1186.590626233948;1189.2685998111272;1193.570314686712;1199.4362649554869;1206.90344772418;12938.223706577635;12989.090763646644;13006.219626822232;13102.148360356547;13132.671683207805;286766;288169;288905;289060;289128;54573.181402279;58348.416515835146;59387.70306148442;59678.40321596815;59867;1477.5198060630123;1488.694817562397;1493.4175671488129;1498.4680438444414;1505.3752692632436;29290;29411.558826618046;29489.410211796072;29696.55455168131;29972.4005519884;39381.80309098436;39497.40753888751;39572.2085558293;39637.2072558553;39668.603313966494;223663;223944;225021;225095;226462;33534.832325838215;35202.647973520965;35255.11862203466;35451.113722157155;35504.28991420209;42234;43646;43895.56104438915;43959;44477.777611111735;33165.17087072843;33250.833745831114;33549.83225083859;33605.83197083999;33710.49434258439;32943.50584741279;33159.668403316624;33384.666153338156;33544.49683254704;33575;38463;38558.6144138555;38580.228395432496;38939.415908761956;39023.609763902;32360.83819580887;32955;32993.175170620925;33173.17067073342;33192.67229310801;24393.822933407413;24501.264962051166;24721.381965451008;24923.87538062298;25030.74969250357 04:28:56 INFO - PID 25255 | |2;jquery.html;600.6396162302619;602.8613418913646;609.7347653770613;627.6798832595376;630.9242890853097;22951.540969180627;23734.16930407436;23741.762582374173;23794.762052379472;23853.165139220153;24309.635355469683;25822.612660810104;25915.870420647916;25992.22023339298;26004.869975650094;6450.870982580357;6604.075429439882;6642.5350225484235;6645.734170633169;6648.069270302161;6478.2226132864125;6616.27221005689;6897.965305204234;6933.272006439327;6939.306069393071;6840.931590684104;6899.724011039552;6903.20613129489;6912.5852448852975;6918.930810691904;6597;6796.864062718742;6838.7264509419565;6853.314668533107;6889.4832887533385;6383.425491705722;6387.457066149383;6390.233172019341;6392.968035159794;6412.39082287182;30387.392252155056;30525.38949221003;30711.539326910115;30781.076567703003;30841.383172336427;2241.058755322752;2267.2291420916804;2267.7278726552754;2279.4757205842584;2290.793828555438;19668.213271469125;20880.895595521924;20957.371278861658;21033.894830525904;21065.368038958855;2549.209744979044;2572.9099481518056;2587.0815860369485;2610.4909542639193;2623.081921327539;132817;140000;152489.23755381262;160070;160510;15859.369923677159;16376.344946202256;16516.587085322724;16549.172541372882;16652.16739163037;25347.366315842253;25417.491650167263;25489.49021019569;25525.8723706384;25625;10321.690349289533;10458.633947811943;10462.163026957978;10642.946785266024;10674.626388076535;9336.859947100798;9350.896048389059;9402.48286344244;9489.193418559362;9528.666496672584;9633.951830240803;9694.448888177998;9961.302708810314;10012.249081318785;10043.949780251052;2735.2341344423594;2828.858557072138;2852.543593025106;2895.9276018099717;2924.1373794730607;998.9453365968224;1024.1960061351951;1026.2098184397971;1037.5382954585289;1042.259995403257;20673.706082734807;21098.367048988555;21189.364319070453;21259;21386.358409247747;1082.3181395720803;1090.2422816142864;1091.0725882999545;1096.4079397125463;1106.391484683437;11313.71715707114;11494.827577586342;11634.401618664635;11638.185327027028;11790.94104529472;594802;619545;620924;621466;628446;126896;131318;131331;131748;133287;1115.1914861725347;1190.470240742861;1219.5792451604182;1264.2035517624022;1269.2194300505307;22150.003249853475;22156.556868862855;22230.44423889351;22466.77533224712;22631.20790772315;65367.34632653613;65631.67184164049;66553.33446665666;66776.33223667702;69098;81465;81721;81962.18037819545;82114.58942705249;82828.17171828204;20736;21317.147314107264;21415;21459.570808584052;21485.67771483398;23609.52780944406;24006.63990040116;24014.51970960606;24019.879900600386;24120.517589648458;19326.4202073938;19521.316753913547;19620.313289034817;19659.115339809458;19859.602807943473;17293.65412691714;17580.73628895542;17801.1989460472;18000.189991450156;18385.90807045956;19943.800561994776;19944.20223191056;20145.294914677917;20332.288369906983;20396.286129985972;20208.49478763043;20359.79640203579;20395.4901127473;20413.795862041785;20536;19419.708704369164;19513.902430487757;19721.70417443768;19737.51968602332;19771.901140494207 04:28:56 INFO - PID 25255 | |3;mootools.html;1441.949531766388;1493.469818214534;1499.3702644889145;1501.3093976770685;1515.6817068415635;16334.183290835455;17038.914805425986;17050.5737356566;17093.82906170938;17101.486955391327;16613.916930415362;16642.334306627752;16685.416010439636;16699.499015029538;16742.49772506824;2795.3241487443947;2842.7725781937447;2885.047934181715;2886.494863398897;2887.307046308881;2839.33275680215;2859.442408730298;2861.4277144571065;2863.3414314707525;2868.4693331733592;2690.273626120946;2757.310672331917;2761.0336382266246;2763.9170824875287;2764.419471910901;2753.187809596164;2783.3320003199287;2785.220138361262;2788.4283721836973;2788.442311537701;2728.45430913818;2755.145904769508;2757.462294852505;2760.3651160233258;2762.323230808445;15900.602484937854;16246.918765406215;16251.51245462638;16267.83732162675;16285.185740712917;8057;8306.70926517575;8343.624536895853;8353.122922093153;8355.331573474108;5171.922421163686;5314.202869569557;5495.368032676253;5586.553075753971;5621.915671264935;2047;2083.4062292246635;2087.561612061469;2118.4597927528534;2200.8899555022185;25857.353566160808;26705.732942670715;26759.464810703677;30333.393332133233;31209.37581248385;7430.145533263636;7528.924710752932;7545.886811697829;7699;7700.499467534704;22579.43551411228;22618.886905565367;22648.320550383516;22668.433289167573;22693.092276309093;8437.57812109392;8556.61495232716;8556.88760461136;8572.057073721886;8600.741977740678;7608.201138880338;7624.046994125734;7670.386369090459;7711.460197786101;7722.343600793995;5890.2342695449315;6033.5173186145885;6156.5998210117095;6171.166892469549;6250.249970003538;1830.3868204151493;1833.8074502177346;1870.551067743759;1895.3650527073585;1899.8290153886217;987.782687808681;1001.3341128149845;1007.2244371833647;1012.2661070723814;1030.3972176276811;8663.740087797376;8718.128187181232;8738.737837864874;8761.868571971425;8831.735047948572;801.6512816924657;806.209914284005;807.648672827322;809.1180613131597;824.9381296402794;5266.683998960075;5288.7091209983055;5365.865853353697;5369.275147855068;5372.140457526777;23055.654165187534;24529.509409811704;24947.251582452554;25153.74846251514;25227.873860630578;24203.152889648776;24495.755042449346;24531.38671533226;24567;24709.5058098837;12420;12452.626421207378;12614.117011809269;12618.495260189673;12650;9481.194098501705;9519.904800951901;9559.378640388353;9561.282903782105;9569.473678947446;20444.386668399973;20468.181272748923;20614.793852061888;21258.681119782905;21820.67268990935;20719;21063.262785802424;21375.14499420068;21971.780282196974;22058.227962021254;13249.867501325249;13445.193288402726;13457.192568445924;13481.32593370308;13512.459501620218;15458.768118478456;15509.379624815332;15770.369185232463;15784.36862525532;15790.76313855316;12569.371531423576;12619.87380126187;12939.482420703067;12948.611541653765;13096.345182740633;12255.938720306342;12333.93833030829;12576.685582860502;12692.746145076862;12907.290099044643;10923.289986150874;11014;11101.666949991513;11138.888611113785;11150.219484635998;8417.326613870999;8447.45091569046;8484.40609157353;8547.444416112932;8559.700410485602;8138.715144969898;8146.959265203636;8216.137305583066;8251.546164960984;8326.875096873431 04:28:56 INFO - PID 25255 | |4;prototype.html;566.4307371092052;579.5942840011987;584.4798129664598;594.3848117198697;601.4166258729033;6213;6265.498760099193;6294.244690637124;6316.30520642729;6377.776777812784;6224.097505861659;6300.30696623371;6305.590136641115;6329.398707122816;6349.523785716066;5126.92309615356;5127.769250383723;5156.613254005946;5158.716270605115;5170.896582068356;5057.645964782449;5122.385313762354;5129.153689641204;5184.53339199472;5200.479952004788;4800.207965685658;4895.167821470359;4927.753612319388;4966.801327946896;4987.705655398503;4957.454679985199;4967.602591792648;4967.652264341497;4985.002999400098;4992.201247800334;4427.822887084512;4520.61574766142;4520.728756274634;4531.977340113312;4541.250693635579;6018.608790428652;6086.482648974842;6129.325774164839;6129.877402451971;6133.907991380132;5867.941320586782;5888.941110588882;5891.082430183923;5954.612950158269;5959.344472108065;6589.143411356541;6627.635480048595;6683.766068187639;6686.632235227061;6697.3637504437;5080.263361812554;5090.669106508065;5092.8217512387255;5093;5128.538431541179;6693.933060669428;6697.19633643961;6756.223034351064;6819;7020.824479388005;5169.53474187325;5235.816746413857;5264.763085661154;5266.2890509781455;5272.841814745564;5300.44345343741;5342.572594192456;5352.143657014859;5361.812336568201;5364.490373414519;5931.911021334683;5938.851528711731;5964.612300200473;5973.2965561351375;5973.492253158443;6546.345365463416;6666.233383160901;6691.933080669131;6765.763198288135;6775;1800.8379245867934;1828.7805463344484;1829.6798060339388;1842.7143792711884;1843.0784607696153;1265.8227848101353;1294.2752058847075;1295.6242689619905;1308.6466654003366;1330.5476138113088;6032.758689652453;6091.177691011745;6124.326324104345;6151.72317245725;6186.443220110212;6604.966975165094;6621.702023408958;6623.735050597922;6627.4366678831875;6681.9331806682285;6058.879676966366;6064.302605200371;6079.3920607939735;6161.907571386433;6208.72060757258;3470.479428085757;3490.197254631449;3495.2310491691787;3532.7703699259464;3559.4660800880074;6962.373386395248;7042.964785176041;7049.647517624098;7057.752978645723;7066.823329416806;6875;6893.620850853151;6903.861922761516;6920.169579650374;7019.333163349474;7041.683124259421;7083.18543367509;7109.28907109285;7115.608641524663;7132.786016419515;6760.898586521304;6772.187337519332;6795.218549866829;6810.897836532356;6818.761343352959;6753.459723222229;6759.155105611799;6811.727530898708;6820.829479263058;6854.554453960476;6804.829879253059;6837.179538455417;6895;6899.758508452381;6983.216496699777;5207.27098206259;5210.374755029421;5214.8957020860125;5223.268742376146;5251.372074656869;6485.189351331083;6654.700538475877;6690.799276021728;6742.224644165984;6784.457243420613;6306.927822270133;6521.934780652323;6540.444062254762;6639.336066393322;6650.235222949423;6496.317886622026;6519.250286217146;6576.309487503743;6592.923229182792;6605.688009196742;6238.594491358047;6448.032795080775;6451.967740161269;6458.354164583692;6466.4503517201565;6456.386643268882;6537.673116344259;6591.571547849375;6600.504962127764;6654.301298363602;6358.300586935526;6381.925874091032;6423.357664233539;6426.196725409324;6466.321036291121 04:28:56 INFO - PID 25255 | |5;yui.html;966.9709908702741;991.8363470027446;997.2719914462442;1011.9930668984362;1019.989800101999;19746.111424985873;19891.30380436685;19914.99999999998;19924.203031878707;20961.68557471637;19348.903255483703;19638.312659056937;19642.607147857034;19679.803201967978;19730.309439169632;1043.937363758175;1072.2976450424944;1074.6185104287983;1076.8761592416854;1077.0414331245197;1081.783643271345;1081.8809930907594;1082.5507414423005;1083.5774048121239;1083.951222195003;1048.2557384257186;1097;1109.3343993603853;1109.378747901174;1109.8057839877988;1100.0154861399044;1103.1064837481665;1104.4146602300793;1104.7182968343102;1105.2705214558353;779.4894344204555;800.3117319105564;801.3909428834128;802.8795680647892;803.9437239393245;18596.884186948828;18698.813011869843;18732.625347492973;18798.812011879843;18826.529336766554;23319.53360932789;23440.179593714307;23867.283981480585;24117.638235426482;24132.87933560321;18456.723149152775;18604;18641.44075677732;18744.15651295695;18812.24751009958;3939.153082087365;3942.231264903345;3943.7830919299427;3945.7237993340477;3946.605339466059;136564.31717841447;163058;164123.1793841035;167760;169596;19225.807741922403;19423;19464.124114414884;19505.902470487556;19582.80417195838;36223.27553448916;36550.634493654725;36954.26091478155;36985.89042328735;37125.44311835324;9640.228781697448;9768.21854251658;9814.116729494379;9875.506224688881;9887.45618990948;4294.076773493652;4366.104948485553;4371.23503386906;4381.364702118239;4420.403245561808;5984.611000284967;6127;6136.631802091889;6153.692315384213;6161.661108639068;1209.848768903887;1240.9813852792215;1254.8368712067556;1256.9120161588785;1257.7233008738062;755.5882044285768;761.40990732183;773.5706682790992;778.3111945927894;788.286600626434;13204.735905282032;13359.398827052806;13485.595432137052;13620.867200543733;13981.881449484017;726.8946002829666;735.955842649432;747.5776186454635;748.2741740511804;750.2609929219714;5935.673537955454;5949.286085669747;6041.305249896318;6065.332813390523;6157.568970172135;147518.26240868727;149528;150722;155154.22422887813;157499;105136;105448;129859.35070324776;137865;138451;110706.44646776875;112193;114708.42645786717;115428.99999999833;121465;13366.324285764;13744.244066576433;13957.696979998187;14140.363683634056;14410.42358305698;50917.981640367725;52361.7381913088;52750;53094.73452632712;54877.45122548883;67058.66470667615;68494.65752671205;88710.55644721736;90427;92219.53890230505;19840.50398740043;20307;20348.28780992658;20382.28661996823;20713.585728285652;22799.65800513027;23408.648870266617;23464.530709386057;23753.881230593735;24034.87982560076;21694.80678562631;21958.780412195672;22049.33851984443;22278.665820013037;22467.550648987257;21539.2461263855;21549.24577639775;21641.02615382343;21736.891315543322;22207.22274720377;19494.707579386602;21263.36209913705;21484.14063437445;21811.67282490732;21928.232511862647;15300.617484562976;15329.310181042103;15415.76876346878;15706.76439853426;15875.761863571825;15160;15309.38762449534;15374.692506149591;15443.6139096519;15525 04:28:56 INFO - PID 25255 | __end_tp_report 04:28:56 INFO - PID 25255 | __start_cc_report 04:28:56 INFO - PID 25255 | _x_x_mozilla_cycle_collect,289 04:28:56 INFO - PID 25255 | __end_cc_report 04:28:56 INFO - PID 25255 | __startTimestamp1506511736465__endTimestamp 04:28:56 INFO - PID 25255 | 04:28:56 INFO - PID 25255 | ------- Summary: start ------- 04:28:56 INFO - PID 25255 | Number of tests: 6 04:28:56 INFO - PID 25255 | 04:28:56 INFO - PID 25255 | [#0] dojo.html Cycles:165 Average:6309.87 Median:6566.41 stddev:754.37 (11.5%) stddev-sans-first:731.69 04:28:56 INFO - PID 25255 | Values: 3855.2 3883.2 3896.6 3901.7 3929.9 6179.8 6236.2 6290.0 6298.4 6430.7 6286.4 6310.2 6385.9 6398.2 6408.0 5544.6 5624.5 5647.7 5706.3 5735.0 5559.2 5595.4 5693.9 5709.9 5721.9 5526.3 5581.8 5618.6 5647.3 5665.0 5664.7 5674.1 5685.1 5686.9 5689.6 5590.7 5601.7 5612.7 5640.0 5643.0 6202.2 6203.1 6328.9 6366.4 6399.8 6772.2 6779.9 6836.7 6855.3 6914.7 6804.5 6912.6 6921.7 6925.4 6964.0 6335.9 6343.6 6369.5 6426.5 6437.6 7073.2 7076.2 7158.0 7158.5 7160.4 6805.9 6809.5 6843.6 6867.0 6890.2 6779.1 6854.9 6867.7 6937.5 6940.0 6878.4 6894.4 6914.3 6915.6 6924.0 6730.2 6847.7 6921.0 6921.2 6946.5 5015.9 5022.0 5032.8 5094.4 5105.9 4542.8 4559.3 4611.9 4632.3 4634.2 6302.2 6317.6 6355.7 6362.9 6384.6 6664.1 6743.8 6792.8 6863.5 6911.3 6209.2 6316.4 6324.4 6326.2 6337.1 5524.9 5563.3 5571.3 5571.7 5573.4 7054.8 7064.4 7162.1 7163.9 7189.9 6750.6 6779.5 6844.9 6871.8 6879.5 7071.6 7104.5 7148.8 7159.0 7169.6 6770.9 6780.1 6791.9 6886.2 6891.9 6791.3 6800.6 6907.1 6916.6 6931.8 6598.0 6665.2 6671.0 6684.8 6689.6 6534.7 6569.2 6575.8 6673.7 6694.1 6499.4 6579.1 6581.8 6586.0 6598.6 6566.4 6659.4 6670.7 6672.7 6683.8 6446.1 6478.3 6533.3 6546.5 6547.8 04:28:56 INFO - PID 25255 | 04:28:56 INFO - PID 25255 | [#1] ext.html Cycles:180 Average:42316.33 Median:35477.70 stddev:64903.42 (182.9%) stddev-sans-first:65011.18 04:28:56 INFO - PID 25255 | Values: 954.2 965.1 989.2 1008.7 1017.4 41154.6 41754.6 41765.8 41807.2 41885.2 39345.6 39437.4 39515.4 39777.8 39825.0 7414.3 7550.0 7555.7 7557.1 7561.2 7376.2 7423.9 7479.7 7482.3 7491.3 8221.5 8388.1 8394.2 8399.4 8406.5 8279.3 8307.9 8354.2 8359.8 8365.0 8057.2 8169.2 8191.8 8230.0 8238.2 40318.2 40342.8 40554.4 40800.0 41012.0 3600.6 3636.7 3659.7 3660.1 3662.8 33173.2 33273.5 33518.5 33553.0 33566.7 5433.3 5441.9 5443.6 5458.0 5465.0 228037.0 230262.8 230354.0 230784.0 231313.0 53147.0 53339.5 53783.7 53809.7 54018.7 57277.1 57392.7 57424.0 57490.0 57802.0 17014.4 17240.0 17349.7 17395.0 17396.4 13986.9 14065.6 14080.5 14081.5 14107.6 15369.6 15526.2 15546.2 15600.8 15717.0 6096.4 6097.3 6120.9 6241.9 6246.3 1077.7 1084.8 1089.5 1092.4 1101.8 24233.3 24560.5 24583.1 24688.8 24727.8 1186.6 1189.3 1193.6 1199.4 1206.9 12938.2 12989.1 13006.2 13102.1 13132.7 286766.0 288169.0 288905.0 289060.0 289128.0 54573.2 58348.4 59387.7 59678.4 59867.0 1477.5 1488.7 1493.4 1498.5 1505.4 29290.0 29411.6 29489.4 29696.6 29972.4 39381.8 39497.4 39572.2 39637.2 39668.6 223663.0 223944.0 225021.0 225095.0 226462.0 33534.8 35202.6 35255.1 35451.1 35504.3 42234.0 43646.0 43895.6 43959.0 44477.8 33165.2 33250.8 33549.8 33605.8 33710.5 32943.5 33159.7 33384.7 33544.5 33575.0 38463.0 38558.6 38580.2 38939.4 39023.6 32360.8 32955.0 32993.2 33173.2 33192.7 24393.8 24501.3 24721.4 24923.9 25030.7 04:28:56 INFO - PID 25255 | 04:28:56 INFO - PID 25255 | [#2] jquery.html Cycles:180 Average:40584.59 Median:12621.17 stddev:103071.10 (816.7%) stddev-sans-first:103316.52 04:28:56 INFO - PID 25255 | Values: 600.6 602.9 609.7 627.7 630.9 22951.5 23734.2 23741.8 23794.8 23853.2 24309.6 25822.6 25915.9 25992.2 26004.9 6450.9 6604.1 6642.5 6645.7 6648.1 6478.2 6616.3 6898.0 6933.3 6939.3 6840.9 6899.7 6903.2 6912.6 6918.9 6597.0 6796.9 6838.7 6853.3 6889.5 6383.4 6387.5 6390.2 6393.0 6412.4 30387.4 30525.4 30711.5 30781.1 30841.4 2241.1 2267.2 2267.7 2279.5 2290.8 19668.2 20880.9 20957.4 21033.9 21065.4 2549.2 2572.9 2587.1 2610.5 2623.1 132817.0 140000.0 152489.2 160070.0 160510.0 15859.4 16376.3 16516.6 16549.2 16652.2 25347.4 25417.5 25489.5 25525.9 25625.0 10321.7 10458.6 10462.2 10642.9 10674.6 9336.9 9350.9 9402.5 9489.2 9528.7 9634.0 9694.4 9961.3 10012.2 10043.9 2735.2 2828.9 2852.5 2895.9 2924.1 998.9 1024.2 1026.2 1037.5 1042.3 20673.7 21098.4 21189.4 21259.0 21386.4 1082.3 1090.2 1091.1 1096.4 1106.4 11313.7 11494.8 11634.4 11638.2 11790.9 594802.0 619545.0 620924.0 621466.0 628446.0 126896.0 131318.0 131331.0 131748.0 133287.0 1115.2 1190.5 1219.6 1264.2 1269.2 22150.0 22156.6 22230.4 22466.8 22631.2 65367.3 65631.7 66553.3 66776.3 69098.0 81465.0 81721.0 81962.2 82114.6 82828.2 20736.0 21317.1 21415.0 21459.6 21485.7 23609.5 24006.6 24014.5 24019.9 24120.5 19326.4 19521.3 19620.3 19659.1 19859.6 17293.7 17580.7 17801.2 18000.2 18385.9 19943.8 19944.2 20145.3 20332.3 20396.3 20208.5 20359.8 20395.5 20413.8 20536.0 19419.7 19513.9 19721.7 19737.5 19771.9 04:28:56 INFO - PID 25255 | 04:28:56 INFO - PID 25255 | [#3] mootools.html Cycles:180 Average:10426.66 Median:26732.60 stddev:7597.64 (28.4%) stddev-sans-first:7588.97 04:28:56 INFO - PID 25255 | Values: 1441.9 1493.5 1499.4 1501.3 1515.7 16334.2 17038.9 17050.6 17093.8 17101.5 16613.9 16642.3 16685.4 16699.5 16742.5 2795.3 2842.8 2885.0 2886.5 2887.3 2839.3 2859.4 2861.4 2863.3 2868.5 2690.3 2757.3 2761.0 2763.9 2764.4 2753.2 2783.3 2785.2 2788.4 2788.4 2728.5 2755.1 2757.5 2760.4 2762.3 15900.6 16246.9 16251.5 16267.8 16285.2 8057.0 8306.7 8343.6 8353.1 8355.3 5171.9 5314.2 5495.4 5586.6 5621.9 2047.0 2083.4 2087.6 2118.5 2200.9 25857.4 26705.7 26759.5 30333.4 31209.4 7430.1 7528.9 7545.9 7699.0 7700.5 22579.4 22618.9 22648.3 22668.4 22693.1 8437.6 8556.6 8556.9 8572.1 8600.7 7608.2 7624.0 7670.4 7711.5 7722.3 5890.2 6033.5 6156.6 6171.2 6250.2 1830.4 1833.8 1870.6 1895.4 1899.8 987.8 1001.3 1007.2 1012.3 1030.4 8663.7 8718.1 8738.7 8761.9 8831.7 801.7 806.2 807.6 809.1 824.9 5266.7 5288.7 5365.9 5369.3 5372.1 23055.7 24529.5 24947.3 25153.7 25227.9 24203.2 24495.8 24531.4 24567.0 24709.5 12420.0 12452.6 12614.1 12618.5 12650.0 9481.2 9519.9 9559.4 9561.3 9569.5 20444.4 20468.2 20614.8 21258.7 21820.7 20719.0 21063.3 21375.1 21971.8 22058.2 13249.9 13445.2 13457.2 13481.3 13512.5 15458.8 15509.4 15770.4 15784.4 15790.8 12569.4 12619.9 12939.5 12948.6 13096.3 12255.9 12333.9 12576.7 12692.7 12907.3 10923.3 11014.0 11101.7 11138.9 11150.2 8417.3 8447.5 8484.4 8547.4 8559.7 8138.7 8147.0 8216.1 8251.5 8326.9 04:28:56 INFO - PID 25255 | 04:28:56 INFO - PID 25255 | [#4] prototype.html Cycles:180 Average:5640.75 Median:6218.55 stddev:1566.67 (25.2%) stddev-sans-first:1524.07 04:28:56 INFO - PID 25255 | Values: 566.4 579.6 584.5 594.4 601.4 6213.0 6265.5 6294.2 6316.3 6377.8 6224.1 6300.3 6305.6 6329.4 6349.5 5126.9 5127.8 5156.6 5158.7 5170.9 5057.6 5122.4 5129.2 5184.5 5200.5 4800.2 4895.2 4927.8 4966.8 4987.7 4957.5 4967.6 4967.7 4985.0 4992.2 4427.8 4520.6 4520.7 4532.0 4541.3 6018.6 6086.5 6129.3 6129.9 6133.9 5867.9 5888.9 5891.1 5954.6 5959.3 6589.1 6627.6 6683.8 6686.6 6697.4 5080.3 5090.7 5092.8 5093.0 5128.5 6693.9 6697.2 6756.2 6819.0 7020.8 5169.5 5235.8 5264.8 5266.3 5272.8 5300.4 5342.6 5352.1 5361.8 5364.5 5931.9 5938.9 5964.6 5973.3 5973.5 6546.3 6666.2 6691.9 6765.8 6775.0 1800.8 1828.8 1829.7 1842.7 1843.1 1265.8 1294.3 1295.6 1308.6 1330.5 6032.8 6091.2 6124.3 6151.7 6186.4 6605.0 6621.7 6623.7 6627.4 6681.9 6058.9 6064.3 6079.4 6161.9 6208.7 3470.5 3490.2 3495.2 3532.8 3559.5 6962.4 7043.0 7049.6 7057.8 7066.8 6875.0 6893.6 6903.9 6920.2 7019.3 7041.7 7083.2 7109.3 7115.6 7132.8 6760.9 6772.2 6795.2 6810.9 6818.8 6753.5 6759.2 6811.7 6820.8 6854.6 6804.8 6837.2 6895.0 6899.8 6983.2 5207.3 5210.4 5214.9 5223.3 5251.4 6485.2 6654.7 6690.8 6742.2 6784.5 6306.9 6521.9 6540.4 6639.3 6650.2 6496.3 6519.3 6576.3 6592.9 6605.7 6238.6 6448.0 6452.0 6458.4 6466.5 6456.4 6537.7 6591.6 6600.5 6654.3 6358.3 6381.9 6423.4 6426.2 6466.3 04:28:56 INFO - PID 25255 | 04:28:56 INFO - PID 25255 | [#5] yui.html Cycles:180 Average:29114.65 Median:19903.15 stddev:42236.50 (212.2%) stddev-sans-first:42302.10 04:28:56 INFO - PID 25255 | Values: 967.0 991.8 997.3 1012.0 1020.0 19746.1 19891.3 19915.0 19924.2 20961.7 19348.9 19638.3 19642.6 19679.8 19730.3 1043.9 1072.3 1074.6 1076.9 1077.0 1081.8 1081.9 1082.6 1083.6 1084.0 1048.3 1097.0 1109.3 1109.4 1109.8 1100.0 1103.1 1104.4 1104.7 1105.3 779.5 800.3 801.4 802.9 803.9 18596.9 18698.8 18732.6 18798.8 18826.5 23319.5 23440.2 23867.3 24117.6 24132.9 18456.7 18604.0 18641.4 18744.2 18812.2 3939.2 3942.2 3943.8 3945.7 3946.6 136564.3 163058.0 164123.2 167760.0 169596.0 19225.8 19423.0 19464.1 19505.9 19582.8 36223.3 36550.6 36954.3 36985.9 37125.4 9640.2 9768.2 9814.1 9875.5 9887.5 4294.1 4366.1 4371.2 4381.4 4420.4 5984.6 6127.0 6136.6 6153.7 6161.7 1209.8 1241.0 1254.8 1256.9 1257.7 755.6 761.4 773.6 778.3 788.3 13204.7 13359.4 13485.6 13620.9 13981.9 726.9 736.0 747.6 748.3 750.3 5935.7 5949.3 6041.3 6065.3 6157.6 147518.3 149528.0 150722.0 155154.2 157499.0 105136.0 105448.0 129859.4 137865.0 138451.0 110706.4 112193.0 114708.4 115429.0 121465.0 13366.3 13744.2 13957.7 14140.4 14410.4 50918.0 52361.7 52750.0 53094.7 54877.5 67058.7 68494.7 88710.6 90427.0 92219.5 19840.5 20307.0 20348.3 20382.3 20713.6 22799.7 23408.6 23464.5 23753.9 24034.9 21694.8 21958.8 22049.3 22278.7 22467.6 21539.2 21549.2 21641.0 21736.9 22207.2 19494.7 21263.4 21484.1 21811.7 21928.2 15300.6 15329.3 15415.8 15706.8 15875.8 15160.0 15309.4 15374.7 15443.6 15525.0 04:28:56 INFO - PID 25255 | -------- Summary: end -------- 04:28:56 INFO - PID 25255 | 04:28:57 INFO - TEST-INFO | 25255: exit 0 04:28:57 INFO - TEST-OK | dromaeo_css | took 1108806ms 04:28:57 INFO - TEST-START | kraken 04:28:57 INFO - Initialising browser for kraken test... 04:28:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47704/getInfo.html -profile /tmp/tmpZS2lPK/profile 04:28:57 INFO - TEST-INFO | started process 25631 (/builds/slave/test/build/application/firefox/firefox http://localhost:47704/getInfo.html) 04:29:02 INFO - TEST-INFO | 25631: exit 0 04:29:02 INFO - Browser initialized. 04:29:02 INFO - Running cycle 1/1 for kraken test... 04:29:02 INFO - Using env: {'DISPLAY': ':0', 04:29:02 INFO - 'HOME': '/home/cltbld', 04:29:02 INFO - 'JSGC_DISABLE_POISONING': '1', 04:29:02 INFO - 'LANG': 'en_US.UTF-8', 04:29:02 INFO - 'LANGUAGE': 'en_US:en', 04:29:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:29:02 INFO - 'LOGNAME': 'cltbld', 04:29:02 INFO - 'MAIL': '/var/mail/cltbld', 04:29:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:29:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:29:02 INFO - 'MOZ_CRASHREPORTER': '1', 04:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:29:02 INFO - 'MOZ_NO_REMOTE': '1', 04:29:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:29:02 INFO - 'NO_EM_RESTART': '1', 04:29:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:02 INFO - 'PWD': '/builds/slave/test', 04:29:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:29:02 INFO - 'RUST_BACKTRACE': 'full', 04:29:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:29:02 INFO - 'SHELL': '/bin/bash', 04:29:02 INFO - 'SHLVL': '1', 04:29:02 INFO - 'TERM': 'linux', 04:29:02 INFO - 'TMOUT': '86400', 04:29:02 INFO - 'USER': 'cltbld', 04:29:02 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760', 04:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:02 INFO - '_': '/tools/buildbot/bin/python'} 04:29:02 INFO - TEST-INFO | started process 25870 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZS2lPK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:29:03 INFO - PID 25870 | 04:29:03 INFO - PID 25870 | (/builds/slave/test/build/application/firefox/firefox:25920): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:29:03 INFO - PID 25870 | 04:29:13 INFO - PID 25870 | RSS: Main: 144891904 04:29:13 INFO - PID 25870 | 04:29:16 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47704/tests/kraken/driver.html?testName=audio-beat-detection) 04:29:17 INFO - PID 25870 | RSS: Main: 150671360 04:29:17 INFO - PID 25870 | 04:29:19 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47704/tests/kraken/driver.html?testName=audio-dft) 04:29:20 INFO - PID 25870 | RSS: Main: 151134208 04:29:20 INFO - PID 25870 | 04:29:23 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47704/tests/kraken/driver.html?testName=audio-fft) 04:29:23 INFO - PID 25870 | RSS: Main: 147546112 04:29:23 INFO - PID 25870 | 04:29:25 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47704/tests/kraken/driver.html?testName=audio-oscillator) 04:29:26 INFO - PID 25870 | RSS: Main: 149102592 04:29:26 INFO - PID 25870 | 04:29:28 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47704/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:29:29 INFO - PID 25870 | RSS: Main: 148226048 04:29:29 INFO - PID 25870 | 04:29:32 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47704/tests/kraken/driver.html?testName=imaging-darkroom) 04:29:32 INFO - PID 25870 | RSS: Main: 148213760 04:29:32 INFO - PID 25870 | 04:29:36 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47704/tests/kraken/driver.html?testName=imaging-desaturate) 04:29:36 INFO - PID 25870 | RSS: Main: 149262336 04:29:36 INFO - PID 25870 | 04:29:39 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47704/tests/kraken/driver.html?testName=json-parse-financial) 04:29:40 INFO - PID 25870 | RSS: Main: 151343104 04:29:40 INFO - PID 25870 | 04:29:42 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47704/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:29:42 INFO - PID 25870 | RSS: Main: 151171072 04:29:42 INFO - PID 25870 | 04:29:44 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47704/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:29:44 INFO - PID 25870 | RSS: Main: 150974464 04:29:44 INFO - PID 25870 | 04:29:46 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47704/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:29:47 INFO - PID 25870 | RSS: Main: 148189184 04:29:47 INFO - PID 25870 | 04:29:49 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47704/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:29:50 INFO - PID 25870 | RSS: Main: 147755008 04:29:50 INFO - PID 25870 | 04:29:53 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47704/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:29:53 INFO - PID 25870 | RSS: Main: 149131264 04:29:53 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | Cycle 1(1): loaded http://localhost:47704/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47704/tests/kraken/driver.html?testName=ai-astar) 04:29:55 INFO - PID 25870 | RSS: Main: 149917696 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | __start_tp_report 04:29:55 INFO - PID 25870 | _x_x_mozilla_page_load 04:29:55 INFO - PID 25870 | _x_x_mozilla_page_load_details 04:29:55 INFO - PID 25870 | |i|pagename|runs| 04:29:55 INFO - PID 25870 | |0;ai-astar;105;105;105;106;108;107;107;106;106;104 04:29:55 INFO - PID 25870 | |1;audio-beat-detection;144;110;138;170;112;141;143;113;171;117 04:29:55 INFO - PID 25870 | |2;audio-dft;162;162;161;161;163;162;161;164;164;165 04:29:55 INFO - PID 25870 | |3;audio-fft;75;75;74;74;74;84;73;74;79;76 04:29:55 INFO - PID 25870 | |4;audio-oscillator;78;78;79;77;76;77;78;76;77;83 04:29:55 INFO - PID 25870 | |5;imaging-gaussian-blur;113;113;114;113;113;110;117;114;114;115 04:29:55 INFO - PID 25870 | |6;imaging-darkroom;150;151;154;150;150;153;151;151;150;150 04:29:55 INFO - PID 25870 | |7;imaging-desaturate;94;94;94;94;94;96;96;94;95;94 04:29:55 INFO - PID 25870 | |8;json-parse-financial;47;46;45;45;47;46;46;46;44;44 04:29:55 INFO - PID 25870 | |9;json-stringify-tinderbox;36;41;37;37;36;35;36;36;38;42 04:29:55 INFO - PID 25870 | |10;stanford-crypto-aes;72;70;72;72;72;72;77;74;69;70 04:29:55 INFO - PID 25870 | |11;stanford-crypto-ccm;108;101;105;105;113;106;110;109;119;107 04:29:55 INFO - PID 25870 | |12;stanford-crypto-pbkdf2;148;154;150;156;152;155;157;153;151;158 04:29:55 INFO - PID 25870 | |13;stanford-crypto-sha256-iterative;63;64;64;60;60;58;61;58;58;60 04:29:55 INFO - PID 25870 | __end_tp_report 04:29:55 INFO - PID 25870 | __start_cc_report 04:29:55 INFO - PID 25870 | _x_x_mozilla_cycle_collect,697 04:29:55 INFO - PID 25870 | __end_cc_report 04:29:55 INFO - PID 25870 | __startTimestamp1506511795741__endTimestamp 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | ------- Summary: start ------- 04:29:55 INFO - PID 25870 | Number of tests: 14 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#0] ai-astar Cycles:10 Average:105.90 Median:106.00 stddev:1.20 (1.1%) stddev-sans-first:1.22 04:29:55 INFO - PID 25870 | Values: 105.0 105.0 105.0 106.0 108.0 107.0 107.0 106.0 106.0 104.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:142.00 stddev:22.77 (16.0%) stddev-sans-first:23.96 04:29:55 INFO - PID 25870 | Values: 144.0 110.0 138.0 170.0 112.0 141.0 143.0 113.0 171.0 117.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:1.43 (0.9%) stddev-sans-first:1.51 04:29:55 INFO - PID 25870 | Values: 162.0 162.0 161.0 161.0 163.0 162.0 161.0 164.0 164.0 165.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#3] audio-fft Cycles:10 Average:75.80 Median:75.00 stddev:3.33 (4.4%) stddev-sans-first:3.52 04:29:55 INFO - PID 25870 | Values: 75.0 75.0 74.0 74.0 74.0 84.0 73.0 74.0 79.0 76.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:78.00 stddev:2.02 (2.6%) stddev-sans-first:2.15 04:29:55 INFO - PID 25870 | Values: 78.0 78.0 79.0 77.0 76.0 77.0 78.0 76.0 77.0 83.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:1.78 (1.6%) stddev-sans-first:1.87 04:29:55 INFO - PID 25870 | Values: 113.0 113.0 114.0 113.0 113.0 110.0 117.0 114.0 114.0 115.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#6] imaging-darkroom Cycles:10 Average:151.00 Median:151.00 stddev:1.41 (0.9%) stddev-sans-first:1.45 04:29:55 INFO - PID 25870 | Values: 150.0 151.0 154.0 150.0 150.0 153.0 151.0 151.0 150.0 150.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 04:29:55 INFO - PID 25870 | Values: 94.0 94.0 94.0 94.0 94.0 96.0 96.0 94.0 95.0 94.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#8] json-parse-financial Cycles:10 Average:45.60 Median:46.00 stddev:1.07 (2.3%) stddev-sans-first:1.01 04:29:55 INFO - PID 25870 | Values: 47.0 46.0 45.0 45.0 47.0 46.0 46.0 46.0 44.0 44.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#9] json-stringify-tinderbox Cycles:10 Average:37.40 Median:37.00 stddev:2.32 (6.3%) stddev-sans-first:2.40 04:29:55 INFO - PID 25870 | Values: 36.0 41.0 37.0 37.0 36.0 35.0 36.0 36.0 38.0 42.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#10] stanford-crypto-aes Cycles:10 Average:72.00 Median:72.00 stddev:2.26 (3.1%) stddev-sans-first:2.40 04:29:55 INFO - PID 25870 | Values: 72.0 70.0 72.0 72.0 72.0 72.0 77.0 74.0 69.0 70.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#11] stanford-crypto-ccm Cycles:10 Average:108.30 Median:108.50 stddev:4.97 (4.6%) stddev-sans-first:5.27 04:29:55 INFO - PID 25870 | Values: 108.0 101.0 105.0 105.0 113.0 106.0 110.0 109.0 119.0 107.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:154.50 stddev:3.20 (2.1%) stddev-sans-first:2.74 04:29:55 INFO - PID 25870 | Values: 148.0 154.0 150.0 156.0 152.0 155.0 157.0 153.0 151.0 158.0 04:29:55 INFO - PID 25870 | 04:29:55 INFO - PID 25870 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.60 Median:60.50 stddev:2.37 (3.9%) stddev-sans-first:2.35 04:29:55 INFO - PID 25870 | Values: 63.0 64.0 64.0 60.0 60.0 58.0 61.0 58.0 58.0 60.0 04:29:55 INFO - PID 25870 | -------- Summary: end -------- 04:29:55 INFO - PID 25870 | 04:29:56 INFO - PID 25870 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:29:56 INFO - TEST-INFO | 25870: exit 0 04:29:56 INFO - TEST-OK | kraken | took 59291ms 04:29:56 INFO - SUITE-END | took 1168s 04:29:56 INFO - Completed test suite (00:19:28) 04:29:56 INFO - javascript benchmark 04:29:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9166.799307546784, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3855.2482265958133, 3883.184531248438, 3896.649301562859, 3901.7073719471045, 3929.90175245619, 6179.814605561829, 6236.220472440945, 6290.0, 6298.433141017314, 6430.710618022188, 6286.3713628637115, 6310.211223597051, 6385.936140638604, 6398.232212134539, 6407.967960160193, 5544.584156188282, 5624.465675760796, 5647.661140331593, 5706.28671416073, 5735.0250457422335, 5559.221708960728, 5595.440455954413, 5693.943060569403, 5709.914351284713, 5721.942780572203, 5526.309211348582, 5581.832545023656, 5618.606697531174, 5647.29408823897, 5664.980303545361, 5664.745086471119, 5674.120511320752, 5685.090385538294, 5686.943130568683, 5689.630174038715, 5590.692511911843, 5601.747921343549, 5612.747426365823, 5639.984802735506, 5643.040683083886, 6202.224721909762, 6203.100550420164, 6328.873422531524, 6366.427021568081, 6399.784041032189, 6772.2211945626395, 6779.898301525481, 6836.692348844264, 6855.314468553154, 6914.686209620207, 6804.489663275224, 6912.55068420551, 6921.653917304115, 6925.411340036103, 6963.9651801740665, 6335.904961425582, 6343.555951083426, 6369.490440764727, 6426.485881129498, 6437.613743175378, 7073.221945585981, 7076.150861896605, 7157.964210178916, 7158.498905076594, 7160.427165826722, 6805.863882722318, 6809.523333366716, 6843.62360070191, 6867.0, 6890.2076261229595, 6779.084823548757, 6854.862902742017, 6867.656617169122, 6937.549059311129, 6939.965300173467, 6878.415334696507, 6894.379505844498, 6914.308569143045, 6915.585064896122, 6924.0, 6730.226024007204, 6847.657617119125, 6920.9653951730925, 6921.2386637470845, 6946.5137440378685, 5015.899682006412, 5022.0, 5032.7735251913755, 5094.363204599425, 5105.897882042413, 4542.750148741851, 4559.338895860082, 4611.930821037688, 4632.305154226893, 4634.23535116702, 6302.212223472066, 6317.6209427434505, 6355.650439225795, 6362.872742545123, 6384.585001974857, 6664.100346453167, 6743.831404214836, 6792.7962161134255, 6863.519553631198, 6911.343422374866, 6209.223847019122, 6316.368363163647, 6324.430801227912, 6326.177596912372, 6337.081123237197, 5524.917126243109, 5563.332400112013, 5571.275734154534, 5571.749271282802, 5573.442655734475, 7054.823629409306, 7064.434845212473, 7162.104736907887, 7163.89254161188, 7189.928100718926, 6750.628715420601, 6779.525433219627, 6844.93155068443, 6871.759488418082, 6879.518433709793, 7071.575705457689, 7104.538205016657, 7148.821279468055, 7159.0, 7169.641517923978, 6770.898436523357, 6780.118584583973, 6791.93208067913, 6886.208086070166, 6891.862162756817, 6791.286914873867, 6800.591964482147, 6907.102076729993, 6916.585004899521, 6931.757388491378, 6598.0, 6665.166854143232, 6670.966000269966, 6684.799456016327, 6689.632070235989, 6534.673266336569, 6569.178852643419, 6575.769848055295, 6673.666316684244, 6694.096296999843, 6499.38255865692, 6579.111819904251, 6581.769638062645, 6585.967070164618, 6598.60408375499, 6566.409023187936, 6659.433948114464, 6670.699818508082, 6672.699728512326, 6683.7660681875905, 6446.129772480843, 6478.287388387368, 6533.281339052795, 6546.476281897341, 6547.803565893031], "value": 6258.480046411811, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [954.1603389017663, 965.1024547171132, 989.2036910287218, 1008.6873069348502, 1017.4404077757234, 41154.58845411545, 41754.58245417545, 41765.79117104414, 41807.16385672289, 41885.162296754046, 39345.606543934555, 39437.40843887337, 39515.40726889099, 39777.80111099448, 39825.0, 7414.332710056107, 7550.0, 7555.659995300197, 7557.0931488221495, 7561.206073362273, 7376.151742549622, 7423.925760742378, 7479.7382091626805, 7482.289182527678, 7491.288327608869, 8221.54781487018, 8388.07731149573, 8394.160583941619, 8399.412041157122, 8406.495610263373, 8279.254867061964, 8307.916920830805, 8354.248117669438, 8359.791005224857, 8365.038020627644, 8057.234562716591, 8169.183081691784, 8191.836163276761, 8230.0, 8238.217369349963, 40318.1856816444, 40342.79828600868, 40554.39168412476, 40800.0, 41012.0, 3600.6039335673054, 3636.654517820803, 3659.743817932746, 3660.084978755311, 3662.816859157058, 33173.17067073318, 33273.500897486556, 33518.49722254168, 33553.0, 33566.66433335684, 5433.347998240197, 5441.918371224435, 5443.564514838803, 5458.044842152607, 5465.0, 228037.0, 230262.84868575717, 230354.0, 230784.0, 231313.0, 53147.0, 53339.46660533422, 53783.73108134434, 53809.730951344995, 54018.729906350214, 57277.14084288739, 57392.71303643455, 57424.0, 57490.0, 57802.0, 17014.404495842835, 17240.0, 17349.7397539037, 17395.0, 17396.39112631052, 13986.930065349812, 14065.578032659037, 14080.507182248572, 14081.507147249797, 14107.647308817363, 15369.615759606098, 15526.223688815515, 15546.222688865737, 15600.843991560167, 15717.0, 6096.433586351945, 6097.3292937776805, 6120.877582448326, 6241.906371404432, 6246.250449946036, 1077.7305673581604, 1084.7613525024483, 1089.4607169451172, 1092.3664274720643, 1101.7686285879895, 24233.273001809623, 24560.508789824104, 24583.13959011426, 24688.766611192223, 24727.752722472906, 1186.590626233948, 1189.2685998111272, 1193.570314686712, 1199.4362649554869, 1206.90344772418, 12938.223706577635, 12989.090763646644, 13006.219626822232, 13102.148360356547, 13132.671683207805, 286766.0, 288169.0, 288905.0, 289060.0, 289128.0, 54573.181402279, 58348.416515835146, 59387.70306148442, 59678.40321596815, 59867.0, 1477.5198060630123, 1488.694817562397, 1493.4175671488129, 1498.4680438444414, 1505.3752692632436, 29290.0, 29411.558826618046, 29489.410211796072, 29696.55455168131, 29972.4005519884, 39381.80309098436, 39497.40753888751, 39572.2085558293, 39637.2072558553, 39668.603313966494, 223663.0, 223944.0, 225021.0, 225095.0, 226462.0, 33534.832325838215, 35202.647973520965, 35255.11862203466, 35451.113722157155, 35504.28991420209, 42234.0, 43646.0, 43895.56104438915, 43959.0, 44477.777611111735, 33165.17087072843, 33250.833745831114, 33549.83225083859, 33605.83197083999, 33710.49434258439, 32943.50584741279, 33159.668403316624, 33384.666153338156, 33544.49683254704, 33575.0, 38463.0, 38558.6144138555, 38580.228395432496, 38939.415908761956, 39023.609763902, 32360.83819580887, 32955.0, 32993.175170620925, 33173.17067073342, 33192.67229310801, 24393.822933407413, 24501.264962051166, 24721.381965451008, 24923.87538062298, 25030.74969250357], "value": 18402.30098743141, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.6396162302619, 602.8613418913646, 609.7347653770613, 627.6798832595376, 630.9242890853097, 22951.540969180627, 23734.16930407436, 23741.762582374173, 23794.762052379472, 23853.165139220153, 24309.635355469683, 25822.612660810104, 25915.870420647916, 25992.22023339298, 26004.869975650094, 6450.870982580357, 6604.075429439882, 6642.5350225484235, 6645.734170633169, 6648.069270302161, 6478.2226132864125, 6616.27221005689, 6897.965305204234, 6933.272006439327, 6939.306069393071, 6840.931590684104, 6899.724011039552, 6903.20613129489, 6912.5852448852975, 6918.930810691904, 6597.0, 6796.864062718742, 6838.7264509419565, 6853.314668533107, 6889.4832887533385, 6383.425491705722, 6387.457066149383, 6390.233172019341, 6392.968035159794, 6412.39082287182, 30387.392252155056, 30525.38949221003, 30711.539326910115, 30781.076567703003, 30841.383172336427, 2241.058755322752, 2267.2291420916804, 2267.7278726552754, 2279.4757205842584, 2290.793828555438, 19668.213271469125, 20880.895595521924, 20957.371278861658, 21033.894830525904, 21065.368038958855, 2549.209744979044, 2572.9099481518056, 2587.0815860369485, 2610.4909542639193, 2623.081921327539, 132817.0, 140000.0, 152489.23755381262, 160070.0, 160510.0, 15859.369923677159, 16376.344946202256, 16516.587085322724, 16549.172541372882, 16652.16739163037, 25347.366315842253, 25417.491650167263, 25489.49021019569, 25525.8723706384, 25625.0, 10321.690349289533, 10458.633947811943, 10462.163026957978, 10642.946785266024, 10674.626388076535, 9336.859947100798, 9350.896048389059, 9402.48286344244, 9489.193418559362, 9528.666496672584, 9633.951830240803, 9694.448888177998, 9961.302708810314, 10012.249081318785, 10043.949780251052, 2735.2341344423594, 2828.858557072138, 2852.543593025106, 2895.9276018099717, 2924.1373794730607, 998.9453365968224, 1024.1960061351951, 1026.2098184397971, 1037.5382954585289, 1042.259995403257, 20673.706082734807, 21098.367048988555, 21189.364319070453, 21259.0, 21386.358409247747, 1082.3181395720803, 1090.2422816142864, 1091.0725882999545, 1096.4079397125463, 1106.391484683437, 11313.71715707114, 11494.827577586342, 11634.401618664635, 11638.185327027028, 11790.94104529472, 594802.0, 619545.0, 620924.0, 621466.0, 628446.0, 126896.0, 131318.0, 131331.0, 131748.0, 133287.0, 1115.1914861725347, 1190.470240742861, 1219.5792451604182, 1264.2035517624022, 1269.2194300505307, 22150.003249853475, 22156.556868862855, 22230.44423889351, 22466.77533224712, 22631.20790772315, 65367.34632653613, 65631.67184164049, 66553.33446665666, 66776.33223667702, 69098.0, 81465.0, 81721.0, 81962.18037819545, 82114.58942705249, 82828.17171828204, 20736.0, 21317.147314107264, 21415.0, 21459.570808584052, 21485.67771483398, 23609.52780944406, 24006.63990040116, 24014.51970960606, 24019.879900600386, 24120.517589648458, 19326.4202073938, 19521.316753913547, 19620.313289034817, 19659.115339809458, 19859.602807943473, 17293.65412691714, 17580.73628895542, 17801.1989460472, 18000.189991450156, 18385.90807045956, 19943.800561994776, 19944.20223191056, 20145.294914677917, 20332.288369906983, 20396.286129985972, 20208.49478763043, 20359.79640203579, 20395.4901127473, 20413.795862041785, 20536.0, 19419.708704369164, 19513.902430487757, 19721.70417443768, 19737.51968602332, 19771.901140494207], "value": 13066.668016156875, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.949531766388, 1493.469818214534, 1499.3702644889145, 1501.3093976770685, 1515.6817068415635, 16334.183290835455, 17038.914805425986, 17050.5737356566, 17093.82906170938, 17101.486955391327, 16613.916930415362, 16642.334306627752, 16685.416010439636, 16699.499015029538, 16742.49772506824, 2795.3241487443947, 2842.7725781937447, 2885.047934181715, 2886.494863398897, 2887.307046308881, 2839.33275680215, 2859.442408730298, 2861.4277144571065, 2863.3414314707525, 2868.4693331733592, 2690.273626120946, 2757.310672331917, 2761.0336382266246, 2763.9170824875287, 2764.419471910901, 2753.187809596164, 2783.3320003199287, 2785.220138361262, 2788.4283721836973, 2788.442311537701, 2728.45430913818, 2755.145904769508, 2757.462294852505, 2760.3651160233258, 2762.323230808445, 15900.602484937854, 16246.918765406215, 16251.51245462638, 16267.83732162675, 16285.185740712917, 8057.0, 8306.70926517575, 8343.624536895853, 8353.122922093153, 8355.331573474108, 5171.922421163686, 5314.202869569557, 5495.368032676253, 5586.553075753971, 5621.915671264935, 2047.0, 2083.4062292246635, 2087.561612061469, 2118.4597927528534, 2200.8899555022185, 25857.353566160808, 26705.732942670715, 26759.464810703677, 30333.393332133233, 31209.37581248385, 7430.145533263636, 7528.924710752932, 7545.886811697829, 7699.0, 7700.499467534704, 22579.43551411228, 22618.886905565367, 22648.320550383516, 22668.433289167573, 22693.092276309093, 8437.57812109392, 8556.61495232716, 8556.88760461136, 8572.057073721886, 8600.741977740678, 7608.201138880338, 7624.046994125734, 7670.386369090459, 7711.460197786101, 7722.343600793995, 5890.2342695449315, 6033.5173186145885, 6156.5998210117095, 6171.166892469549, 6250.249970003538, 1830.3868204151493, 1833.8074502177346, 1870.551067743759, 1895.3650527073585, 1899.8290153886217, 987.782687808681, 1001.3341128149845, 1007.2244371833647, 1012.2661070723814, 1030.3972176276811, 8663.740087797376, 8718.128187181232, 8738.737837864874, 8761.868571971425, 8831.735047948572, 801.6512816924657, 806.209914284005, 807.648672827322, 809.1180613131597, 824.9381296402794, 5266.683998960075, 5288.7091209983055, 5365.865853353697, 5369.275147855068, 5372.140457526777, 23055.654165187534, 24529.509409811704, 24947.251582452554, 25153.74846251514, 25227.873860630578, 24203.152889648776, 24495.755042449346, 24531.38671533226, 24567.0, 24709.5058098837, 12420.0, 12452.626421207378, 12614.117011809269, 12618.495260189673, 12650.0, 9481.194098501705, 9519.904800951901, 9559.378640388353, 9561.282903782105, 9569.473678947446, 20444.386668399973, 20468.181272748923, 20614.793852061888, 21258.681119782905, 21820.67268990935, 20719.0, 21063.262785802424, 21375.14499420068, 21971.780282196974, 22058.227962021254, 13249.867501325249, 13445.193288402726, 13457.192568445924, 13481.32593370308, 13512.459501620218, 15458.768118478456, 15509.379624815332, 15770.369185232463, 15784.36862525532, 15790.76313855316, 12569.371531423576, 12619.87380126187, 12939.482420703067, 12948.611541653765, 13096.345182740633, 12255.938720306342, 12333.93833030829, 12576.685582860502, 12692.746145076862, 12907.290099044643, 10923.289986150874, 11014.0, 11101.666949991513, 11138.888611113785, 11150.219484635998, 8417.326613870999, 8447.45091569046, 8484.40609157353, 8547.444416112932, 8559.700410485602, 8138.715144969898, 8146.959265203636, 8216.137305583066, 8251.546164960984, 8326.875096873431], "value": 7315.080682925545, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.4307371092052, 579.5942840011987, 584.4798129664598, 594.3848117198697, 601.4166258729033, 6213.0, 6265.498760099193, 6294.244690637124, 6316.30520642729, 6377.776777812784, 6224.097505861659, 6300.30696623371, 6305.590136641115, 6329.398707122816, 6349.523785716066, 5126.92309615356, 5127.769250383723, 5156.613254005946, 5158.716270605115, 5170.896582068356, 5057.645964782449, 5122.385313762354, 5129.153689641204, 5184.53339199472, 5200.479952004788, 4800.207965685658, 4895.167821470359, 4927.753612319388, 4966.801327946896, 4987.705655398503, 4957.454679985199, 4967.602591792648, 4967.652264341497, 4985.002999400098, 4992.201247800334, 4427.822887084512, 4520.61574766142, 4520.728756274634, 4531.977340113312, 4541.250693635579, 6018.608790428652, 6086.482648974842, 6129.325774164839, 6129.877402451971, 6133.907991380132, 5867.941320586782, 5888.941110588882, 5891.082430183923, 5954.612950158269, 5959.344472108065, 6589.143411356541, 6627.635480048595, 6683.766068187639, 6686.632235227061, 6697.3637504437, 5080.263361812554, 5090.669106508065, 5092.8217512387255, 5093.0, 5128.538431541179, 6693.933060669428, 6697.19633643961, 6756.223034351064, 6819.0, 7020.824479388005, 5169.53474187325, 5235.816746413857, 5264.763085661154, 5266.2890509781455, 5272.841814745564, 5300.44345343741, 5342.572594192456, 5352.143657014859, 5361.812336568201, 5364.490373414519, 5931.911021334683, 5938.851528711731, 5964.612300200473, 5973.2965561351375, 5973.492253158443, 6546.345365463416, 6666.233383160901, 6691.933080669131, 6765.763198288135, 6775.0, 1800.8379245867934, 1828.7805463344484, 1829.6798060339388, 1842.7143792711884, 1843.0784607696153, 1265.8227848101353, 1294.2752058847075, 1295.6242689619905, 1308.6466654003366, 1330.5476138113088, 6032.758689652453, 6091.177691011745, 6124.326324104345, 6151.72317245725, 6186.443220110212, 6604.966975165094, 6621.702023408958, 6623.735050597922, 6627.4366678831875, 6681.9331806682285, 6058.879676966366, 6064.302605200371, 6079.3920607939735, 6161.907571386433, 6208.72060757258, 3470.479428085757, 3490.197254631449, 3495.2310491691787, 3532.7703699259464, 3559.4660800880074, 6962.373386395248, 7042.964785176041, 7049.647517624098, 7057.752978645723, 7066.823329416806, 6875.0, 6893.620850853151, 6903.861922761516, 6920.169579650374, 7019.333163349474, 7041.683124259421, 7083.18543367509, 7109.28907109285, 7115.608641524663, 7132.786016419515, 6760.898586521304, 6772.187337519332, 6795.218549866829, 6810.897836532356, 6818.761343352959, 6753.459723222229, 6759.155105611799, 6811.727530898708, 6820.829479263058, 6854.554453960476, 6804.829879253059, 6837.179538455417, 6895.0, 6899.758508452381, 6983.216496699777, 5207.27098206259, 5210.374755029421, 5214.8957020860125, 5223.268742376146, 5251.372074656869, 6485.189351331083, 6654.700538475877, 6690.799276021728, 6742.224644165984, 6784.457243420613, 6306.927822270133, 6521.934780652323, 6540.444062254762, 6639.336066393322, 6650.235222949423, 6496.317886622026, 6519.250286217146, 6576.309487503743, 6592.923229182792, 6605.688009196742, 6238.594491358047, 6448.032795080775, 6451.967740161269, 6458.354164583692, 6466.4503517201565, 6456.386643268882, 6537.673116344259, 6591.571547849375, 6600.504962127764, 6654.301298363602, 6358.300586935526, 6381.925874091032, 6423.357664233539, 6426.196725409324, 6466.321036291121], "value": 5194.912635845466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [966.9709908702741, 991.8363470027446, 997.2719914462442, 1011.9930668984362, 1019.989800101999, 19746.111424985873, 19891.30380436685, 19914.99999999998, 19924.203031878707, 20961.68557471637, 19348.903255483703, 19638.312659056937, 19642.607147857034, 19679.803201967978, 19730.309439169632, 1043.937363758175, 1072.2976450424944, 1074.6185104287983, 1076.8761592416854, 1077.0414331245197, 1081.783643271345, 1081.8809930907594, 1082.5507414423005, 1083.5774048121239, 1083.951222195003, 1048.2557384257186, 1097.0, 1109.3343993603853, 1109.378747901174, 1109.8057839877988, 1100.0154861399044, 1103.1064837481665, 1104.4146602300793, 1104.7182968343102, 1105.2705214558353, 779.4894344204555, 800.3117319105564, 801.3909428834128, 802.8795680647892, 803.9437239393245, 18596.884186948828, 18698.813011869843, 18732.625347492973, 18798.812011879843, 18826.529336766554, 23319.53360932789, 23440.179593714307, 23867.283981480585, 24117.638235426482, 24132.87933560321, 18456.723149152775, 18604.0, 18641.44075677732, 18744.15651295695, 18812.24751009958, 3939.153082087365, 3942.231264903345, 3943.7830919299427, 3945.7237993340477, 3946.605339466059, 136564.31717841447, 163058.0, 164123.1793841035, 167760.0, 169596.0, 19225.807741922403, 19423.0, 19464.124114414884, 19505.902470487556, 19582.80417195838, 36223.27553448916, 36550.634493654725, 36954.26091478155, 36985.89042328735, 37125.44311835324, 9640.228781697448, 9768.21854251658, 9814.116729494379, 9875.506224688881, 9887.45618990948, 4294.076773493652, 4366.104948485553, 4371.23503386906, 4381.364702118239, 4420.403245561808, 5984.611000284967, 6127.0, 6136.631802091889, 6153.692315384213, 6161.661108639068, 1209.848768903887, 1240.9813852792215, 1254.8368712067556, 1256.9120161588785, 1257.7233008738062, 755.5882044285768, 761.40990732183, 773.5706682790992, 778.3111945927894, 788.286600626434, 13204.735905282032, 13359.398827052806, 13485.595432137052, 13620.867200543733, 13981.881449484017, 726.8946002829666, 735.955842649432, 747.5776186454635, 748.2741740511804, 750.2609929219714, 5935.673537955454, 5949.286085669747, 6041.305249896318, 6065.332813390523, 6157.568970172135, 147518.26240868727, 149528.0, 150722.0, 155154.22422887813, 157499.0, 105136.0, 105448.0, 129859.35070324776, 137865.0, 138451.0, 110706.44646776875, 112193.0, 114708.42645786717, 115428.99999999833, 121465.0, 13366.324285764, 13744.244066576433, 13957.696979998187, 14140.363683634056, 14410.42358305698, 50917.981640367725, 52361.7381913088, 52750.0, 53094.73452632712, 54877.45122548883, 67058.66470667615, 68494.65752671205, 88710.55644721736, 90427.0, 92219.53890230505, 19840.50398740043, 20307.0, 20348.28780992658, 20382.28661996823, 20713.585728285652, 22799.65800513027, 23408.648870266617, 23464.530709386057, 23753.881230593735, 24034.87982560076, 21694.80678562631, 21958.780412195672, 22049.33851984443, 22278.665820013037, 22467.550648987257, 21539.2461263855, 21549.24577639775, 21641.02615382343, 21736.891315543322, 22207.22274720377, 19494.707579386602, 21263.36209913705, 21484.14063437445, 21811.67282490732, 21928.232511862647, 15300.617484562976, 15329.310181042103, 15415.76876346878, 15706.76439853426, 15875.761863571825, 15160.0, 15309.38762449534, 15374.692506149591, 15443.6139096519, 15525.0], "value": 10374.718434325798, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1394.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 105.0, 106.0, 108.0, 107.0, 107.0, 106.0, 106.0, 104.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 138.0, 170.0, 112.0, 141.0, 143.0, 113.0, 171.0, 117.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 161.0, 163.0, 162.0, 161.0, 164.0, 164.0, 165.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 74.0, 74.0, 84.0, 73.0, 74.0, 79.0, 76.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 77.0, 76.0, 77.0, 78.0, 76.0, 77.0, 83.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 110.0, 117.0, 114.0, 114.0, 115.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 154.0, 150.0, 150.0, 153.0, 151.0, 151.0, 150.0, 150.0], "value": 151.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 96.0, 96.0, 94.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 46.0, 45.0, 45.0, 47.0, 46.0, 46.0, 46.0, 44.0, 44.0], "value": 45.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 41.0, 37.0, 37.0, 36.0, 35.0, 36.0, 36.0, 38.0, 42.0], "value": 37.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 72.0, 72.0, 72.0, 72.0, 77.0, 74.0, 69.0, 70.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 101.0, 105.0, 105.0, 113.0, 106.0, 110.0, 109.0, 119.0, 107.0], "value": 108.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 154.0, 150.0, 156.0, 152.0, 155.0, 157.0, 153.0, 151.0, 158.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 60.0, 60.0, 58.0, 61.0, 58.0, 58.0, 60.0], "value": 60.6, "unit": "score"}]}]} 04:29:56 INFO - Return code: 0 04:29:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:29:57 INFO - # TBPL SUCCESS # 04:29:57 INFO - Running post-action listener: _package_coverage_data 04:29:57 INFO - Running post-action listener: _resource_record_post_action 04:29:57 INFO - [mozharness: 2017-09-27 11:29:57.039799Z] Finished run-tests step (success) 04:29:57 INFO - Running post-run listener: _resource_record_post_run 04:29:57 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 334073856; Read time: 12; Write time: 290032 04:29:57 INFO - TinderboxPrint: CPU usage
13.1% 04:29:57 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:29:57 INFO - TinderboxPrint: I/O write bytes / time
334,073,856 / 290,032 04:29:57 INFO - TinderboxPrint: CPU idle
8,222.6 (86.9%) 04:29:57 INFO - TinderboxPrint: CPU user
1,189.1 (12.6%) 04:29:57 INFO - TinderboxPrint: Swap in / out
0 / 0 04:29:57 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13377536; Read time: 0; Write time: 3768 04:29:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:29:57 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 0; Write bytes: 320258048; Read time: 0; Write time: 286264 04:29:57 INFO - Running post-run listener: _upload_blobber_files 04:29:57 INFO - Blob upload gear active. 04:29:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:29:57 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 04:29:57 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'] 04:29:57 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 04:29:58 INFO - (blobuploader) - INFO - Open directory for files ... 04:29:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:29:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:30:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:00 INFO - (blobuploader) - INFO - Done attempting. 04:30:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:30:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:30:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:01 INFO - (blobuploader) - INFO - Done attempting. 04:30:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:30:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:30:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:02 INFO - (blobuploader) - INFO - Done attempting. 04:30:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:30:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:30:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:10 INFO - (blobuploader) - INFO - Done attempting. 04:30:10 INFO - (blobuploader) - INFO - Iteration through files over. 04:30:10 INFO - Return code: 0 04:30:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:30:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:30:10 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab4378f327acc31257b8ea47779ae3abb28dd0065a373ba5d99b0da50ef47c6f24666d97a22c154b9cba6670e55bb459ffbbeeedb53f91ebb8cf0bdd7918f5f0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3d6cb5c7d390957239e6051b67f4c0a5b0d8d1ff86bfa7a888ac0284f85fffd3880034b394dcd0a0301fee4f30d951029d1c086065bb7c4e47e231921ac9bc9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/248da4a8ebb1e9198b0538120004f8340ef375ac3f34bb6b6dc36b40d814c673d8435f5bef33bdfa34645b0523ce0c476ff7e5c116cdc554b69afffd21f1e486", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14dd25c8798da13450207ee7532cceddbae92373828a9f792a12a1f329db4022a504f01a814f09d9cd937ea78ff3b28f57bbf2f7b50ef6a9edad18e661acf144"} 04:30:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:30:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:30:10 INFO - Contents: 04:30:10 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab4378f327acc31257b8ea47779ae3abb28dd0065a373ba5d99b0da50ef47c6f24666d97a22c154b9cba6670e55bb459ffbbeeedb53f91ebb8cf0bdd7918f5f0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3d6cb5c7d390957239e6051b67f4c0a5b0d8d1ff86bfa7a888ac0284f85fffd3880034b394dcd0a0301fee4f30d951029d1c086065bb7c4e47e231921ac9bc9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/248da4a8ebb1e9198b0538120004f8340ef375ac3f34bb6b6dc36b40d814c673d8435f5bef33bdfa34645b0523ce0c476ff7e5c116cdc554b69afffd21f1e486", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14dd25c8798da13450207ee7532cceddbae92373828a9f792a12a1f329db4022a504f01a814f09d9cd937ea78ff3b28f57bbf2f7b50ef6a9edad18e661acf144"} 04:30:10 INFO - Running post-run listener: copy_logs_to_upload_dir 04:30:10 INFO - Copying logs to upload dir... 04:30:10 INFO - mkdir: /builds/slave/test/build/upload/logs 04:30:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.750559 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-27 04:30:10.730716) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 04:30:10.735291) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760 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/ab4378f327acc31257b8ea47779ae3abb28dd0065a373ba5d99b0da50ef47c6f24666d97a22c154b9cba6670e55bb459ffbbeeedb53f91ebb8cf0bdd7918f5f0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3d6cb5c7d390957239e6051b67f4c0a5b0d8d1ff86bfa7a888ac0284f85fffd3880034b394dcd0a0301fee4f30d951029d1c086065bb7c4e47e231921ac9bc9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/248da4a8ebb1e9198b0538120004f8340ef375ac3f34bb6b6dc36b40d814c673d8435f5bef33bdfa34645b0523ce0c476ff7e5c116cdc554b69afffd21f1e486", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14dd25c8798da13450207ee7532cceddbae92373828a9f792a12a1f329db4022a504f01a814f09d9cd937ea78ff3b28f57bbf2f7b50ef6a9edad18e661acf144"} build_url:https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011428 build_url: 'https://queue.taskcluster.net/v1/task/Ar8OP6SQTLyEO3BLSuuQ1Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab4378f327acc31257b8ea47779ae3abb28dd0065a373ba5d99b0da50ef47c6f24666d97a22c154b9cba6670e55bb459ffbbeeedb53f91ebb8cf0bdd7918f5f0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3d6cb5c7d390957239e6051b67f4c0a5b0d8d1ff86bfa7a888ac0284f85fffd3880034b394dcd0a0301fee4f30d951029d1c086065bb7c4e47e231921ac9bc9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/248da4a8ebb1e9198b0538120004f8340ef375ac3f34bb6b6dc36b40d814c673d8435f5bef33bdfa34645b0523ce0c476ff7e5c116cdc554b69afffd21f1e486", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14dd25c8798da13450207ee7532cceddbae92373828a9f792a12a1f329db4022a504f01a814f09d9cd937ea78ff3b28f57bbf2f7b50ef6a9edad18e661acf144"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 04:30:10.766272) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:30:10.766905) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1506510446.934411-213453760 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004598 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:30:10.848684) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 04:30:10.852007) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 04:30:10.852293) ========= ========= Total master_lag: 1.03 =========