builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-038 starttime: 1504306406.04 results: success (0) revision: 73e8f351b28ffc30e8077be21ce7298f4648ca5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:26.037020) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:26.037474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:26.037751) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:26.055352) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:26.055701) ========= 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=8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 15:53:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 44.8M=0s 2017-09-01 15:53:26 (44.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124598 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:26.196839) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:26.197166) ========= 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=8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016641 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:26.248178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-01 15:53:26.249192) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 73e8f351b28ffc30e8077be21ce7298f4648ca5a --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 73e8f351b28ffc30e8077be21ce7298f4648ca5a --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=8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 15:53:26,245 truncating revision to first 12 chars 2017-09-01 15:53:26,245 Setting DEBUG logging. 2017-09-01 15:53:26,245 attempt 1/10 2017-09-01 15:53:26,245 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/73e8f351b28f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 15:53:26,923 unpacking tar archive at: mozilla-central-73e8f351b28f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.066237 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-01 15:53:27.334429) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:27.334749) ========= script_repo_revision: 73e8f351b28ffc30e8077be21ce7298f4648ca5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:27.335128) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:27.335407) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 15:53:27.348359) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-09-01 15:53:27.348686) ========= /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=8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:53:27 INFO - MultiFileLogger online at 20170901 15:53:27 in /builds/slave/test 15:53:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:53:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:53:27 INFO - {'append_to_log': False, 15:53:27 INFO - 'base_work_dir': '/builds/slave/test', 15:53:27 INFO - 'blob_upload_branch': 'Firefox', 15:53:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:53:27 INFO - 'branch': 'Firefox', 15:53:27 INFO - 'buildbot_json_path': 'buildprops.json', 15:53:27 INFO - 'code_coverage': False, 15:53:27 INFO - 'config_files': ('talos/linux_config.py',), 15:53:27 INFO - 'default_actions': ('clobber', 15:53:27 INFO - 'read-buildbot-config', 15:53:27 INFO - 'download-and-extract', 15:53:27 INFO - 'populate-webroot', 15:53:27 INFO - 'create-virtualenv', 15:53:27 INFO - 'install', 15:53:27 INFO - 'setup-mitmproxy', 15:53:27 INFO - 'run-tests'), 15:53:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:53:27 INFO - 'disable_ccov_upload': False, 15:53:27 INFO - 'disable_stylo': False, 15:53:27 INFO - 'download_minidump_stackwalk': True, 15:53:27 INFO - 'download_symbols': 'ondemand', 15:53:27 INFO - 'enable_stylo': False, 15:53:27 INFO - 'enable_webrender': False, 15:53:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:53:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:53:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:53:27 INFO - 'gecko_profile': False, 15:53:27 INFO - 'gecko_profile_interval': 0, 15:53:27 INFO - 'installer_path': 'installer.exe', 15:53:27 INFO - 'log_level': 'info', 15:53:27 INFO - 'log_name': 'talos', 15:53:27 INFO - 'log_to_console': True, 15:53:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:53:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:53:27 INFO - 'opt_config_files': (), 15:53:27 INFO - 'pip_index': False, 15:53:27 INFO - 'suite': 'dromaeojs-e10s', 15:53:27 INFO - 'system_bits': '32', 15:53:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:53:27 INFO - 'title': 'talos-linux64-ix-038', 15:53:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:53:27 INFO - 'use_talos_json': True, 15:53:27 INFO - 'verify': 'False', 15:53:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:53:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:53:27 INFO - 'work_dir': 'build'} 15:53:27 INFO - [mozharness: 2017-09-01 22:53:27.488258Z] Running clobber step. 15:53:27 INFO - Running pre-action listener: _resource_record_pre_action 15:53:27 INFO - Running main action method: clobber 15:53:27 INFO - rmtree: /builds/slave/test/build 15:53:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:53:29 INFO - Running post-action listener: _resource_record_post_action 15:53:29 INFO - [mozharness: 2017-09-01 22:53:29.158906Z] Finished clobber step (success) 15:53:29 INFO - [mozharness: 2017-09-01 22:53:29.159004Z] Running read-buildbot-config step. 15:53:29 INFO - Running pre-action listener: _resource_record_pre_action 15:53:29 INFO - Running main action method: read_buildbot_config 15:53:29 INFO - Using buildbot properties: 15:53:29 INFO - { 15:53:29 INFO - "project": "", 15:53:29 INFO - "product": "firefox", 15:53:29 INFO - "who": "kwierso@gmail.com", 15:53:29 INFO - "installer_path": "public/build/target.tar.bz2", 15:53:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 15:53:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:53:29 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 15:53:29 INFO - "stage_platform": "linux64-stylosequential", 15:53:29 INFO - "basedir": "/builds/slave/test", 15:53:29 INFO - "buildnumber": 183, 15:53:29 INFO - "platform": "ubuntu64_hw_styloseq", 15:53:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:53:29 INFO - "slavebuilddir": "test", 15:53:29 INFO - "taskId": "eBxBoIjsTNazCyzaRBpUTw", 15:53:29 INFO - "branch": "mozilla-central", 15:53:29 INFO - "script_repo_revision": "production", 15:53:29 INFO - "revision": "73e8f351b28ffc30e8077be21ce7298f4648ca5a", 15:53:29 INFO - "slavename": "talos-linux64-ix-038", 15:53:29 INFO - "repo_path": "mozilla-central" 15:53:29 INFO - } 15:53:29 INFO - Finding installer, test and symbols from parent task. 15:53:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBxBoIjsTNazCyzaRBpUTw'}, attempt #1 15:53:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBxBoIjsTNazCyzaRBpUTw'}, attempt #1 15:53:30 INFO - Task dependencies: B3zBBhhZS1Sz7X0NYgNFnw 15:53:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw'}, attempt #1 15:53:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 15:53:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.test_packages.json 15:53:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.crashreporter-symbols.zip 15:53:30 INFO - Running post-action listener: _resource_record_post_action 15:53:30 INFO - [mozharness: 2017-09-01 22:53:30.587227Z] Finished read-buildbot-config step (success) 15:53:30 INFO - [mozharness: 2017-09-01 22:53:30.587442Z] Running download-and-extract step. 15:53:30 INFO - Running pre-action listener: _resource_record_pre_action 15:53:30 INFO - Running main action method: download_and_extract 15:53:30 INFO - mkdir: /builds/slave/test/build/tests 15:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:53:30 INFO - trying https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.test_packages.json 15:53:30 INFO - Downloading https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:53:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:53:31 INFO - Downloaded 989 bytes. 15:53:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:53:31 INFO - Using the following test package requirements: 15:53:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:53:31 INFO - u'common': [u'target.common.tests.zip'], 15:53:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:53:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:53:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:53:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:53:31 INFO - u'mozbase': [u'target.common.tests.zip'], 15:53:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:53:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:53:31 INFO - u'web-platform': [u'target.common.tests.zip', 15:53:31 INFO - u'target.web-platform.tests.tar.gz'], 15:53:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:53:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:53:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip 15:53:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:53:31 INFO - Fetch https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip into memory 15:53:32 INFO - Content-Length response header: 54957611 15:53:32 INFO - Bytes received: 54957611 15:53:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:53:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip 15:53:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:53:43 INFO - Fetch https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip into memory 15:53:44 INFO - Content-Length response header: 54957611 15:53:44 INFO - Bytes received: 54957611 15:54:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.talos.tests.zip 15:54:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:54:02 INFO - Fetch https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.talos.tests.zip into memory 15:54:03 INFO - Content-Length response header: 14496650 15:54:03 INFO - Bytes received: 14496650 15:54: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')]} 15:54:03 INFO - trying https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 15:54:03 INFO - Downloading https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:54:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:54:04 INFO - Downloaded 62528630 bytes. 15:54:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 15:54:04 INFO - mkdir: /builds/slave/test/properties 15:54:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:54:04 INFO - Writing to file /builds/slave/test/properties/build_url 15:54:04 INFO - Contents: 15:54:04 INFO - build_url:https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 15:54:04 INFO - Running post-action listener: _resource_record_post_action 15:54:04 INFO - Running post-action listener: find_tests_for_verification 15:54:04 INFO - Running post-action listener: set_extra_try_arguments 15:54:04 INFO - [mozharness: 2017-09-01 22:54:04.939830Z] Finished download-and-extract step (success) 15:54:04 INFO - [mozharness: 2017-09-01 22:54:04.939926Z] Running populate-webroot step. 15:54:04 INFO - Running pre-action listener: _resource_record_pre_action 15:54:04 INFO - Running main action method: populate_webroot 15:54:04 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:54:04 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:54:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:54:04 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:54:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:54:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:04 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:54:04 INFO - 'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:54:04 INFO - 'tests': ['damp', 'tps']}, 15:54:04 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:54:04 INFO - 'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['damp', 'tps']}, 15:54:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:54:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:54:04 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:54:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:54:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:54:04 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:54:04 INFO - 'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:54:04 INFO - 'other-e10s': {'tests': ['a11yr', 15:54:04 INFO - 'ts_paint', 15:54:04 INFO - 'tpaint', 15:54:04 INFO - 'sessionrestore', 15:54:04 INFO - 'sessionrestore_many_windows', 15:54:04 INFO - 'sessionrestore_no_auto_restore', 15:54:04 INFO - 'tabpaint']}, 15:54:04 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['a11yr', 15:54:04 INFO - 'ts_paint', 15:54:04 INFO - 'tpaint', 15:54:04 INFO - 'sessionrestore', 15:54:04 INFO - 'sessionrestore_many_windows', 15:54:04 INFO - 'sessionrestore_no_auto_restore', 15:54:04 INFO - 'tabpaint']}, 15:54:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:54:04 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:54:04 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['bloom_basic_singleton']}, 15:54:04 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['bloom_basic']}, 15:54:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:54:04 INFO - 'tsvgr_opacity', 15:54:04 INFO - 'tart', 15:54:04 INFO - 'tscrollx', 15:54:04 INFO - 'tsvg_static']}, 15:54:04 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['tsvgx', 15:54:04 INFO - 'tsvgr_opacity', 15:54:04 INFO - 'tart', 15:54:04 INFO - 'tscrollx', 15:54:04 INFO - 'tsvg_static']}, 15:54:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:54:04 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:54:04 INFO - 'talos_options': ['--enable-stylo'], 15:54:04 INFO - 'tests': ['tp5o']}, 15:54:04 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:54:04 INFO - 'talos_options': ['--mitmproxy', 15:54:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:54:04 INFO - '--firstNonBlankPaint'], 15:54:04 INFO - 'tests': ['tp6_google', 15:54:04 INFO - 'tp6_youtube', 15:54:04 INFO - 'tp6_amazon', 15:54:04 INFO - 'tp6_facebook']}, 15:54:04 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:54:04 INFO - 'talos_options': ['--enable-stylo', 15:54:04 INFO - '--mitmproxy', 15:54:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:54:04 INFO - '--firstNonBlankPaint'], 15:54:04 INFO - 'tests': ['tp6_google', 15:54:04 INFO - 'tp6_youtube', 15:54:04 INFO - 'tp6_amazon', 15:54:04 INFO - 'tp6_facebook']}, 15:54:04 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:54:04 INFO - 'talos_options': ['--enable-stylo', 15:54:04 INFO - '--stylo-threads=1', 15:54:04 INFO - '--mitmproxy', 15:54:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:54:04 INFO - '--firstNonBlankPaint'], 15:54:04 INFO - 'tests': ['tp6_google', 15:54:04 INFO - 'tp6_youtube', 15:54:04 INFO - 'tp6_amazon', 15:54:04 INFO - 'tp6_facebook']}, 15:54:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:54:04 INFO - 'talos_options': ['--xperf_path', 15:54:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:04 INFO - 'tests': ['tp5n']}, 15:54:04 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:54:04 INFO - 'talos_options': ['--enable-stylo', 15:54:04 INFO - '--xperf_path', 15:54:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:04 INFO - 'tests': ['tp5n']}}} 15:54:04 INFO - Running post-action listener: _resource_record_post_action 15:54:04 INFO - [mozharness: 2017-09-01 22:54:04.954153Z] Finished populate-webroot step (success) 15:54:04 INFO - [mozharness: 2017-09-01 22:54:04.954242Z] Running create-virtualenv step. 15:54:04 INFO - Running pre-action listener: _resource_record_pre_action 15:54:04 INFO - Running main action method: create_virtualenv 15:54:04 INFO - Creating virtualenv /builds/slave/test/build/venv 15:54:04 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:54:04 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:54:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:54:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:54:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:54:05 INFO - Installing distribute.............................................................................................................................................................................................done. 15:54:05 INFO - Installing pip...............done. 15:54:05 INFO - Return code: 0 15:54:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:54:05 INFO - Reading from file tmpfile_stdout 15:54:05 INFO - Output received: 15:54:05 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:54:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:54:05 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')]} 15:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:05 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')]} 15:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:05 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 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:05 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 15:54:05 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 15:54:05 INFO - Using env: {'DISPLAY': ':0', 15:54:05 INFO - 'HOME': '/home/cltbld', 15:54:05 INFO - 'LANG': 'en_US.UTF-8', 15:54:05 INFO - 'LANGUAGE': 'en_US:en', 15:54:05 INFO - 'LOGNAME': 'cltbld', 15:54:05 INFO - 'MAIL': '/var/mail/cltbld', 15:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:05 INFO - 'MOZ_NO_REMOTE': '1', 15:54:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:05 INFO - 'NO_EM_RESTART': '1', 15:54:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:05 INFO - 'PWD': '/builds/slave/test', 15:54:05 INFO - 'SHELL': '/bin/bash', 15:54:05 INFO - 'SHLVL': '1', 15:54:05 INFO - 'TERM': 'linux', 15:54:05 INFO - 'TMOUT': '86400', 15:54:05 INFO - 'USER': 'cltbld', 15:54:05 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:05 INFO - '_': '/tools/buildbot/bin/python'} 15:54:07 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:54:07 INFO - Downloading/unpacking pip>=1.5 15:54:07 INFO - Running setup.py egg_info for package pip 15:54:07 INFO - warning: no files found matching 'pip/cacert.pem' 15:54:07 INFO - warning: no files found matching '*.html' under directory 'docs' 15:54:07 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:54:07 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:54:07 INFO - Installing collected packages: pip 15:54:07 INFO - Found existing installation: pip 1.1 15:54:07 INFO - Uninstalling pip: 15:54:07 INFO - Successfully uninstalled pip 15:54:07 INFO - Running setup.py install for pip 15:54:07 INFO - warning: no files found matching 'pip/cacert.pem' 15:54:07 INFO - warning: no files found matching '*.html' under directory 'docs' 15:54:07 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:54:07 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:54:07 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:54:07 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:54:07 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:54:07 INFO - Successfully installed pip 15:54:07 INFO - Cleaning up... 15:54:07 INFO - Return code: 0 15:54:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:07 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 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:07 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 15:54:07 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 15:54:07 INFO - Using env: {'DISPLAY': ':0', 15:54:07 INFO - 'HOME': '/home/cltbld', 15:54:07 INFO - 'LANG': 'en_US.UTF-8', 15:54:07 INFO - 'LANGUAGE': 'en_US:en', 15:54:07 INFO - 'LOGNAME': 'cltbld', 15:54:07 INFO - 'MAIL': '/var/mail/cltbld', 15:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:07 INFO - 'MOZ_NO_REMOTE': '1', 15:54:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:07 INFO - 'NO_EM_RESTART': '1', 15:54:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:07 INFO - 'PWD': '/builds/slave/test', 15:54:07 INFO - 'SHELL': '/bin/bash', 15:54:07 INFO - 'SHLVL': '1', 15:54:07 INFO - 'TERM': 'linux', 15:54:07 INFO - 'TMOUT': '86400', 15:54:07 INFO - 'USER': 'cltbld', 15:54:07 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:07 INFO - '_': '/tools/buildbot/bin/python'} 15:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:07 INFO - Downloading/unpacking psutil>=3.1.1 15:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:54:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:08 INFO - Installing collected packages: psutil 15:54:08 INFO - Running setup.py install for psutil 15:54:08 INFO - building 'psutil._psutil_linux' extension 15:54:08 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 15:54:08 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 15:54:08 INFO - building 'psutil._psutil_posix' extension 15:54:08 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 15:54:08 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 15:54:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:09 INFO - Successfully installed psutil 15:54:09 INFO - Cleaning up... 15:54:09 INFO - Return code: 0 15:54:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:54: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')]} 15:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54: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')]} 15:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:54:09 INFO - Using env: {'DISPLAY': ':0', 15:54:09 INFO - 'HOME': '/home/cltbld', 15:54:09 INFO - 'LANG': 'en_US.UTF-8', 15:54:09 INFO - 'LANGUAGE': 'en_US:en', 15:54:09 INFO - 'LOGNAME': 'cltbld', 15:54:09 INFO - 'MAIL': '/var/mail/cltbld', 15:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:09 INFO - 'MOZ_NO_REMOTE': '1', 15:54:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:09 INFO - 'NO_EM_RESTART': '1', 15:54:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:09 INFO - 'PWD': '/builds/slave/test', 15:54:09 INFO - 'SHELL': '/bin/bash', 15:54:09 INFO - 'SHLVL': '1', 15:54:09 INFO - 'TERM': 'linux', 15:54:09 INFO - 'TMOUT': '86400', 15:54:09 INFO - 'USER': 'cltbld', 15:54:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:09 INFO - '_': '/tools/buildbot/bin/python'} 15:54:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:54:09 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 15:54:09 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 15:54:09 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 15:54:09 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 15:54:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:54:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:54:10 INFO - Installing collected packages: mozsystemmonitor 15:54:10 INFO - Running setup.py install for mozsystemmonitor 15:54:10 INFO - Successfully installed mozsystemmonitor 15:54:10 INFO - Cleaning up... 15:54:10 INFO - Return code: 0 15:54:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:54:10 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')]} 15:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:10 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')]} 15:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:10 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 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:10 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 15:54:10 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 15:54:10 INFO - Using env: {'DISPLAY': ':0', 15:54:10 INFO - 'HOME': '/home/cltbld', 15:54:10 INFO - 'LANG': 'en_US.UTF-8', 15:54:10 INFO - 'LANGUAGE': 'en_US:en', 15:54:10 INFO - 'LOGNAME': 'cltbld', 15:54:10 INFO - 'MAIL': '/var/mail/cltbld', 15:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:10 INFO - 'MOZ_NO_REMOTE': '1', 15:54:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:10 INFO - 'NO_EM_RESTART': '1', 15:54:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:10 INFO - 'PWD': '/builds/slave/test', 15:54:10 INFO - 'SHELL': '/bin/bash', 15:54:10 INFO - 'SHLVL': '1', 15:54:10 INFO - 'TERM': 'linux', 15:54:10 INFO - 'TMOUT': '86400', 15:54:10 INFO - 'USER': 'cltbld', 15:54:10 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:10 INFO - '_': '/tools/buildbot/bin/python'} 15:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:10 INFO - Downloading/unpacking jsonschema==2.5.1 15:54:10 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 15:54:10 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 15:54:10 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 15:54:10 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 15:54:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:54:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:54:11 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 15:54:11 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 15:54:11 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 15:54:11 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 15:54:11 INFO - Downloading functools32-3.2.3-2.tar.gz 15:54:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:54:12 INFO - warning: no files found matching '*.txt' 15:54:12 INFO - no previously-included directories found matching 'build' 15:54:12 INFO - no previously-included directories found matching 'dist' 15:54:12 INFO - no previously-included directories found matching '.git*' 15:54:12 INFO - Installing collected packages: jsonschema, functools32 15:54:12 INFO - Running setup.py install for functools32 15:54:12 INFO - warning: no files found matching '*.txt' 15:54:12 INFO - no previously-included directories found matching 'build' 15:54:12 INFO - no previously-included directories found matching 'dist' 15:54:12 INFO - no previously-included directories found matching '.git*' 15:54:12 INFO - Successfully installed jsonschema functools32 15:54:12 INFO - Cleaning up... 15:54:12 INFO - Return code: 0 15:54:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:54:12 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')]} 15:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:12 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')]} 15:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:12 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 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:12 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 15:54:12 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 15:54:12 INFO - Using env: {'DISPLAY': ':0', 15:54:12 INFO - 'HOME': '/home/cltbld', 15:54:12 INFO - 'LANG': 'en_US.UTF-8', 15:54:12 INFO - 'LANGUAGE': 'en_US:en', 15:54:12 INFO - 'LOGNAME': 'cltbld', 15:54:12 INFO - 'MAIL': '/var/mail/cltbld', 15:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:12 INFO - 'MOZ_NO_REMOTE': '1', 15:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:12 INFO - 'NO_EM_RESTART': '1', 15:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:12 INFO - 'PWD': '/builds/slave/test', 15:54:12 INFO - 'SHELL': '/bin/bash', 15:54:12 INFO - 'SHLVL': '1', 15:54:12 INFO - 'TERM': 'linux', 15:54:12 INFO - 'TMOUT': '86400', 15:54:12 INFO - 'USER': 'cltbld', 15:54:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:12 INFO - '_': '/tools/buildbot/bin/python'} 15:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:54:12 INFO - Cleaning up... 15:54:12 INFO - Return code: 0 15:54:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:54:12 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')]} 15:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:12 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')]} 15:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:12 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 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:12 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 15:54:12 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 15:54:12 INFO - Using env: {'DISPLAY': ':0', 15:54:12 INFO - 'HOME': '/home/cltbld', 15:54:12 INFO - 'LANG': 'en_US.UTF-8', 15:54:12 INFO - 'LANGUAGE': 'en_US:en', 15:54:12 INFO - 'LOGNAME': 'cltbld', 15:54:12 INFO - 'MAIL': '/var/mail/cltbld', 15:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:12 INFO - 'MOZ_NO_REMOTE': '1', 15:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:12 INFO - 'NO_EM_RESTART': '1', 15:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:12 INFO - 'PWD': '/builds/slave/test', 15:54:12 INFO - 'SHELL': '/bin/bash', 15:54:12 INFO - 'SHLVL': '1', 15:54:12 INFO - 'TERM': 'linux', 15:54:12 INFO - 'TMOUT': '86400', 15:54:12 INFO - 'USER': 'cltbld', 15:54:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:12 INFO - '_': '/tools/buildbot/bin/python'} 15:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:12 INFO - Downloading/unpacking blobuploader==1.2.4 15:54:12 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 15:54:12 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 15:54:12 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 15:54:12 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 15:54:13 INFO - Downloading blobuploader-1.2.4.tar.gz 15:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:54:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:54:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:54:14 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 15:54:14 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 15:54:14 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 15:54:14 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 15:54:14 INFO - Downloading docopt-0.6.1.tar.gz 15:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:54:14 INFO - Installing collected packages: blobuploader, requests, docopt 15:54:14 INFO - Running setup.py install for blobuploader 15:54:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:54:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:54:14 INFO - Running setup.py install for requests 15:54:14 INFO - Running setup.py install for docopt 15:54:14 INFO - Successfully installed blobuploader requests docopt 15:54:14 INFO - Cleaning up... 15:54:14 INFO - Return code: 0 15:54:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:14 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 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:14 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 15:54:14 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 15:54:14 INFO - Using env: {'DISPLAY': ':0', 15:54:14 INFO - 'HOME': '/home/cltbld', 15:54:14 INFO - 'LANG': 'en_US.UTF-8', 15:54:14 INFO - 'LANGUAGE': 'en_US:en', 15:54:14 INFO - 'LOGNAME': 'cltbld', 15:54:14 INFO - 'MAIL': '/var/mail/cltbld', 15:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:14 INFO - 'MOZ_NO_REMOTE': '1', 15:54:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:14 INFO - 'NO_EM_RESTART': '1', 15:54:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:14 INFO - 'PWD': '/builds/slave/test', 15:54:14 INFO - 'SHELL': '/bin/bash', 15:54:14 INFO - 'SHLVL': '1', 15:54:14 INFO - 'TERM': 'linux', 15:54:14 INFO - 'TMOUT': '86400', 15:54:14 INFO - 'USER': 'cltbld', 15:54:14 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:14 INFO - '_': '/tools/buildbot/bin/python'} 15:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:15 INFO - Running setup.py (path:/tmp/pip-nBNoFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:15 INFO - Running setup.py (path:/tmp/pip-XGplTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:15 INFO - Running setup.py (path:/tmp/pip-yvexKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:15 INFO - Running setup.py (path:/tmp/pip-Pszm2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:15 INFO - Running setup.py (path:/tmp/pip-iUuPg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:15 INFO - Running setup.py (path:/tmp/pip-raCV_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:15 INFO - Running setup.py (path:/tmp/pip-53vZBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:15 INFO - Running setup.py (path:/tmp/pip-Qzt9P4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:15 INFO - Running setup.py (path:/tmp/pip-V1HC2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:15 INFO - Running setup.py (path:/tmp/pip-V1X4a6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:15 INFO - Running setup.py (path:/tmp/pip-6VtGx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:15 INFO - Running setup.py (path:/tmp/pip-8YKX5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:16 INFO - Running setup.py (path:/tmp/pip-1ExLgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:16 INFO - Running setup.py (path:/tmp/pip-mM0z0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:16 INFO - Running setup.py (path:/tmp/pip-sNwsr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:16 INFO - Running setup.py (path:/tmp/pip-JP2Ee4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:16 INFO - Running setup.py (path:/tmp/pip-FcoFNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:54:16 INFO - Running setup.py install for manifestparser 15:54:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:54:16 INFO - Running setup.py install for mozcrash 15:54:16 INFO - Running setup.py install for mozdebug 15:54:16 INFO - Running setup.py install for mozdevice 15:54:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:54:16 INFO - Running setup.py install for mozfile 15:54:16 INFO - Running setup.py install for mozhttpd 15:54:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:54:16 INFO - Running setup.py install for mozinfo 15:54:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Running setup.py install for mozInstall 15:54:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Running setup.py install for mozleak 15:54:17 INFO - Running setup.py install for mozlog 15:54:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Running setup.py install for moznetwork 15:54:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Running setup.py install for mozprocess 15:54:17 INFO - Running setup.py install for mozprofile 15:54:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Running setup.py install for mozrunner 15:54:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:54:17 INFO - Running setup.py install for mozscreenshot 15:54:17 INFO - Running setup.py install for moztest 15:54:17 INFO - Running setup.py install for mozversion 15:54:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:54:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:54:18 INFO - Cleaning up... 15:54:18 INFO - Return code: 0 15:54:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:18 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')]} 15:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:18 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')]} 15:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:18 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 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:18 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 15:54:18 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 15:54:18 INFO - Using env: {'DISPLAY': ':0', 15:54:18 INFO - 'HOME': '/home/cltbld', 15:54:18 INFO - 'LANG': 'en_US.UTF-8', 15:54:18 INFO - 'LANGUAGE': 'en_US:en', 15:54:18 INFO - 'LOGNAME': 'cltbld', 15:54:18 INFO - 'MAIL': '/var/mail/cltbld', 15:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:18 INFO - 'MOZ_NO_REMOTE': '1', 15:54:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:18 INFO - 'NO_EM_RESTART': '1', 15:54:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:18 INFO - 'PWD': '/builds/slave/test', 15:54:18 INFO - 'SHELL': '/bin/bash', 15:54:18 INFO - 'SHLVL': '1', 15:54:18 INFO - 'TERM': 'linux', 15:54:18 INFO - 'TMOUT': '86400', 15:54:18 INFO - 'USER': 'cltbld', 15:54:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:18 INFO - '_': '/tools/buildbot/bin/python'} 15:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:18 INFO - Running setup.py (path:/tmp/pip-ElCqCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:18 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)) 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:18 INFO - Running setup.py (path:/tmp/pip-lElyQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:18 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)) 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:18 INFO - Running setup.py (path:/tmp/pip-K32PNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:18 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)) 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:18 INFO - Running setup.py (path:/tmp/pip-qHN_cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:18 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)) 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:18 INFO - Running setup.py (path:/tmp/pip-Qyvi7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:18 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)) 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:18 INFO - Running setup.py (path:/tmp/pip-fj613M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:18 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)) 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:18 INFO - Running setup.py (path:/tmp/pip-ifFJR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:18 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)) 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:18 INFO - Running setup.py (path:/tmp/pip-UeCVA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:18 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)) 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:18 INFO - Running setup.py (path:/tmp/pip-WYJOTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:18 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)) 15:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:18 INFO - Running setup.py (path:/tmp/pip-XQFY3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:19 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)) 15:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:19 INFO - Running setup.py (path:/tmp/pip-iVi7tJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:19 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)) 15:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:19 INFO - Running setup.py (path:/tmp/pip-LnPIrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:19 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)) 15:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:19 INFO - Running setup.py (path:/tmp/pip-nr0ePx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:19 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)) 15:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:19 INFO - Running setup.py (path:/tmp/pip-SGQ7HF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:19 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)) 15:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:19 INFO - Running setup.py (path:/tmp/pip-arBa3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:19 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)) 15:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:19 INFO - Running setup.py (path:/tmp/pip-uFBYAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:19 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)) 15:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:19 INFO - Running setup.py (path:/tmp/pip-NFLBs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:19 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)) 15:54:19 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)) 15:54:19 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)) 15:54:19 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)) 15:54:19 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)) 15:54:19 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)) 15:54:19 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)) 15:54:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:20 INFO - Downloading blessings-1.6.tar.gz 15:54:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:54:20 INFO - Installing collected packages: blessings 15:54:20 INFO - Running setup.py install for blessings 15:54:20 INFO - Successfully installed blessings 15:54:20 INFO - Cleaning up... 15:54:20 INFO - Return code: 0 15:54:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:54:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:21 INFO - Reading from file tmpfile_stdout 15:54:21 INFO - Current package versions: 15:54:21 INFO - argparse == 1.2.1 15:54:21 INFO - blessings == 1.6 15:54:21 INFO - blobuploader == 1.2.4 15:54:21 INFO - docopt == 0.6.1 15:54:21 INFO - functools32 == 3.2.3-2 15:54:21 INFO - jsonschema == 2.5.1 15:54:21 INFO - manifestparser == 1.1 15:54:21 INFO - mozInstall == 1.13 15:54:21 INFO - mozcrash == 1.0 15:54:21 INFO - mozdebug == 0.1 15:54:21 INFO - mozdevice == 0.51 15:54:21 INFO - mozfile == 1.2 15:54:21 INFO - mozhttpd == 0.7 15:54:21 INFO - mozinfo == 0.10 15:54:21 INFO - mozleak == 0.1 15:54:21 INFO - mozlog == 3.5 15:54:21 INFO - moznetwork == 0.27 15:54:21 INFO - mozprocess == 0.25 15:54:21 INFO - mozprofile == 0.28 15:54:21 INFO - mozrunner == 6.13 15:54:21 INFO - mozscreenshot == 0.1 15:54:21 INFO - mozsystemmonitor == 0.3 15:54:21 INFO - moztest == 0.8 15:54:21 INFO - mozversion == 1.4 15:54:21 INFO - psutil == 3.1.1 15:54:21 INFO - requests == 1.2.3 15:54:21 INFO - wsgiref == 0.1.2 15:54:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:21 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 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:21 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 15:54:21 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 15:54:21 INFO - Using env: {'DISPLAY': ':0', 15:54:21 INFO - 'HOME': '/home/cltbld', 15:54:21 INFO - 'LANG': 'en_US.UTF-8', 15:54:21 INFO - 'LANGUAGE': 'en_US:en', 15:54:21 INFO - 'LOGNAME': 'cltbld', 15:54:21 INFO - 'MAIL': '/var/mail/cltbld', 15:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:21 INFO - 'MOZ_NO_REMOTE': '1', 15:54:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:21 INFO - 'NO_EM_RESTART': '1', 15:54:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:21 INFO - 'PWD': '/builds/slave/test', 15:54:21 INFO - 'SHELL': '/bin/bash', 15:54:21 INFO - 'SHLVL': '1', 15:54:21 INFO - 'TERM': 'linux', 15:54:21 INFO - 'TMOUT': '86400', 15:54:21 INFO - 'USER': 'cltbld', 15:54:21 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:21 INFO - '_': '/tools/buildbot/bin/python'} 15:54:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:21 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)) 15:54:21 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)) 15:54:21 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)) 15:54:21 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)) 15:54:21 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)) 15:54:21 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)) 15:54:21 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)) 15:54:21 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)) 15:54:21 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)) 15:54:21 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:54:22 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)) 15:54:22 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)) 15:54:22 INFO - Installing collected packages: simplejson 15:54:22 INFO - Running setup.py install for simplejson 15:54:22 INFO - building 'simplejson._speedups' extension 15:54:22 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 15:54:23 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 15:54:23 INFO - Successfully installed simplejson 15:54:23 INFO - Cleaning up... 15:54:23 INFO - Return code: 0 15:54:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:54: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')]} 15:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54: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')]} 15:54:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:23 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 0xb401f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd09a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeaca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, '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': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', '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 15:54:23 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 15:54:23 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 15:54:23 INFO - Using env: {'DISPLAY': ':0', 15:54:23 INFO - 'HOME': '/home/cltbld', 15:54:23 INFO - 'LANG': 'en_US.UTF-8', 15:54:23 INFO - 'LANGUAGE': 'en_US:en', 15:54:23 INFO - 'LOGNAME': 'cltbld', 15:54:23 INFO - 'MAIL': '/var/mail/cltbld', 15:54:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:23 INFO - 'MOZ_NO_REMOTE': '1', 15:54:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:23 INFO - 'NO_EM_RESTART': '1', 15:54:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:23 INFO - 'PWD': '/builds/slave/test', 15:54:23 INFO - 'SHELL': '/bin/bash', 15:54:23 INFO - 'SHLVL': '1', 15:54:23 INFO - 'TERM': 'linux', 15:54:23 INFO - 'TMOUT': '86400', 15:54:23 INFO - 'USER': 'cltbld', 15:54:23 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:23 INFO - '_': '/tools/buildbot/bin/python'} 15:54:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:54:23 INFO - Cleaning up... 15:54:23 INFO - Return code: 0 15:54:23 INFO - Running post-action listener: _resource_record_post_action 15:54:23 INFO - Running post-action listener: _start_resource_monitoring 15:54:23 INFO - Starting resource monitoring. 15:54:23 INFO - [mozharness: 2017-09-01 22:54:23.803757Z] Finished create-virtualenv step (success) 15:54:23 INFO - [mozharness: 2017-09-01 22:54:23.804277Z] Running install step. 15:54:23 INFO - Running pre-action listener: _resource_record_pre_action 15:54:23 INFO - Running main action method: install 15:54:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:24 INFO - Reading from file tmpfile_stdout 15:54:24 INFO - Detecting whether we're running mozinstall >=1.0... 15:54:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:54:24 INFO - Reading from file tmpfile_stdout 15:54:24 INFO - Output received: 15:54:24 INFO - Usage: mozinstall [options] installer 15:54:24 INFO - Options: 15:54:24 INFO - -h, --help show this help message and exit 15:54:24 INFO - -d DEST, --destination=DEST 15:54:24 INFO - Directory to install application into. [default: 15:54:24 INFO - "/builds/slave/test"] 15:54:24 INFO - --app=APP Application being installed. [default: firefox] 15:54:24 INFO - mkdir: /builds/slave/test/build/application 15:54:24 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'] 15:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:54:40 INFO - Reading from file tmpfile_stdout 15:54:40 INFO - Output received: 15:54:40 INFO - /builds/slave/test/build/application/firefox/firefox 15:54:40 INFO - Running post-action listener: _resource_record_post_action 15:54:40 INFO - [mozharness: 2017-09-01 22:54:40.630535Z] Finished install step (success) 15:54:40 INFO - [mozharness: 2017-09-01 22:54:40.630787Z] Running setup-mitmproxy step. 15:54:40 INFO - Running pre-action listener: _resource_record_pre_action 15:54:40 INFO - Running main action method: setup_mitmproxy 15:54:40 INFO - Skipping: mitmproxy is not required 15:54:40 INFO - Running post-action listener: _resource_record_post_action 15:54:40 INFO - [mozharness: 2017-09-01 22:54:40.632037Z] Finished setup-mitmproxy step (success) 15:54:40 INFO - [mozharness: 2017-09-01 22:54:40.632257Z] Running run-tests step. 15:54:40 INFO - Running pre-action listener: _resource_record_pre_action 15:54:40 INFO - Running pre-action listener: _set_gcov_prefix 15:54:40 INFO - Running main action method: run_tests 15:54:40 WARNING - Try message not found. 15:54:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:54:40 INFO - Python 2.7.3 15:54:40 INFO - Return code: 0 15:54:40 INFO - grabbing minidump binary from tooltool 15:54: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')]} 15:54:40 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 0xeb0090>, '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 0xeb0d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeaaf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:54:40 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 15:54:40 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 15:54:40 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 15:54:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:54:40 INFO - Return code: 0 15:54:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:54:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:54:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:54:40 INFO - ENV: RUST_BACKTRACE is now full 15:54:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:54:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:54:40 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/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 15:54:40 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/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 15:54:40 INFO - Using env: {'DISPLAY': ':0', 15:54:40 INFO - 'HOME': '/home/cltbld', 15:54:40 INFO - 'LANG': 'en_US.UTF-8', 15:54:40 INFO - 'LANGUAGE': 'en_US:en', 15:54:40 INFO - 'LOGNAME': 'cltbld', 15:54:40 INFO - 'MAIL': '/var/mail/cltbld', 15:54:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:54:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:40 INFO - 'MOZ_NO_REMOTE': '1', 15:54:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:40 INFO - 'NO_EM_RESTART': '1', 15:54:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:40 INFO - 'PWD': '/builds/slave/test', 15:54:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:54:40 INFO - 'RUST_BACKTRACE': 'full', 15:54:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:54:40 INFO - 'SHELL': '/bin/bash', 15:54:40 INFO - 'SHLVL': '1', 15:54:40 INFO - 'STYLO_FORCE_ENABLED': '1', 15:54:40 INFO - 'STYLO_THREADS': '1', 15:54:40 INFO - 'TERM': 'linux', 15:54:40 INFO - 'TMOUT': '86400', 15:54:40 INFO - 'USER': 'cltbld', 15:54:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:40 INFO - '_': '/tools/buildbot/bin/python'} 15:54:40 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/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 15:54:40 INFO - mozversion application_buildid: 20170901215936 15:54:40 INFO - mozversion application_changeset: 73e8f351b28ffc30e8077be21ce7298f4648ca5a 15:54:40 INFO - mozversion application_display_name: Nightly 15:54:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:54:40 INFO - mozversion application_name: Firefox 15:54:40 INFO - mozversion application_remotingname: firefox 15:54:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:54:40 INFO - mozversion application_vendor: Mozilla 15:54:40 INFO - mozversion application_version: 57.0a1 15:54:41 INFO - mozversion platform_buildid: 20170901215936 15:54:41 INFO - mozversion platform_changeset: 73e8f351b28ffc30e8077be21ce7298f4648ca5a 15:54:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:54:41 INFO - mozversion platform_version: 57.0a1 15:54:41 INFO - using testdate: 1504306480 15:54:41 INFO - actual date: 1504306480 15:54:41 INFO - starting webserver on 'localhost:33261' 15:54:41 INFO - SUITE-START | Running 2 tests 15:54:41 INFO - TEST-START | dromaeo_css 15:54:41 INFO - Initialising browser for dromaeo_css test... 15:54:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33261/getInfo.html -profile /tmp/tmp5r4cQE/profile 15:54:41 INFO - TEST-INFO | started process 15891 (/builds/slave/test/build/application/firefox/firefox http://localhost:33261/getInfo.html) 15:54:47 INFO - TEST-INFO | 15891: exit 0 15:54:47 INFO - Browser initialized. 15:54:47 INFO - Running cycle 1/1 for dromaeo_css test... 15:54:47 INFO - Using env: {'DISPLAY': ':0', 15:54:47 INFO - 'HOME': '/home/cltbld', 15:54:47 INFO - 'JSGC_DISABLE_POISONING': '1', 15:54:47 INFO - 'LANG': 'en_US.UTF-8', 15:54:47 INFO - 'LANGUAGE': 'en_US:en', 15:54:47 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:54:47 INFO - 'LOGNAME': 'cltbld', 15:54:47 INFO - 'MAIL': '/var/mail/cltbld', 15:54:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:54:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:54:47 INFO - 'MOZ_CRASHREPORTER': '1', 15:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:47 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:54:47 INFO - 'MOZ_NO_REMOTE': '1', 15:54:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:54:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:47 INFO - 'NO_EM_RESTART': '1', 15:54:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:47 INFO - 'PWD': '/builds/slave/test', 15:54:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:54:47 INFO - 'RUST_BACKTRACE': 'full', 15:54:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:54:47 INFO - 'SHELL': '/bin/bash', 15:54:47 INFO - 'SHLVL': '1', 15:54:47 INFO - 'STYLO_FORCE_ENABLED': '1', 15:54:47 INFO - 'STYLO_THREADS': '1', 15:54:47 INFO - 'TERM': 'linux', 15:54:47 INFO - 'TMOUT': '86400', 15:54:47 INFO - 'USER': 'cltbld', 15:54:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 15:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:47 INFO - '_': '/tools/buildbot/bin/python'} 15:54:47 INFO - TEST-INFO | started process 16076 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5r4cQE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:54:48 INFO - PID 16076 | 15:54:48 INFO - PID 16076 | (/builds/slave/test/build/application/firefox/firefox:16127): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:54:48 INFO - PID 16076 | 15:54:58 INFO - PID 16076 | RSS: Main: 170999808 15:54:58 INFO - PID 16076 | 15:57:57 INFO - PID 16076 | Cycle 1(1): loaded http://localhost:33261/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33261/tests/dromaeo/cssquery-ext.html) 15:57:58 INFO - PID 16076 | RSS: Main: 174137344 15:57:58 INFO - PID 16076 | 16:01:00 INFO - PID 16076 | Cycle 1(1): loaded http://localhost:33261/tests/dromaeo/cssquery-ext.html (next: http://localhost:33261/tests/dromaeo/cssquery-jquery.html) 16:01:01 INFO - PID 16076 | RSS: Main: 170434560 16:01:01 INFO - PID 16076 | 16:04:03 INFO - PID 16076 | Cycle 1(1): loaded http://localhost:33261/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33261/tests/dromaeo/cssquery-mootools.html) 16:04:03 INFO - PID 16076 | RSS: Main: 172777472 16:04:03 INFO - PID 16076 | 16:07:05 INFO - PID 16076 | Cycle 1(1): loaded http://localhost:33261/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33261/tests/dromaeo/cssquery-prototype.html) 16:07:05 INFO - PID 16076 | RSS: Main: 171253760 16:07:05 INFO - PID 16076 | 16:10:09 INFO - PID 16076 | Cycle 1(1): loaded http://localhost:33261/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33261/tests/dromaeo/cssquery-yui.html) 16:10:10 INFO - PID 16076 | RSS: Main: 174628864 16:10:10 INFO - PID 16076 | 16:13:12 INFO - PID 16076 | Cycle 1(1): loaded http://localhost:33261/tests/dromaeo/cssquery-yui.html (next: http://localhost:33261/tests/dromaeo/cssquery-dojo.html) 16:13:12 INFO - PID 16076 | RSS: Main: 175411200 16:13:12 INFO - PID 16076 | 16:13:12 INFO - PID 16076 | __start_tp_report 16:13:12 INFO - PID 16076 | _x_x_mozilla_page_load 16:13:12 INFO - PID 16076 | _x_x_mozilla_page_load_details 16:13:12 INFO - PID 16076 | |i|pagename|runs| 16:13:12 INFO - PID 16076 | |0;dojo.html;3255.1699316674258;3309.2554175310556;3313.0557791029532;3322.4684050551914;3330.833458327083;6418.358164183579;6482;6537.934620653792;6578.210614726234;6582.4075833175;6347.904781428282;6450.0324951257335;6452.64510451925;6460.127882735829;6494.51291153163;5907.054871220595;5982.102684597302;5996.580239383245;6001.789937352193;6030.185924900126;5971.790987315445;5991.550633702468;6042.486388656969;6042.758289668408;6078.756849726028;961.5550028475428;5601.131824567198;5908.172855800191;5938.406159384071;6033.336333003366;5955.761769529214;5969.701514924236;6024.457798798086;6033.064874944391;6058.878822423571;5930.525557955396;5940.138679891435;6011.879762404727;6020.4581587656885;6028.367021462727;6403.871922561522;6517.6089434633595;6521.250056243545;6557.311482294338;6593.044008618771;6941.757038503679;6986.755463558751;7047.68285427157;7053.153621565394;7057.964710176468;6835.453163746888;6920.653967301665;6927.826804329881;6930.584164950069;7048.118985126859;6419.743210271584;6495.740170393178;6529.412352888263;6530.706118224643;6547.148870646785;7168.426525877917;7267.7819665410125;7273.745418910313;7281.599512026889;7286.635668216568;6820.35206655367;6836.555623884432;6837.384635382839;6912.997615345746;6914.792556223321;6750.223724271674;6886.24251332353;6892;7010.544314619636;7029.5430796998035;6939.340762627542;6995.230524642387;7007.649617519103;7016.36852683261;7027.43780497559;6947.617881016593;7044.823879403056;7051.5063945523325;7066.3640272374705;7069.151701795818;5384.91922621161;5458.45415458459;5467.289252397163;5468.043092458804;5469.671819690831;4746.8101275948575;4773.71357718538;4794.952050479451;4820.855374338776;4848.612111031109;5835.9708201458725;5899.233099697012;5924.940750592439;5943.465088142001;5945.613535120204;6761.763338283235;6776.356246156607;6808.080909077211;6938.757143499954;6969.965150174318;6077.3314935357075;6107.358727333655;6107.725152368154;6113.755449782047;6115.449609535074;6028.788992385245;6036.245469316335;6042.154098426224;6045.758169673251;6069.02895536712;6763.807868933846;6765.830854228585;6774.525783195129;6823.93176068233;6867.450603951672;6996.195437524546;7005.649717514206;7077.1153605798245;7093.609851458219;7103.041089452858;6561.737530498917;6682.83292917681;6743.696533656095;6760.18877734675;6833.145856767904;6947.79156625302;6963.8607227854145;6972.163340399185;6974.302569743188;6982.371586557234;7033.507654464139;7116.644167791486;7135.607541585263;7136.0723105995885;7140.107486564179;6745.831354215988;6758.189017317954;6763.830904227434;6859.691313890987;6860.348266914635;6630;6710.16122984627;6711.765088221889;6712.463002959849;6727.058211850443;6626.436752875867;6650.73397064112;6651.600903945779;6732.596044237362;6739.191297044386;6864.553804002724;6871.587704737732;6888.862222755417;6898.275681053417;6921.2386637468835;6570.5400621956005;6574.3754343337305;6662.6335551545135;6676.098726672031;6680.632565208765 16:13:12 INFO - PID 16076 | |1;ext.html;785.7799816051504;846.1918867481555;872.7861673889898;875.816078623489;1126.9098472122232;29327.41345173098;30036.24909377264;30077;39118.60881391185;40269.39595906064;40622.593774062254;41125.58874411255;41164.176716465656;41234.38148427776;41250.17499650005;7242.891356629655;7651.349635280993;7805.921940780577;7882.054153501559;7909.327707144899;7695.461317707763;7796.376289896823;7799.376049916022;7800.180980996999;7802.414818888577;7784.260495252942;7840.294373506384;7846.646900889474;7847.725329613465;7850.371970242367;7718.729844455445;7798.571078590675;7810.3361214296565;7815.296623303873;7823.061232652061;7627.8855817162785;7667.501612395176;7673.808154796175;7679.923200767977;7680.308772210527;37250.813745931366;37515.62484375149;37541.436878446846;37552.24895502102;37553.06117347061;3556.3242983833247;3590.3716849551483;3591.1560783215923;3593.6765691087926;3594.3350480160993;30522.54216186759;30539.389212215854;30572.235694107603;30575.08274751761;30686.693133068606;5035.597152227813;5163.793448262065;5194.7662355193725;5196.376434827807;5228.607854410896;179592;180713;181942.09028954903;182480;182555.08722456437;46832;46960.765196174485;47128.764356178;47174.5282547177;47185;49125;49154.26268605974;49358.506414936106;49380;49397;14962.925185374004;15384.769228461582;15396.692066158614;15412.768808467881;15436.845631543765;10423.5830566778;10604.522796474177;12225.510979560719;12383.071269654813;12418.875811241953;13778.173309601256;13793.517226897011;13817.930910345585;13834.30828458573;13936.930315348358;5400.351957765093;5423.511883930465;5433.565314774809;5544.223808666709;5635.408282130399;1002.4636819301638;1010.0858722855827;1012.6303899076784;1021.6832781837505;1025.8102251083574;21946;28660.85669571639;28964.27589310242;29589.704102959124;29773.10680679583;1074.7904158689137;1083.1226706367868;1085.4138765066775;1096.4243772019754;1112.2047735868894;12364.258144511357;12396.81404778929;12504.687382815502;12511.624651260478;16098.75851862223;227593;229447;229861;230990;231108;55270.72364638151;55671;55803;55877.44122558804;56090.43909560934;1436.8275806903239;1449.8840092792552;1475.970480590382;1479.681868398278;1489.6946126044136;26549.336266592716;26669.733302666726;27108.29573410598;27558.311042224104;27732.722672773016;37270.44094338533;37376;37397.43903841499;37470;37524.249515010095;203694;204396;204694;204746;205024;33119.66880331262;36459.45310820287;36876;36914.63085369112;36942;43169.13661726685;44194.558054419045;44604;44729;45080.77459612681;32694.83652581722;32761.67238327586;32836.67163328336;32903.34193316168;33249.335013300086;32491.512627310138;32543.511847321835;32731.509027364133;32994.34011319808;33387.332253355286;36362.8181859089;37173;37245.81377093206;37254.06864828401;37269.254614908095;31820.204494887814;32082.35835283328;32099.197520062186;32332.35335293234;32369;23655.172068977503;23858.40353991164;24563.87718061398;24630.753692462848;24756 16:13:12 INFO - PID 16076 | |2;jquery.html;550.7195769835133;552.1689856765568;562.3982338897379;564.7656222667594;568.4628026514944;21797.782022179792;22428;22551.097956081758;22707.432314192134;22753.431164220903;25133.748662513368;25623.487530249386;25646;25652.743472565267;25661.101861434854;6662.267150613441;6825.795226143199;6839.897401538981;6843.726250949956;6866.485013623973;5963.671998040106;6516;6524.902126468107;6564.737410503598;6702.932970670279;6471.0940468334475;6475.287718350977;6480.57876238043;6487.902681459758;6491.28595854458;6472.7410903563805;6473.514486413538;6474.449671777905;6476.546641735081;6479.773207937723;6029.78895738647;6038.426349496791;6038.818835434944;6039.003564411867;6040.728167232441;27869.58195627067;28821.855890720413;28956.42087158266;29167.124986250445;29457.55813662797;2015.2946468735972;2084.6351888419467;2095.570408066358;2098.590774798915;2104.7895210478982;19367.90316048425;19886;19938.60122797536;19963.90018049915;20004.499887502785;2401.5677178107935;2417.226487523993;2435.890384932682;2441.4872876695913;2503.699556053285;131021.34489327493;134551;135511.32244338814;135692;136165;14050.227237501831;14762.630934226516;14815.25923703832;15018;15085.54743357701;21279.148834046773;22733.43166420853;23062.42343941415;23083.192088276828;23140.65289020666;9801.852972205423;9936.652217172508;10010.099091081838;10078.244131690153;10088.243381746252;9073.909260907438;9104.226140777975;9107.316951228684;9211.447313161232;9260.536973151315;9173.357864949523;9469.810603787886;9483.47840868745;9493.382930109521;9541.141297283279;2731.6995130535624;2747.3406382468465;2772.223777342347;2790.246633408968;2879.7696184305205;957.7940742740205;969.2585172343163;992.9156021738089;996.5415908682088;1008.5058321422597;19605.705914411294;19628.803711962984;19896.204151833765;20164.294249701485;20197.49506262355;1026.5277972132903;1041.95832166714;1048.2452634103433;1060.4008735064663;1061.851340812287;10826.837597436044;10910.563577456971;10912.56349746017;10914.290571112851;11254.774904501865;438276;457219;457318;472384;472486;104640;111917;112069;114472;121453;1199.7300607363272;1215.9695369697893;1239.6528971888067;1244.0980289290192;1244.321844594698;20835.270765523135;21232.7876721237;21286.680699789205;21289.467763306042;21307.14771409126;56632;58106.4189358101;58218.7089064552;59694;61689.69155154195;69017;69303;69640.65179674272;69986.30013699798;70149.29850701631;14182.219977901315;14651.267436627913;14680.119192848464;14697.55907322782;14704.852951470348;23016.42458938473;23084.538309234056;23227;23306.766932330458;23351.182708605116;18235.726464102783;18546.907265463586;18662;18739.812601873808;18870.811291886905;16405.671886561962;16658.91670541688;16795.832041679427;16811.579710507336;17377.304907803547;18722;19081.618367632847;19169;19185.90407047956;19375.224991000767;14436.639084022983;14451.855481445471;14454.421823127377;14476.493322733653;14488.85511144875;17766.378176763752;18186.36347727823;18479.53801154982;18527.81472185261;18541.2583496664 16:13:12 INFO - PID 16076 | |3;mootools.html;1317.8484474285458;1355.2681551961941;1374.2029622818766;1374.2922394966588;1385.4042761612507;14925;15113.395464181447;15557.766633500492;15573.37706491739;15578.22108894555;15605.375784968588;15849.920750396232;15851;15875.36498540057;15879.920600396981;2661.7072122066556;2723.183045086476;2725.7683096936685;2726.8636568171514;2727.481778462096;2696.5550684137193;2783.151138902634;2802.929926751827;2805.3126983888974;2809.452156829409;2647.9735202648017;2691.717369676185;2692.8653567321653;2696.7303269672975;2699.392636656756;2598.896044158241;2621.947561048778;2650.6156607291837;2650.8542030188237;2655.2830735701446;2646.298730836311;2649.1919964410845;2662.8402295862115;2665.840049597011;2669.27929459045;14637.926810365987;14933.25333733309;14956.700865982728;14960;14968.47610333644;8026.277635012877;8307.83384332316;8309.709160179425;8309.792255193608;8310.958445207736;5384.313316134576;5579.274694289757;5669.40471250521;5697.259356283698;5706.857328566777;1878.5209771508178;1889.9622007559772;1904.2763749775136;1950.502621831438;1972.2406873353718;26166.6075008875;26388.472230555282;26402.207933761827;26433.47133057328;26501.60247596268;6602.801915942529;6715.764948226789;6764.5603035802515;6862.9313706862295;6868.793936181923;16911.49265522036;17016.149192540324;17092.48722538326;17136.057516836692;17173.91413042952;7910.881336779953;7919.841603167904;7920.5247685139075;7922.247386498273;7924.881126783102;5824.796132135355;6526.24948130971;7301.269873012762;7464.962675186698;7478.85042299151;5204.583633309327;5315.893682126336;5363.731813409314;5363.865903352369;5468.671879687232;1495;1514.9166795826116;1518.7949626800462;1526.809148856393;1536.5313579358403;834.870595057767;838.1995194589152;848.4781859156574;848.5078654380444;865.5196366016795;7008.33420825031;7016.2632923543315;7031.683574239171;7065.611391373421;7090.468214883905;684.4387602166275;715.2811424518429;719.2843121094544;721.8339781850099;722.0432926372472;4487.483939346952;4504.527024662363;4570.8171673133365;4576.885577860514;4631.861044168681;20857.269995550385;21926.232581859877;21986.560268794536;22055;22233.44416389603;20636.380908572766;21594.784052159277;21614.56770864574;21626.783732162792;21642.458938526663;10837.458127093614;11099.056580190612;11099.056580190774;11114.166437517219;11169.609063682732;8752.21230089295;9031.45811251327;9141.3601047926;9160.679376222068;9250.95374523123;18485.81514184841;18529.81470185335;18764;18973.90513047426;19327.420177394702;18667;18963.715544267125;19013.33453329127;19121.08789121077;19232;12085.335306558223;12275.386230688608;12344.13591048618;12344.62966111018;12348.567800126952;13320.267385293902;13459.259740714355;13579.59261222165;13777.862221377658;13787.172769633855;10826.133909287395;10931.508082136483;11035.944820275847;11207.551697932318;11276.887231127585;10927;10933.671989840317;11059.668209953714;11291.3789741565;11454.083673306282;9150.725478235663;9215.078492151;9299.814003720023;9434.481103539096;9459.858102128612;7196.712131514681;7243;7282.4902256841515;7291.380232680282;7312.744053958086;7309.8903516446235;7362.6686799093095;7433.479656423999;7472.29013243741;7480.3641690456925 16:13:12 INFO - PID 16076 | |4;prototype.html;496.16891706391783;499.35583097803834;532.9546988505977;538.2464549630514;540.8945255675144;6424.807255782328;6445.838854028651;6463.159789227399;6475.158229430168;6593.802185934418;6105.328413874481;6216.191895053648;6218.383220362704;6259.374062593738;6334.036448700906;5014.548690617844;5017.774200161001;5099.311592934962;5118.641695081327;5154.32993710817;4918.213085906255;5061.721605311706;5072.3405957225505;5080.263361812535;5102.566281866046;5007.14878470659;5009.899802003958;5079.323823138374;5084.211947148198;5108.5913126949945;4989.750512474362;4992.550670439678;5044.672096313728;5066.69599824008;5087.109755792721;4515.193922431021;4530.79611417487;4545.499995000548;4562.6121779648765;4585.587297143273;6223.408776166257;6269.899583639441;6330.923742963707;6338.841528961767;6368.140301059344;5780.913286300709;5784.595078344518;5859.29688437386;5870.471657550841;5887.587868849182;6573.967130164367;6576.276609572943;6578.703958321839;6653.941095931526;6675.432588230006;4878.780454879539;4901.289313049623;4912.238603016538;4949.82675606352;4985.202367621199;7015.719371225196;7022.683979220897;7029.89455158173;7082.645867706646;7132.144142702857;5031.52200540948;5046.34397528319;5048.242763585492;5075.796968121308;5082.720450375265;5127.666701664454;5133.947540754211;5138.30632864566;5158.535731784157;5184.9740751296;5825.88348233033;5847.26909136358;5866.266716660418;5881.617694849821;5883.735231914574;6708.899366509506;6715.496337774686;6742.494312926452;6761.830954226184;6778.864422711518;1673.21358961288;1714.7770789797496;1728.161841506868;1733.7572739816435;1741.5384922995415;1205.9457324420423;1227.545808051009;1233.3586535001928;1237.665830225852;1261.186534685123;5835.607400965584;5888.823335299948;5926.081457374028;5934.732937017759;5954.345022047572;6698.765543206062;6769.898451523231;6789.321067893171;6819.590824550443;6825.55633883796;5829.766809327664;5905.4685078343155;5905.498032667186;5909.438603332676;5963.582549221513;3633.94549081764;3636.6726994570618;3648.2338708871434;3670.944935825965;3697.4084146536425;6619.404253617197;6641.66791660415;6669.666516674147;6720.857454232694;6747.426468750211;6967.825804354931;7082.362587367059;7138.821529461701;7161.785146445615;7182.317679820409;6614.8910012294145;6737.8652426951185;6756.358145976124;6799.586146917773;6805.625690587065;6937.236903940461;6951.235364110045;6984.161900571964;7028.191757947901;7037.542559733601;6880.311968803079;6906.896396553955;6912.135983002125;6992.762316059016;7029.507934444539;6626.204855417381;6740.494462915399;6760.985852122221;6794.010635320641;6799.728010879509;5600.207933461254;5612.915806262991;5621.943780562306;5632.5775566831835;5643;6859.005444210462;6889.724411023503;6919.411849992807;6920.411765000032;6992.61540615271;6499.772507962199;6547.312532184052;6592.31945318829;6696.593715319643;6699.9665001674675;6374.681265936777;6504.511886798095;6635.777014700129;6638.767643132467;6671.933280667326;6390.233172019387;6515.54391192612;6565.113709649328;6571.178602674666;6608.306127856506;6655.966720166368;6677.265500795006;6711;6714.429273511806;6729.360710732472;6245;6426.807195784134;6431.774887878902;6455.354464553507;6485.3514648534765 16:13:12 INFO - PID 16076 | |5;yui.html;907.9909200907991;915.482752244982;924.7826760711232;926.1803304075895;927.2026057590475;17323.82676173238;17723.468295951126;17729.822701772995;17760.111994400293;18846.71729924049;20414.183432662714;21093;21275.36173914781;21316.14735410582;21357.893210533963;959.1559427703611;974.7027156717198;979.4025644356936;979.5102448775613;979.902009799018;954.236610711428;992.9156021738161;1004.7136566078669;1006.0543089495887;1006.1849901579744;1064.4677661169414;1066.3015724700333;1066.3335415365398;1066.4294602387724;1066.8826429092794;1061.0185578340042;1062.606835470873;1062.9627963021296;1063.0432610650398;1065.0521036277719;773.1649818196335;773.7446642607925;774.5120574038376;775.1202385292677;782.3311069036006;18947.43157705271;18960.81039189618;19101.522461938424;19117.330893418664;19372;20665.89667051655;20666.896665516728;21058.5788284235;21072.789272107235;21426.5714685707;15606.453774117852;15611.141387223699;15703.293351799197;15753.369865205377;17549.21028553718;3766.190269092131;3817.9809100954594;3820.8089595520114;3822.5412950446125;3827.291950989048;120987;125389;125970;126204;127725;15875.841241587666;16189.352425903067;16233.83766162347;16469.176541172892;16582.83417165837;30194;30303.848480757457;30325;30404.695953040628;30418.84790576033;8857.37998340123;8965.148310910454;8986.955065224764;8997.460152390877;8998.55007249635;4319.956800431955;4463.553644635511;4476.798544065525;4481.820727170877;4487.7980490877335;5665.093585026376;5806.1581070744505;5856.560757943172;5856.707164641751;5857.062869940874;1023.3910823060268;1040.9323393979366;1063.2132222155549;1063.611781699674;1066.1311031509294;674.2953613473834;683.8871586188322;688.8174633722068;699.3356311504092;703.3212949503651;11821.645350639494;11991.820122698167;12027.518899244107;12382.5666101686;13129.803052954214;684.9452043836482;693.4279219643814;696.7735485967081;710.0556260173956;712.0636363182399;5398.163284690879;5624.21261023457;5665.603407761417;5830.125481177773;5844.473997340175;106786;108195;108283;108537.45731271293;108658;94119.52940235392;94501.5274923621;95632;98544.50727746316;100179.49910250402;82520.58739706383;83793.58103209444;84597.57701211455;84790.15209847823;86501.13498865056;12527.749445010866;12545.184563003375;12607.87392126067;12622.873771262537;13091.934540327238;39044.804775975936;40550.59449405586;40847;41419.378709319986;42482.36276455918;65203;66339.66830165818;66380.66809666115;67120.66439667965;68783.3121668797;18920.243190272784;19172.233110675417;19187;19235.4229373119;19265.13306901221;20992.68510972306;21273.149074037483;21441.46396340104;21478.14087436485;21569.137234510443;19213.711794322815;19375.8062419374;19526.121324540145;19849;19942.50143746418;18256.634867302313;19020.90489547599;19029.429117126507;19300.613987719888;20041.39875803728;14374.784378234544;14392.640183995483;18823.34118305853;18841.057947102316;19453.708194376813;11481.081513479068;11690.123240756808;11715.648530544097;11863.228890122115;11873.109516786448;14021.228832414316;14455.204963726675;14743.926280368529;14937.477188298357;15392.615184620026 16:13:12 INFO - PID 16076 | __end_tp_report 16:13:12 INFO - PID 16076 | __start_cc_report 16:13:12 INFO - PID 16076 | _x_x_mozilla_cycle_collect,363 16:13:12 INFO - PID 16076 | __end_cc_report 16:13:12 INFO - PID 16076 | __startTimestamp1504307592344__endTimestamp 16:13:12 INFO - PID 16076 | 16:13:12 INFO - PID 16076 | ------- Summary: start ------- 16:13:12 INFO - PID 16076 | Number of tests: 6 16:13:12 INFO - PID 16076 | 16:13:12 INFO - PID 16076 | [#0] dojo.html Cycles:165 Average:6384.97 Median:6676.10 stddev:879.78 (13.2%) stddev-sans-first:847.52 16:13:12 INFO - PID 16076 | Values: 3255.2 3309.3 3313.1 3322.5 3330.8 6418.4 6482.0 6537.9 6578.2 6582.4 6347.9 6450.0 6452.6 6460.1 6494.5 5907.1 5982.1 5996.6 6001.8 6030.2 5971.8 5991.6 6042.5 6042.8 6078.8 961.6 5601.1 5908.2 5938.4 6033.3 5955.8 5969.7 6024.5 6033.1 6058.9 5930.5 5940.1 6011.9 6020.5 6028.4 6403.9 6517.6 6521.3 6557.3 6593.0 6941.8 6986.8 7047.7 7053.2 7058.0 6835.5 6920.7 6927.8 6930.6 7048.1 6419.7 6495.7 6529.4 6530.7 6547.1 7168.4 7267.8 7273.7 7281.6 7286.6 6820.4 6836.6 6837.4 6913.0 6914.8 6750.2 6886.2 6892.0 7010.5 7029.5 6939.3 6995.2 7007.6 7016.4 7027.4 6947.6 7044.8 7051.5 7066.4 7069.2 5384.9 5458.5 5467.3 5468.0 5469.7 4746.8 4773.7 4795.0 4820.9 4848.6 5836.0 5899.2 5924.9 5943.5 5945.6 6761.8 6776.4 6808.1 6938.8 6970.0 6077.3 6107.4 6107.7 6113.8 6115.4 6028.8 6036.2 6042.2 6045.8 6069.0 6763.8 6765.8 6774.5 6823.9 6867.5 6996.2 7005.6 7077.1 7093.6 7103.0 6561.7 6682.8 6743.7 6760.2 6833.1 6947.8 6963.9 6972.2 6974.3 6982.4 7033.5 7116.6 7135.6 7136.1 7140.1 6745.8 6758.2 6763.8 6859.7 6860.3 6630.0 6710.2 6711.8 6712.5 6727.1 6626.4 6650.7 6651.6 6732.6 6739.2 6864.6 6871.6 6888.9 6898.3 6921.2 6570.5 6574.4 6662.6 6676.1 6680.6 16:13:12 INFO - PID 16076 | 16:13:12 INFO - PID 16076 | [#1] ext.html Cycles:180 Average:37614.48 Median:3573.35 stddev:53384.24 (1494.0%) stddev-sans-first:53462.37 16:13:12 INFO - PID 16076 | Values: 785.8 846.2 872.8 875.8 1126.9 29327.4 30036.2 30077.0 39118.6 40269.4 40622.6 41125.6 41164.2 41234.4 41250.2 7242.9 7651.3 7805.9 7882.1 7909.3 7695.5 7796.4 7799.4 7800.2 7802.4 7784.3 7840.3 7846.6 7847.7 7850.4 7718.7 7798.6 7810.3 7815.3 7823.1 7627.9 7667.5 7673.8 7679.9 7680.3 37250.8 37515.6 37541.4 37552.2 37553.1 3556.3 3590.4 3591.2 3593.7 3594.3 30522.5 30539.4 30572.2 30575.1 30686.7 5035.6 5163.8 5194.8 5196.4 5228.6 179592.0 180713.0 181942.1 182480.0 182555.1 46832.0 46960.8 47128.8 47174.5 47185.0 49125.0 49154.3 49358.5 49380.0 49397.0 14962.9 15384.8 15396.7 15412.8 15436.8 10423.6 10604.5 12225.5 12383.1 12418.9 13778.2 13793.5 13817.9 13834.3 13936.9 5400.4 5423.5 5433.6 5544.2 5635.4 1002.5 1010.1 1012.6 1021.7 1025.8 21946.0 28660.9 28964.3 29589.7 29773.1 1074.8 1083.1 1085.4 1096.4 1112.2 12364.3 12396.8 12504.7 12511.6 16098.8 227593.0 229447.0 229861.0 230990.0 231108.0 55270.7 55671.0 55803.0 55877.4 56090.4 1436.8 1449.9 1476.0 1479.7 1489.7 26549.3 26669.7 27108.3 27558.3 27732.7 37270.4 37376.0 37397.4 37470.0 37524.2 203694.0 204396.0 204694.0 204746.0 205024.0 33119.7 36459.5 36876.0 36914.6 36942.0 43169.1 44194.6 44604.0 44729.0 45080.8 32694.8 32761.7 32836.7 32903.3 33249.3 32491.5 32543.5 32731.5 32994.3 33387.3 36362.8 37173.0 37245.8 37254.1 37269.3 31820.2 32082.4 32099.2 32332.4 32369.0 23655.2 23858.4 24563.9 24630.8 24756.0 16:13:12 INFO - PID 16076 | 16:13:12 INFO - PID 16076 | [#2] jquery.html Cycles:180 Average:33852.13 Median:22884.93 stddev:77704.55 (339.5%) stddev-sans-first:77882.31 16:13:12 INFO - PID 16076 | Values: 550.7 552.2 562.4 564.8 568.5 21797.8 22428.0 22551.1 22707.4 22753.4 25133.7 25623.5 25646.0 25652.7 25661.1 6662.3 6825.8 6839.9 6843.7 6866.5 5963.7 6516.0 6524.9 6564.7 6702.9 6471.1 6475.3 6480.6 6487.9 6491.3 6472.7 6473.5 6474.4 6476.5 6479.8 6029.8 6038.4 6038.8 6039.0 6040.7 27869.6 28821.9 28956.4 29167.1 29457.6 2015.3 2084.6 2095.6 2098.6 2104.8 19367.9 19886.0 19938.6 19963.9 20004.5 2401.6 2417.2 2435.9 2441.5 2503.7 131021.3 134551.0 135511.3 135692.0 136165.0 14050.2 14762.6 14815.3 15018.0 15085.5 21279.1 22733.4 23062.4 23083.2 23140.7 9801.9 9936.7 10010.1 10078.2 10088.2 9073.9 9104.2 9107.3 9211.4 9260.5 9173.4 9469.8 9483.5 9493.4 9541.1 2731.7 2747.3 2772.2 2790.2 2879.8 957.8 969.3 992.9 996.5 1008.5 19605.7 19628.8 19896.2 20164.3 20197.5 1026.5 1042.0 1048.2 1060.4 1061.9 10826.8 10910.6 10912.6 10914.3 11254.8 438276.0 457219.0 457318.0 472384.0 472486.0 104640.0 111917.0 112069.0 114472.0 121453.0 1199.7 1216.0 1239.7 1244.1 1244.3 20835.3 21232.8 21286.7 21289.5 21307.1 56632.0 58106.4 58218.7 59694.0 61689.7 69017.0 69303.0 69640.7 69986.3 70149.3 14182.2 14651.3 14680.1 14697.6 14704.9 23016.4 23084.5 23227.0 23306.8 23351.2 18235.7 18546.9 18662.0 18739.8 18870.8 16405.7 16658.9 16795.8 16811.6 17377.3 18722.0 19081.6 19169.0 19185.9 19375.2 14436.6 14451.9 14454.4 14476.5 14488.9 17766.4 18186.4 18479.5 18527.8 18541.3 16:13:12 INFO - PID 16076 | 16:13:12 INFO - PID 16076 | [#3] mootools.html Cycles:180 Average:9304.30 Median:14576.23 stddev:6705.49 (46.0%) stddev-sans-first:6697.45 16:13:12 INFO - PID 16076 | Values: 1317.8 1355.3 1374.2 1374.3 1385.4 14925.0 15113.4 15557.8 15573.4 15578.2 15605.4 15849.9 15851.0 15875.4 15879.9 2661.7 2723.2 2725.8 2726.9 2727.5 2696.6 2783.2 2802.9 2805.3 2809.5 2648.0 2691.7 2692.9 2696.7 2699.4 2598.9 2621.9 2650.6 2650.9 2655.3 2646.3 2649.2 2662.8 2665.8 2669.3 14637.9 14933.3 14956.7 14960.0 14968.5 8026.3 8307.8 8309.7 8309.8 8311.0 5384.3 5579.3 5669.4 5697.3 5706.9 1878.5 1890.0 1904.3 1950.5 1972.2 26166.6 26388.5 26402.2 26433.5 26501.6 6602.8 6715.8 6764.6 6862.9 6868.8 16911.5 17016.1 17092.5 17136.1 17173.9 7910.9 7919.8 7920.5 7922.2 7924.9 5824.8 6526.2 7301.3 7465.0 7478.9 5204.6 5315.9 5363.7 5363.9 5468.7 1495.0 1514.9 1518.8 1526.8 1536.5 834.9 838.2 848.5 848.5 865.5 7008.3 7016.3 7031.7 7065.6 7090.5 684.4 715.3 719.3 721.8 722.0 4487.5 4504.5 4570.8 4576.9 4631.9 20857.3 21926.2 21986.6 22055.0 22233.4 20636.4 21594.8 21614.6 21626.8 21642.5 10837.5 11099.1 11099.1 11114.2 11169.6 8752.2 9031.5 9141.4 9160.7 9251.0 18485.8 18529.8 18764.0 18973.9 19327.4 18667.0 18963.7 19013.3 19121.1 19232.0 12085.3 12275.4 12344.1 12344.6 12348.6 13320.3 13459.3 13579.6 13777.9 13787.2 10826.1 10931.5 11035.9 11207.6 11276.9 10927.0 10933.7 11059.7 11291.4 11454.1 9150.7 9215.1 9299.8 9434.5 9459.9 7196.7 7243.0 7282.5 7291.4 7312.7 7309.9 7362.7 7433.5 7472.3 7480.4 16:13:12 INFO - PID 16076 | 16:13:12 INFO - PID 16076 | [#4] prototype.html Cycles:180 Average:5647.12 Median:6264.64 stddev:1594.09 (25.4%) stddev-sans-first:1550.97 16:13:12 INFO - PID 16076 | Values: 496.2 499.4 533.0 538.2 540.9 6424.8 6445.8 6463.2 6475.2 6593.8 6105.3 6216.2 6218.4 6259.4 6334.0 5014.5 5017.8 5099.3 5118.6 5154.3 4918.2 5061.7 5072.3 5080.3 5102.6 5007.1 5009.9 5079.3 5084.2 5108.6 4989.8 4992.6 5044.7 5066.7 5087.1 4515.2 4530.8 4545.5 4562.6 4585.6 6223.4 6269.9 6330.9 6338.8 6368.1 5780.9 5784.6 5859.3 5870.5 5887.6 6574.0 6576.3 6578.7 6653.9 6675.4 4878.8 4901.3 4912.2 4949.8 4985.2 7015.7 7022.7 7029.9 7082.6 7132.1 5031.5 5046.3 5048.2 5075.8 5082.7 5127.7 5133.9 5138.3 5158.5 5185.0 5825.9 5847.3 5866.3 5881.6 5883.7 6708.9 6715.5 6742.5 6761.8 6778.9 1673.2 1714.8 1728.2 1733.8 1741.5 1205.9 1227.5 1233.4 1237.7 1261.2 5835.6 5888.8 5926.1 5934.7 5954.3 6698.8 6769.9 6789.3 6819.6 6825.6 5829.8 5905.5 5905.5 5909.4 5963.6 3633.9 3636.7 3648.2 3670.9 3697.4 6619.4 6641.7 6669.7 6720.9 6747.4 6967.8 7082.4 7138.8 7161.8 7182.3 6614.9 6737.9 6756.4 6799.6 6805.6 6937.2 6951.2 6984.2 7028.2 7037.5 6880.3 6906.9 6912.1 6992.8 7029.5 6626.2 6740.5 6761.0 6794.0 6799.7 5600.2 5612.9 5621.9 5632.6 5643.0 6859.0 6889.7 6919.4 6920.4 6992.6 6499.8 6547.3 6592.3 6696.6 6700.0 6374.7 6504.5 6635.8 6638.8 6671.9 6390.2 6515.5 6565.1 6571.2 6608.3 6656.0 6677.3 6711.0 6714.4 6729.4 6245.0 6426.8 6431.8 6455.4 6485.4 16:13:12 INFO - PID 16076 | 16:13:12 INFO - PID 16076 | [#5] yui.html Cycles:180 Average:23567.21 Median:20666.40 stddev:31588.09 (152.8%) stddev-sans-first:31630.88 16:13:12 INFO - PID 16076 | Values: 908.0 915.5 924.8 926.2 927.2 17323.8 17723.5 17729.8 17760.1 18846.7 20414.2 21093.0 21275.4 21316.1 21357.9 959.2 974.7 979.4 979.5 979.9 954.2 992.9 1004.7 1006.1 1006.2 1064.5 1066.3 1066.3 1066.4 1066.9 1061.0 1062.6 1063.0 1063.0 1065.1 773.2 773.7 774.5 775.1 782.3 18947.4 18960.8 19101.5 19117.3 19372.0 20665.9 20666.9 21058.6 21072.8 21426.6 15606.5 15611.1 15703.3 15753.4 17549.2 3766.2 3818.0 3820.8 3822.5 3827.3 120987.0 125389.0 125970.0 126204.0 127725.0 15875.8 16189.4 16233.8 16469.2 16582.8 30194.0 30303.8 30325.0 30404.7 30418.8 8857.4 8965.1 8987.0 8997.5 8998.6 4320.0 4463.6 4476.8 4481.8 4487.8 5665.1 5806.2 5856.6 5856.7 5857.1 1023.4 1040.9 1063.2 1063.6 1066.1 674.3 683.9 688.8 699.3 703.3 11821.6 11991.8 12027.5 12382.6 13129.8 684.9 693.4 696.8 710.1 712.1 5398.2 5624.2 5665.6 5830.1 5844.5 106786.0 108195.0 108283.0 108537.5 108658.0 94119.5 94501.5 95632.0 98544.5 100179.5 82520.6 83793.6 84597.6 84790.2 86501.1 12527.7 12545.2 12607.9 12622.9 13091.9 39044.8 40550.6 40847.0 41419.4 42482.4 65203.0 66339.7 66380.7 67120.7 68783.3 18920.2 19172.2 19187.0 19235.4 19265.1 20992.7 21273.1 21441.5 21478.1 21569.1 19213.7 19375.8 19526.1 19849.0 19942.5 18256.6 19020.9 19029.4 19300.6 20041.4 14374.8 14392.6 18823.3 18841.1 19453.7 11481.1 11690.1 11715.6 11863.2 11873.1 14021.2 14455.2 14743.9 14937.5 15392.6 16:13:12 INFO - PID 16076 | -------- Summary: end -------- 16:13:12 INFO - PID 16076 | 16:13:13 INFO - TEST-INFO | 16076: exit 0 16:13:13 INFO - TEST-OK | dromaeo_css | took 1112123ms 16:13:13 INFO - TEST-START | kraken 16:13:13 INFO - Initialising browser for kraken test... 16:13:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33261/getInfo.html -profile /tmp/tmpUyMq4R/profile 16:13:13 INFO - TEST-INFO | started process 16452 (/builds/slave/test/build/application/firefox/firefox http://localhost:33261/getInfo.html) 16:13:19 INFO - TEST-INFO | 16452: exit 0 16:13:19 INFO - Browser initialized. 16:13:19 INFO - Running cycle 1/1 for kraken test... 16:13:19 INFO - Using env: {'DISPLAY': ':0', 16:13:19 INFO - 'HOME': '/home/cltbld', 16:13:19 INFO - 'JSGC_DISABLE_POISONING': '1', 16:13:19 INFO - 'LANG': 'en_US.UTF-8', 16:13:19 INFO - 'LANGUAGE': 'en_US:en', 16:13:19 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:13:19 INFO - 'LOGNAME': 'cltbld', 16:13:19 INFO - 'MAIL': '/var/mail/cltbld', 16:13:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:13:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:13:19 INFO - 'MOZ_CRASHREPORTER': '1', 16:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:19 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:13:19 INFO - 'MOZ_NO_REMOTE': '1', 16:13:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:19 INFO - 'NO_EM_RESTART': '1', 16:13:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:19 INFO - 'PWD': '/builds/slave/test', 16:13:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:13:19 INFO - 'RUST_BACKTRACE': 'full', 16:13:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:13:19 INFO - 'SHELL': '/bin/bash', 16:13:19 INFO - 'SHLVL': '1', 16:13:19 INFO - 'STYLO_FORCE_ENABLED': '1', 16:13:19 INFO - 'STYLO_THREADS': '1', 16:13:19 INFO - 'TERM': 'linux', 16:13:19 INFO - 'TMOUT': '86400', 16:13:19 INFO - 'USER': 'cltbld', 16:13:19 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873', 16:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:19 INFO - '_': '/tools/buildbot/bin/python'} 16:13:19 INFO - TEST-INFO | started process 16639 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUyMq4R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:20 INFO - PID 16639 | 16:13:20 INFO - PID 16639 | (/builds/slave/test/build/application/firefox/firefox:16690): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:13:20 INFO - PID 16639 | 16:13:30 INFO - PID 16639 | RSS: Main: 170938368 16:13:30 INFO - PID 16639 | 16:13:33 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33261/tests/kraken/driver.html?testName=audio-beat-detection) 16:13:34 INFO - PID 16639 | RSS: Main: 175529984 16:13:34 INFO - PID 16639 | 16:13:36 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33261/tests/kraken/driver.html?testName=audio-dft) 16:13:37 INFO - PID 16639 | RSS: Main: 176287744 16:13:37 INFO - PID 16639 | 16:13:40 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33261/tests/kraken/driver.html?testName=audio-fft) 16:13:41 INFO - PID 16639 | RSS: Main: 171286528 16:13:41 INFO - PID 16639 | 16:13:43 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33261/tests/kraken/driver.html?testName=audio-oscillator) 16:13:43 INFO - PID 16639 | RSS: Main: 171843584 16:13:43 INFO - PID 16639 | 16:13:46 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33261/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:13:46 INFO - PID 16639 | RSS: Main: 172736512 16:13:46 INFO - PID 16639 | 16:13:49 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33261/tests/kraken/driver.html?testName=imaging-darkroom) 16:13:50 INFO - PID 16639 | RSS: Main: 174686208 16:13:50 INFO - PID 16639 | 16:13:54 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33261/tests/kraken/driver.html?testName=imaging-desaturate) 16:13:54 INFO - PID 16639 | RSS: Main: 174579712 16:13:54 INFO - PID 16639 | 16:13:57 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33261/tests/kraken/driver.html?testName=json-parse-financial) 16:13:58 INFO - PID 16639 | RSS: Main: 174727168 16:13:58 INFO - PID 16639 | 16:13:59 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33261/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:14:00 INFO - PID 16639 | RSS: Main: 174243840 16:14:00 INFO - PID 16639 | 16:14:02 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33261/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:14:02 INFO - PID 16639 | RSS: Main: 174731264 16:14:02 INFO - PID 16639 | 16:14:04 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33261/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:14:05 INFO - PID 16639 | RSS: Main: 171638784 16:14:05 INFO - PID 16639 | 16:14:07 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33261/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:14:08 INFO - PID 16639 | RSS: Main: 172220416 16:14:08 INFO - PID 16639 | 16:14:11 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33261/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:14:12 INFO - PID 16639 | RSS: Main: 171606016 16:14:12 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | Cycle 1(1): loaded http://localhost:33261/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33261/tests/kraken/driver.html?testName=ai-astar) 16:14:14 INFO - PID 16639 | RSS: Main: 173756416 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | __start_tp_report 16:14:14 INFO - PID 16639 | _x_x_mozilla_page_load 16:14:14 INFO - PID 16639 | _x_x_mozilla_page_load_details 16:14:14 INFO - PID 16639 | |i|pagename|runs| 16:14:14 INFO - PID 16639 | |0;ai-astar;96;107;102;107;105;106;106;98;107;109 16:14:14 INFO - PID 16639 | |1;audio-beat-detection;152;119;143;190;121;150;142;120;176;123 16:14:14 INFO - PID 16639 | |2;audio-dft;164;162;162;161;161;162;160;163;168;166 16:14:14 INFO - PID 16639 | |3;audio-fft;73;73;75;171;73;74;73;74;169;73 16:14:14 INFO - PID 16639 | |4;audio-oscillator;79;80;135;79;79;78;123;80;79;134 16:14:14 INFO - PID 16639 | |5;imaging-gaussian-blur;114;113;114;114;114;114;114;115;115;114 16:14:14 INFO - PID 16639 | |6;imaging-darkroom;148;147;148;147;147;148;148;148;147;147 16:14:14 INFO - PID 16639 | |7;imaging-desaturate;94;94;100;94;100;93;94;94;94;94 16:14:14 INFO - PID 16639 | |8;json-parse-financial;53;54;55;54;53;54;54;53;53;53 16:14:14 INFO - PID 16639 | |9;json-stringify-tinderbox;38;38;40;38;38;37;38;38;38;38 16:14:14 INFO - PID 16639 | |10;stanford-crypto-aes;80;72;74;74;75;74;73;72;73;73 16:14:14 INFO - PID 16639 | |11;stanford-crypto-ccm;119;127;122;129;132;123;114;121;119;168 16:14:14 INFO - PID 16639 | |12;stanford-crypto-pbkdf2;170;175;174;168;168;177;167;172;178;169 16:14:14 INFO - PID 16639 | |13;stanford-crypto-sha256-iterative;66;64;63;65;66;62;64;67;69;63 16:14:14 INFO - PID 16639 | __end_tp_report 16:14:14 INFO - PID 16639 | __start_cc_report 16:14:14 INFO - PID 16639 | _x_x_mozilla_cycle_collect,901 16:14:14 INFO - PID 16639 | __end_cc_report 16:14:14 INFO - PID 16639 | __startTimestamp1504307654438__endTimestamp 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | ------- Summary: start ------- 16:14:14 INFO - PID 16639 | Number of tests: 14 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#0] ai-astar Cycles:10 Average:104.30 Median:107.00 stddev:4.27 (4.0%) stddev-sans-first:3.31 16:14:14 INFO - PID 16639 | Values: 96.0 107.0 102.0 107.0 105.0 106.0 106.0 98.0 107.0 109.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:146.50 stddev:24.53 (16.7%) stddev-sans-first:25.83 16:14:14 INFO - PID 16639 | Values: 152.0 119.0 143.0 190.0 121.0 150.0 142.0 120.0 176.0 123.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#2] audio-dft Cycles:10 Average:162.90 Median:162.50 stddev:2.47 (1.5%) stddev-sans-first:2.59 16:14:14 INFO - PID 16639 | Values: 164.0 162.0 162.0 161.0 161.0 162.0 160.0 163.0 168.0 166.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#3] audio-fft Cycles:10 Average:92.80 Median:73.00 stddev:40.70 (55.7%) stddev-sans-first:42.53 16:14:14 INFO - PID 16639 | Values: 73.0 73.0 75.0 171.0 73.0 74.0 73.0 74.0 169.0 73.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:79.00 stddev:25.09 (31.8%) stddev-sans-first:25.97 16:14:14 INFO - PID 16639 | Values: 79.0 80.0 135.0 79.0 79.0 78.0 123.0 80.0 79.0 134.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 16:14:14 INFO - PID 16639 | Values: 114.0 113.0 114.0 114.0 114.0 114.0 114.0 115.0 115.0 114.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 16:14:14 INFO - PID 16639 | Values: 148.0 147.0 148.0 147.0 147.0 148.0 148.0 148.0 147.0 147.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:94.00 stddev:2.60 (2.8%) stddev-sans-first:2.73 16:14:14 INFO - PID 16639 | Values: 94.0 94.0 100.0 94.0 100.0 93.0 94.0 94.0 94.0 94.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 16:14:14 INFO - PID 16639 | Values: 53.0 54.0 55.0 54.0 53.0 54.0 54.0 53.0 53.0 53.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.74 (1.9%) stddev-sans-first:0.78 16:14:14 INFO - PID 16639 | Values: 38.0 38.0 40.0 38.0 38.0 37.0 38.0 38.0 38.0 38.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:74.00 stddev:2.31 (3.1%) stddev-sans-first:1.00 16:14:14 INFO - PID 16639 | Values: 80.0 72.0 74.0 74.0 75.0 74.0 73.0 72.0 73.0 73.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:125.00 stddev:15.21 (12.2%) stddev-sans-first:15.83 16:14:14 INFO - PID 16639 | Values: 119.0 127.0 122.0 129.0 132.0 123.0 114.0 121.0 119.0 168.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:173.00 stddev:3.99 (2.3%) stddev-sans-first:4.18 16:14:14 INFO - PID 16639 | Values: 170.0 175.0 174.0 168.0 168.0 177.0 167.0 172.0 178.0 169.0 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.50 stddev:2.13 (3.3%) stddev-sans-first:2.22 16:14:14 INFO - PID 16639 | Values: 66.0 64.0 63.0 65.0 66.0 62.0 64.0 67.0 69.0 63.0 16:14:14 INFO - PID 16639 | -------- Summary: end -------- 16:14:14 INFO - PID 16639 | 16:14:14 INFO - PID 16639 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:14:15 INFO - TEST-INFO | 16639: exit 0 16:14:15 INFO - TEST-OK | kraken | took 62227ms 16:14:15 INFO - SUITE-END | took 1174s 16:14:15 INFO - Completed test suite (00:19:34) 16:14:15 INFO - javascript benchmark 16:14:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8620.982174547575, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3255.1699316674258, 3309.2554175310556, 3313.0557791029532, 3322.4684050551914, 3330.833458327083, 6418.358164183579, 6482.0, 6537.934620653792, 6578.210614726234, 6582.4075833175, 6347.904781428282, 6450.0324951257335, 6452.64510451925, 6460.127882735829, 6494.51291153163, 5907.054871220595, 5982.102684597302, 5996.580239383245, 6001.789937352193, 6030.185924900126, 5971.790987315445, 5991.550633702468, 6042.486388656969, 6042.758289668408, 6078.756849726028, 961.5550028475428, 5601.131824567198, 5908.172855800191, 5938.406159384071, 6033.336333003366, 5955.761769529214, 5969.701514924236, 6024.457798798086, 6033.064874944391, 6058.878822423571, 5930.525557955396, 5940.138679891435, 6011.879762404727, 6020.4581587656885, 6028.367021462727, 6403.871922561522, 6517.6089434633595, 6521.250056243545, 6557.311482294338, 6593.044008618771, 6941.757038503679, 6986.755463558751, 7047.68285427157, 7053.153621565394, 7057.964710176468, 6835.453163746888, 6920.653967301665, 6927.826804329881, 6930.584164950069, 7048.118985126859, 6419.743210271584, 6495.740170393178, 6529.412352888263, 6530.706118224643, 6547.148870646785, 7168.426525877917, 7267.7819665410125, 7273.745418910313, 7281.599512026889, 7286.635668216568, 6820.35206655367, 6836.555623884432, 6837.384635382839, 6912.997615345746, 6914.792556223321, 6750.223724271674, 6886.24251332353, 6892.0, 7010.544314619636, 7029.5430796998035, 6939.340762627542, 6995.230524642387, 7007.649617519103, 7016.36852683261, 7027.43780497559, 6947.617881016593, 7044.823879403056, 7051.5063945523325, 7066.3640272374705, 7069.151701795818, 5384.91922621161, 5458.45415458459, 5467.289252397163, 5468.043092458804, 5469.671819690831, 4746.8101275948575, 4773.71357718538, 4794.952050479451, 4820.855374338776, 4848.612111031109, 5835.9708201458725, 5899.233099697012, 5924.940750592439, 5943.465088142001, 5945.613535120204, 6761.763338283235, 6776.356246156607, 6808.080909077211, 6938.757143499954, 6969.965150174318, 6077.3314935357075, 6107.358727333655, 6107.725152368154, 6113.755449782047, 6115.449609535074, 6028.788992385245, 6036.245469316335, 6042.154098426224, 6045.758169673251, 6069.02895536712, 6763.807868933846, 6765.830854228585, 6774.525783195129, 6823.93176068233, 6867.450603951672, 6996.195437524546, 7005.649717514206, 7077.1153605798245, 7093.609851458219, 7103.041089452858, 6561.737530498917, 6682.83292917681, 6743.696533656095, 6760.18877734675, 6833.145856767904, 6947.79156625302, 6963.8607227854145, 6972.163340399185, 6974.302569743188, 6982.371586557234, 7033.507654464139, 7116.644167791486, 7135.607541585263, 7136.0723105995885, 7140.107486564179, 6745.831354215988, 6758.189017317954, 6763.830904227434, 6859.691313890987, 6860.348266914635, 6630.0, 6710.16122984627, 6711.765088221889, 6712.463002959849, 6727.058211850443, 6626.436752875867, 6650.73397064112, 6651.600903945779, 6732.596044237362, 6739.191297044386, 6864.553804002724, 6871.587704737732, 6888.862222755417, 6898.275681053417, 6921.2386637468835, 6570.5400621956005, 6574.3754343337305, 6662.6335551545135, 6676.098726672031, 6680.632565208765], "value": 6321.070353909857, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.7799816051504, 846.1918867481555, 872.7861673889898, 875.816078623489, 1126.9098472122232, 29327.41345173098, 30036.24909377264, 30077.0, 39118.60881391185, 40269.39595906064, 40622.593774062254, 41125.58874411255, 41164.176716465656, 41234.38148427776, 41250.17499650005, 7242.891356629655, 7651.349635280993, 7805.921940780577, 7882.054153501559, 7909.327707144899, 7695.461317707763, 7796.376289896823, 7799.376049916022, 7800.180980996999, 7802.414818888577, 7784.260495252942, 7840.294373506384, 7846.646900889474, 7847.725329613465, 7850.371970242367, 7718.729844455445, 7798.571078590675, 7810.3361214296565, 7815.296623303873, 7823.061232652061, 7627.8855817162785, 7667.501612395176, 7673.808154796175, 7679.923200767977, 7680.308772210527, 37250.813745931366, 37515.62484375149, 37541.436878446846, 37552.24895502102, 37553.06117347061, 3556.3242983833247, 3590.3716849551483, 3591.1560783215923, 3593.6765691087926, 3594.3350480160993, 30522.54216186759, 30539.389212215854, 30572.235694107603, 30575.08274751761, 30686.693133068606, 5035.597152227813, 5163.793448262065, 5194.7662355193725, 5196.376434827807, 5228.607854410896, 179592.0, 180713.0, 181942.09028954903, 182480.0, 182555.08722456437, 46832.0, 46960.765196174485, 47128.764356178, 47174.5282547177, 47185.0, 49125.0, 49154.26268605974, 49358.506414936106, 49380.0, 49397.0, 14962.925185374004, 15384.769228461582, 15396.692066158614, 15412.768808467881, 15436.845631543765, 10423.5830566778, 10604.522796474177, 12225.510979560719, 12383.071269654813, 12418.875811241953, 13778.173309601256, 13793.517226897011, 13817.930910345585, 13834.30828458573, 13936.930315348358, 5400.351957765093, 5423.511883930465, 5433.565314774809, 5544.223808666709, 5635.408282130399, 1002.4636819301638, 1010.0858722855827, 1012.6303899076784, 1021.6832781837505, 1025.8102251083574, 21946.0, 28660.85669571639, 28964.27589310242, 29589.704102959124, 29773.10680679583, 1074.7904158689137, 1083.1226706367868, 1085.4138765066775, 1096.4243772019754, 1112.2047735868894, 12364.258144511357, 12396.81404778929, 12504.687382815502, 12511.624651260478, 16098.75851862223, 227593.0, 229447.0, 229861.0, 230990.0, 231108.0, 55270.72364638151, 55671.0, 55803.0, 55877.44122558804, 56090.43909560934, 1436.8275806903239, 1449.8840092792552, 1475.970480590382, 1479.681868398278, 1489.6946126044136, 26549.336266592716, 26669.733302666726, 27108.29573410598, 27558.311042224104, 27732.722672773016, 37270.44094338533, 37376.0, 37397.43903841499, 37470.0, 37524.249515010095, 203694.0, 204396.0, 204694.0, 204746.0, 205024.0, 33119.66880331262, 36459.45310820287, 36876.0, 36914.63085369112, 36942.0, 43169.13661726685, 44194.558054419045, 44604.0, 44729.0, 45080.77459612681, 32694.83652581722, 32761.67238327586, 32836.67163328336, 32903.34193316168, 33249.335013300086, 32491.512627310138, 32543.511847321835, 32731.509027364133, 32994.34011319808, 33387.332253355286, 36362.8181859089, 37173.0, 37245.81377093206, 37254.06864828401, 37269.254614908095, 31820.204494887814, 32082.35835283328, 32099.197520062186, 32332.35335293234, 32369.0, 23655.172068977503, 23858.40353991164, 24563.87718061398, 24630.753692462848, 24756.0], "value": 17250.041785661426, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.7195769835133, 552.1689856765568, 562.3982338897379, 564.7656222667594, 568.4628026514944, 21797.782022179792, 22428.0, 22551.097956081758, 22707.432314192134, 22753.431164220903, 25133.748662513368, 25623.487530249386, 25646.0, 25652.743472565267, 25661.101861434854, 6662.267150613441, 6825.795226143199, 6839.897401538981, 6843.726250949956, 6866.485013623973, 5963.671998040106, 6516.0, 6524.902126468107, 6564.737410503598, 6702.932970670279, 6471.0940468334475, 6475.287718350977, 6480.57876238043, 6487.902681459758, 6491.28595854458, 6472.7410903563805, 6473.514486413538, 6474.449671777905, 6476.546641735081, 6479.773207937723, 6029.78895738647, 6038.426349496791, 6038.818835434944, 6039.003564411867, 6040.728167232441, 27869.58195627067, 28821.855890720413, 28956.42087158266, 29167.124986250445, 29457.55813662797, 2015.2946468735972, 2084.6351888419467, 2095.570408066358, 2098.590774798915, 2104.7895210478982, 19367.90316048425, 19886.0, 19938.60122797536, 19963.90018049915, 20004.499887502785, 2401.5677178107935, 2417.226487523993, 2435.890384932682, 2441.4872876695913, 2503.699556053285, 131021.34489327493, 134551.0, 135511.32244338814, 135692.0, 136165.0, 14050.227237501831, 14762.630934226516, 14815.25923703832, 15018.0, 15085.54743357701, 21279.148834046773, 22733.43166420853, 23062.42343941415, 23083.192088276828, 23140.65289020666, 9801.852972205423, 9936.652217172508, 10010.099091081838, 10078.244131690153, 10088.243381746252, 9073.909260907438, 9104.226140777975, 9107.316951228684, 9211.447313161232, 9260.536973151315, 9173.357864949523, 9469.810603787886, 9483.47840868745, 9493.382930109521, 9541.141297283279, 2731.6995130535624, 2747.3406382468465, 2772.223777342347, 2790.246633408968, 2879.7696184305205, 957.7940742740205, 969.2585172343163, 992.9156021738089, 996.5415908682088, 1008.5058321422597, 19605.705914411294, 19628.803711962984, 19896.204151833765, 20164.294249701485, 20197.49506262355, 1026.5277972132903, 1041.95832166714, 1048.2452634103433, 1060.4008735064663, 1061.851340812287, 10826.837597436044, 10910.563577456971, 10912.56349746017, 10914.290571112851, 11254.774904501865, 438276.0, 457219.0, 457318.0, 472384.0, 472486.0, 104640.0, 111917.0, 112069.0, 114472.0, 121453.0, 1199.7300607363272, 1215.9695369697893, 1239.6528971888067, 1244.0980289290192, 1244.321844594698, 20835.270765523135, 21232.7876721237, 21286.680699789205, 21289.467763306042, 21307.14771409126, 56632.0, 58106.4189358101, 58218.7089064552, 59694.0, 61689.69155154195, 69017.0, 69303.0, 69640.65179674272, 69986.30013699798, 70149.29850701631, 14182.219977901315, 14651.267436627913, 14680.119192848464, 14697.55907322782, 14704.852951470348, 23016.42458938473, 23084.538309234056, 23227.0, 23306.766932330458, 23351.182708605116, 18235.726464102783, 18546.907265463586, 18662.0, 18739.812601873808, 18870.811291886905, 16405.671886561962, 16658.91670541688, 16795.832041679427, 16811.579710507336, 17377.304907803547, 18722.0, 19081.618367632847, 19169.0, 19185.90407047956, 19375.224991000767, 14436.639084022983, 14451.855481445471, 14454.421823127377, 14476.493322733653, 14488.85511144875, 17766.378176763752, 18186.36347727823, 18479.53801154982, 18527.81472185261, 18541.2583496664], "value": 12019.097700169717, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.8484474285458, 1355.2681551961941, 1374.2029622818766, 1374.2922394966588, 1385.4042761612507, 14925.0, 15113.395464181447, 15557.766633500492, 15573.37706491739, 15578.22108894555, 15605.375784968588, 15849.920750396232, 15851.0, 15875.36498540057, 15879.920600396981, 2661.7072122066556, 2723.183045086476, 2725.7683096936685, 2726.8636568171514, 2727.481778462096, 2696.5550684137193, 2783.151138902634, 2802.929926751827, 2805.3126983888974, 2809.452156829409, 2647.9735202648017, 2691.717369676185, 2692.8653567321653, 2696.7303269672975, 2699.392636656756, 2598.896044158241, 2621.947561048778, 2650.6156607291837, 2650.8542030188237, 2655.2830735701446, 2646.298730836311, 2649.1919964410845, 2662.8402295862115, 2665.840049597011, 2669.27929459045, 14637.926810365987, 14933.25333733309, 14956.700865982728, 14960.0, 14968.47610333644, 8026.277635012877, 8307.83384332316, 8309.709160179425, 8309.792255193608, 8310.958445207736, 5384.313316134576, 5579.274694289757, 5669.40471250521, 5697.259356283698, 5706.857328566777, 1878.5209771508178, 1889.9622007559772, 1904.2763749775136, 1950.502621831438, 1972.2406873353718, 26166.6075008875, 26388.472230555282, 26402.207933761827, 26433.47133057328, 26501.60247596268, 6602.801915942529, 6715.764948226789, 6764.5603035802515, 6862.9313706862295, 6868.793936181923, 16911.49265522036, 17016.149192540324, 17092.48722538326, 17136.057516836692, 17173.91413042952, 7910.881336779953, 7919.841603167904, 7920.5247685139075, 7922.247386498273, 7924.881126783102, 5824.796132135355, 6526.24948130971, 7301.269873012762, 7464.962675186698, 7478.85042299151, 5204.583633309327, 5315.893682126336, 5363.731813409314, 5363.865903352369, 5468.671879687232, 1495.0, 1514.9166795826116, 1518.7949626800462, 1526.809148856393, 1536.5313579358403, 834.870595057767, 838.1995194589152, 848.4781859156574, 848.5078654380444, 865.5196366016795, 7008.33420825031, 7016.2632923543315, 7031.683574239171, 7065.611391373421, 7090.468214883905, 684.4387602166275, 715.2811424518429, 719.2843121094544, 721.8339781850099, 722.0432926372472, 4487.483939346952, 4504.527024662363, 4570.8171673133365, 4576.885577860514, 4631.861044168681, 20857.269995550385, 21926.232581859877, 21986.560268794536, 22055.0, 22233.44416389603, 20636.380908572766, 21594.784052159277, 21614.56770864574, 21626.783732162792, 21642.458938526663, 10837.458127093614, 11099.056580190612, 11099.056580190774, 11114.166437517219, 11169.609063682732, 8752.21230089295, 9031.45811251327, 9141.3601047926, 9160.679376222068, 9250.95374523123, 18485.81514184841, 18529.81470185335, 18764.0, 18973.90513047426, 19327.420177394702, 18667.0, 18963.715544267125, 19013.33453329127, 19121.08789121077, 19232.0, 12085.335306558223, 12275.386230688608, 12344.13591048618, 12344.62966111018, 12348.567800126952, 13320.267385293902, 13459.259740714355, 13579.59261222165, 13777.862221377658, 13787.172769633855, 10826.133909287395, 10931.508082136483, 11035.944820275847, 11207.551697932318, 11276.887231127585, 10927.0, 10933.671989840317, 11059.668209953714, 11291.3789741565, 11454.083673306282, 9150.725478235663, 9215.078492151, 9299.814003720023, 9434.481103539096, 9459.858102128612, 7196.712131514681, 7243.0, 7282.4902256841515, 7291.380232680282, 7312.744053958086, 7309.8903516446235, 7362.6686799093095, 7433.479656423999, 7472.29013243741, 7480.3641690456925], "value": 6577.134807838166, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.16891706391783, 499.35583097803834, 532.9546988505977, 538.2464549630514, 540.8945255675144, 6424.807255782328, 6445.838854028651, 6463.159789227399, 6475.158229430168, 6593.802185934418, 6105.328413874481, 6216.191895053648, 6218.383220362704, 6259.374062593738, 6334.036448700906, 5014.548690617844, 5017.774200161001, 5099.311592934962, 5118.641695081327, 5154.32993710817, 4918.213085906255, 5061.721605311706, 5072.3405957225505, 5080.263361812535, 5102.566281866046, 5007.14878470659, 5009.899802003958, 5079.323823138374, 5084.211947148198, 5108.5913126949945, 4989.750512474362, 4992.550670439678, 5044.672096313728, 5066.69599824008, 5087.109755792721, 4515.193922431021, 4530.79611417487, 4545.499995000548, 4562.6121779648765, 4585.587297143273, 6223.408776166257, 6269.899583639441, 6330.923742963707, 6338.841528961767, 6368.140301059344, 5780.913286300709, 5784.595078344518, 5859.29688437386, 5870.471657550841, 5887.587868849182, 6573.967130164367, 6576.276609572943, 6578.703958321839, 6653.941095931526, 6675.432588230006, 4878.780454879539, 4901.289313049623, 4912.238603016538, 4949.82675606352, 4985.202367621199, 7015.719371225196, 7022.683979220897, 7029.89455158173, 7082.645867706646, 7132.144142702857, 5031.52200540948, 5046.34397528319, 5048.242763585492, 5075.796968121308, 5082.720450375265, 5127.666701664454, 5133.947540754211, 5138.30632864566, 5158.535731784157, 5184.9740751296, 5825.88348233033, 5847.26909136358, 5866.266716660418, 5881.617694849821, 5883.735231914574, 6708.899366509506, 6715.496337774686, 6742.494312926452, 6761.830954226184, 6778.864422711518, 1673.21358961288, 1714.7770789797496, 1728.161841506868, 1733.7572739816435, 1741.5384922995415, 1205.9457324420423, 1227.545808051009, 1233.3586535001928, 1237.665830225852, 1261.186534685123, 5835.607400965584, 5888.823335299948, 5926.081457374028, 5934.732937017759, 5954.345022047572, 6698.765543206062, 6769.898451523231, 6789.321067893171, 6819.590824550443, 6825.55633883796, 5829.766809327664, 5905.4685078343155, 5905.498032667186, 5909.438603332676, 5963.582549221513, 3633.94549081764, 3636.6726994570618, 3648.2338708871434, 3670.944935825965, 3697.4084146536425, 6619.404253617197, 6641.66791660415, 6669.666516674147, 6720.857454232694, 6747.426468750211, 6967.825804354931, 7082.362587367059, 7138.821529461701, 7161.785146445615, 7182.317679820409, 6614.8910012294145, 6737.8652426951185, 6756.358145976124, 6799.586146917773, 6805.625690587065, 6937.236903940461, 6951.235364110045, 6984.161900571964, 7028.191757947901, 7037.542559733601, 6880.311968803079, 6906.896396553955, 6912.135983002125, 6992.762316059016, 7029.507934444539, 6626.204855417381, 6740.494462915399, 6760.985852122221, 6794.010635320641, 6799.728010879509, 5600.207933461254, 5612.915806262991, 5621.943780562306, 5632.5775566831835, 5643.0, 6859.005444210462, 6889.724411023503, 6919.411849992807, 6920.411765000032, 6992.61540615271, 6499.772507962199, 6547.312532184052, 6592.31945318829, 6696.593715319643, 6699.9665001674675, 6374.681265936777, 6504.511886798095, 6635.777014700129, 6638.767643132467, 6671.933280667326, 6390.233172019387, 6515.54391192612, 6565.113709649328, 6571.178602674666, 6608.306127856506, 6655.966720166368, 6677.265500795006, 6711.0, 6714.429273511806, 6729.360710732472, 6245.0, 6426.807195784134, 6431.774887878902, 6455.354464553507, 6485.3514648534765], "value": 5172.855518654803, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [907.9909200907991, 915.482752244982, 924.7826760711232, 926.1803304075895, 927.2026057590475, 17323.82676173238, 17723.468295951126, 17729.822701772995, 17760.111994400293, 18846.71729924049, 20414.183432662714, 21093.0, 21275.36173914781, 21316.14735410582, 21357.893210533963, 959.1559427703611, 974.7027156717198, 979.4025644356936, 979.5102448775613, 979.902009799018, 954.236610711428, 992.9156021738161, 1004.7136566078669, 1006.0543089495887, 1006.1849901579744, 1064.4677661169414, 1066.3015724700333, 1066.3335415365398, 1066.4294602387724, 1066.8826429092794, 1061.0185578340042, 1062.606835470873, 1062.9627963021296, 1063.0432610650398, 1065.0521036277719, 773.1649818196335, 773.7446642607925, 774.5120574038376, 775.1202385292677, 782.3311069036006, 18947.43157705271, 18960.81039189618, 19101.522461938424, 19117.330893418664, 19372.0, 20665.89667051655, 20666.896665516728, 21058.5788284235, 21072.789272107235, 21426.5714685707, 15606.453774117852, 15611.141387223699, 15703.293351799197, 15753.369865205377, 17549.21028553718, 3766.190269092131, 3817.9809100954594, 3820.8089595520114, 3822.5412950446125, 3827.291950989048, 120987.0, 125389.0, 125970.0, 126204.0, 127725.0, 15875.841241587666, 16189.352425903067, 16233.83766162347, 16469.176541172892, 16582.83417165837, 30194.0, 30303.848480757457, 30325.0, 30404.695953040628, 30418.84790576033, 8857.37998340123, 8965.148310910454, 8986.955065224764, 8997.460152390877, 8998.55007249635, 4319.956800431955, 4463.553644635511, 4476.798544065525, 4481.820727170877, 4487.7980490877335, 5665.093585026376, 5806.1581070744505, 5856.560757943172, 5856.707164641751, 5857.062869940874, 1023.3910823060268, 1040.9323393979366, 1063.2132222155549, 1063.611781699674, 1066.1311031509294, 674.2953613473834, 683.8871586188322, 688.8174633722068, 699.3356311504092, 703.3212949503651, 11821.645350639494, 11991.820122698167, 12027.518899244107, 12382.5666101686, 13129.803052954214, 684.9452043836482, 693.4279219643814, 696.7735485967081, 710.0556260173956, 712.0636363182399, 5398.163284690879, 5624.21261023457, 5665.603407761417, 5830.125481177773, 5844.473997340175, 106786.0, 108195.0, 108283.0, 108537.45731271293, 108658.0, 94119.52940235392, 94501.5274923621, 95632.0, 98544.50727746316, 100179.49910250402, 82520.58739706383, 83793.58103209444, 84597.57701211455, 84790.15209847823, 86501.13498865056, 12527.749445010866, 12545.184563003375, 12607.87392126067, 12622.873771262537, 13091.934540327238, 39044.804775975936, 40550.59449405586, 40847.0, 41419.378709319986, 42482.36276455918, 65203.0, 66339.66830165818, 66380.66809666115, 67120.66439667965, 68783.3121668797, 18920.243190272784, 19172.233110675417, 19187.0, 19235.4229373119, 19265.13306901221, 20992.68510972306, 21273.149074037483, 21441.46396340104, 21478.14087436485, 21569.137234510443, 19213.711794322815, 19375.8062419374, 19526.121324540145, 19849.0, 19942.50143746418, 18256.634867302313, 19020.90489547599, 19029.429117126507, 19300.613987719888, 20041.39875803728, 14374.784378234544, 14392.640183995483, 18823.34118305853, 18841.057947102316, 19453.708194376813, 11481.081513479068, 11690.123240756808, 11715.648530544097, 11863.228890122115, 11873.109516786448, 14021.228832414316, 14455.204963726675, 14743.926280368529, 14937.477188298357, 15392.615184620026], "value": 9206.527156664659, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 107.0, 102.0, 107.0, 105.0, 106.0, 106.0, 98.0, 107.0, 109.0], "value": 104.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 119.0, 143.0, 190.0, 121.0, 150.0, 142.0, 120.0, 176.0, 123.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 161.0, 161.0, 162.0, 160.0, 163.0, 168.0, 166.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 75.0, 171.0, 73.0, 74.0, 73.0, 74.0, 169.0, 73.0], "value": 92.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 135.0, 79.0, 79.0, 78.0, 123.0, 80.0, 79.0, 134.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 115.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 147.0, 147.0, 148.0, 148.0, 148.0, 147.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 100.0, 94.0, 100.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 55.0, 54.0, 53.0, 54.0, 54.0, 53.0, 53.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 40.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 72.0, 74.0, 74.0, 75.0, 74.0, 73.0, 72.0, 73.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 127.0, 122.0, 129.0, 132.0, 123.0, 114.0, 121.0, 119.0, 168.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 175.0, 174.0, 168.0, 168.0, 177.0, 167.0, 172.0, 178.0, 169.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 63.0, 65.0, 66.0, 62.0, 64.0, 67.0, 69.0, 63.0], "value": 64.9, "unit": "score"}]}]} 16:14:15 INFO - Return code: 0 16:14:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:14:15 INFO - # TBPL SUCCESS # 16:14:15 INFO - Running post-action listener: _package_coverage_data 16:14:15 INFO - Running post-action listener: _resource_record_post_action 16:14:15 INFO - [mozharness: 2017-09-01 23:14:15.704330Z] Finished run-tests step (success) 16:14:15 INFO - Running post-run listener: _resource_record_post_run 16:14:15 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 28672; Write bytes: 394416128; Read time: 708; Write time: 520044 16:14:15 INFO - TinderboxPrint: CPU usage
13.1% 16:14:15 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 708 16:14:15 INFO - TinderboxPrint: I/O write bytes / time
394,416,128 / 520,044 16:14:15 INFO - TinderboxPrint: CPU idle
8,245.5 (86.8%) 16:14:15 INFO - TinderboxPrint: CPU user
1,193.4 (12.6%) 16:14:15 INFO - TinderboxPrint: Swap in / out
0 / 0 16:14:15 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 4096; Write bytes: 113545216; Read time: 368; Write time: 181880 16:14:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:14:15 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 24576; Write bytes: 280866816; Read time: 340; Write time: 338140 16:14:16 INFO - Running post-run listener: _upload_blobber_files 16:14:16 INFO - Blob upload gear active. 16:14:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:14:16 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 16:14:16 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'] 16:14:16 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 16:14:16 INFO - (blobuploader) - INFO - Open directory for files ... 16:14:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:14:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:14:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:17 INFO - (blobuploader) - INFO - Done attempting. 16:14:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:14:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:21 INFO - (blobuploader) - INFO - Done attempting. 16:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:14:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:22 INFO - (blobuploader) - INFO - Done attempting. 16:14:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:14:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:14:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:23 INFO - (blobuploader) - INFO - Done attempting. 16:14:23 INFO - (blobuploader) - INFO - Iteration through files over. 16:14:23 INFO - Return code: 0 16:14:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:14:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:14:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a6993223b8fc99a9b80296444238b6f9ee60042dd3a3bbf9ec9887d9b94d2c2dde1ba6956af59f92a6f63e7e3507109adb5a1cbd9707f1c11365f10d61dcd59", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e73460c61ed83d7a2a3049c187e38af4ea9330ea1f260165a80edab3d341644d869370c3faaeedea62a153383b45c0372df401bedfaadde6ab81c449ecd78f34", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/411b526e89da67d045404a3aaf5f9e2d22a0a289a55ac5f4b5f26237d83cb8e1e7b1e28c69d90f62dc70f81afead990340810b8642d25982684a8b6863f18e35", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b66e15e43bf2a21bd4c0b0261ee49a207ff7f5210af79bde0bab05e3aaa04531e5ae29fa1f42b00742c228fa8b5282b1b21016db952e3bac4017cbb64ccaaf14"} 16:14:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:14:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:14:23 INFO - Contents: 16:14:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a6993223b8fc99a9b80296444238b6f9ee60042dd3a3bbf9ec9887d9b94d2c2dde1ba6956af59f92a6f63e7e3507109adb5a1cbd9707f1c11365f10d61dcd59", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e73460c61ed83d7a2a3049c187e38af4ea9330ea1f260165a80edab3d341644d869370c3faaeedea62a153383b45c0372df401bedfaadde6ab81c449ecd78f34", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/411b526e89da67d045404a3aaf5f9e2d22a0a289a55ac5f4b5f26237d83cb8e1e7b1e28c69d90f62dc70f81afead990340810b8642d25982684a8b6863f18e35", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b66e15e43bf2a21bd4c0b0261ee49a207ff7f5210af79bde0bab05e3aaa04531e5ae29fa1f42b00742c228fa8b5282b1b21016db952e3bac4017cbb64ccaaf14"} 16:14:23 INFO - Running post-run listener: copy_logs_to_upload_dir 16:14:23 INFO - Copying logs to upload dir... 16:14:23 INFO - mkdir: /builds/slave/test/build/upload/logs 16:14:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.206805 ========= master_lag: -0.29 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-09-01 16:14:23.269151) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 16:14:23.276802) ========= 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=8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a6993223b8fc99a9b80296444238b6f9ee60042dd3a3bbf9ec9887d9b94d2c2dde1ba6956af59f92a6f63e7e3507109adb5a1cbd9707f1c11365f10d61dcd59", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e73460c61ed83d7a2a3049c187e38af4ea9330ea1f260165a80edab3d341644d869370c3faaeedea62a153383b45c0372df401bedfaadde6ab81c449ecd78f34", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/411b526e89da67d045404a3aaf5f9e2d22a0a289a55ac5f4b5f26237d83cb8e1e7b1e28c69d90f62dc70f81afead990340810b8642d25982684a8b6863f18e35", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b66e15e43bf2a21bd4c0b0261ee49a207ff7f5210af79bde0bab05e3aaa04531e5ae29fa1f42b00742c228fa8b5282b1b21016db952e3bac4017cbb64ccaaf14"} build_url:https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011355 build_url: 'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a6993223b8fc99a9b80296444238b6f9ee60042dd3a3bbf9ec9887d9b94d2c2dde1ba6956af59f92a6f63e7e3507109adb5a1cbd9707f1c11365f10d61dcd59", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e73460c61ed83d7a2a3049c187e38af4ea9330ea1f260165a80edab3d341644d869370c3faaeedea62a153383b45c0372df401bedfaadde6ab81c449ecd78f34", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/411b526e89da67d045404a3aaf5f9e2d22a0a289a55ac5f4b5f26237d83cb8e1e7b1e28c69d90f62dc70f81afead990340810b8642d25982684a8b6863f18e35", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b66e15e43bf2a21bd4c0b0261ee49a207ff7f5210af79bde0bab05e3aaa04531e5ae29fa1f42b00742c228fa8b5282b1b21016db952e3bac4017cbb64ccaaf14"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 16:14:23.312632) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 16:14:23.313073) ========= 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=8783db0840cc980e80bb0bd400000236-1504306404.902133-1483813873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 16:14:23.395429) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 16:14:23.395871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 16:14:23.396285) ========= ========= Total master_lag: -0.11 =========