builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-027 starttime: 1507029184.94 results: success (0) revision: 11fe0a2895aab26c57bcfe61b3041d7837e954cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:04.942333) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:04.942891) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:04.943178) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:05.003702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:05.004053) ========= 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=1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 04:13:05-- 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% 225M=0s 2017-10-03 04:13:05 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150879 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:05.369873) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:05.370236) ========= 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=1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017275 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:05.426735) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:05.427042) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 11fe0a2895aab26c57bcfe61b3041d7837e954cd --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 11fe0a2895aab26c57bcfe61b3041d7837e954cd --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=1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 04:13:05,499 truncating revision to first 12 chars 2017-10-03 04:13:05,499 Setting DEBUG logging. 2017-10-03 04:13:05,500 attempt 1/10 2017-10-03 04:13:05,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/11fe0a2895aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 04:13:05,930 unpacking tar archive at: mozilla-central-11fe0a2895aa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.726728 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:06.174273) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:06.174586) ========= script_repo_revision: 11fe0a2895aab26c57bcfe61b3041d7837e954cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:06.174959) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:06.175217) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 04:13:06.195377) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-10-03 04:13:06.195764) ========= /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=1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:13:06 INFO - MultiFileLogger online at 20171003 04:13:06 in /builds/slave/test 04:13:06 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:13:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:13:06 INFO - {'append_to_log': False, 04:13:06 INFO - 'base_work_dir': '/builds/slave/test', 04:13:06 INFO - 'blob_upload_branch': 'Firefox', 04:13:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:13:06 INFO - 'branch': 'Firefox', 04:13:06 INFO - 'buildbot_json_path': 'buildprops.json', 04:13:06 INFO - 'code_coverage': False, 04:13:06 INFO - 'config_files': ('talos/linux_config.py',), 04:13:06 INFO - 'default_actions': ('clobber', 04:13:06 INFO - 'read-buildbot-config', 04:13:06 INFO - 'download-and-extract', 04:13:06 INFO - 'populate-webroot', 04:13:06 INFO - 'create-virtualenv', 04:13:06 INFO - 'install', 04:13:06 INFO - 'setup-mitmproxy', 04:13:06 INFO - 'run-tests'), 04:13:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:13:06 INFO - 'disable_ccov_upload': False, 04:13:06 INFO - 'disable_stylo': False, 04:13:06 INFO - 'download_minidump_stackwalk': True, 04:13:06 INFO - 'download_symbols': 'ondemand', 04:13:06 INFO - 'enable_stylo': False, 04:13:06 INFO - 'enable_webrender': False, 04:13:06 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:13:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:13:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:13:06 INFO - 'gecko_profile': False, 04:13:06 INFO - 'gecko_profile_interval': 0, 04:13:06 INFO - 'installer_path': 'installer.exe', 04:13:06 INFO - 'log_level': 'info', 04:13:06 INFO - 'log_name': 'talos', 04:13:06 INFO - 'log_to_console': True, 04:13:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:13:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:13:06 INFO - 'opt_config_files': (), 04:13:06 INFO - 'pip_index': False, 04:13:06 INFO - 'suite': 'dromaeojs-e10s', 04:13:06 INFO - 'system_bits': '32', 04:13:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:13:06 INFO - 'title': 'talos-linux64-ix-027', 04:13:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:13:06 INFO - 'use_talos_json': True, 04:13:06 INFO - 'verify': 'False', 04:13:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:13:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:13:06 INFO - 'work_dir': 'build'} 04:13:06 INFO - [mozharness: 2017-10-03 11:13:06.418614Z] Running clobber step. 04:13:06 INFO - Running pre-action listener: _resource_record_pre_action 04:13:06 INFO - Running main action method: clobber 04:13:06 INFO - rmtree: /builds/slave/test/build 04:13:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:13:07 INFO - Running post-action listener: _resource_record_post_action 04:13:07 INFO - [mozharness: 2017-10-03 11:13:07.932140Z] Finished clobber step (success) 04:13:07 INFO - [mozharness: 2017-10-03 11:13:07.932238Z] Running read-buildbot-config step. 04:13:07 INFO - Running pre-action listener: _resource_record_pre_action 04:13:07 INFO - Running main action method: read_buildbot_config 04:13:07 INFO - Using buildbot properties: 04:13:07 INFO - { 04:13:07 INFO - "project": "", 04:13:07 INFO - "product": "firefox", 04:13:07 INFO - "who": "archaeopteryx@coole-files.de", 04:13:07 INFO - "installer_path": "public/build/target.tar.bz2", 04:13:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:13:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:13:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:13:07 INFO - "stage_platform": "linux64-pgo", 04:13:07 INFO - "basedir": "/builds/slave/test", 04:13:07 INFO - "buildnumber": 228, 04:13:07 INFO - "platform": "ubuntu64_hw", 04:13:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:13:07 INFO - "slavebuilddir": "test", 04:13:07 INFO - "taskId": "BMt23jwdSYmh1vB1ktE_pg", 04:13:07 INFO - "branch": "mozilla-central", 04:13:07 INFO - "script_repo_revision": "production", 04:13:07 INFO - "revision": "11fe0a2895aab26c57bcfe61b3041d7837e954cd", 04:13:07 INFO - "slavename": "talos-linux64-ix-027", 04:13:07 INFO - "repo_path": "mozilla-central" 04:13:07 INFO - } 04:13:07 INFO - Finding installer, test and symbols from parent task. 04:13:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BMt23jwdSYmh1vB1ktE_pg'}, attempt #1 04:13:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BMt23jwdSYmh1vB1ktE_pg'}, attempt #1 04:13:09 INFO - Task dependencies: HK3Cnu3ZQ2u3dGHBaXWQPg 04:13:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg'}, attempt #1 04:13:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.tar.bz2 04:13:09 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.test_packages.json 04:13:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.crashreporter-symbols.zip 04:13:09 INFO - Running post-action listener: _resource_record_post_action 04:13:09 INFO - [mozharness: 2017-10-03 11:13:09.687942Z] Finished read-buildbot-config step (success) 04:13:09 INFO - [mozharness: 2017-10-03 11:13:09.688160Z] Running download-and-extract step. 04:13:09 INFO - Running pre-action listener: _resource_record_pre_action 04:13:09 INFO - Running main action method: download_and_extract 04:13:09 INFO - mkdir: /builds/slave/test/build/tests 04:13: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:13:09 INFO - trying https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.test_packages.json 04:13:09 INFO - Downloading https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:13:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:13:10 INFO - Downloaded 989 bytes. 04:13:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:13:10 INFO - Using the following test package requirements: 04:13:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:13:10 INFO - u'common': [u'target.common.tests.zip'], 04:13:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:13:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:13:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:13:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:13:10 INFO - u'mozbase': [u'target.common.tests.zip'], 04:13:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:13:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:13:10 INFO - u'web-platform': [u'target.common.tests.zip', 04:13:10 INFO - u'target.web-platform.tests.tar.gz'], 04:13:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:13:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:13:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.common.tests.zip 04:13:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:13:10 INFO - Fetch https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.common.tests.zip into memory 04:13:11 INFO - Content-Length response header: 55875174 04:13:11 INFO - Bytes received: 55875174 04:13:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.talos.tests.zip 04:13:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:13:22 INFO - Fetch https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.talos.tests.zip into memory 04:13:23 INFO - Content-Length response header: 14522427 04:13:23 INFO - Bytes received: 14522427 04:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:23 INFO - trying https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.tar.bz2 04:13:23 INFO - Downloading https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:13:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:13:24 INFO - Downloaded 62287392 bytes. 04:13:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.tar.bz2 04:13:25 INFO - mkdir: /builds/slave/test/properties 04:13:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:13:25 INFO - Writing to file /builds/slave/test/properties/build_url 04:13:25 INFO - Contents: 04:13:25 INFO - build_url:https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.tar.bz2 04:13:25 INFO - Running post-action listener: _resource_record_post_action 04:13:25 INFO - Running post-action listener: find_tests_for_verification 04:13:25 INFO - Running post-action listener: set_extra_try_arguments 04:13:25 INFO - [mozharness: 2017-10-03 11:13:25.255264Z] Finished download-and-extract step (success) 04:13:25 INFO - [mozharness: 2017-10-03 11:13:25.255365Z] Running populate-webroot step. 04:13:25 INFO - Running pre-action listener: _resource_record_pre_action 04:13:25 INFO - Running main action method: populate_webroot 04:13:25 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:13:25 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['tresize', 04:13:25 INFO - 'tcanvasmark']}, 04:13:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:13:25 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['dromaeo_css', 04:13:25 INFO - 'kraken']}, 04:13:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:13:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:25 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:13:25 INFO - 'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:13:25 INFO - 'tests': ['damp', 'tps']}, 04:13:25 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:13:25 INFO - 'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['damp', 'tps']}, 04:13:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:13:25 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['dromaeo_dom']}, 04:13:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:13:25 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['basic_compositor_video', 04:13:25 INFO - 'glvideo']}, 04:13:25 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:13:25 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:13:25 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:13:25 INFO - 'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['ts_paint_webext', 04:13:25 INFO - 'tp5o_webext']}, 04:13:25 INFO - 'other-e10s': {'tests': ['a11yr', 04:13:25 INFO - 'ts_paint', 04:13:25 INFO - 'tpaint', 04:13:25 INFO - 'sessionrestore', 04:13:25 INFO - 'sessionrestore_many_windows', 04:13:25 INFO - 'sessionrestore_no_auto_restore', 04:13:25 INFO - 'tabpaint', 04:13:25 INFO - 'cpstartup']}, 04:13:25 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['a11yr', 04:13:25 INFO - 'ts_paint', 04:13:25 INFO - 'tpaint', 04:13:25 INFO - 'sessionrestore', 04:13:25 INFO - 'sessionrestore_many_windows', 04:13:25 INFO - 'sessionrestore_no_auto_restore', 04:13:25 INFO - 'tabpaint', 04:13:25 INFO - 'cpstartup']}, 04:13:25 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:13:25 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:13:25 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['perf_reftest_singletons']}, 04:13:25 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['perf_reftest']}, 04:13:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:13:25 INFO - 'tsvgr_opacity', 04:13:25 INFO - 'tart', 04:13:25 INFO - 'tscrollx', 04:13:25 INFO - 'tsvg_static']}, 04:13:25 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['tsvgx', 04:13:25 INFO - 'tsvgr_opacity', 04:13:25 INFO - 'tart', 04:13:25 INFO - 'tscrollx', 04:13:25 INFO - 'tsvg_static']}, 04:13:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:13:25 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:13:25 INFO - 'talos_options': ['--disable-stylo'], 04:13:25 INFO - 'tests': ['tp5o']}, 04:13:25 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:13:25 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:13:25 INFO - 'talos_options': ['--mitmproxy', 04:13:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:13:25 INFO - '--firstNonBlankPaint'], 04:13:25 INFO - 'tests': ['tp6_google', 04:13:25 INFO - 'tp6_youtube', 04:13:25 INFO - 'tp6_amazon', 04:13:25 INFO - 'tp6_facebook']}, 04:13:25 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:13:25 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:13:25 INFO - 'talos_options': ['--disable-stylo', 04:13:25 INFO - '--mitmproxy', 04:13:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:13:25 INFO - '--firstNonBlankPaint'], 04:13:25 INFO - 'tests': ['tp6_google', 04:13:25 INFO - 'tp6_youtube', 04:13:25 INFO - 'tp6_amazon', 04:13:25 INFO - 'tp6_facebook']}, 04:13:25 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:13:25 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:13:25 INFO - 'talos_options': ['--stylo-threads=1', 04:13:25 INFO - '--mitmproxy', 04:13:25 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:13:25 INFO - '--firstNonBlankPaint'], 04:13:25 INFO - 'tests': ['tp6_google', 04:13:25 INFO - 'tp6_youtube', 04:13:25 INFO - 'tp6_amazon', 04:13:25 INFO - 'tp6_facebook']}, 04:13:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:13:25 INFO - 'talos_options': ['--xperf_path', 04:13:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:25 INFO - 'tests': ['tp5n']}, 04:13:25 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:13:25 INFO - 'talos_options': ['--disable-stylo', 04:13:25 INFO - '--xperf_path', 04:13:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:25 INFO - 'tests': ['tp5n']}}} 04:13:25 INFO - Running post-action listener: _resource_record_post_action 04:13:25 INFO - [mozharness: 2017-10-03 11:13:25.272677Z] Finished populate-webroot step (success) 04:13:25 INFO - [mozharness: 2017-10-03 11:13:25.272767Z] Running create-virtualenv step. 04:13:25 INFO - Running pre-action listener: _resource_record_pre_action 04:13:25 INFO - Running main action method: create_virtualenv 04:13:25 INFO - Creating virtualenv /builds/slave/test/build/venv 04:13:25 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:13:25 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:13:25 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:13:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:13:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:13:25 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:26 INFO - Installing pip...............done. 04:13:26 INFO - Return code: 0 04:13:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:13:26 INFO - Reading from file tmpfile_stdout 04:13:26 INFO - Output received: 04:13:26 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:13:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13:26 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:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:26 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:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:26 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 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:26 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:13:26 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:13:26 INFO - Using env: {'DISPLAY': ':0', 04:13:26 INFO - 'HOME': '/home/cltbld', 04:13:26 INFO - 'LANG': 'en_US.UTF-8', 04:13:26 INFO - 'LANGUAGE': 'en_US:en', 04:13:26 INFO - 'LOGNAME': 'cltbld', 04:13:26 INFO - 'MAIL': '/var/mail/cltbld', 04:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:26 INFO - 'MOZ_NO_REMOTE': '1', 04:13:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:26 INFO - 'NO_EM_RESTART': '1', 04:13:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:26 INFO - 'PWD': '/builds/slave/test', 04:13:26 INFO - 'SHELL': '/bin/bash', 04:13:26 INFO - 'SHLVL': '1', 04:13:26 INFO - 'TERM': 'linux', 04:13:26 INFO - 'TMOUT': '86400', 04:13:26 INFO - 'USER': 'cltbld', 04:13:26 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:26 INFO - '_': '/tools/buildbot/bin/python'} 04:13:27 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:13:27 INFO - Downloading/unpacking pip>=1.5 04:13:27 INFO - Running setup.py egg_info for package pip 04:13:27 INFO - warning: no files found matching 'pip/cacert.pem' 04:13:27 INFO - warning: no files found matching '*.html' under directory 'docs' 04:13:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:13:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:13:27 INFO - Installing collected packages: pip 04:13:27 INFO - Found existing installation: pip 1.1 04:13:27 INFO - Uninstalling pip: 04:13:27 INFO - Successfully uninstalled pip 04:13:27 INFO - Running setup.py install for pip 04:13:27 INFO - warning: no files found matching 'pip/cacert.pem' 04:13:27 INFO - warning: no files found matching '*.html' under directory 'docs' 04:13:27 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:13:27 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:13:27 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:13:27 INFO - Successfully installed pip 04:13:27 INFO - Cleaning up... 04:13:27 INFO - Return code: 0 04:13:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:13: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:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13: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:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:13:27 INFO - Using env: {'DISPLAY': ':0', 04:13:27 INFO - 'HOME': '/home/cltbld', 04:13:27 INFO - 'LANG': 'en_US.UTF-8', 04:13:27 INFO - 'LANGUAGE': 'en_US:en', 04:13:27 INFO - 'LOGNAME': 'cltbld', 04:13:27 INFO - 'MAIL': '/var/mail/cltbld', 04:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:27 INFO - 'MOZ_NO_REMOTE': '1', 04:13:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:27 INFO - 'NO_EM_RESTART': '1', 04:13:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:27 INFO - 'PWD': '/builds/slave/test', 04:13:27 INFO - 'SHELL': '/bin/bash', 04:13:27 INFO - 'SHLVL': '1', 04:13:27 INFO - 'TERM': 'linux', 04:13:27 INFO - 'TMOUT': '86400', 04:13:27 INFO - 'USER': 'cltbld', 04:13:27 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:27 INFO - '_': '/tools/buildbot/bin/python'} 04:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:27 INFO - Downloading/unpacking psutil>=3.1.1 04:13:27 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:13:27 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:13:27 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:13:27 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:13:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:29 INFO - Installing collected packages: psutil 04:13:29 INFO - Running setup.py install for psutil 04:13:29 INFO - building 'psutil._psutil_linux' extension 04:13:29 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:13:29 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:13:29 INFO - building 'psutil._psutil_posix' extension 04:13:29 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:13:29 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:13:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:29 INFO - Successfully installed psutil 04:13:29 INFO - Cleaning up... 04:13:29 INFO - Return code: 0 04:13:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:13:29 INFO - Using env: {'DISPLAY': ':0', 04:13:29 INFO - 'HOME': '/home/cltbld', 04:13:29 INFO - 'LANG': 'en_US.UTF-8', 04:13:29 INFO - 'LANGUAGE': 'en_US:en', 04:13:29 INFO - 'LOGNAME': 'cltbld', 04:13:29 INFO - 'MAIL': '/var/mail/cltbld', 04:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:29 INFO - 'MOZ_NO_REMOTE': '1', 04:13:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:29 INFO - 'NO_EM_RESTART': '1', 04:13:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:29 INFO - 'PWD': '/builds/slave/test', 04:13:29 INFO - 'SHELL': '/bin/bash', 04:13:29 INFO - 'SHLVL': '1', 04:13:29 INFO - 'TERM': 'linux', 04:13:29 INFO - 'TMOUT': '86400', 04:13:29 INFO - 'USER': 'cltbld', 04:13:29 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:29 INFO - '_': '/tools/buildbot/bin/python'} 04:13:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:13:31 INFO - Installing collected packages: mozsystemmonitor 04:13:31 INFO - Running setup.py install for mozsystemmonitor 04:13:31 INFO - Successfully installed mozsystemmonitor 04:13:31 INFO - Cleaning up... 04:13:32 INFO - Return code: 0 04:13:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:13: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:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13: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:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:13:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:13:32 INFO - Using env: {'DISPLAY': ':0', 04:13:32 INFO - 'HOME': '/home/cltbld', 04:13:32 INFO - 'LANG': 'en_US.UTF-8', 04:13:32 INFO - 'LANGUAGE': 'en_US:en', 04:13:32 INFO - 'LOGNAME': 'cltbld', 04:13:32 INFO - 'MAIL': '/var/mail/cltbld', 04:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:32 INFO - 'MOZ_NO_REMOTE': '1', 04:13:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:32 INFO - 'NO_EM_RESTART': '1', 04:13:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:32 INFO - 'PWD': '/builds/slave/test', 04:13:32 INFO - 'SHELL': '/bin/bash', 04:13:32 INFO - 'SHLVL': '1', 04:13:32 INFO - 'TERM': 'linux', 04:13:32 INFO - 'TMOUT': '86400', 04:13:32 INFO - 'USER': 'cltbld', 04:13:32 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:32 INFO - '_': '/tools/buildbot/bin/python'} 04:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:32 INFO - Downloading/unpacking jsonschema==2.5.1 04:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:13:33 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:13:33 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:13:33 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:13:33 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:13:33 INFO - Downloading functools32-3.2.3-2.tar.gz 04:13:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:33 INFO - warning: no files found matching '*.txt' 04:13:33 INFO - no previously-included directories found matching 'build' 04:13:33 INFO - no previously-included directories found matching 'dist' 04:13:33 INFO - no previously-included directories found matching '.git*' 04:13:33 INFO - Installing collected packages: jsonschema, functools32 04:13:33 INFO - Running setup.py install for functools32 04:13:34 INFO - warning: no files found matching '*.txt' 04:13:34 INFO - no previously-included directories found matching 'build' 04:13:34 INFO - no previously-included directories found matching 'dist' 04:13:34 INFO - no previously-included directories found matching '.git*' 04:13:34 INFO - Successfully installed jsonschema functools32 04:13:34 INFO - Cleaning up... 04:13:34 INFO - Return code: 0 04:13:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:13:34 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:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:34 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:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:34 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 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:34 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:13:34 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:13:34 INFO - Using env: {'DISPLAY': ':0', 04:13:34 INFO - 'HOME': '/home/cltbld', 04:13:34 INFO - 'LANG': 'en_US.UTF-8', 04:13:34 INFO - 'LANGUAGE': 'en_US:en', 04:13:34 INFO - 'LOGNAME': 'cltbld', 04:13:34 INFO - 'MAIL': '/var/mail/cltbld', 04:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:34 INFO - 'MOZ_NO_REMOTE': '1', 04:13:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:34 INFO - 'NO_EM_RESTART': '1', 04:13:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:34 INFO - 'PWD': '/builds/slave/test', 04:13:34 INFO - 'SHELL': '/bin/bash', 04:13:34 INFO - 'SHLVL': '1', 04:13:34 INFO - 'TERM': 'linux', 04:13:34 INFO - 'TMOUT': '86400', 04:13:34 INFO - 'USER': 'cltbld', 04:13:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:34 INFO - '_': '/tools/buildbot/bin/python'} 04:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:13:34 INFO - Cleaning up... 04:13:34 INFO - Return code: 0 04:13:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:13:34 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:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:34 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:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:34 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 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:34 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:13:34 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:13:34 INFO - Using env: {'DISPLAY': ':0', 04:13:34 INFO - 'HOME': '/home/cltbld', 04:13:34 INFO - 'LANG': 'en_US.UTF-8', 04:13:34 INFO - 'LANGUAGE': 'en_US:en', 04:13:34 INFO - 'LOGNAME': 'cltbld', 04:13:34 INFO - 'MAIL': '/var/mail/cltbld', 04:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:34 INFO - 'MOZ_NO_REMOTE': '1', 04:13:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:34 INFO - 'NO_EM_RESTART': '1', 04:13:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:34 INFO - 'PWD': '/builds/slave/test', 04:13:34 INFO - 'SHELL': '/bin/bash', 04:13:34 INFO - 'SHLVL': '1', 04:13:34 INFO - 'TERM': 'linux', 04:13:34 INFO - 'TMOUT': '86400', 04:13:34 INFO - 'USER': 'cltbld', 04:13:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:34 INFO - '_': '/tools/buildbot/bin/python'} 04:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:34 INFO - Downloading/unpacking blobuploader==1.2.4 04:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:35 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13:36 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:13:36 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:13:36 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:13:36 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:13:36 INFO - Downloading docopt-0.6.1.tar.gz 04:13:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:36 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:36 INFO - Running setup.py install for blobuploader 04:13:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:36 INFO - Running setup.py install for requests 04:13:36 INFO - Running setup.py install for docopt 04:13:36 INFO - Successfully installed blobuploader requests docopt 04:13:36 INFO - Cleaning up... 04:13:36 INFO - Return code: 0 04:13:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:36 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:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:36 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:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:36 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 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:36 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:13:36 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:13:36 INFO - Using env: {'DISPLAY': ':0', 04:13:36 INFO - 'HOME': '/home/cltbld', 04:13:36 INFO - 'LANG': 'en_US.UTF-8', 04:13:36 INFO - 'LANGUAGE': 'en_US:en', 04:13:36 INFO - 'LOGNAME': 'cltbld', 04:13:36 INFO - 'MAIL': '/var/mail/cltbld', 04:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:36 INFO - 'MOZ_NO_REMOTE': '1', 04:13:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:36 INFO - 'NO_EM_RESTART': '1', 04:13:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:36 INFO - 'PWD': '/builds/slave/test', 04:13:36 INFO - 'SHELL': '/bin/bash', 04:13:36 INFO - 'SHLVL': '1', 04:13:36 INFO - 'TERM': 'linux', 04:13:36 INFO - 'TMOUT': '86400', 04:13:36 INFO - 'USER': 'cltbld', 04:13:36 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:36 INFO - '_': '/tools/buildbot/bin/python'} 04:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:37 INFO - Running setup.py (path:/tmp/pip-Uwd0Xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:37 INFO - Running setup.py (path:/tmp/pip-FFNy9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:37 INFO - Running setup.py (path:/tmp/pip-ZUsOTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:37 INFO - Running setup.py (path:/tmp/pip-O2ynrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:37 INFO - Running setup.py (path:/tmp/pip-Xz8cF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:37 INFO - Running setup.py (path:/tmp/pip-A0lYWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:37 INFO - Running setup.py (path:/tmp/pip-GOiATV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:37 INFO - Running setup.py (path:/tmp/pip-z3Zpss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:37 INFO - Running setup.py (path:/tmp/pip-sp1Sst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:37 INFO - Running setup.py (path:/tmp/pip-pjNlNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:37 INFO - Running setup.py (path:/tmp/pip-Kt1OvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:37 INFO - Running setup.py (path:/tmp/pip-mLZ2Eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:37 INFO - Running setup.py (path:/tmp/pip-GqI_SZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:37 INFO - Running setup.py (path:/tmp/pip-WDfZNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:38 INFO - Running setup.py (path:/tmp/pip-kTknlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:38 INFO - Running setup.py (path:/tmp/pip-dloDhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:38 INFO - Running setup.py (path:/tmp/pip-sc6TWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:38 INFO - Running setup.py install for manifestparser 04:13:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:38 INFO - Running setup.py install for mozcrash 04:13:38 INFO - Running setup.py install for mozdebug 04:13:38 INFO - Running setup.py install for mozdevice 04:13:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:38 INFO - Running setup.py install for mozfile 04:13:38 INFO - Running setup.py install for mozhttpd 04:13:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:38 INFO - Running setup.py install for mozinfo 04:13:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:38 INFO - Running setup.py install for mozInstall 04:13:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Running setup.py install for mozleak 04:13:39 INFO - Running setup.py install for mozlog 04:13:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Running setup.py install for moznetwork 04:13:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Running setup.py install for mozprocess 04:13:39 INFO - Running setup.py install for mozprofile 04:13:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Running setup.py install for mozrunner 04:13:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:39 INFO - Running setup.py install for mozscreenshot 04:13:39 INFO - Running setup.py install for moztest 04:13:39 INFO - Running setup.py install for mozversion 04:13:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:40 INFO - Cleaning up... 04:13:40 INFO - Return code: 0 04:13:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:40 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:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:40 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:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:40 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 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:40 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:13:40 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:13:40 INFO - Using env: {'DISPLAY': ':0', 04:13:40 INFO - 'HOME': '/home/cltbld', 04:13:40 INFO - 'LANG': 'en_US.UTF-8', 04:13:40 INFO - 'LANGUAGE': 'en_US:en', 04:13:40 INFO - 'LOGNAME': 'cltbld', 04:13:40 INFO - 'MAIL': '/var/mail/cltbld', 04:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:40 INFO - 'MOZ_NO_REMOTE': '1', 04:13:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:40 INFO - 'NO_EM_RESTART': '1', 04:13:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:40 INFO - 'PWD': '/builds/slave/test', 04:13:40 INFO - 'SHELL': '/bin/bash', 04:13:40 INFO - 'SHLVL': '1', 04:13:40 INFO - 'TERM': 'linux', 04:13:40 INFO - 'TMOUT': '86400', 04:13:40 INFO - 'USER': 'cltbld', 04:13:40 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:40 INFO - '_': '/tools/buildbot/bin/python'} 04:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:40 INFO - Running setup.py (path:/tmp/pip-J4AtJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:40 INFO - Running setup.py (path:/tmp/pip-3Sellb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:40 INFO - Running setup.py (path:/tmp/pip-ASodHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:40 INFO - Running setup.py (path:/tmp/pip-qOib0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:40 INFO - Running setup.py (path:/tmp/pip-_fsRue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:40 INFO - Running setup.py (path:/tmp/pip-FbiFaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:40 INFO - Running setup.py (path:/tmp/pip-TqjfPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:40 INFO - Running setup.py (path:/tmp/pip-BWGySh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:40 INFO - Running setup.py (path:/tmp/pip-e87h_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:40 INFO - Running setup.py (path:/tmp/pip-RUe3nI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:40 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:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:40 INFO - Running setup.py (path:/tmp/pip-RiJnOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:41 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:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:41 INFO - Running setup.py (path:/tmp/pip-Y_e9G9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:41 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:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:41 INFO - Running setup.py (path:/tmp/pip-skNHmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:41 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:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:41 INFO - Running setup.py (path:/tmp/pip-89oD8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:41 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:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:41 INFO - Running setup.py (path:/tmp/pip-4mFc97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:41 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:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:41 INFO - Running setup.py (path:/tmp/pip-JikO6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:41 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:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:41 INFO - Running setup.py (path:/tmp/pip-DnYrvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:41 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:13:41 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:13:41 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:13:41 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:13:41 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:13:41 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:13:41 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:13:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:41 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:13:41 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:13:41 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:13:41 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:13:42 INFO - Downloading blessings-1.6.tar.gz 04:13:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:42 INFO - Installing collected packages: blessings 04:13:42 INFO - Running setup.py install for blessings 04:13:42 INFO - Successfully installed blessings 04:13:42 INFO - Cleaning up... 04:13:42 INFO - Return code: 0 04:13:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:43 INFO - Reading from file tmpfile_stdout 04:13:43 INFO - Current package versions: 04:13:43 INFO - argparse == 1.2.1 04:13:43 INFO - blessings == 1.6 04:13:43 INFO - blobuploader == 1.2.4 04:13:43 INFO - docopt == 0.6.1 04:13:43 INFO - functools32 == 3.2.3-2 04:13:43 INFO - jsonschema == 2.5.1 04:13:43 INFO - manifestparser == 1.1 04:13:43 INFO - mozInstall == 1.13 04:13:43 INFO - mozcrash == 1.0 04:13:43 INFO - mozdebug == 0.1 04:13:43 INFO - mozdevice == 0.51 04:13:43 INFO - mozfile == 1.2 04:13:43 INFO - mozhttpd == 0.7 04:13:43 INFO - mozinfo == 0.10 04:13:43 INFO - mozleak == 0.1 04:13:43 INFO - mozlog == 3.5 04:13:43 INFO - moznetwork == 0.27 04:13:43 INFO - mozprocess == 0.25 04:13:43 INFO - mozprofile == 0.28 04:13:43 INFO - mozrunner == 6.13 04:13:43 INFO - mozscreenshot == 0.1 04:13:43 INFO - mozsystemmonitor == 0.3 04:13:43 INFO - moztest == 0.8 04:13:43 INFO - mozversion == 1.4 04:13:43 INFO - psutil == 3.1.1 04:13:43 INFO - requests == 1.2.3 04:13:43 INFO - wsgiref == 0.1.2 04:13:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:43 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 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:43 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:13:43 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:13:43 INFO - Using env: {'DISPLAY': ':0', 04:13:43 INFO - 'HOME': '/home/cltbld', 04:13:43 INFO - 'LANG': 'en_US.UTF-8', 04:13:43 INFO - 'LANGUAGE': 'en_US:en', 04:13:43 INFO - 'LOGNAME': 'cltbld', 04:13:43 INFO - 'MAIL': '/var/mail/cltbld', 04:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:43 INFO - 'MOZ_NO_REMOTE': '1', 04:13:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:43 INFO - 'NO_EM_RESTART': '1', 04:13:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:43 INFO - 'PWD': '/builds/slave/test', 04:13:43 INFO - 'SHELL': '/bin/bash', 04:13:43 INFO - 'SHLVL': '1', 04:13:43 INFO - 'TERM': 'linux', 04:13:43 INFO - 'TMOUT': '86400', 04:13:43 INFO - 'USER': 'cltbld', 04:13:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:43 INFO - '_': '/tools/buildbot/bin/python'} 04:13:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:43 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:13:43 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:13:43 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:13:43 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:13:43 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:13:43 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:13:43 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:13:43 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:13:43 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:13:43 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:13:43 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:13:43 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:13:43 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:13:43 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:13:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:13:44 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:13:44 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:13:44 INFO - Installing collected packages: simplejson 04:13:44 INFO - Running setup.py install for simplejson 04:13:44 INFO - building 'simplejson._speedups' extension 04:13:44 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:13:45 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:13:45 INFO - Successfully installed simplejson 04:13:45 INFO - Cleaning up... 04:13:45 INFO - Return code: 0 04:13:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13: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:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13: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:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, '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': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', '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:13:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:13:45 INFO - Using env: {'DISPLAY': ':0', 04:13:45 INFO - 'HOME': '/home/cltbld', 04:13:45 INFO - 'LANG': 'en_US.UTF-8', 04:13:45 INFO - 'LANGUAGE': 'en_US:en', 04:13:45 INFO - 'LOGNAME': 'cltbld', 04:13:45 INFO - 'MAIL': '/var/mail/cltbld', 04:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:45 INFO - 'MOZ_NO_REMOTE': '1', 04:13:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:45 INFO - 'NO_EM_RESTART': '1', 04:13:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:45 INFO - 'PWD': '/builds/slave/test', 04:13:45 INFO - 'SHELL': '/bin/bash', 04:13:45 INFO - 'SHLVL': '1', 04:13:45 INFO - 'TERM': 'linux', 04:13:45 INFO - 'TMOUT': '86400', 04:13:45 INFO - 'USER': 'cltbld', 04:13:45 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:45 INFO - '_': '/tools/buildbot/bin/python'} 04:13:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:13:45 INFO - Cleaning up... 04:13:45 INFO - Return code: 0 04:13:45 INFO - Running post-action listener: _resource_record_post_action 04:13:45 INFO - Running post-action listener: _start_resource_monitoring 04:13:45 INFO - Starting resource monitoring. 04:13:45 INFO - [mozharness: 2017-10-03 11:13:45.724228Z] Finished create-virtualenv step (success) 04:13:45 INFO - [mozharness: 2017-10-03 11:13:45.724746Z] Running install step. 04:13:45 INFO - Running pre-action listener: _resource_record_pre_action 04:13:45 INFO - Running main action method: install 04:13:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:45 INFO - Reading from file tmpfile_stdout 04:13:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:46 INFO - Reading from file tmpfile_stdout 04:13:46 INFO - Output received: 04:13:46 INFO - Usage: mozinstall [options] installer 04:13:46 INFO - Options: 04:13:46 INFO - -h, --help show this help message and exit 04:13:46 INFO - -d DEST, --destination=DEST 04:13:46 INFO - Directory to install application into. [default: 04:13:46 INFO - "/builds/slave/test"] 04:13:46 INFO - --app=APP Application being installed. [default: firefox] 04:13:46 INFO - mkdir: /builds/slave/test/build/application 04:13:46 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:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:14:03 INFO - Reading from file tmpfile_stdout 04:14:03 INFO - Output received: 04:14:03 INFO - /builds/slave/test/build/application/firefox/firefox 04:14:03 INFO - Running post-action listener: _resource_record_post_action 04:14:03 INFO - [mozharness: 2017-10-03 11:14:03.671990Z] Finished install step (success) 04:14:03 INFO - [mozharness: 2017-10-03 11:14:03.672242Z] Running setup-mitmproxy step. 04:14:03 INFO - Running pre-action listener: _resource_record_pre_action 04:14:03 INFO - Running main action method: setup_mitmproxy 04:14:03 INFO - Skipping: mitmproxy is not required 04:14:03 INFO - Running post-action listener: _resource_record_post_action 04:14:03 INFO - [mozharness: 2017-10-03 11:14:03.673365Z] Finished setup-mitmproxy step (success) 04:14:03 INFO - [mozharness: 2017-10-03 11:14:03.673602Z] Running run-tests step. 04:14:03 INFO - Running pre-action listener: _resource_record_pre_action 04:14:03 INFO - Running pre-action listener: _set_gcov_prefix 04:14:03 INFO - Running main action method: run_tests 04:14:03 WARNING - Try message not found. 04:14:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:14:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:14:03 INFO - Python 2.7.3 04:14:03 INFO - Return code: 0 04:14:03 INFO - grabbing minidump binary from tooltool 04:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:03 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 0x1c2ed60>, '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 0x1c2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c2fef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:14:03 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:14:03 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:14:03 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:14:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:14:03 INFO - Return code: 0 04:14:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:14:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:14:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:14:03 INFO - ENV: RUST_BACKTRACE is now full 04:14:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:14:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:14:03 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/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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:14:03 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/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-027 --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:14:03 INFO - Using env: {'DISPLAY': ':0', 04:14:03 INFO - 'HOME': '/home/cltbld', 04:14:03 INFO - 'LANG': 'en_US.UTF-8', 04:14:03 INFO - 'LANGUAGE': 'en_US:en', 04:14:03 INFO - 'LOGNAME': 'cltbld', 04:14:03 INFO - 'MAIL': '/var/mail/cltbld', 04:14:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:14:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:03 INFO - 'MOZ_NO_REMOTE': '1', 04:14:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:14:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:03 INFO - 'NO_EM_RESTART': '1', 04:14:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:03 INFO - 'PWD': '/builds/slave/test', 04:14:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:14:03 INFO - 'RUST_BACKTRACE': 'full', 04:14:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:14:03 INFO - 'SHELL': '/bin/bash', 04:14:03 INFO - 'SHLVL': '1', 04:14:03 INFO - 'TERM': 'linux', 04:14:03 INFO - 'TMOUT': '86400', 04:14:03 INFO - 'USER': 'cltbld', 04:14:03 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:03 INFO - '_': '/tools/buildbot/bin/python'} 04:14:03 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/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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:14:04 INFO - mozversion application_buildid: 20171003094409 04:14:04 INFO - mozversion application_changeset: 11fe0a2895aab26c57bcfe61b3041d7837e954cd 04:14:04 INFO - mozversion application_display_name: Nightly 04:14:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:14:04 INFO - mozversion application_name: Firefox 04:14:04 INFO - mozversion application_remotingname: firefox 04:14:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:14:04 INFO - mozversion application_vendor: Mozilla 04:14:04 INFO - mozversion application_version: 58.0a1 04:14:04 INFO - mozversion platform_buildid: 20171003094409 04:14:04 INFO - mozversion platform_changeset: 11fe0a2895aab26c57bcfe61b3041d7837e954cd 04:14:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:14:04 INFO - mozversion platform_version: 58.0a1 04:14:04 INFO - using testdate: 1507029244 04:14:04 INFO - actual date: 1507029244 04:14:04 INFO - starting webserver on 'localhost:53847' 04:14:04 INFO - SUITE-START | Running 2 tests 04:14:04 INFO - TEST-START | dromaeo_css 04:14:04 INFO - Initialising browser for dromaeo_css test... 04:14:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53847/getInfo.html -profile /tmp/tmpT6qikZ/profile 04:14:04 INFO - TEST-INFO | started process 29063 (/builds/slave/test/build/application/firefox/firefox http://localhost:53847/getInfo.html) 04:14:08 INFO - TEST-INFO | 29063: exit 0 04:14:08 INFO - Browser initialized. 04:14:08 INFO - Running cycle 1/1 for dromaeo_css test... 04:14:08 INFO - Using env: {'DISPLAY': ':0', 04:14:08 INFO - 'HOME': '/home/cltbld', 04:14:08 INFO - 'JSGC_DISABLE_POISONING': '1', 04:14:08 INFO - 'LANG': 'en_US.UTF-8', 04:14:08 INFO - 'LANGUAGE': 'en_US:en', 04:14:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:14:08 INFO - 'LOGNAME': 'cltbld', 04:14:08 INFO - 'MAIL': '/var/mail/cltbld', 04:14:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:14:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:14:08 INFO - 'MOZ_CRASHREPORTER': '1', 04:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:14:08 INFO - 'MOZ_NO_REMOTE': '1', 04:14:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:14:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:08 INFO - 'NO_EM_RESTART': '1', 04:14:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:08 INFO - 'PWD': '/builds/slave/test', 04:14:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:14:08 INFO - 'RUST_BACKTRACE': 'full', 04:14:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:14:08 INFO - 'SHELL': '/bin/bash', 04:14:08 INFO - 'SHLVL': '1', 04:14:08 INFO - 'TERM': 'linux', 04:14:08 INFO - 'TMOUT': '86400', 04:14:08 INFO - 'USER': 'cltbld', 04:14:08 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:08 INFO - '_': '/tools/buildbot/bin/python'} 04:14:08 INFO - TEST-INFO | started process 29337 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT6qikZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:09 INFO - PID 29337 | 04:14:09 INFO - PID 29337 | (/builds/slave/test/build/application/firefox/firefox:29387): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:14:09 INFO - PID 29337 | 04:14:19 INFO - PID 29337 | RSS: Main: 148082688 04:14:19 INFO - PID 29337 | 04:17:17 INFO - PID 29337 | Cycle 1(1): loaded http://localhost:53847/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53847/tests/dromaeo/cssquery-ext.html) 04:17:18 INFO - PID 29337 | RSS: Main: 144531456 04:17:18 INFO - PID 29337 | 04:20:20 INFO - PID 29337 | Cycle 1(1): loaded http://localhost:53847/tests/dromaeo/cssquery-ext.html (next: http://localhost:53847/tests/dromaeo/cssquery-jquery.html) 04:20:20 INFO - PID 29337 | RSS: Main: 145199104 04:20:20 INFO - PID 29337 | 04:23:22 INFO - PID 29337 | Cycle 1(1): loaded http://localhost:53847/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53847/tests/dromaeo/cssquery-mootools.html) 04:23:22 INFO - PID 29337 | RSS: Main: 146587648 04:23:22 INFO - PID 29337 | 04:26:24 INFO - PID 29337 | Cycle 1(1): loaded http://localhost:53847/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53847/tests/dromaeo/cssquery-prototype.html) 04:26:24 INFO - PID 29337 | RSS: Main: 146378752 04:26:24 INFO - PID 29337 | 04:29:27 INFO - PID 29337 | Cycle 1(1): loaded http://localhost:53847/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53847/tests/dromaeo/cssquery-yui.html) 04:29:28 INFO - PID 29337 | RSS: Main: 149569536 04:29:28 INFO - PID 29337 | 04:32:29 INFO - PID 29337 | Cycle 1(1): loaded http://localhost:53847/tests/dromaeo/cssquery-yui.html (next: http://localhost:53847/tests/dromaeo/cssquery-dojo.html) 04:32:30 INFO - PID 29337 | RSS: Main: 150618112 04:32:30 INFO - PID 29337 | 04:32:30 INFO - PID 29337 | __start_tp_report 04:32:30 INFO - PID 29337 | _x_x_mozilla_page_load 04:32:30 INFO - PID 29337 | _x_x_mozilla_page_load_details 04:32:30 INFO - PID 29337 | |i|pagename|runs| 04:32:30 INFO - PID 29337 | |0;dojo.html;3896.5908579599145;3920.7647541147517;3926.7251292409524;3929.8428062877488;3932.8623498177562;6264.874702505947;6298.6535740534255;6304.26800829587;6469.741210351586;6470.094186813844;6356.141920840685;6386.616802991812;6436.80689579311;6491.058796474508;6510.869782604345;5746.683932383718;5754.050581654043;5763.711814409263;5803.100519419497;5865.296164460271;969.4514430280561;5694;5719.6854173020465;5777.0756678931375;5800.9129863052085;5688.4311568842995;5704.71476426177;5765.250517432749;5776.162456443813;5779.1620215069;5732.856678583027;5752.971235143839;5804.158397032449;5811.302643682785;5815.941840581582;5701.315842098975;5709.115087161496;5773.509251713609;5788.305403351583;5797.043487824504;6318.589291696025;6372.235331760218;6394.904076438857;6447.484201263887;6463.224413070461;6852.6916288767125;6926.445884329292;6927.792166235021;6941.79174624757;6945.756898508528;6993.125859267591;7003.194632617263;7004.929950700479;7007.054047703597;7127.4654400920135;6411.198600174978;6492.707828147744;6495.382938620824;6497.642629655367;6514.120593719835;7103.325184107502;7107.218205997337;7107.715691372287;7201.567905925661;7205.855882882313;6751.291114433012;6828.556143850733;6854.3145685432055;6915.861682766316;6919.688614012382;6945.0971373721095;7014.123234595676;7030.367266945999;7033.577985320897;7037.7184912602925;6919.584824910521;6959.060526828915;6981.476389270723;6996.720131194696;7019;6893.689783959734;6922.134733158356;6932.792016239521;7022.0520229769345;7042.964785176144;5033.219850923113;5065.670731402447;5091.3126727892;5152.433232344439;5162.45794191612;4604.723716577016;4635.3742244797195;4638.2115040443205;4679.134360143385;4720.103180395714;6246.094316324198;6247.562670613013;6317.96841015792;6332.33510481402;6334.619922804646;6831.385175334244;6846.383825455633;6846.8972965405555;6940.7917762467205;6944.236134025253;6271.560990730605;6308.495320374359;6349.555531112777;6353.555251132469;6363.554551181465;5514.365847927459;5579.972100139474;5584.96678114542;5639.915401268902;5643.351014633287;7154.60649664263;7169.749058782918;7276.96361518189;7283.7450689225625;7287.198408175096;6881.552699074444;6900.689468973807;6959.164900212007;6964.303569643096;6993.720251189896;6978.616176110363;7115.075040244735;7154.069970903749;7196.10048743907;7259.6007219601315;6762.357576030269;6774.93225067743;6781.287964763629;6789.524733268821;6790.8641827164165;6922.58464492132;6968.442524597918;6986.615736134561;6996.790096297119;7055.964720176366;6599.4720422365135;6608.339166083353;6617.569857959217;6706;6710;6577.80266592003;6639.800805975829;6691.933080669131;6692.230393504809;6701.128853249046;6366.554341196072;6400.23197216337;6520.576162549609;6524.641144737085;6529.281779004401;6737.157855268091;6827.93172068273;6828.487863410165;6832.316768323128;6838.589684618939;6499.025146228104;6542.967285163734;6611.636360000246;6613.537052406477;6640.07039014548 04:32:30 INFO - PID 29337 | |1;ext.html;921.617528725579;948.7106432538077;978.8287049766292;979.1090108001715;983.4246965525167;44167.55832441674;45084.0983180337;45170.32244516335;45197.32204016938;45264;42530.574694253046;44754;45496.54503454964;45541.54458455423;45555;8474.23731864132;8557.529335886524;8597.656093756243;8599.398042137052;8605.139486051406;8347.207015333533;8526.829463410728;8534.658613655447;8535.487870727746;8543.316534677211;8517.06312305646;8570.82858342836;8585.828283434328;8609.913900860975;8628.223459888608;8418.452800567944;8495.108013658539;8551.059383467813;8562.186592273723;8576.742697719048;8333.708320208763;8403.495790252544;8407.747767566983;8408.579571021424;8414.747557573282;39631.8018409909;39841;39881.60118398808;39975.600243997476;41555;3740.597276021492;3748.231612519427;3771.113788259757;3796.4874741909766;3807.485989391452;34710.132246693785;34720.47919281187;34814.825925870464;34849.47725784115;34879.12802179966;5395.028894798935;5523.889522209573;5524.917126243109;5541.972290138524;5547.944520554784;232299;237311;238792;238817;248117.9999999982;54688.726556366964;54853.177202342;54854.17718734222;54912.45087549074;54940.17589736157;57291.42708572861;57303.42696573064;57433.71283143641;57444;57444.42555574475;16567.834321656872;16593.087380194203;16741.079240641637;16758.664826703396;16888.324467021182;13475;13523.797143042862;13545.187288762705;13555.118917270345;13639.590812275648;14113.858861411254;14321.57035288943;14353.64115897111;14366.928165359106;14436;5377.650452720561;5453.263809385682;5482.671039737628;5579.7489112990015;5650.3502097258515;1077.2997551591372;1088.6353071720903;1092.093562343253;1094.6332978452149;1110.816715241976;25311.240662780147;25784.871075644503;25923.740762592133;26094.60858087092;26209.344766380993;1184.7571247894161;1189.5717541684987;1195.205188549622;1195.9282443053444;1206.9637910862689;13514.59456216315;13633.863661363457;13674.521391751241;13779.931100344435;13850.93074534641;295430;298087;298778;299531;300317;57980;58255.70872145612;58521.70739146277;58670.41329586649;58679.70660146672;1467.9045862018934;1525.9542213733605;1526.9312880920386;1531.19612203594;1532.96167595811;24695.753042469347;24857.938217493764;24919.127830525846;24968.625470618317;24972.001119955;41847.58152418437;42028;42100.57899420967;42116.1576768469;42123.7893810529;241800;241940;242045;242379;242800;33869;34437.827810860785;37072.258554828215;37393.25213495769;37486.06284842901;48204.51795482;48478.51521484881;48557.514424856716;48982;49457.752711236215;35286.235688216;35603.10992225215;35655;35887.28225435529;36564.0858978517;34757.826210868785;35360.82319588386;35781.82109089438;35907.64092359043;36040.09899752422;40326.39510407405;40725;40868.38697419601;40958.79520602378;41012.58987410207;33398;33662;33808.66191338153;34092.488612670335;34592.65407345894;25316.74683253144;25757.484850302513;26040.479190416463;26301;26495.73504264933 04:32:30 INFO - PID 29337 | |2;jquery.html;630.2972186012595;636.8439732265596;637.9968100159499;638.5115386729153;639.322318342557;23311.883440582795;23418.765812341873;24349;24453.510929781416;24700.382490437747;26004.869975650094;26947.05685301015;26996;27073.729262707366;27101.322466938287;6814.7274109035825;6821.590704557718;6836.248012718597;6874.759383421581;6948.826279343031;6402.871942561146;6660.733570657168;6694.698738556776;6696.263411024784;6700.564463309894;6592.17597800275;6595.835104122388;6597.37324954131;6605.174353205849;6606.735730570771;6561.376669216417;6580.276169621337;6598.703058362361;6605.174353205849;6614.206295244578;6175.845603859894;6194.0708893666315;6201;6219.720112594899;6223.408776166257;28911.277218069717;30307.848460757774;30524;30663.69336306653;30706.539401908765;2243.5849367866995;2271.1710225767642;2279.783420575043;2280.04238219948;2284.1434462076722;20473.89763051175;21101.472463188544;21104.472388190417;21104.577908441897;21122.15511379543;2560.6927168739685;2615.5292047431453;2678.035907073452;2681.410089780245;2691.1657386210336;136227;145473.2726336372;152440.9999999989;159186;160125;15292.92949493547;16730.330786768638;16747.74878376801;16758.58103547421;17007.129215786255;26407.207783766517;26472.0734774282;26543.203703888914;26622.866885665837;26654.600180997302;10106.444145571917;10158.238132139972;10223.846642300372;10422.895771042193;10539.736506587244;9032.45805251687;9087.54562271897;9159.221466175317;9266.490343031199;9316.534173291308;9767.609295628237;10071.24465665078;10143.137833284254;10154.847677284699;10168.491575421347;2942.1173647905543;2949.8967536136133;2951.114665600311;2966.8638580222205;3037.240689827543;1036.9274150809522;1049.9580016799396;1057.4765491081964;1069.4813015687384;1071.8177909755204;20922.37232883016;20968.47578810542;21261.893690531757;21412.25057122993;21490.57018859614;1090.3093030093191;1117.6479408986306;1118.5078565431184;1127.8646562412398;1129.0741591894569;11354.65936021904;11357.772844543228;11537.19239653233;11541.480633371351;11662.591809286634;594456;598925;599996;607063;608953;130895.34552327177;132892;133150.33424832814;136552;137885;1225.6997035726126;1232.4577186038114;1267.8351814264086;1268.1376663868473;1275.6173148055732;18934.14796334196;18975.620487589895;19054.7141792876;19109.61780764349;19228.807711923262;65475.34524654692;65896;66028.6698566504;68302.65848670923;68765.65617171882;83630;84647.5767621158;85118.57440712757;85696;85887;15610.9219453902;15948.282327295068;15979.201039947724;15996.68006639884;16187.838121619105;23594.17420390278;23604.409889752893;23716.05135794549;23976.160834370712;24222.152224672758;19592.216311347387;19659;19835.702464462756;19975.60048799045;19997;16612.667746645242;17183.054931978862;17466.563335916704;17813.287468501116;17836.643267134845;19975.700364494252;20011.399658010283;20080.698789517875;20319.187232510532;20392.388228353175;15575.221238938235;15576.22118894028;15726.528204153894;15730.76403853966;15733.44932927342;19567.510812229808;19616.705749414057;19662.21351145938;19770.30803921856;19778.109985050418 04:32:30 INFO - PID 29337 | |3;mootools.html;1393.8884889208862;1519.2859356102629;1524.5654988328329;1530.2731202678729;1531.471642283412;17671.558211044714;18418.723719144207;18460.16929238184;18488;18488.445346639623;17879.64240715188;18065.909670451663;18073;18090.276388944458;18115.909420452914;2757.3382388226782;2864.3698386354968;2881.150060732089;2882.5964364988813;2887.841168735719;2760.5721113227482;2765.3777899972447;2780.0408858943633;2793.1480898326004;2852.1301003194017;2779.777617790582;2785.665720113589;2799.7480226779585;2809.438112377533;2815.549512078058;2690.0584795321674;2703.067441732599;2710;2720.7687346575567;2721.3468767495856;2670.465906818644;2672.7995400344857;2675.170697083891;2680.892764289426;2682.517146913555;16894.15529223546;17228.741568876478;17261.482155535356;17280.913595431943;17285.567860803454;8333.33333333338;8398.874016889751;8685.609147588373;8695.69565065226;8698.478091314479;5386.299781028441;5440.292761940922;5461.808836690697;5576.749046292926;5626.2685850839125;2140.176032227588;2151.107290474451;2169.273293446697;2213.7786221377896;2236.1279101150562;30894.0731778047;31142;31271.37457250865;31285.217869553217;31317;7194.748183813542;7360.631968401559;7395.112586489656;7571.432142589328;7615.771526854203;20547.794522054886;20612.278570249968;20681.379558612964;20700.275490357766;20713.378598642066;8496.40525163245;8619.6983105591;8621;8625.827483450421;8671.436356636925;7828.686852525949;7836.255555722311;7891.802704932308;7928.920710792933;7975.601219939096;5161.793528258828;5595.944040559623;5787.334456537468;5810.244668193108;5812.854678633068;1532.1343440955825;1626.634007348361;1653.9586510337338;1672.9293252318525;1676.6646670665916;926.2080920812742;937.723371605378;942.7124726958343;947.5783276441917;959.3859929645033;8821.779455513535;9103.99944196151;9133.954330228305;9154.908450915538;9243.214326782165;1044.9477526123815;1049.21833234241;1052.7577458598773;1053.2890299048113;1063.6330465989222;5673.319201695823;5753.482186603226;5980.85047873798;5984.491318237911;6084.361142080106;25489.872550637127;26466.86766566155;26522;26782.330441738723;26785.196444106707;25095.24714258575;26287.868560657458;26289.73710262911;26382.340441489116;26460.0738974135;12279.079069069856;12680.175788573713;12689.175203611736;12845.871541284467;12858.485660573473;8977.865332019894;9025.774355641162;9096.636134554808;9101.226395756436;9115.27077833785;21788.564228715655;22102.77897221007;22254.443638909157;22275.66586501236;22374.105035798384;22846.200382986517;23058;23317.53364932658;23704.40738981539;23728.762712372656;12226.20529665569;12276.570320038756;12360.629181124581;12449.004079673785;12521.248725076524;14166.150030998173;15445.536633901002;15447.691046179238;15467.226638668246;15601.765973510179;12001.699957501132;12046.277223366626;12489.750204995667;12494.562690305796;12580.559680411141;11499.597514087302;11678.474468649101;11780.175387722777;11886.28682279066;11962;9634.807303853744;9684.740983672078;9772.31593788428;9796.608135674778;9814.9018509814;8044.597770111588;8151.413378113611;8205.138460461805;8293.170682931659;8344.87482687748;8151.918480815353;8270.875936861072;8284.917150828414;8286.751397458085;8301.958490207511 04:32:30 INFO - PID 29337 | |4;prototype.html;555.111821086262;574.1301927579715;584.3951510186956;589.3782059926776;591.704147926037;6072.965556538559;6207.5965062270925;6264.34224406437;6267.467265282445;6304.653244071575;6083.695815209233;6159.090681888618;6189.628622282667;6204.813855584329;6239.001759718446;5119.2321151827155;5143.639945203838;5161.896762064756;5164;5168.095583272931;4809.062232864593;4935.470004298661;5114.488551144893;5124.308218390507;5154.768035438415;4958.578520825734;4970.70175789452;4988.201887697969;4995.225740010304;5028.446870844205;4957.90084198314;5001.399832020181;5015.69905805651;5018;5018.447970723218;4445.1331990261915;4521.59305662492;4536.977315113436;4558.566936141061;4588.334691469719;6047.7580896764075;6086.878262434727;6091.253878864511;6155.753769849201;6162.4453799157845;5871.735771890275;5908.763649454017;5934.1692163097205;5969.850753731148;5971.402859714038;6593.93406065938;6651.368120028589;6677.732890684367;6692.63190524521;6711.765088221938;5109.41241757199;5116.948830511685;5144.922826157611;5153.458886816905;5176.974115129438;6619.768308109193;6743.865122697519;6828.351306625912;6873.381395674363;6911;5252.268820213468;5274.8945021100135;5285.207218917193;5292.470752924677;5301.840944771663;5324.387695415076;5329.387120481117;5364.758585863646;5380.520356901805;5392.56859451243;5839.270091238595;5841;5874.471297583238;5916;5964.7912323068485;6655.101561289261;6659.367360100783;6686.364795344434;6694.464442844561;6751.831204219934;1821.772278465192;1847.6120014796775;1851.7314989326471;1867.318428773515;1872.092035362848;1275.171138759795;1309.2013871538354;1346;1348.9595312140652;1359.9456021759217;5992.250968628921;5999.610025348426;6012.6392416455155;6028.216331876828;6063.605865618721;6695.665216739241;6715.160604924385;6742.932570674328;6757.594544327356;6778.288279730656;6060.272767267956;6074.9392506075255;6114.327423983358;6127.6936153193055;6131.14164017038;3535.6110827809434;3567.340042092221;3572.8749493767596;3576.6230001449107;3592.946105808415;7033.824154396181;7071.92928070723;7106.360427561544;7106.964465177641;7111.502194846415;6738.932610673831;6824.4199243064895;6885.793426197122;6951.131108611424;6982.720691172296;6970.79087627372;6971.930280697128;7067.540609860343;7090.539114957408;7114.288571142844;6629;6637.70130344126;6648.301928297459;6681.064650948968;6684.565503242274;6476.967615161894;6699.497537684596;6713.757954778382;6722.899156512754;6735.797926062226;6841.8973715395305;6896.034555162181;6962.721491140298;7037.612931288623;7043.225245222919;5151.896962060813;5158.123119069692;5191.870203244949;5199.948000520098;5243.265942768092;6513.2835388108215;6565.474762018932;6634.237062737654;6719.664016799043;6755.1556055493065;6165.321814600301;6399.776007839703;6520;6578.76974305897;6611.900821487778;6481.4166724994975;6538.1500404947055;6549.345065493412;6589.24223714279;6596.8455520284715;6211.9689401554515;6339.207599050119;6417.128327580212;6420.454261387647;6459.61242325462;6542.574732642363;6544.411003009752;6700.420338169937;6704;6705.507310102341;6390.657961828068;6411.134496843053;6432.2602900667025;6442.387973142544;6459.935400645933 04:32:30 INFO - PID 29337 | |5;yui.html;982.9164521015713;984.7242772023832;1010.8635334229881;1015.4364327798069;1018.3482571154458;19324.227030918762;19740.407787766373;19825.900870495647;19844.702329465035;24332;20276.695849562268;20279.18883244672;20324.59350812983;20365.28721494748;20452;1015.6343716262139;1031.2884109964139;1036.8341065429513;1037.6160820496427;1040.6617849199004;1084.6312253833694;1093.5899037860802;1097.8737445193824;1098.0447011100314;1099.1701265544543;1120.4341807387298;1120.7198200449889;1122.927009744368;1126.8140756775163;1133.336997856255;1124.162498938288;1131.1459847814929;1132.7677826045683;1135.3244819332526;1135.9716007099848;826.2233500509562;827.6275675945789;827.7392621324263;828.3207769628907;833.5790425834914;19441.611167776708;19642.70535941962;19862.602747945104;19914.302999394953;21354.57290854174;23195.072197112095;23325.650115248285;23596.528069438686;23696.526069478685;23728.762712373;16762.916185418995;17211.397601083903;17243.31026758928;17272.56818579533;17273.740893886603;3780.092777733352;3905.7265991380614;3927.037875720438;3961.8019099045223;3969.0871099647243;156556;158387;162869;166010.1699491507;166457;17383;17803.19885605151;17852.642947140983;17921.103944802708;17933.461996140137;33589.16027099293;34316;34376.31247375038;34494.310113798085;35594.64405355913;9298.442093474414;9583.856242156373;9599.856002159973;9621.374610650286;9669.323147379757;4628.861134165981;4663.906721865544;4701.177293973541;4711.246200607886;4738.0523895221095;5829.854253643608;5944.108383742473;6003.249593800775;6020;6036.124761909587;1005.0050450054393;1030.4281123976116;1036.9481525923673;1043.9895601044045;1045.0594464981577;712.0991945189339;726.8364617961022;732.7472022152349;748.7828529726427;751.7744676596999;12528.937355313348;13180.143290686075;13289.468421263042;13588.11677240996;13732.931335343459;718.9568625882464;725.3037084399036;725.3254473339739;725.3399406540024;735.4594373135741;6053.939460605337;6201.379862013853;6247.937520624827;6269.8746025079245;6376.33048529907;156594;156761.43238567468;157354.21322893313;161858;164253;136944;138581;138988;140871;144202.27898860438;118542.40728796301;119097;119423;119651.40174299073;120066.39966800285;11412.771744565229;11488.253263537843;11577.363245021268;11634.418279085843;11751.177417580688;56511;56627.71686141543;56928;56937;57389.713051434475;72726.63636681995;72801.6359918197;72924;73414.63292683502;73935.63032184805;15958.361665533714;16039.839601603835;16121.919390402973;16122;16242.918785405996;17173.74239386435;17260.82739172592;17451.738223926397;17471;17545.035023073855;16970.40603578869;16978.49064528066;17012.48962531126;17188.484345469657;17216.569585760455;16823.579410514834;16941;17133.22900469457;17160;17169.82830171682;16606.335746570487;16789.496315110566;16851.15744212809;17045.659086818443;17094;13365.331733413095;13562.86437135616;13616.65958351049;13753.862461375258;13824.86175138276;12280.508779649072;12699.55551555691;12703;12737.681557961125;12881.484740610646 04:32:30 INFO - PID 29337 | __end_tp_report 04:32:30 INFO - PID 29337 | __start_cc_report 04:32:30 INFO - PID 29337 | _x_x_mozilla_cycle_collect,265 04:32:30 INFO - PID 29337 | __end_cc_report 04:32:30 INFO - PID 29337 | __startTimestamp1507030350201__endTimestamp 04:32:30 INFO - PID 29337 | 04:32:30 INFO - PID 29337 | ------- Summary: start ------- 04:32:30 INFO - PID 29337 | Number of tests: 6 04:32:30 INFO - PID 29337 | 04:32:30 INFO - PID 29337 | [#0] dojo.html Cycles:165 Average:6347.37 Median:6599.47 stddev:862.94 (13.1%) stddev-sans-first:843.90 04:32:30 INFO - PID 29337 | Values: 3896.6 3920.8 3926.7 3929.8 3932.9 6264.9 6298.7 6304.3 6469.7 6470.1 6356.1 6386.6 6436.8 6491.1 6510.9 5746.7 5754.1 5763.7 5803.1 5865.3 969.5 5694.0 5719.7 5777.1 5800.9 5688.4 5704.7 5765.3 5776.2 5779.2 5732.9 5753.0 5804.2 5811.3 5815.9 5701.3 5709.1 5773.5 5788.3 5797.0 6318.6 6372.2 6394.9 6447.5 6463.2 6852.7 6926.4 6927.8 6941.8 6945.8 6993.1 7003.2 7004.9 7007.1 7127.5 6411.2 6492.7 6495.4 6497.6 6514.1 7103.3 7107.2 7107.7 7201.6 7205.9 6751.3 6828.6 6854.3 6915.9 6919.7 6945.1 7014.1 7030.4 7033.6 7037.7 6919.6 6959.1 6981.5 6996.7 7019.0 6893.7 6922.1 6932.8 7022.1 7043.0 5033.2 5065.7 5091.3 5152.4 5162.5 4604.7 4635.4 4638.2 4679.1 4720.1 6246.1 6247.6 6318.0 6332.3 6334.6 6831.4 6846.4 6846.9 6940.8 6944.2 6271.6 6308.5 6349.6 6353.6 6363.6 5514.4 5580.0 5585.0 5639.9 5643.4 7154.6 7169.7 7277.0 7283.7 7287.2 6881.6 6900.7 6959.2 6964.3 6993.7 6978.6 7115.1 7154.1 7196.1 7259.6 6762.4 6774.9 6781.3 6789.5 6790.9 6922.6 6968.4 6986.6 6996.8 7056.0 6599.5 6608.3 6617.6 6706.0 6710.0 6577.8 6639.8 6691.9 6692.2 6701.1 6366.6 6400.2 6520.6 6524.6 6529.3 6737.2 6827.9 6828.5 6832.3 6838.6 6499.0 6543.0 6611.6 6613.5 6640.1 04:32:30 INFO - PID 29337 | 04:32:30 INFO - PID 29337 | [#1] ext.html Cycles:180 Average:44029.68 Median:20617.15 stddev:68042.39 (330.0%) stddev-sans-first:68156.28 04:32:30 INFO - PID 29337 | Values: 921.6 948.7 978.8 979.1 983.4 44167.6 45084.1 45170.3 45197.3 45264.0 42530.6 44754.0 45496.5 45541.5 45555.0 8474.2 8557.5 8597.7 8599.4 8605.1 8347.2 8526.8 8534.7 8535.5 8543.3 8517.1 8570.8 8585.8 8609.9 8628.2 8418.5 8495.1 8551.1 8562.2 8576.7 8333.7 8403.5 8407.7 8408.6 8414.7 39631.8 39841.0 39881.6 39975.6 41555.0 3740.6 3748.2 3771.1 3796.5 3807.5 34710.1 34720.5 34814.8 34849.5 34879.1 5395.0 5523.9 5524.9 5542.0 5547.9 232299.0 237311.0 238792.0 238817.0 248118.0 54688.7 54853.2 54854.2 54912.5 54940.2 57291.4 57303.4 57433.7 57444.0 57444.4 16567.8 16593.1 16741.1 16758.7 16888.3 13475.0 13523.8 13545.2 13555.1 13639.6 14113.9 14321.6 14353.6 14366.9 14436.0 5377.7 5453.3 5482.7 5579.7 5650.4 1077.3 1088.6 1092.1 1094.6 1110.8 25311.2 25784.9 25923.7 26094.6 26209.3 1184.8 1189.6 1195.2 1195.9 1207.0 13514.6 13633.9 13674.5 13779.9 13850.9 295430.0 298087.0 298778.0 299531.0 300317.0 57980.0 58255.7 58521.7 58670.4 58679.7 1467.9 1526.0 1526.9 1531.2 1533.0 24695.8 24857.9 24919.1 24968.6 24972.0 41847.6 42028.0 42100.6 42116.2 42123.8 241800.0 241940.0 242045.0 242379.0 242800.0 33869.0 34437.8 37072.3 37393.3 37486.1 48204.5 48478.5 48557.5 48982.0 49457.8 35286.2 35603.1 35655.0 35887.3 36564.1 34757.8 35360.8 35781.8 35907.6 36040.1 40326.4 40725.0 40868.4 40958.8 41012.6 33398.0 33662.0 33808.7 34092.5 34592.7 25316.7 25757.5 26040.5 26301.0 26495.7 04:32:30 INFO - PID 29337 | 04:32:30 INFO - PID 29337 | [#2] jquery.html Cycles:180 Average:40067.85 Median:2275.48 stddev:100928.75 (4435.5%) stddev-sans-first:101168.44 04:32:30 INFO - PID 29337 | Values: 630.3 636.8 638.0 638.5 639.3 23311.9 23418.8 24349.0 24453.5 24700.4 26004.9 26947.1 26996.0 27073.7 27101.3 6814.7 6821.6 6836.2 6874.8 6948.8 6402.9 6660.7 6694.7 6696.3 6700.6 6592.2 6595.8 6597.4 6605.2 6606.7 6561.4 6580.3 6598.7 6605.2 6614.2 6175.8 6194.1 6201.0 6219.7 6223.4 28911.3 30307.8 30524.0 30663.7 30706.5 2243.6 2271.2 2279.8 2280.0 2284.1 20473.9 21101.5 21104.5 21104.6 21122.2 2560.7 2615.5 2678.0 2681.4 2691.2 136227.0 145473.3 152441.0 159186.0 160125.0 15292.9 16730.3 16747.7 16758.6 17007.1 26407.2 26472.1 26543.2 26622.9 26654.6 10106.4 10158.2 10223.8 10422.9 10539.7 9032.5 9087.5 9159.2 9266.5 9316.5 9767.6 10071.2 10143.1 10154.8 10168.5 2942.1 2949.9 2951.1 2966.9 3037.2 1036.9 1050.0 1057.5 1069.5 1071.8 20922.4 20968.5 21261.9 21412.3 21490.6 1090.3 1117.6 1118.5 1127.9 1129.1 11354.7 11357.8 11537.2 11541.5 11662.6 594456.0 598925.0 599996.0 607063.0 608953.0 130895.3 132892.0 133150.3 136552.0 137885.0 1225.7 1232.5 1267.8 1268.1 1275.6 18934.1 18975.6 19054.7 19109.6 19228.8 65475.3 65896.0 66028.7 68302.7 68765.7 83630.0 84647.6 85118.6 85696.0 85887.0 15610.9 15948.3 15979.2 15996.7 16187.8 23594.2 23604.4 23716.1 23976.2 24222.2 19592.2 19659.0 19835.7 19975.6 19997.0 16612.7 17183.1 17466.6 17813.3 17836.6 19975.7 20011.4 20080.7 20319.2 20392.4 15575.2 15576.2 15726.5 15730.8 15733.4 19567.5 19616.7 19662.2 19770.3 19778.1 04:32:30 INFO - PID 29337 | 04:32:30 INFO - PID 29337 | [#3] mootools.html Cycles:180 Average:10631.67 Median:2696.56 stddev:8091.12 (300.1%) stddev-sans-first:8084.05 04:32:30 INFO - PID 29337 | Values: 1393.9 1519.3 1524.6 1530.3 1531.5 17671.6 18418.7 18460.2 18488.0 18488.4 17879.6 18065.9 18073.0 18090.3 18115.9 2757.3 2864.4 2881.2 2882.6 2887.8 2760.6 2765.4 2780.0 2793.1 2852.1 2779.8 2785.7 2799.7 2809.4 2815.5 2690.1 2703.1 2710.0 2720.8 2721.3 2670.5 2672.8 2675.2 2680.9 2682.5 16894.2 17228.7 17261.5 17280.9 17285.6 8333.3 8398.9 8685.6 8695.7 8698.5 5386.3 5440.3 5461.8 5576.7 5626.3 2140.2 2151.1 2169.3 2213.8 2236.1 30894.1 31142.0 31271.4 31285.2 31317.0 7194.7 7360.6 7395.1 7571.4 7615.8 20547.8 20612.3 20681.4 20700.3 20713.4 8496.4 8619.7 8621.0 8625.8 8671.4 7828.7 7836.3 7891.8 7928.9 7975.6 5161.8 5595.9 5787.3 5810.2 5812.9 1532.1 1626.6 1654.0 1672.9 1676.7 926.2 937.7 942.7 947.6 959.4 8821.8 9104.0 9134.0 9154.9 9243.2 1044.9 1049.2 1052.8 1053.3 1063.6 5673.3 5753.5 5980.9 5984.5 6084.4 25489.9 26466.9 26522.0 26782.3 26785.2 25095.2 26287.9 26289.7 26382.3 26460.1 12279.1 12680.2 12689.2 12845.9 12858.5 8977.9 9025.8 9096.6 9101.2 9115.3 21788.6 22102.8 22254.4 22275.7 22374.1 22846.2 23058.0 23317.5 23704.4 23728.8 12226.2 12276.6 12360.6 12449.0 12521.2 14166.2 15445.5 15447.7 15467.2 15601.8 12001.7 12046.3 12489.8 12494.6 12580.6 11499.6 11678.5 11780.2 11886.3 11962.0 9634.8 9684.7 9772.3 9796.6 9814.9 8044.6 8151.4 8205.1 8293.2 8344.9 8151.9 8270.9 8284.9 8286.8 8302.0 04:32:30 INFO - PID 29337 | 04:32:30 INFO - PID 29337 | [#4] prototype.html Cycles:180 Average:5630.54 Median:6157.42 stddev:1553.64 (25.2%) stddev-sans-first:1510.58 04:32:30 INFO - PID 29337 | Values: 555.1 574.1 584.4 589.4 591.7 6073.0 6207.6 6264.3 6267.5 6304.7 6083.7 6159.1 6189.6 6204.8 6239.0 5119.2 5143.6 5161.9 5164.0 5168.1 4809.1 4935.5 5114.5 5124.3 5154.8 4958.6 4970.7 4988.2 4995.2 5028.4 4957.9 5001.4 5015.7 5018.0 5018.4 4445.1 4521.6 4537.0 4558.6 4588.3 6047.8 6086.9 6091.3 6155.8 6162.4 5871.7 5908.8 5934.2 5969.9 5971.4 6593.9 6651.4 6677.7 6692.6 6711.8 5109.4 5116.9 5144.9 5153.5 5177.0 6619.8 6743.9 6828.4 6873.4 6911.0 5252.3 5274.9 5285.2 5292.5 5301.8 5324.4 5329.4 5364.8 5380.5 5392.6 5839.3 5841.0 5874.5 5916.0 5964.8 6655.1 6659.4 6686.4 6694.5 6751.8 1821.8 1847.6 1851.7 1867.3 1872.1 1275.2 1309.2 1346.0 1349.0 1359.9 5992.3 5999.6 6012.6 6028.2 6063.6 6695.7 6715.2 6742.9 6757.6 6778.3 6060.3 6074.9 6114.3 6127.7 6131.1 3535.6 3567.3 3572.9 3576.6 3592.9 7033.8 7071.9 7106.4 7107.0 7111.5 6738.9 6824.4 6885.8 6951.1 6982.7 6970.8 6971.9 7067.5 7090.5 7114.3 6629.0 6637.7 6648.3 6681.1 6684.6 6477.0 6699.5 6713.8 6722.9 6735.8 6841.9 6896.0 6962.7 7037.6 7043.2 5151.9 5158.1 5191.9 5199.9 5243.3 6513.3 6565.5 6634.2 6719.7 6755.2 6165.3 6399.8 6520.0 6578.8 6611.9 6481.4 6538.2 6549.3 6589.2 6596.8 6212.0 6339.2 6417.1 6420.5 6459.6 6542.6 6544.4 6700.4 6704.0 6705.5 6390.7 6411.1 6432.3 6442.4 6459.9 04:32:30 INFO - PID 29337 | 04:32:30 INFO - PID 29337 | [#5] yui.html Cycles:180 Average:28869.29 Median:17229.94 stddev:44215.72 (256.6%) stddev-sans-first:44290.18 04:32:30 INFO - PID 29337 | Values: 982.9 984.7 1010.9 1015.4 1018.3 19324.2 19740.4 19825.9 19844.7 24332.0 20276.7 20279.2 20324.6 20365.3 20452.0 1015.6 1031.3 1036.8 1037.6 1040.7 1084.6 1093.6 1097.9 1098.0 1099.2 1120.4 1120.7 1122.9 1126.8 1133.3 1124.2 1131.1 1132.8 1135.3 1136.0 826.2 827.6 827.7 828.3 833.6 19441.6 19642.7 19862.6 19914.3 21354.6 23195.1 23325.7 23596.5 23696.5 23728.8 16762.9 17211.4 17243.3 17272.6 17273.7 3780.1 3905.7 3927.0 3961.8 3969.1 156556.0 158387.0 162869.0 166010.2 166457.0 17383.0 17803.2 17852.6 17921.1 17933.5 33589.2 34316.0 34376.3 34494.3 35594.6 9298.4 9583.9 9599.9 9621.4 9669.3 4628.9 4663.9 4701.2 4711.2 4738.1 5829.9 5944.1 6003.2 6020.0 6036.1 1005.0 1030.4 1036.9 1044.0 1045.1 712.1 726.8 732.7 748.8 751.8 12528.9 13180.1 13289.5 13588.1 13732.9 719.0 725.3 725.3 725.3 735.5 6053.9 6201.4 6247.9 6269.9 6376.3 156594.0 156761.4 157354.2 161858.0 164253.0 136944.0 138581.0 138988.0 140871.0 144202.3 118542.4 119097.0 119423.0 119651.4 120066.4 11412.8 11488.3 11577.4 11634.4 11751.2 56511.0 56627.7 56928.0 56937.0 57389.7 72726.6 72801.6 72924.0 73414.6 73935.6 15958.4 16039.8 16121.9 16122.0 16242.9 17173.7 17260.8 17451.7 17471.0 17545.0 16970.4 16978.5 17012.5 17188.5 17216.6 16823.6 16941.0 17133.2 17160.0 17169.8 16606.3 16789.5 16851.2 17045.7 17094.0 13365.3 13562.9 13616.7 13753.9 13824.9 12280.5 12699.6 12703.0 12737.7 12881.5 04:32:30 INFO - PID 29337 | -------- Summary: end -------- 04:32:30 INFO - PID 29337 | 04:32:30 INFO - TEST-INFO | 29337: exit 0 04:32:30 INFO - TEST-OK | dromaeo_css | took 1106668ms 04:32:30 INFO - TEST-START | kraken 04:32:30 INFO - Initialising browser for kraken test... 04:32:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53847/getInfo.html -profile /tmp/tmp3cDX2Z/profile 04:32:30 INFO - TEST-INFO | started process 29739 (/builds/slave/test/build/application/firefox/firefox http://localhost:53847/getInfo.html) 04:32:35 INFO - TEST-INFO | 29739: exit 0 04:32:35 INFO - Browser initialized. 04:32:35 INFO - Running cycle 1/1 for kraken test... 04:32:35 INFO - Using env: {'DISPLAY': ':0', 04:32:35 INFO - 'HOME': '/home/cltbld', 04:32:35 INFO - 'JSGC_DISABLE_POISONING': '1', 04:32:35 INFO - 'LANG': 'en_US.UTF-8', 04:32:35 INFO - 'LANGUAGE': 'en_US:en', 04:32:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:32:35 INFO - 'LOGNAME': 'cltbld', 04:32:35 INFO - 'MAIL': '/var/mail/cltbld', 04:32:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:32:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:32:35 INFO - 'MOZ_CRASHREPORTER': '1', 04:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:32:35 INFO - 'MOZ_NO_REMOTE': '1', 04:32:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:35 INFO - 'NO_EM_RESTART': '1', 04:32:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:35 INFO - 'PWD': '/builds/slave/test', 04:32:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:32:35 INFO - 'RUST_BACKTRACE': 'full', 04:32:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:32:35 INFO - 'SHELL': '/bin/bash', 04:32:35 INFO - 'SHLVL': '1', 04:32:35 INFO - 'TERM': 'linux', 04:32:35 INFO - 'TMOUT': '86400', 04:32:35 INFO - 'USER': 'cltbld', 04:32:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818', 04:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:35 INFO - '_': '/tools/buildbot/bin/python'} 04:32:35 INFO - TEST-INFO | started process 30011 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3cDX2Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:36 INFO - PID 30011 | 04:32:36 INFO - PID 30011 | (/builds/slave/test/build/application/firefox/firefox:30061): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:32:36 INFO - PID 30011 | 04:32:46 INFO - PID 30011 | RSS: Main: 148574208 04:32:46 INFO - PID 30011 | 04:32:49 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53847/tests/kraken/driver.html?testName=audio-beat-detection) 04:32:49 INFO - PID 30011 | RSS: Main: 154058752 04:32:49 INFO - PID 30011 | 04:32:52 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53847/tests/kraken/driver.html?testName=audio-dft) 04:32:53 INFO - PID 30011 | RSS: Main: 153964544 04:32:53 INFO - PID 30011 | 04:32:55 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53847/tests/kraken/driver.html?testName=audio-fft) 04:32:56 INFO - PID 30011 | RSS: Main: 149975040 04:32:56 INFO - PID 30011 | 04:32:58 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53847/tests/kraken/driver.html?testName=audio-oscillator) 04:32:59 INFO - PID 30011 | RSS: Main: 151171072 04:32:59 INFO - PID 30011 | 04:33:01 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53847/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:33:01 INFO - PID 30011 | RSS: Main: 150728704 04:33:01 INFO - PID 30011 | 04:33:04 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53847/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:05 INFO - PID 30011 | RSS: Main: 150798336 04:33:05 INFO - PID 30011 | 04:33:09 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53847/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:09 INFO - PID 30011 | RSS: Main: 152117248 04:33:09 INFO - PID 30011 | 04:33:12 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53847/tests/kraken/driver.html?testName=json-parse-financial) 04:33:13 INFO - PID 30011 | RSS: Main: 153464832 04:33:13 INFO - PID 30011 | 04:33:14 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53847/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:15 INFO - PID 30011 | RSS: Main: 154132480 04:33:15 INFO - PID 30011 | 04:33:16 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53847/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:17 INFO - PID 30011 | RSS: Main: 154079232 04:33:17 INFO - PID 30011 | 04:33:19 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53847/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:20 INFO - PID 30011 | RSS: Main: 150851584 04:33:20 INFO - PID 30011 | 04:33:22 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:23 INFO - PID 30011 | RSS: Main: 151146496 04:33:23 INFO - PID 30011 | 04:33:25 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:33:26 INFO - PID 30011 | RSS: Main: 151166976 04:33:26 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | Cycle 1(1): loaded http://localhost:53847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53847/tests/kraken/driver.html?testName=ai-astar) 04:33:28 INFO - PID 30011 | RSS: Main: 152522752 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | __start_tp_report 04:33:28 INFO - PID 30011 | _x_x_mozilla_page_load 04:33:28 INFO - PID 30011 | _x_x_mozilla_page_load_details 04:33:28 INFO - PID 30011 | |i|pagename|runs| 04:33:28 INFO - PID 30011 | |0;ai-astar;106;106;105;106;105;107;107;105;105;104 04:33:28 INFO - PID 30011 | |1;audio-beat-detection;112;116;112;114;112;137;109;111;113;112 04:33:28 INFO - PID 30011 | |2;audio-dft;161;162;161;161;163;163;161;163;163;164 04:33:28 INFO - PID 30011 | |3;audio-fft;74;74;73;73;74;75;73;73;73;73 04:33:28 INFO - PID 30011 | |4;audio-oscillator;79;79;77;81;77;77;78;77;78;78 04:33:28 INFO - PID 30011 | |5;imaging-gaussian-blur;113;114;114;113;113;113;113;114;117;114 04:33:28 INFO - PID 30011 | |6;imaging-darkroom;152;148;150;150;148;149;151;149;149;151 04:33:28 INFO - PID 30011 | |7;imaging-desaturate;94;94;95;94;95;95;95;95;95;95 04:33:28 INFO - PID 30011 | |8;json-parse-financial;46;44;45;46;44;45;45;47;44;44 04:33:28 INFO - PID 30011 | |9;json-stringify-tinderbox;36;36;35;36;35;35;35;36;36;35 04:33:28 INFO - PID 30011 | |10;stanford-crypto-aes;70;69;69;68;69;68;77;77;69;72 04:33:28 INFO - PID 30011 | |11;stanford-crypto-ccm;103;109;112;106;106;105;106;108;109;102 04:33:28 INFO - PID 30011 | |12;stanford-crypto-pbkdf2;160;152;160;150;156;146;151;149;151;150 04:33:28 INFO - PID 30011 | |13;stanford-crypto-sha256-iterative;58;59;59;59;60;59;63;60;59;59 04:33:28 INFO - PID 30011 | __end_tp_report 04:33:28 INFO - PID 30011 | __start_cc_report 04:33:28 INFO - PID 30011 | _x_x_mozilla_cycle_collect,706 04:33:28 INFO - PID 30011 | __end_cc_report 04:33:28 INFO - PID 30011 | __startTimestamp1507030408350__endTimestamp 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | ------- Summary: start ------- 04:33:28 INFO - PID 30011 | Number of tests: 14 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#0] ai-astar Cycles:10 Average:105.60 Median:106.00 stddev:0.97 (0.9%) stddev-sans-first:1.01 04:33:28 INFO - PID 30011 | Values: 106.0 106.0 105.0 106.0 105.0 107.0 107.0 105.0 105.0 104.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#1] audio-beat-detection Cycles:10 Average:114.80 Median:112.50 stddev:8.01 (7.1%) stddev-sans-first:8.43 04:33:28 INFO - PID 30011 | Values: 112.0 116.0 112.0 114.0 112.0 137.0 109.0 111.0 113.0 112.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#2] audio-dft Cycles:10 Average:162.20 Median:163.00 stddev:1.14 (0.7%) stddev-sans-first:1.12 04:33:28 INFO - PID 30011 | Values: 161.0 162.0 161.0 161.0 163.0 163.0 161.0 163.0 163.0 164.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#3] audio-fft Cycles:10 Average:73.50 Median:73.50 stddev:0.71 (1.0%) stddev-sans-first:0.73 04:33:28 INFO - PID 30011 | Values: 74.0 74.0 73.0 73.0 74.0 75.0 73.0 73.0 73.0 73.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:78.00 stddev:1.29 (1.6%) stddev-sans-first:1.32 04:33:28 INFO - PID 30011 | Values: 79.0 79.0 77.0 81.0 77.0 77.0 78.0 77.0 78.0 78.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:1.23 (1.1%) stddev-sans-first:1.27 04:33:28 INFO - PID 30011 | Values: 113.0 114.0 114.0 113.0 113.0 113.0 113.0 114.0 117.0 114.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:1.34 (0.9%) stddev-sans-first:1.13 04:33:28 INFO - PID 30011 | Values: 152.0 148.0 150.0 150.0 148.0 149.0 151.0 149.0 149.0 151.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 04:33:28 INFO - PID 30011 | Values: 94.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#8] json-parse-financial Cycles:10 Average:45.00 Median:45.00 stddev:1.05 (2.3%) stddev-sans-first:1.05 04:33:28 INFO - PID 30011 | Values: 46.0 44.0 45.0 46.0 44.0 45.0 45.0 47.0 44.0 44.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#9] json-stringify-tinderbox Cycles:10 Average:35.50 Median:36.00 stddev:0.53 (1.5%) stddev-sans-first:0.53 04:33:28 INFO - PID 30011 | Values: 36.0 36.0 35.0 36.0 35.0 35.0 35.0 36.0 36.0 35.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#10] stanford-crypto-aes Cycles:10 Average:70.80 Median:69.50 stddev:3.46 (5.0%) stddev-sans-first:3.66 04:33:28 INFO - PID 30011 | Values: 70.0 69.0 69.0 68.0 69.0 68.0 77.0 77.0 69.0 72.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#11] stanford-crypto-ccm Cycles:10 Average:106.60 Median:107.00 stddev:2.99 (2.8%) stddev-sans-first:2.87 04:33:28 INFO - PID 30011 | Values: 103.0 109.0 112.0 106.0 106.0 105.0 106.0 108.0 109.0 102.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:151.50 stddev:4.67 (3.1%) stddev-sans-first:4.09 04:33:28 INFO - PID 30011 | Values: 160.0 152.0 160.0 150.0 156.0 146.0 151.0 149.0 151.0 150.0 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.50 Median:59.00 stddev:1.35 (2.3%) stddev-sans-first:1.32 04:33:28 INFO - PID 30011 | Values: 58.0 59.0 59.0 59.0 60.0 59.0 63.0 60.0 59.0 59.0 04:33:28 INFO - PID 30011 | -------- Summary: end -------- 04:33:28 INFO - PID 30011 | 04:33:28 INFO - PID 30011 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:33:29 INFO - TEST-INFO | 30011: exit 0 04:33:29 INFO - TEST-OK | kraken | took 58370ms 04:33:29 INFO - SUITE-END | took 1165s 04:33:29 INFO - Completed test suite (00:19:25) 04:33:29 INFO - javascript benchmark 04:33:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9122.131870967683, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3896.5908579599145, 3920.7647541147517, 3926.7251292409524, 3929.8428062877488, 3932.8623498177562, 6264.874702505947, 6298.6535740534255, 6304.26800829587, 6469.741210351586, 6470.094186813844, 6356.141920840685, 6386.616802991812, 6436.80689579311, 6491.058796474508, 6510.869782604345, 5746.683932383718, 5754.050581654043, 5763.711814409263, 5803.100519419497, 5865.296164460271, 969.4514430280561, 5694.0, 5719.6854173020465, 5777.0756678931375, 5800.9129863052085, 5688.4311568842995, 5704.71476426177, 5765.250517432749, 5776.162456443813, 5779.1620215069, 5732.856678583027, 5752.971235143839, 5804.158397032449, 5811.302643682785, 5815.941840581582, 5701.315842098975, 5709.115087161496, 5773.509251713609, 5788.305403351583, 5797.043487824504, 6318.589291696025, 6372.235331760218, 6394.904076438857, 6447.484201263887, 6463.224413070461, 6852.6916288767125, 6926.445884329292, 6927.792166235021, 6941.79174624757, 6945.756898508528, 6993.125859267591, 7003.194632617263, 7004.929950700479, 7007.054047703597, 7127.4654400920135, 6411.198600174978, 6492.707828147744, 6495.382938620824, 6497.642629655367, 6514.120593719835, 7103.325184107502, 7107.218205997337, 7107.715691372287, 7201.567905925661, 7205.855882882313, 6751.291114433012, 6828.556143850733, 6854.3145685432055, 6915.861682766316, 6919.688614012382, 6945.0971373721095, 7014.123234595676, 7030.367266945999, 7033.577985320897, 7037.7184912602925, 6919.584824910521, 6959.060526828915, 6981.476389270723, 6996.720131194696, 7019.0, 6893.689783959734, 6922.134733158356, 6932.792016239521, 7022.0520229769345, 7042.964785176144, 5033.219850923113, 5065.670731402447, 5091.3126727892, 5152.433232344439, 5162.45794191612, 4604.723716577016, 4635.3742244797195, 4638.2115040443205, 4679.134360143385, 4720.103180395714, 6246.094316324198, 6247.562670613013, 6317.96841015792, 6332.33510481402, 6334.619922804646, 6831.385175334244, 6846.383825455633, 6846.8972965405555, 6940.7917762467205, 6944.236134025253, 6271.560990730605, 6308.495320374359, 6349.555531112777, 6353.555251132469, 6363.554551181465, 5514.365847927459, 5579.972100139474, 5584.96678114542, 5639.915401268902, 5643.351014633287, 7154.60649664263, 7169.749058782918, 7276.96361518189, 7283.7450689225625, 7287.198408175096, 6881.552699074444, 6900.689468973807, 6959.164900212007, 6964.303569643096, 6993.720251189896, 6978.616176110363, 7115.075040244735, 7154.069970903749, 7196.10048743907, 7259.6007219601315, 6762.357576030269, 6774.93225067743, 6781.287964763629, 6789.524733268821, 6790.8641827164165, 6922.58464492132, 6968.442524597918, 6986.615736134561, 6996.790096297119, 7055.964720176366, 6599.4720422365135, 6608.339166083353, 6617.569857959217, 6706.0, 6710.0, 6577.80266592003, 6639.800805975829, 6691.933080669131, 6692.230393504809, 6701.128853249046, 6366.554341196072, 6400.23197216337, 6520.576162549609, 6524.641144737085, 6529.281779004401, 6737.157855268091, 6827.93172068273, 6828.487863410165, 6832.316768323128, 6838.589684618939, 6499.025146228104, 6542.967285163734, 6611.636360000246, 6613.537052406477, 6640.07039014548], "value": 6290.515692380241, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [921.617528725579, 948.7106432538077, 978.8287049766292, 979.1090108001715, 983.4246965525167, 44167.55832441674, 45084.0983180337, 45170.32244516335, 45197.32204016938, 45264.0, 42530.574694253046, 44754.0, 45496.54503454964, 45541.54458455423, 45555.0, 8474.23731864132, 8557.529335886524, 8597.656093756243, 8599.398042137052, 8605.139486051406, 8347.207015333533, 8526.829463410728, 8534.658613655447, 8535.487870727746, 8543.316534677211, 8517.06312305646, 8570.82858342836, 8585.828283434328, 8609.913900860975, 8628.223459888608, 8418.452800567944, 8495.108013658539, 8551.059383467813, 8562.186592273723, 8576.742697719048, 8333.708320208763, 8403.495790252544, 8407.747767566983, 8408.579571021424, 8414.747557573282, 39631.8018409909, 39841.0, 39881.60118398808, 39975.600243997476, 41555.0, 3740.597276021492, 3748.231612519427, 3771.113788259757, 3796.4874741909766, 3807.485989391452, 34710.132246693785, 34720.47919281187, 34814.825925870464, 34849.47725784115, 34879.12802179966, 5395.028894798935, 5523.889522209573, 5524.917126243109, 5541.972290138524, 5547.944520554784, 232299.0, 237311.0, 238792.0, 238817.0, 248117.9999999982, 54688.726556366964, 54853.177202342, 54854.17718734222, 54912.45087549074, 54940.17589736157, 57291.42708572861, 57303.42696573064, 57433.71283143641, 57444.0, 57444.42555574475, 16567.834321656872, 16593.087380194203, 16741.079240641637, 16758.664826703396, 16888.324467021182, 13475.0, 13523.797143042862, 13545.187288762705, 13555.118917270345, 13639.590812275648, 14113.858861411254, 14321.57035288943, 14353.64115897111, 14366.928165359106, 14436.0, 5377.650452720561, 5453.263809385682, 5482.671039737628, 5579.7489112990015, 5650.3502097258515, 1077.2997551591372, 1088.6353071720903, 1092.093562343253, 1094.6332978452149, 1110.816715241976, 25311.240662780147, 25784.871075644503, 25923.740762592133, 26094.60858087092, 26209.344766380993, 1184.7571247894161, 1189.5717541684987, 1195.205188549622, 1195.9282443053444, 1206.9637910862689, 13514.59456216315, 13633.863661363457, 13674.521391751241, 13779.931100344435, 13850.93074534641, 295430.0, 298087.0, 298778.0, 299531.0, 300317.0, 57980.0, 58255.70872145612, 58521.70739146277, 58670.41329586649, 58679.70660146672, 1467.9045862018934, 1525.9542213733605, 1526.9312880920386, 1531.19612203594, 1532.96167595811, 24695.753042469347, 24857.938217493764, 24919.127830525846, 24968.625470618317, 24972.001119955, 41847.58152418437, 42028.0, 42100.57899420967, 42116.1576768469, 42123.7893810529, 241800.0, 241940.0, 242045.0, 242379.0, 242800.0, 33869.0, 34437.827810860785, 37072.258554828215, 37393.25213495769, 37486.06284842901, 48204.51795482, 48478.51521484881, 48557.514424856716, 48982.0, 49457.752711236215, 35286.235688216, 35603.10992225215, 35655.0, 35887.28225435529, 36564.0858978517, 34757.826210868785, 35360.82319588386, 35781.82109089438, 35907.64092359043, 36040.09899752422, 40326.39510407405, 40725.0, 40868.38697419601, 40958.79520602378, 41012.58987410207, 33398.0, 33662.0, 33808.66191338153, 34092.488612670335, 34592.65407345894, 25316.74683253144, 25757.484850302513, 26040.479190416463, 26301.0, 26495.73504264933], "value": 18868.409203319556, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.2972186012595, 636.8439732265596, 637.9968100159499, 638.5115386729153, 639.322318342557, 23311.883440582795, 23418.765812341873, 24349.0, 24453.510929781416, 24700.382490437747, 26004.869975650094, 26947.05685301015, 26996.0, 27073.729262707366, 27101.322466938287, 6814.7274109035825, 6821.590704557718, 6836.248012718597, 6874.759383421581, 6948.826279343031, 6402.871942561146, 6660.733570657168, 6694.698738556776, 6696.263411024784, 6700.564463309894, 6592.17597800275, 6595.835104122388, 6597.37324954131, 6605.174353205849, 6606.735730570771, 6561.376669216417, 6580.276169621337, 6598.703058362361, 6605.174353205849, 6614.206295244578, 6175.845603859894, 6194.0708893666315, 6201.0, 6219.720112594899, 6223.408776166257, 28911.277218069717, 30307.848460757774, 30524.0, 30663.69336306653, 30706.539401908765, 2243.5849367866995, 2271.1710225767642, 2279.783420575043, 2280.04238219948, 2284.1434462076722, 20473.89763051175, 21101.472463188544, 21104.472388190417, 21104.577908441897, 21122.15511379543, 2560.6927168739685, 2615.5292047431453, 2678.035907073452, 2681.410089780245, 2691.1657386210336, 136227.0, 145473.2726336372, 152440.9999999989, 159186.0, 160125.0, 15292.92949493547, 16730.330786768638, 16747.74878376801, 16758.58103547421, 17007.129215786255, 26407.207783766517, 26472.0734774282, 26543.203703888914, 26622.866885665837, 26654.600180997302, 10106.444145571917, 10158.238132139972, 10223.846642300372, 10422.895771042193, 10539.736506587244, 9032.45805251687, 9087.54562271897, 9159.221466175317, 9266.490343031199, 9316.534173291308, 9767.609295628237, 10071.24465665078, 10143.137833284254, 10154.847677284699, 10168.491575421347, 2942.1173647905543, 2949.8967536136133, 2951.114665600311, 2966.8638580222205, 3037.240689827543, 1036.9274150809522, 1049.9580016799396, 1057.4765491081964, 1069.4813015687384, 1071.8177909755204, 20922.37232883016, 20968.47578810542, 21261.893690531757, 21412.25057122993, 21490.57018859614, 1090.3093030093191, 1117.6479408986306, 1118.5078565431184, 1127.8646562412398, 1129.0741591894569, 11354.65936021904, 11357.772844543228, 11537.19239653233, 11541.480633371351, 11662.591809286634, 594456.0, 598925.0, 599996.0, 607063.0, 608953.0, 130895.34552327177, 132892.0, 133150.33424832814, 136552.0, 137885.0, 1225.6997035726126, 1232.4577186038114, 1267.8351814264086, 1268.1376663868473, 1275.6173148055732, 18934.14796334196, 18975.620487589895, 19054.7141792876, 19109.61780764349, 19228.807711923262, 65475.34524654692, 65896.0, 66028.6698566504, 68302.65848670923, 68765.65617171882, 83630.0, 84647.5767621158, 85118.57440712757, 85696.0, 85887.0, 15610.9219453902, 15948.282327295068, 15979.201039947724, 15996.68006639884, 16187.838121619105, 23594.17420390278, 23604.409889752893, 23716.05135794549, 23976.160834370712, 24222.152224672758, 19592.216311347387, 19659.0, 19835.702464462756, 19975.60048799045, 19997.0, 16612.667746645242, 17183.054931978862, 17466.563335916704, 17813.287468501116, 17836.643267134845, 19975.700364494252, 20011.399658010283, 20080.698789517875, 20319.187232510532, 20392.388228353175, 15575.221238938235, 15576.22118894028, 15726.528204153894, 15730.76403853966, 15733.44932927342, 19567.510812229808, 19616.705749414057, 19662.21351145938, 19770.30803921856, 19778.109985050418], "value": 12886.631146473006, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.8884889208862, 1519.2859356102629, 1524.5654988328329, 1530.2731202678729, 1531.471642283412, 17671.558211044714, 18418.723719144207, 18460.16929238184, 18488.0, 18488.445346639623, 17879.64240715188, 18065.909670451663, 18073.0, 18090.276388944458, 18115.909420452914, 2757.3382388226782, 2864.3698386354968, 2881.150060732089, 2882.5964364988813, 2887.841168735719, 2760.5721113227482, 2765.3777899972447, 2780.0408858943633, 2793.1480898326004, 2852.1301003194017, 2779.777617790582, 2785.665720113589, 2799.7480226779585, 2809.438112377533, 2815.549512078058, 2690.0584795321674, 2703.067441732599, 2710.0, 2720.7687346575567, 2721.3468767495856, 2670.465906818644, 2672.7995400344857, 2675.170697083891, 2680.892764289426, 2682.517146913555, 16894.15529223546, 17228.741568876478, 17261.482155535356, 17280.913595431943, 17285.567860803454, 8333.33333333338, 8398.874016889751, 8685.609147588373, 8695.69565065226, 8698.478091314479, 5386.299781028441, 5440.292761940922, 5461.808836690697, 5576.749046292926, 5626.2685850839125, 2140.176032227588, 2151.107290474451, 2169.273293446697, 2213.7786221377896, 2236.1279101150562, 30894.0731778047, 31142.0, 31271.37457250865, 31285.217869553217, 31317.0, 7194.748183813542, 7360.631968401559, 7395.112586489656, 7571.432142589328, 7615.771526854203, 20547.794522054886, 20612.278570249968, 20681.379558612964, 20700.275490357766, 20713.378598642066, 8496.40525163245, 8619.6983105591, 8621.0, 8625.827483450421, 8671.436356636925, 7828.686852525949, 7836.255555722311, 7891.802704932308, 7928.920710792933, 7975.601219939096, 5161.793528258828, 5595.944040559623, 5787.334456537468, 5810.244668193108, 5812.854678633068, 1532.1343440955825, 1626.634007348361, 1653.9586510337338, 1672.9293252318525, 1676.6646670665916, 926.2080920812742, 937.723371605378, 942.7124726958343, 947.5783276441917, 959.3859929645033, 8821.779455513535, 9103.99944196151, 9133.954330228305, 9154.908450915538, 9243.214326782165, 1044.9477526123815, 1049.21833234241, 1052.7577458598773, 1053.2890299048113, 1063.6330465989222, 5673.319201695823, 5753.482186603226, 5980.85047873798, 5984.491318237911, 6084.361142080106, 25489.872550637127, 26466.86766566155, 26522.0, 26782.330441738723, 26785.196444106707, 25095.24714258575, 26287.868560657458, 26289.73710262911, 26382.340441489116, 26460.0738974135, 12279.079069069856, 12680.175788573713, 12689.175203611736, 12845.871541284467, 12858.485660573473, 8977.865332019894, 9025.774355641162, 9096.636134554808, 9101.226395756436, 9115.27077833785, 21788.564228715655, 22102.77897221007, 22254.443638909157, 22275.66586501236, 22374.105035798384, 22846.200382986517, 23058.0, 23317.53364932658, 23704.40738981539, 23728.762712372656, 12226.20529665569, 12276.570320038756, 12360.629181124581, 12449.004079673785, 12521.248725076524, 14166.150030998173, 15445.536633901002, 15447.691046179238, 15467.226638668246, 15601.765973510179, 12001.699957501132, 12046.277223366626, 12489.750204995667, 12494.562690305796, 12580.559680411141, 11499.597514087302, 11678.474468649101, 11780.175387722777, 11886.28682279066, 11962.0, 9634.807303853744, 9684.740983672078, 9772.31593788428, 9796.608135674778, 9814.9018509814, 8044.597770111588, 8151.413378113611, 8205.138460461805, 8293.170682931659, 8344.87482687748, 8151.918480815353, 8270.875936861072, 8284.917150828414, 8286.751397458085, 8301.958490207511], "value": 7366.1742084142315, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.111821086262, 574.1301927579715, 584.3951510186956, 589.3782059926776, 591.704147926037, 6072.965556538559, 6207.5965062270925, 6264.34224406437, 6267.467265282445, 6304.653244071575, 6083.695815209233, 6159.090681888618, 6189.628622282667, 6204.813855584329, 6239.001759718446, 5119.2321151827155, 5143.639945203838, 5161.896762064756, 5164.0, 5168.095583272931, 4809.062232864593, 4935.470004298661, 5114.488551144893, 5124.308218390507, 5154.768035438415, 4958.578520825734, 4970.70175789452, 4988.201887697969, 4995.225740010304, 5028.446870844205, 4957.90084198314, 5001.399832020181, 5015.69905805651, 5018.0, 5018.447970723218, 4445.1331990261915, 4521.59305662492, 4536.977315113436, 4558.566936141061, 4588.334691469719, 6047.7580896764075, 6086.878262434727, 6091.253878864511, 6155.753769849201, 6162.4453799157845, 5871.735771890275, 5908.763649454017, 5934.1692163097205, 5969.850753731148, 5971.402859714038, 6593.93406065938, 6651.368120028589, 6677.732890684367, 6692.63190524521, 6711.765088221938, 5109.41241757199, 5116.948830511685, 5144.922826157611, 5153.458886816905, 5176.974115129438, 6619.768308109193, 6743.865122697519, 6828.351306625912, 6873.381395674363, 6911.0, 5252.268820213468, 5274.8945021100135, 5285.207218917193, 5292.470752924677, 5301.840944771663, 5324.387695415076, 5329.387120481117, 5364.758585863646, 5380.520356901805, 5392.56859451243, 5839.270091238595, 5841.0, 5874.471297583238, 5916.0, 5964.7912323068485, 6655.101561289261, 6659.367360100783, 6686.364795344434, 6694.464442844561, 6751.831204219934, 1821.772278465192, 1847.6120014796775, 1851.7314989326471, 1867.318428773515, 1872.092035362848, 1275.171138759795, 1309.2013871538354, 1346.0, 1348.9595312140652, 1359.9456021759217, 5992.250968628921, 5999.610025348426, 6012.6392416455155, 6028.216331876828, 6063.605865618721, 6695.665216739241, 6715.160604924385, 6742.932570674328, 6757.594544327356, 6778.288279730656, 6060.272767267956, 6074.9392506075255, 6114.327423983358, 6127.6936153193055, 6131.14164017038, 3535.6110827809434, 3567.340042092221, 3572.8749493767596, 3576.6230001449107, 3592.946105808415, 7033.824154396181, 7071.92928070723, 7106.360427561544, 7106.964465177641, 7111.502194846415, 6738.932610673831, 6824.4199243064895, 6885.793426197122, 6951.131108611424, 6982.720691172296, 6970.79087627372, 6971.930280697128, 7067.540609860343, 7090.539114957408, 7114.288571142844, 6629.0, 6637.70130344126, 6648.301928297459, 6681.064650948968, 6684.565503242274, 6476.967615161894, 6699.497537684596, 6713.757954778382, 6722.899156512754, 6735.797926062226, 6841.8973715395305, 6896.034555162181, 6962.721491140298, 7037.612931288623, 7043.225245222919, 5151.896962060813, 5158.123119069692, 5191.870203244949, 5199.948000520098, 5243.265942768092, 6513.2835388108215, 6565.474762018932, 6634.237062737654, 6719.664016799043, 6755.1556055493065, 6165.321814600301, 6399.776007839703, 6520.0, 6578.76974305897, 6611.900821487778, 6481.4166724994975, 6538.1500404947055, 6549.345065493412, 6589.24223714279, 6596.8455520284715, 6211.9689401554515, 6339.207599050119, 6417.128327580212, 6420.454261387647, 6459.61242325462, 6542.574732642363, 6544.411003009752, 6700.420338169937, 6704.0, 6705.507310102341, 6390.657961828068, 6411.134496843053, 6432.2602900667025, 6442.387973142544, 6459.935400645933], "value": 5190.482337130568, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [982.9164521015713, 984.7242772023832, 1010.8635334229881, 1015.4364327798069, 1018.3482571154458, 19324.227030918762, 19740.407787766373, 19825.900870495647, 19844.702329465035, 24332.0, 20276.695849562268, 20279.18883244672, 20324.59350812983, 20365.28721494748, 20452.0, 1015.6343716262139, 1031.2884109964139, 1036.8341065429513, 1037.6160820496427, 1040.6617849199004, 1084.6312253833694, 1093.5899037860802, 1097.8737445193824, 1098.0447011100314, 1099.1701265544543, 1120.4341807387298, 1120.7198200449889, 1122.927009744368, 1126.8140756775163, 1133.336997856255, 1124.162498938288, 1131.1459847814929, 1132.7677826045683, 1135.3244819332526, 1135.9716007099848, 826.2233500509562, 827.6275675945789, 827.7392621324263, 828.3207769628907, 833.5790425834914, 19441.611167776708, 19642.70535941962, 19862.602747945104, 19914.302999394953, 21354.57290854174, 23195.072197112095, 23325.650115248285, 23596.528069438686, 23696.526069478685, 23728.762712373, 16762.916185418995, 17211.397601083903, 17243.31026758928, 17272.56818579533, 17273.740893886603, 3780.092777733352, 3905.7265991380614, 3927.037875720438, 3961.8019099045223, 3969.0871099647243, 156556.0, 158387.0, 162869.0, 166010.1699491507, 166457.0, 17383.0, 17803.19885605151, 17852.642947140983, 17921.103944802708, 17933.461996140137, 33589.16027099293, 34316.0, 34376.31247375038, 34494.310113798085, 35594.64405355913, 9298.442093474414, 9583.856242156373, 9599.856002159973, 9621.374610650286, 9669.323147379757, 4628.861134165981, 4663.906721865544, 4701.177293973541, 4711.246200607886, 4738.0523895221095, 5829.854253643608, 5944.108383742473, 6003.249593800775, 6020.0, 6036.124761909587, 1005.0050450054393, 1030.4281123976116, 1036.9481525923673, 1043.9895601044045, 1045.0594464981577, 712.0991945189339, 726.8364617961022, 732.7472022152349, 748.7828529726427, 751.7744676596999, 12528.937355313348, 13180.143290686075, 13289.468421263042, 13588.11677240996, 13732.931335343459, 718.9568625882464, 725.3037084399036, 725.3254473339739, 725.3399406540024, 735.4594373135741, 6053.939460605337, 6201.379862013853, 6247.937520624827, 6269.8746025079245, 6376.33048529907, 156594.0, 156761.43238567468, 157354.21322893313, 161858.0, 164253.0, 136944.0, 138581.0, 138988.0, 140871.0, 144202.27898860438, 118542.40728796301, 119097.0, 119423.0, 119651.40174299073, 120066.39966800285, 11412.771744565229, 11488.253263537843, 11577.363245021268, 11634.418279085843, 11751.177417580688, 56511.0, 56627.71686141543, 56928.0, 56937.0, 57389.713051434475, 72726.63636681995, 72801.6359918197, 72924.0, 73414.63292683502, 73935.63032184805, 15958.361665533714, 16039.839601603835, 16121.919390402973, 16122.0, 16242.918785405996, 17173.74239386435, 17260.82739172592, 17451.738223926397, 17471.0, 17545.035023073855, 16970.40603578869, 16978.49064528066, 17012.48962531126, 17188.484345469657, 17216.569585760455, 16823.579410514834, 16941.0, 17133.22900469457, 17160.0, 17169.82830171682, 16606.335746570487, 16789.496315110566, 16851.15744212809, 17045.659086818443, 17094.0, 13365.331733413095, 13562.86437135616, 13616.65958351049, 13753.862461375258, 13824.86175138276, 12280.508779649072, 12699.55551555691, 12703.0, 12737.681557961125, 12881.484740610646], "value": 9852.368153750429, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1362.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 106.0, 105.0, 106.0, 105.0, 107.0, 107.0, 105.0, 105.0, 104.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 112.0, 114.0, 112.0, 137.0, 109.0, 111.0, 113.0, 112.0], "value": 114.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 163.0, 163.0, 161.0, 163.0, 163.0, 164.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 73.0, 74.0, 75.0, 73.0, 73.0, 73.0, 73.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 77.0, 81.0, 77.0, 77.0, 78.0, 77.0, 78.0, 78.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 113.0, 114.0, 117.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 148.0, 150.0, 150.0, 148.0, 149.0, 151.0, 149.0, 149.0, 151.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 44.0, 45.0, 46.0, 44.0, 45.0, 45.0, 47.0, 44.0, 44.0], "value": 45.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 36.0, 35.0, 36.0, 35.0, 35.0, 35.0, 36.0, 36.0, 35.0], "value": 35.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 68.0, 69.0, 68.0, 77.0, 77.0, 69.0, 72.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 109.0, 112.0, 106.0, 106.0, 105.0, 106.0, 108.0, 109.0, 102.0], "value": 106.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 152.0, 160.0, 150.0, 156.0, 146.0, 151.0, 149.0, 151.0, 150.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 59.0, 60.0, 59.0, 63.0, 60.0, 59.0, 59.0], "value": 59.5, "unit": "score"}]}]} 04:33:29 INFO - Return code: 0 04:33:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:33:29 INFO - # TBPL SUCCESS # 04:33:29 INFO - Running post-action listener: _package_coverage_data 04:33:29 INFO - Running post-action listener: _resource_record_post_action 04:33:29 INFO - [mozharness: 2017-10-03 11:33:29.661306Z] Finished run-tests step (success) 04:33:29 INFO - Running post-run listener: _resource_record_post_run 04:33:29 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 322117632; Read time: 16; Write time: 220756 04:33:29 INFO - TinderboxPrint: CPU usage
13.1% 04:33:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:33:29 INFO - TinderboxPrint: I/O write bytes / time
322,117,632 / 220,756 04:33:29 INFO - TinderboxPrint: CPU idle
8,196.9 (86.9%) 04:33:29 INFO - TinderboxPrint: CPU user
1,187.5 (12.6%) 04:33:29 INFO - TinderboxPrint: Swap in / out
0 / 0 04:33:29 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12476416; Read time: 0; Write time: 2248 04:33:29 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:33:29 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 4096; Write bytes: 309026816; Read time: 16; Write time: 218508 04:33:30 INFO - Running post-run listener: _upload_blobber_files 04:33:30 INFO - Blob upload gear active. 04:33:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:33:30 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:33:30 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:33:30 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:33:30 INFO - (blobuploader) - INFO - Open directory for files ... 04:33:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:33:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:33:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:32 INFO - (blobuploader) - INFO - Done attempting. 04:33:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:33:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:33:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:44 INFO - (blobuploader) - INFO - Done attempting. 04:33:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:33:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:33:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:45 INFO - (blobuploader) - INFO - Done attempting. 04:33:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:33:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:33:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:46 INFO - (blobuploader) - INFO - Done attempting. 04:33:46 INFO - (blobuploader) - INFO - Iteration through files over. 04:33:46 INFO - Return code: 0 04:33:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:33:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:33:46 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61a6ad80facea40ac17ed0a32ac89dbb021bea4a7928cbce03a8e5996d14861da9fa636b8bb31d33c584da66595634537faee9c843cc95fe2b51dd478f95575c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/484a59f9e091a9bbcc1fe4a05fb98dfb1044ddb3c207015b6c8f68a01cb8e0f395e94c93cf9bb3d90be672342759eeab8c3ed25d57328bc15adc5297966e7589", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61fbe6e189fdda847e018c0b3c50799a8fd0d64f371e2b7d3e5d51dc42a792dd78151d6d790a8ae4b8cfcdf5851f206329e05495c3cadc975ce5166ffa489336", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ad952b3fe27026607ca60f2574ea22e4a5858acd95cecb1606ef213678a8169c7b941c3c7135e9b37ce845146b9ce70108d49a96e65efc5fc38fc3188f407df"} 04:33:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:33:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:33:46 INFO - Contents: 04:33:46 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61a6ad80facea40ac17ed0a32ac89dbb021bea4a7928cbce03a8e5996d14861da9fa636b8bb31d33c584da66595634537faee9c843cc95fe2b51dd478f95575c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/484a59f9e091a9bbcc1fe4a05fb98dfb1044ddb3c207015b6c8f68a01cb8e0f395e94c93cf9bb3d90be672342759eeab8c3ed25d57328bc15adc5297966e7589", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61fbe6e189fdda847e018c0b3c50799a8fd0d64f371e2b7d3e5d51dc42a792dd78151d6d790a8ae4b8cfcdf5851f206329e05495c3cadc975ce5166ffa489336", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ad952b3fe27026607ca60f2574ea22e4a5858acd95cecb1606ef213678a8169c7b941c3c7135e9b37ce845146b9ce70108d49a96e65efc5fc38fc3188f407df"} 04:33:46 INFO - Running post-run listener: copy_logs_to_upload_dir 04:33:46 INFO - Copying logs to upload dir... 04:33:46 INFO - mkdir: /builds/slave/test/build/upload/logs 04:33:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.189908 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-10-03 04:33:46.433787) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 04:33:46.438157) ========= 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=1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818 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/61a6ad80facea40ac17ed0a32ac89dbb021bea4a7928cbce03a8e5996d14861da9fa636b8bb31d33c584da66595634537faee9c843cc95fe2b51dd478f95575c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/484a59f9e091a9bbcc1fe4a05fb98dfb1044ddb3c207015b6c8f68a01cb8e0f395e94c93cf9bb3d90be672342759eeab8c3ed25d57328bc15adc5297966e7589", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61fbe6e189fdda847e018c0b3c50799a8fd0d64f371e2b7d3e5d51dc42a792dd78151d6d790a8ae4b8cfcdf5851f206329e05495c3cadc975ce5166ffa489336", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ad952b3fe27026607ca60f2574ea22e4a5858acd95cecb1606ef213678a8169c7b941c3c7135e9b37ce845146b9ce70108d49a96e65efc5fc38fc3188f407df"} build_url:https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/HK3Cnu3ZQ2u3dGHBaXWQPg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61a6ad80facea40ac17ed0a32ac89dbb021bea4a7928cbce03a8e5996d14861da9fa636b8bb31d33c584da66595634537faee9c843cc95fe2b51dd478f95575c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/484a59f9e091a9bbcc1fe4a05fb98dfb1044ddb3c207015b6c8f68a01cb8e0f395e94c93cf9bb3d90be672342759eeab8c3ed25d57328bc15adc5297966e7589", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61fbe6e189fdda847e018c0b3c50799a8fd0d64f371e2b7d3e5d51dc42a792dd78151d6d790a8ae4b8cfcdf5851f206329e05495c3cadc975ce5166ffa489336", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ad952b3fe27026607ca60f2574ea22e4a5858acd95cecb1606ef213678a8169c7b941c3c7135e9b37ce845146b9ce70108d49a96e65efc5fc38fc3188f407df"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 04:33:46.469707) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:33:46.470046) ========= 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=1f5564757af27664ca02d71f000001b0-1507028557.431341-1792811818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 04:33:46.550702) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 04:33:46.551025) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 04:33:46.551335) ========= ========= Total master_lag: 0.42 =========